2018-12-25 14:49:58,776 [salt.utils       :831 ][ERROR   ][1503] DNS lookup or connection check of 'salt' failed.
2018-12-25 14:49:58,777 [salt.minion      :152 ][ERROR   ][1503] Master hostname: 'salt' not found or not responsive. Retrying in 30 seconds
2018-12-25 14:50:02,363 [salt.utils.parsers:1051][WARNING ][1503] Minion received a SIGTERM. Exiting.
2018-12-25 14:51:23,844 [salt.loaded.int.states.file:2298][WARNING ][1854] State for file: /boot/grub/grub.cfg - 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.
2018-12-25 14:51:34,030 [salt.utils.decorators:613 ][WARNING ][1854] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-25 14:51:34,128 [salt.loaded.int.states.file:2298][WARNING ][1854] State for file: /etc/shadow - 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.
2018-12-25 14:51:34,130 [salt.loaded.int.states.file:2298][WARNING ][1854] State for file: /etc/gshadow - 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.
2018-12-25 14:51:34,131 [salt.loaded.int.states.file:2298][WARNING ][1854] State for file: /etc/group- - 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.
2018-12-25 14:51:34,132 [salt.loaded.int.states.file:2298][WARNING ][1854] State for file: /etc/group - 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.
2018-12-25 14:51:34,134 [salt.loaded.int.states.file:2298][WARNING ][1854] State for file: /etc/passwd- - 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.
2018-12-25 14:51:34,135 [salt.loaded.int.states.file:2298][WARNING ][1854] State for file: /etc/passwd - 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.
2018-12-25 14:51:34,137 [salt.loaded.int.states.file:2298][WARNING ][1854] State for file: /etc/gshadow- - 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.
2018-12-25 14:51:34,138 [salt.loaded.int.states.file:2298][WARNING ][1854] State for file: /etc/shadow- - 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.
2018-12-25 14:51:34,352 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][1854] Command '['umount', '/dev/shm']' failed with return code: 32
2018-12-25 14:51:34,353 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][1854] stderr: umount: /dev/shm: target is busy
        (In some cases useful info about processes that
         use the device is found by lsof(8) or fuser(1).)
2018-12-25 14:51:34,354 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][1854] retcode: 32
2018-12-25 14:51:45,263 [salt.utils.parsers:1051][WARNING ][1711] Minion received a SIGTERM. Exiting.
2018-12-25 14:52:34,666 [salt.utils.decorators:613 ][WARNING ][1975] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-25 14:53:05,180 [salt.loaded.int.states.file:2298][WARNING ][4656] State for file: /boot/grub/grub.cfg - 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.
2018-12-25 14:53:08,300 [salt.loaded.int.states.file:2298][WARNING ][4656] State for file: /etc/shadow - 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.
2018-12-25 14:53:08,302 [salt.loaded.int.states.file:2298][WARNING ][4656] State for file: /etc/gshadow - 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.
2018-12-25 14:53:08,304 [salt.loaded.int.states.file:2298][WARNING ][4656] State for file: /etc/group- - 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.
2018-12-25 14:53:08,305 [salt.loaded.int.states.file:2298][WARNING ][4656] State for file: /etc/group - 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.
2018-12-25 14:53:08,307 [salt.loaded.int.states.file:2298][WARNING ][4656] State for file: /etc/passwd- - 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.
2018-12-25 14:53:08,309 [salt.loaded.int.states.file:2298][WARNING ][4656] State for file: /etc/passwd - 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.
2018-12-25 14:53:08,310 [salt.loaded.int.states.file:2298][WARNING ][4656] State for file: /etc/gshadow- - 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.
2018-12-25 14:53:08,311 [salt.loaded.int.states.file:2298][WARNING ][4656] State for file: /etc/shadow- - 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.
2018-12-25 14:53:08,470 [salt.utils.decorators:613 ][WARNING ][4656] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-25 14:53:08,797 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][4656] Command '['umount', '/dev/shm']' failed with return code: 32
2018-12-25 14:53:08,797 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][4656] stderr: umount: /dev/shm: target is busy
        (In some cases useful info about processes that
         use the device is found by lsof(8) or fuser(1).)
2018-12-25 14:53:08,797 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][4656] retcode: 32
2018-12-25 14:53:16,178 [salt.utils.decorators:613 ][WARNING ][4656] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-25 14:53:16,211 [salt.utils.decorators:613 ][WARNING ][4656] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-25 14:54:12,325 [salt.loaded.int.states.file:2298][WARNING ][6613] 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.
2018-12-25 14:57:43,455 [salt.state       :2022][WARNING ][12898] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2018-12-25 14:57:45,185 [salt.utils.decorators:613 ][WARNING ][12898] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-25 14:59:37,783 [salt.utils.decorators:613 ][WARNING ][12898] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-25 15:10:45,537 [salt.utils.decorators:613 ][WARNING ][12898] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-25 15:11:13,194 [salt.utils.decorators:613 ][WARNING ][12898] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-25 15:11:16,182 [salt.loaded.ext.module.maasng:1008][WARNING ][12898] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2018-12-25 15:11:16,183 [salt.loaded.ext.module.maasng:1011][WARNING ][12898] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2018-12-25 15:11:17,028 [salt.loaded.ext.module.maasng:1235][WARNING ][12898] Ignoring parameter vlan:0
2018-12-25 15:11:20,295 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17230] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2018-12-25 15:11:20,322 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17230] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2018-12-25 15:11:20,351 [salt.utils.parsers:1051][WARNING ][1726] Minion received a SIGTERM. Exiting.
2018-12-25 15:11:21,355 [salt.cli.daemons :293 ][INFO    ][17328] Setting up the Salt Minion "mas01.mcp-ovs-ha.local"
2018-12-25 15:11:21,484 [salt.cli.daemons :82  ][INFO    ][17328] Starting up the Salt Minion
2018-12-25 15:11:21,485 [salt.utils.event :1017][INFO    ][17328] Starting pull socket on /var/run/salt/minion/minion_event_501f9ec045_pull.ipc
2018-12-25 15:11:22,089 [salt.minion      :976 ][INFO    ][17328] Creating minion process manager
2018-12-25 15:11:23,219 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][17328] Executing command ['date', '+%z'] in directory '/root'
2018-12-25 15:11:23,238 [salt.utils.schedule:568 ][INFO    ][17328] Updating job settings for scheduled job: __mine_interval
2018-12-25 15:11:23,240 [salt.minion      :1108][INFO    ][17328] Added mine.update to scheduler
2018-12-25 15:11:23,247 [salt.minion      :1975][INFO    ][17328] Minion is starting as user 'root'
2018-12-25 15:11:23,260 [salt.minion      :2336][INFO    ][17328] Minion is ready to receive requests!
2018-12-25 15:11:26,868 [salt.utils.decorators:613 ][WARNING ][17237] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-25 15:11:26,943 [salt.loaded.ext.module.maas:412 ][WARNING ][17237] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2018-12-25 15:11:28,479 [salt.loaded.ext.module.maas:412 ][WARNING ][17237] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2018-12-25 15:11:28,825 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225151128804700
2018-12-25 15:11:28,869 [salt.minion      :1432][INFO    ][17515] Starting a new job with PID 17515
2018-12-25 15:11:28,915 [salt.minion      :1711][INFO    ][17515] Returning information for job: 20181225151128804700
2018-12-25 15:11:29,972 [salt.loaded.ext.module.maas:412 ][WARNING ][17237] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2018-12-25 15:11:31,201 [salt.loaded.ext.module.maas:412 ][WARNING ][17237] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2018-12-25 15:11:32,687 [salt.loaded.ext.module.maas:412 ][WARNING ][17237] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2018-12-25 15:12:14,527 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command state.apply with jid 20181225151214505514
2018-12-25 15:12:14,548 [salt.minion      :1432][INFO    ][17813] Starting a new job with PID 17813
2018-12-25 15:12:20,447 [salt.state       :915 ][INFO    ][17813] Loading fresh modules for state activity
2018-12-25 15:12:20,511 [salt.fileclient  :1219][INFO    ][17813] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2018-12-25 15:12:20,565 [salt.state       :1780][INFO    ][17813] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 15:12:20.565880
2018-12-25 15:12:20,566 [salt.state       :1813][INFO    ][17813] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-12-25 15:12:20,569 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17813] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-12-25 15:12:22,527 [salt.state       :300 ][INFO    ][17813] {'pid': 17837, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-12-25 15:12:22,529 [salt.state       :1951][INFO    ][17813] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 15:12:22.529679 duration_in_ms=1963.786
2018-12-25 15:12:22,534 [salt.state       :1780][INFO    ][17813] Running state [maas.wait_for_machine_status] at time 15:12:22.534325
2018-12-25 15:12:22,535 [salt.state       :1813][INFO    ][17813] Executing state module.run for [maas.wait_for_machine_status]
2018-12-25 15:12:22,537 [salt.utils.decorators:613 ][WARNING ][17813] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-25 15:12:23,491 [salt.loaded.ext.module.maas:1001][INFO    ][17813] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.05658603s left)
2018-12-25 15:12:24,695 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225151224627506
2018-12-25 15:12:24,718 [salt.minion      :1432][INFO    ][17850] Starting a new job with PID 17850
2018-12-25 15:12:24,745 [salt.minion      :1711][INFO    ][17850] Returning information for job: 20181225151224627506
2018-12-25 15:12:34,701 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225151234673660
2018-12-25 15:12:34,736 [salt.minion      :1432][INFO    ][17856] Starting a new job with PID 17856
2018-12-25 15:12:34,762 [salt.minion      :1711][INFO    ][17856] Returning information for job: 20181225151234673660
2018-12-25 15:12:44,719 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225151244694489
2018-12-25 15:12:44,745 [salt.minion      :1432][INFO    ][17872] Starting a new job with PID 17872
2018-12-25 15:12:44,765 [salt.minion      :1711][INFO    ][17872] Returning information for job: 20181225151244694489
2018-12-25 15:12:54,406 [salt.loaded.ext.module.maas:1001][INFO    ][17813] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.14131594s left)
2018-12-25 15:12:54,929 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225151254907541
2018-12-25 15:12:54,952 [salt.minion      :1432][INFO    ][17900] Starting a new job with PID 17900
2018-12-25 15:12:54,974 [salt.minion      :1711][INFO    ][17900] Returning information for job: 20181225151254907541
2018-12-25 15:13:05,150 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225151305125198
2018-12-25 15:13:05,176 [salt.minion      :1432][INFO    ][17905] Starting a new job with PID 17905
2018-12-25 15:13:05,200 [salt.minion      :1711][INFO    ][17905] Returning information for job: 20181225151305125198
2018-12-25 15:13:15,359 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225151315341876
2018-12-25 15:13:15,377 [salt.minion      :1432][INFO    ][17914] Starting a new job with PID 17914
2018-12-25 15:13:15,399 [salt.minion      :1711][INFO    ][17914] Returning information for job: 20181225151315341876
2018-12-25 15:13:25,380 [salt.loaded.ext.module.maas:1001][INFO    ][17813] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.16776896s left)
2018-12-25 15:13:25,576 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225151325551336
2018-12-25 15:13:25,601 [salt.minion      :1432][INFO    ][17942] Starting a new job with PID 17942
2018-12-25 15:13:25,632 [salt.minion      :1711][INFO    ][17942] Returning information for job: 20181225151325551336
2018-12-25 15:13:35,802 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225151335778188
2018-12-25 15:13:35,833 [salt.minion      :1432][INFO    ][17948] Starting a new job with PID 17948
2018-12-25 15:13:35,857 [salt.minion      :1711][INFO    ][17948] Returning information for job: 20181225151335778188
2018-12-25 15:13:46,025 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225151346003476
2018-12-25 15:13:46,050 [salt.minion      :1432][INFO    ][17967] Starting a new job with PID 17967
2018-12-25 15:13:46,072 [salt.minion      :1711][INFO    ][17967] Returning information for job: 20181225151346003476
2018-12-25 15:13:56,230 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225151356212675
2018-12-25 15:13:56,257 [salt.minion      :1432][INFO    ][18018] Starting a new job with PID 18018
2018-12-25 15:13:56,284 [salt.minion      :1711][INFO    ][18018] Returning information for job: 20181225151356212675
2018-12-25 15:13:56,360 [salt.loaded.ext.module.maas:1001][INFO    ][17813] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.18762708s left)
2018-12-25 15:14:06,429 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225151406410716
2018-12-25 15:14:06,456 [salt.minion      :1432][INFO    ][18024] Starting a new job with PID 18024
2018-12-25 15:14:06,478 [salt.minion      :1711][INFO    ][18024] Returning information for job: 20181225151406410716
2018-12-25 15:14:16,641 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225151416625754
2018-12-25 15:14:16,661 [salt.minion      :1432][INFO    ][18070] Starting a new job with PID 18070
2018-12-25 15:14:16,686 [salt.minion      :1711][INFO    ][18070] Returning information for job: 20181225151416625754
2018-12-25 15:14:26,822 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225151426805065
2018-12-25 15:14:26,850 [salt.minion      :1432][INFO    ][18102] Starting a new job with PID 18102
2018-12-25 15:14:26,875 [salt.minion      :1711][INFO    ][18102] Returning information for job: 20181225151426805065
2018-12-25 15:14:27,542 [salt.loaded.ext.module.maas:1001][INFO    ][17813] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.00504899s left)
2018-12-25 15:14:36,836 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225151436818422
2018-12-25 15:14:36,857 [salt.minion      :1432][INFO    ][18111] Starting a new job with PID 18111
2018-12-25 15:14:36,884 [salt.minion      :1711][INFO    ][18111] Returning information for job: 20181225151436818422
2018-12-25 15:14:47,056 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225151447038180
2018-12-25 15:14:47,079 [salt.minion      :1432][INFO    ][18185] Starting a new job with PID 18185
2018-12-25 15:14:47,103 [salt.minion      :1711][INFO    ][18185] Returning information for job: 20181225151447038180
2018-12-25 15:14:57,077 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225151457050179
2018-12-25 15:14:57,103 [salt.minion      :1432][INFO    ][18223] Starting a new job with PID 18223
2018-12-25 15:14:57,132 [salt.minion      :1711][INFO    ][18223] Returning information for job: 20181225151457050179
2018-12-25 15:14:58,774 [salt.loaded.ext.module.maas:1001][INFO    ][17813] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1343.77330995s left)
2018-12-25 15:15:07,097 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225151507075848
2018-12-25 15:15:07,123 [salt.minion      :1432][INFO    ][18255] Starting a new job with PID 18255
2018-12-25 15:15:07,150 [salt.minion      :1711][INFO    ][18255] Returning information for job: 20181225151507075848
2018-12-25 15:15:17,115 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225151517097044
2018-12-25 15:15:17,138 [salt.minion      :1432][INFO    ][18343] Starting a new job with PID 18343
2018-12-25 15:15:17,162 [salt.minion      :1711][INFO    ][18343] Returning information for job: 20181225151517097044
2018-12-25 15:15:27,126 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225151527110318
2018-12-25 15:15:27,146 [salt.minion      :1432][INFO    ][18368] Starting a new job with PID 18368
2018-12-25 15:15:27,169 [salt.minion      :1711][INFO    ][18368] Returning information for job: 20181225151527110318
2018-12-25 15:15:29,990 [salt.loaded.ext.module.maas:1001][INFO    ][17813] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1312.55756593s left)
2018-12-25 15:15:37,326 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225151537305282
2018-12-25 15:15:37,362 [salt.minion      :1432][INFO    ][18463] Starting a new job with PID 18463
2018-12-25 15:15:37,393 [salt.minion      :1711][INFO    ][18463] Returning information for job: 20181225151537305282
2018-12-25 15:15:47,337 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225151547320585
2018-12-25 15:15:47,365 [salt.minion      :1432][INFO    ][18588] Starting a new job with PID 18588
2018-12-25 15:15:47,389 [salt.minion      :1711][INFO    ][18588] Returning information for job: 20181225151547320585
2018-12-25 15:15:57,538 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225151557522563
2018-12-25 15:15:57,560 [salt.minion      :1432][INFO    ][18647] Starting a new job with PID 18647
2018-12-25 15:15:57,584 [salt.minion      :1711][INFO    ][18647] Returning information for job: 20181225151557522563
2018-12-25 15:16:01,663 [salt.loaded.ext.module.maas:1001][INFO    ][17813] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1280.88476706s left)
2018-12-25 15:16:07,738 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225151607714477
2018-12-25 15:16:07,767 [salt.minion      :1432][INFO    ][18673] Starting a new job with PID 18673
2018-12-25 15:16:07,799 [salt.minion      :1711][INFO    ][18673] Returning information for job: 20181225151607714477
2018-12-25 15:16:17,777 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225151617756671
2018-12-25 15:16:17,803 [salt.minion      :1432][INFO    ][18835] Starting a new job with PID 18835
2018-12-25 15:16:17,826 [salt.minion      :1711][INFO    ][18835] Returning information for job: 20181225151617756671
2018-12-25 15:16:27,810 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225151627788021
2018-12-25 15:16:27,838 [salt.minion      :1432][INFO    ][18851] Starting a new job with PID 18851
2018-12-25 15:16:27,863 [salt.minion      :1711][INFO    ][18851] Returning information for job: 20181225151627788021
2018-12-25 15:16:33,401 [salt.loaded.ext.module.maas:1001][INFO    ][17813] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1249.14640808s left)
2018-12-25 15:16:37,837 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225151637812909
2018-12-25 15:16:37,861 [salt.minion      :1432][INFO    ][18879] Starting a new job with PID 18879
2018-12-25 15:16:37,888 [salt.minion      :1711][INFO    ][18879] Returning information for job: 20181225151637812909
2018-12-25 15:16:47,875 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225151647855749
2018-12-25 15:16:47,910 [salt.minion      :1432][INFO    ][19147] Starting a new job with PID 19147
2018-12-25 15:16:47,941 [salt.minion      :1711][INFO    ][19147] Returning information for job: 20181225151647855749
2018-12-25 15:16:57,941 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225151657917677
2018-12-25 15:16:57,962 [salt.minion      :1432][INFO    ][19225] Starting a new job with PID 19225
2018-12-25 15:16:57,985 [salt.minion      :1711][INFO    ][19225] Returning information for job: 20181225151657917677
2018-12-25 15:17:05,786 [salt.loaded.ext.module.maas:1001][INFO    ][17813] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1216.76072907s left)
2018-12-25 15:17:07,974 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225151707957861
2018-12-25 15:17:07,998 [salt.minion      :1432][INFO    ][19296] Starting a new job with PID 19296
2018-12-25 15:17:08,023 [salt.minion      :1711][INFO    ][19296] Returning information for job: 20181225151707957861
2018-12-25 15:17:18,030 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225151718008860
2018-12-25 15:17:18,056 [salt.minion      :1432][INFO    ][19385] Starting a new job with PID 19385
2018-12-25 15:17:18,084 [salt.minion      :1711][INFO    ][19385] Returning information for job: 20181225151718008860
2018-12-25 15:17:28,094 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225151728072061
2018-12-25 15:17:28,117 [salt.minion      :1432][INFO    ][19407] Starting a new job with PID 19407
2018-12-25 15:17:28,144 [salt.minion      :1711][INFO    ][19407] Returning information for job: 20181225151728072061
2018-12-25 15:17:38,110 [salt.loaded.ext.module.maas:1001][INFO    ][17813] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1184.4375751s left)
2018-12-25 15:17:38,145 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225151738127983
2018-12-25 15:17:38,165 [salt.minion      :1432][INFO    ][19436] Starting a new job with PID 19436
2018-12-25 15:17:38,188 [salt.minion      :1711][INFO    ][19436] Returning information for job: 20181225151738127983
2018-12-25 15:17:48,175 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225151748154134
2018-12-25 15:17:48,199 [salt.minion      :1432][INFO    ][19591] Starting a new job with PID 19591
2018-12-25 15:17:48,219 [salt.minion      :1711][INFO    ][19591] Returning information for job: 20181225151748154134
2018-12-25 15:17:58,241 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225151758218328
2018-12-25 15:17:58,268 [salt.minion      :1432][INFO    ][19608] Starting a new job with PID 19608
2018-12-25 15:17:58,294 [salt.minion      :1711][INFO    ][19608] Returning information for job: 20181225151758218328
2018-12-25 15:18:08,320 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225151808302802
2018-12-25 15:18:08,342 [salt.minion      :1432][INFO    ][19622] Starting a new job with PID 19622
2018-12-25 15:18:08,368 [salt.minion      :1711][INFO    ][19622] Returning information for job: 20181225151808302802
2018-12-25 15:18:10,948 [salt.loaded.ext.module.maas:1001][INFO    ][17813] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03']
sleep for:30s Timeout:1500s (1151.59915709s left)
2018-12-25 15:18:18,369 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225151818351609
2018-12-25 15:18:18,393 [salt.minion      :1432][INFO    ][19803] Starting a new job with PID 19803
2018-12-25 15:18:18,417 [salt.minion      :1711][INFO    ][19803] Returning information for job: 20181225151818351609
2018-12-25 15:18:28,458 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225151828440430
2018-12-25 15:18:28,480 [salt.minion      :1432][INFO    ][19809] Starting a new job with PID 19809
2018-12-25 15:18:28,504 [salt.minion      :1711][INFO    ][19809] Returning information for job: 20181225151828440430
2018-12-25 15:18:38,552 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225151838531503
2018-12-25 15:18:38,577 [salt.minion      :1432][INFO    ][19845] Starting a new job with PID 19845
2018-12-25 15:18:38,607 [salt.minion      :1711][INFO    ][19845] Returning information for job: 20181225151838531503
2018-12-25 15:18:44,006 [salt.state       :300 ][INFO    ][17813] {'ret': True}
2018-12-25 15:18:44,007 [salt.state       :1951][INFO    ][17813] Completed state [maas.wait_for_machine_status] at time 15:18:44.007753 duration_in_ms=381473.426
2018-12-25 15:18:44,014 [salt.minion      :1711][INFO    ][17813] Returning information for job: 20181225151214505514
2018-12-25 15:18:44,553 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command state.apply with jid 20181225151844537150
2018-12-25 15:18:44,574 [salt.minion      :1432][INFO    ][19857] Starting a new job with PID 19857
2018-12-25 15:18:50,261 [salt.state       :915 ][INFO    ][19857] Loading fresh modules for state activity
2018-12-25 15:18:50,321 [salt.fileclient  :1219][INFO    ][19857] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2018-12-25 15:18:50,430 [salt.state       :1780][INFO    ][19857] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 15:18:50.430156
2018-12-25 15:18:50,430 [salt.state       :1813][INFO    ][19857] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-12-25 15:18:50,433 [salt.loaded.int.module.cmdmod:395 ][INFO    ][19857] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-12-25 15:18:52,455 [salt.state       :300 ][INFO    ][19857] {'pid': 19883, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-12-25 15:18:52,456 [salt.state       :1951][INFO    ][19857] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 15:18:52.456553 duration_in_ms=2026.397
2018-12-25 15:18:52,459 [salt.state       :1780][INFO    ][19857] Running state [maas_machines_storage_cmp002_lvm] at time 15:18:52.459731
2018-12-25 15:18:52,460 [salt.state       :1813][INFO    ][19857] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2018-12-25 15:18:53,750 [salt.loaded.ext.module.maasng:610 ][INFO    ][19857] qqbrd6
2018-12-25 15:18:53,750 [salt.loaded.ext.module.maasng:626 ][INFO    ][19857] sda
2018-12-25 15:18:54,400 [salt.loaded.ext.module.maasng:361 ][INFO    ][19857] qqbrd6
2018-12-25 15:18:54,498 [salt.loaded.ext.module.maasng:367 ][INFO    ][19857] [{u'model': u'LOGICAL VOLUME', u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/qqbrd6/blockdevices/2/', u'type': u'physical', u'partitions': [{u'uuid': u'94a1e691-d189-4405-8afc-dae2aee37ddf', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qqbrd6', u'device_id': 2, u'filesystem': {u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'uuid': u'404c86df-348a-4cd9-9146-473b28a15544'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/qqbrd6/blockdevices/2/partition/1'}], u'tags': [u'ssd'], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'qqbrd6', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'size': 800109715456}, {u'model': None, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/qqbrd6/blockdevices/4/', u'type': u'virtual', u'partitions': [], u'tags': [], u'filesystem': {u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'uuid': u'662eb529-7ac3-4df3-ab76-5cf82fda1d74'}, u'uuid': u'1298605c-63d8-4990-bea9-f28c314e0fd4', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'qqbrd6', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'used_size': 800097042432, u'id': 4, u'size': 800097042432}]
2018-12-25 15:18:54,499 [salt.loaded.ext.module.maasng:632 ][INFO    ][19857] vgroot
2018-12-25 15:18:54,499 [salt.loaded.ext.module.maasng:635 ][INFO    ][19857] lvroot
2018-12-25 15:18:54,499 [salt.loaded.ext.module.maasng:639 ][INFO    ][19857] 107374182400
2018-12-25 15:18:54,571 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225151854552821
2018-12-25 15:18:54,594 [salt.minion      :1432][INFO    ][19898] Starting a new job with PID 19898
2018-12-25 15:18:54,618 [salt.minion      :1711][INFO    ][19898] Returning information for job: 20181225151854552821
2018-12-25 15:18:55,236 [salt.loaded.ext.module.maasng:645 ][INFO    ][19857] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'swap_size': None, u'ip_addresses': [u'192.168.11.39', u'192.168.11.40'], u'cpu_count': 40, u'power_type': u'ipmi', u'hwe_kernel': u'', u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'8mebq3', u'fabric': u'pxe_admin', 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'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': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 23}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'8mebq3', u'fabric': u'pxe_admin', 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'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'8mebq3', u'fabric': u'pxe_admin', 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'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': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'params': u'', u'system_id': u'qqbrd6', u'type': u'physical', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/qqbrd6/interfaces/6/'}, u'min_hwe_kernel': u'hwe-16.04', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'disable_ipv4': False, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'node_type': 0, u'testing_status': 2, u'system_id': u'qqbrd6', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'owner_data': {}, u'blockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/qqbrd6/blockdevices/2/', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'bbaf5bef-d744-4ff8-92b0-b7f10a80b80d', u'resource_uri': u'/MAAS/api/2.0/nodes/qqbrd6/blockdevices/2/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qqbrd6', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'77131bf1-e8b6-4169-b152-ae981764044e', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 6, u'size': 800101236736}], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'system_id': u'qqbrd6', u'partition_table_type': u'MBR', u'available_size': 0, u'id': 2, u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}, {u'resource_uri': u'/MAAS/api/2.0/nodes/qqbrd6/blockdevices/12/', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'7647ca8e-be6f-4119-8b97-04a005672cda', u'fstype': u'ext4'}, u'uuid': u'84419489-7e57-41d4-ae3a-3fbd6fecf3a4', u'used_for': u'ext4 formatted filesystem mounted at /', u'id_path': None, u'system_id': u'qqbrd6', u'partition_table_type': None, u'available_size': 0, u'id': 12, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'serial': None, u'size': 107374182400}], u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'raids': [], u'physicalblockdevice_set': [{u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/qqbrd6/blockdevices/2/', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'system_id': u'qqbrd6', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id': 2, u'available_size': 0, u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'bbaf5bef-d744-4ff8-92b0-b7f10a80b80d', u'resource_uri': u'/MAAS/api/2.0/nodes/qqbrd6/blockdevices/2/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qqbrd6', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'77131bf1-e8b6-4169-b152-ae981764044e', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 6, u'size': 800101236736}], u'type': u'physical', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'qqbrd6', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'boot_disk': {u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/qqbrd6/blockdevices/2/', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'system_id': u'qqbrd6', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id': 2, u'available_size': 0, u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'bbaf5bef-d744-4ff8-92b0-b7f10a80b80d', u'resource_uri': u'/MAAS/api/2.0/nodes/qqbrd6/blockdevices/2/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qqbrd6', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'77131bf1-e8b6-4169-b152-ae981764044e', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 6, u'size': 800101236736}], u'type': u'physical', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}, u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'8mebq3', u'fabric': u'pxe_admin', 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'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': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 23}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'8mebq3', u'fabric': u'pxe_admin', 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'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'8mebq3', u'fabric': u'pxe_admin', 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'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': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'params': u'', u'system_id': u'qqbrd6', u'type': u'physical', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/qqbrd6/interfaces/6/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:50', u'parents': [], u'params': u'', u'system_id': u'qqbrd6', u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/qqbrd6/interfaces/14/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:51', u'parents': [], u'params': u'', u'system_id': u'qqbrd6', u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/qqbrd6/interfaces/15/'}, {u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 24}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'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': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:48', u'parents': [], u'params': u'', u'system_id': u'qqbrd6', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/qqbrd6/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': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'8mebq3', u'fabric': u'pxe_admin', 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'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': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'mode': u'link_up', u'id': 25}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'8mebq3', u'fabric': u'pxe_admin', 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'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'8mebq3', u'fabric': u'pxe_admin', 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'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': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40'}], u'mac_address': u'9c:b6:54:8a:10:1c', u'parents': [], u'params': u'', u'system_id': u'qqbrd6', u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/qqbrd6/interfaces/16/'}, {u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 26}], u'tags': [u'sriov'], 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': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:49', u'parents': [], u'params': u'', u'system_id': u'qqbrd6', u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/qqbrd6/interfaces/17/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'7647ca8e-be6f-4119-8b97-04a005672cda', u'fstype': u'ext4'}, u'uuid': u'84419489-7e57-41d4-ae3a-3fbd6fecf3a4', u'used_for': u'ext4 formatted filesystem mounted at /', u'resource_uri': u'/MAAS/api/2.0/nodes/qqbrd6/blockdevices/12/', u'id_path': None, u'system_id': u'qqbrd6', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id': 12, u'available_size': 0, u'model': None, u'partitions': [], u'type': u'virtual', u'serial': None, u'size': 107374182400}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/qqbrd6/', u'distro_series': u'', u'memory_test_status': -1}
2018-12-25 15:18:55,240 [salt.state       :300 ][INFO    ][19857] {'new': {'storage_layout': 'lvm'}}
2018-12-25 15:18:55,240 [salt.state       :1951][INFO    ][19857] Completed state [maas_machines_storage_cmp002_lvm] at time 15:18:55.240275 duration_in_ms=2780.543
2018-12-25 15:18:55,240 [salt.state       :1780][INFO    ][19857] Running state [maas_machines_storage_cmp001_lvm] at time 15:18:55.240921
2018-12-25 15:18:55,241 [salt.state       :1813][INFO    ][19857] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2018-12-25 15:18:56,498 [salt.loaded.ext.module.maasng:610 ][INFO    ][19857] sq6qna
2018-12-25 15:18:56,498 [salt.loaded.ext.module.maasng:626 ][INFO    ][19857] sda
2018-12-25 15:18:57,158 [salt.loaded.ext.module.maasng:361 ][INFO    ][19857] sq6qna
2018-12-25 15:18:57,268 [salt.loaded.ext.module.maasng:367 ][INFO    ][19857] [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'sq6qna', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'size': 800101236736, u'uuid': u'cd198d19-abd9-4247-858e-cd9a997bd733', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'sq6qna', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'ca010762-4f78-4262-afb9-dd093ad3e9a0', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/sq6qna/blockdevices/3/partition/2', u'type': u'partition', u'id': 2, u'device_id': 3}], u'used_size': 800106479616, u'id': 3, u'resource_uri': u'/MAAS/api/2.0/nodes/sq6qna/blockdevices/3/'}, {u'size': 800097042432, u'model': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'6026b102-b582-411f-a472-b0a8eefb464f', u'fstype': u'ext4'}, u'uuid': u'a27d64ef-6558-43bd-ba01-b3b965ad78a5', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'sq6qna', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'partitions': [], u'used_size': 800097042432, u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/sq6qna/blockdevices/5/'}]
2018-12-25 15:18:57,269 [salt.loaded.ext.module.maasng:632 ][INFO    ][19857] vgroot
2018-12-25 15:18:57,269 [salt.loaded.ext.module.maasng:635 ][INFO    ][19857] lvroot
2018-12-25 15:18:57,269 [salt.loaded.ext.module.maasng:639 ][INFO    ][19857] 107374182400
2018-12-25 15:18:57,982 [salt.loaded.ext.module.maasng:645 ][INFO    ][19857] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'ip_addresses': [u'192.168.11.38', u'192.168.11.44'], u'cpu_count': 40, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/sq6qna/', u'disable_ipv4': False, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'address_ttl': None, u'system_id': u'sq6qna', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/sq6qna/blockdevices/13/', u'used_size': 107374182400, u'filesystem': {u'mount_options': None, u'uuid': u'3bec3a10-16a5-45a8-b204-023fd95daf10', u'label': u'root', u'mount_point': u'/', u'fstype': u'ext4'}, u'uuid': u'2d9802ec-7089-4403-8903-256a8eb7d4f8', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'sq6qna', u'serial': None, u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 13, u'tags': [], u'partitions': []}], u'blockdevice_set': [{u'block_size': 4096, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/sq6qna/blockdevices/3/', u'used_size': 800106479616, u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'sq6qna', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'size': 800109715456, u'type': u'physical', u'id': 3, u'tags': [u'ssd'], u'partitions': [{u'size': 800101236736, u'uuid': u'c4b9eaba-deff-417e-880c-bcadeba74fb9', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'sq6qna', u'filesystem': {u'mount_options': None, u'uuid': u'2f338a15-b68a-489c-8346-5debf3a9edf6', u'label': None, u'mount_point': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 3, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/sq6qna/blockdevices/3/partition/7'}]}, {u'block_size': 4096, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/sq6qna/blockdevices/13/', u'used_size': 107374182400, u'filesystem': {u'mount_options': None, u'uuid': u'3bec3a10-16a5-45a8-b204-023fd95daf10', u'label': u'root', u'mount_point': u'/', u'fstype': u'ext4'}, u'uuid': u'2d9802ec-7089-4403-8903-256a8eb7d4f8', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'sq6qna', u'serial': None, u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'size': 107374182400, u'type': u'virtual', u'id': 13, u'tags': [], u'partitions': []}], u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'sq6qna', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'size': 800109715456, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/sq6qna/blockdevices/3/', u'used_size': 800106479616, u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'sq6qna', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'id': 3, u'tags': [u'ssd'], u'partitions': [{u'size': 800101236736, u'uuid': u'c4b9eaba-deff-417e-880c-bcadeba74fb9', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'sq6qna', u'filesystem': {u'mount_options': None, u'uuid': u'2f338a15-b68a-489c-8346-5debf3a9edf6', u'label': None, u'mount_point': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 3, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/sq6qna/blockdevices/3/partition/7'}]}, u'interface_set': [{u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'8mebq3', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, 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': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'8mebq3', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, 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': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 29}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:95:a0', u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'8mebq3', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, 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': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'children': [], u'system_id': u'sq6qna', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/sq6qna/interfaces/7/'}, {u'vlan': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:1f:d5', u'enabled': True, u'parents': [], u'discovered': None, u'children': [], u'system_id': u'sq6qna', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/sq6qna/interfaces/20/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'8mebq3', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, 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': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'8mebq3', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, 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': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'mode': u'link_up', u'id': 30}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:95:a4', u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'8mebq3', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, 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': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.44'}], u'children': [], u'system_id': u'sq6qna', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 21, u'resource_uri': u'/MAAS/api/2.0/nodes/sq6qna/interfaces/21/'}, {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'external_dhcp': u'10.20.0.1', u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 31}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:52:cd', u'enabled': True, u'parents': [], u'discovered': None, u'children': [], u'system_id': u'sq6qna', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 22, u'resource_uri': u'/MAAS/api/2.0/nodes/sq6qna/interfaces/22/'}, {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'external_dhcp': u'10.20.0.1', u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 32}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:52:cc', u'enabled': True, u'parents': [], u'discovered': None, u'children': [], u'system_id': u'sq6qna', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/sq6qna/interfaces/18/'}, {u'vlan': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:1f:d4', u'enabled': True, u'parents': [], u'discovered': None, u'children': [], u'system_id': u'sq6qna', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/sq6qna/interfaces/19/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'bcaches': [], u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 800109715456, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/sq6qna/blockdevices/3/', u'used_size': 800106479616, u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'sq6qna', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'id': 3, u'tags': [u'ssd'], u'partitions': [{u'size': 800101236736, u'uuid': u'c4b9eaba-deff-417e-880c-bcadeba74fb9', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'sq6qna', u'filesystem': {u'mount_options': None, u'uuid': u'2f338a15-b68a-489c-8346-5debf3a9edf6', u'label': None, u'mount_point': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 3, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/sq6qna/blockdevices/3/partition/7'}]}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'node_type': 0, u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'boot_interface': {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'8mebq3', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, 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': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'8mebq3', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, 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': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 29}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:95:a0', u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'8mebq3', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, 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': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'children': [], u'system_id': u'sq6qna', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/sq6qna/interfaces/7/'}, u'current_commissioning_result_id': 4, u'commissioning_status_name': u'Passed', u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2018-12-25 15:18:57,984 [salt.state       :300 ][INFO    ][19857] {'new': {'storage_layout': 'lvm'}}
2018-12-25 15:18:57,985 [salt.state       :1951][INFO    ][19857] Completed state [maas_machines_storage_cmp001_lvm] at time 15:18:57.984912 duration_in_ms=2743.991
2018-12-25 15:18:57,989 [salt.minion      :1711][INFO    ][19857] Returning information for job: 20181225151844537150
2018-12-25 15:18:58,529 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command state.apply with jid 20181225151858507667
2018-12-25 15:18:58,550 [salt.minion      :1432][INFO    ][19911] Starting a new job with PID 19911
2018-12-25 15:18:59,306 [salt.state       :915 ][INFO    ][19911] Loading fresh modules for state activity
2018-12-25 15:18:59,362 [salt.fileclient  :1219][INFO    ][19911] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2018-12-25 15:18:59,411 [salt.state       :1780][INFO    ][19911] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 15:18:59.411620
2018-12-25 15:18:59,412 [salt.state       :1813][INFO    ][19911] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-12-25 15:18:59,414 [salt.loaded.int.module.cmdmod:395 ][INFO    ][19911] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-12-25 15:19:01,350 [salt.state       :300 ][INFO    ][19911] {'pid': 19918, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-12-25 15:19:01,351 [salt.state       :1951][INFO    ][19911] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 15:19:01.351552 duration_in_ms=1939.931
2018-12-25 15:19:01,354 [salt.state       :1780][INFO    ][19911] Running state [maas.deploy_machines] at time 15:19:01.354821
2018-12-25 15:19:01,356 [salt.state       :1813][INFO    ][19911] Executing state module.run for [maas.deploy_machines]
2018-12-25 15:19:01,356 [salt.utils.decorators:613 ][WARNING ][19911] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-25 15:19:02,174 [salt.loaded.ext.module.maas:684 ][INFO    ][19911] deploymachines hwe_kernel=hwe-16.04 system_id=qqbrd6 distro_series=xenial
2018-12-25 15:19:04,802 [salt.loaded.ext.module.maas:684 ][INFO    ][19911] deploymachines hwe_kernel=hwe-16.04 system_id=sq6qna distro_series=xenial
2018-12-25 15:19:07,376 [salt.loaded.ext.module.maas:684 ][INFO    ][19911] deploymachines hwe_kernel=hwe-16.04 system_id=c878ww distro_series=xenial
2018-12-25 15:19:08,581 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225151908561953
2018-12-25 15:19:08,616 [salt.minion      :1432][INFO    ][20061] Starting a new job with PID 20061
2018-12-25 15:19:08,638 [salt.minion      :1711][INFO    ][20061] Returning information for job: 20181225151908561953
2018-12-25 15:19:09,903 [salt.loaded.ext.module.maas:684 ][INFO    ][19911] deploymachines hwe_kernel=hwe-16.04 system_id=bsxbn4 distro_series=xenial
2018-12-25 15:19:12,583 [salt.loaded.ext.module.maas:684 ][INFO    ][19911] deploymachines hwe_kernel=hwe-16.04 system_id=73aryt distro_series=xenial
2018-12-25 15:19:15,274 [salt.state       :300 ][INFO    ][19911] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2018-12-25 15:19:15,275 [salt.state       :1951][INFO    ][19911] Completed state [maas.deploy_machines] at time 15:19:15.275919 duration_in_ms=13921.097
2018-12-25 15:19:15,280 [salt.minion      :1711][INFO    ][19911] Returning information for job: 20181225151858507667
2018-12-25 15:19:15,838 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command state.apply with jid 20181225151915820855
2018-12-25 15:19:15,858 [salt.minion      :1432][INFO    ][20211] Starting a new job with PID 20211
2018-12-25 15:19:21,484 [salt.state       :915 ][INFO    ][20211] Loading fresh modules for state activity
2018-12-25 15:19:21,541 [salt.fileclient  :1219][INFO    ][20211] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2018-12-25 15:19:21,596 [salt.state       :1780][INFO    ][20211] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 15:19:21.595932
2018-12-25 15:19:21,596 [salt.state       :1813][INFO    ][20211] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-12-25 15:19:21,599 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20211] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-12-25 15:19:23,534 [salt.state       :300 ][INFO    ][20211] {'pid': 20234, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-12-25 15:19:23,537 [salt.state       :1951][INFO    ][20211] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 15:19:23.537342 duration_in_ms=1941.409
2018-12-25 15:19:23,541 [salt.state       :1780][INFO    ][20211] Running state [maas.wait_for_machine_status] at time 15:19:23.541552
2018-12-25 15:19:23,542 [salt.state       :1813][INFO    ][20211] Executing state module.run for [maas.wait_for_machine_status]
2018-12-25 15:19:23,544 [salt.utils.decorators:613 ][WARNING ][20211] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-25 15:19:25,900 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225151925878568
2018-12-25 15:19:25,934 [salt.minion      :1432][INFO    ][20245] Starting a new job with PID 20245
2018-12-25 15:19:25,961 [salt.minion      :1711][INFO    ][20245] Returning information for job: 20181225151925878568
2018-12-25 15:19:26,866 [salt.loaded.ext.module.maas:1001][INFO    ][20211] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.68788314s left)
2018-12-25 15:19:36,122 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225151936097534
2018-12-25 15:19:36,154 [salt.minion      :1432][INFO    ][20252] Starting a new job with PID 20252
2018-12-25 15:19:36,183 [salt.minion      :1711][INFO    ][20252] Returning information for job: 20181225151936097534
2018-12-25 15:19:46,136 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225151946115309
2018-12-25 15:19:46,156 [salt.minion      :1432][INFO    ][20271] Starting a new job with PID 20271
2018-12-25 15:19:46,185 [salt.minion      :1711][INFO    ][20271] Returning information for job: 20181225151946115309
2018-12-25 15:19:56,348 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225151956322606
2018-12-25 15:19:56,377 [salt.minion      :1432][INFO    ][20306] Starting a new job with PID 20306
2018-12-25 15:19:56,402 [salt.minion      :1711][INFO    ][20306] Returning information for job: 20181225151956322606
2018-12-25 15:20:00,240 [salt.loaded.ext.module.maas:1001][INFO    ][20211] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.31361699s left)
2018-12-25 15:20:06,561 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152006540513
2018-12-25 15:20:06,597 [salt.minion      :1432][INFO    ][20318] Starting a new job with PID 20318
2018-12-25 15:20:06,632 [salt.minion      :1711][INFO    ][20318] Returning information for job: 20181225152006540513
2018-12-25 15:20:16,580 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152016563809
2018-12-25 15:20:16,608 [salt.minion      :1432][INFO    ][20345] Starting a new job with PID 20345
2018-12-25 15:20:16,629 [salt.minion      :1711][INFO    ][20345] Returning information for job: 20181225152016563809
2018-12-25 15:20:26,598 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152026580040
2018-12-25 15:20:26,629 [salt.minion      :1432][INFO    ][20351] Starting a new job with PID 20351
2018-12-25 15:20:26,653 [salt.minion      :1711][INFO    ][20351] Returning information for job: 20181225152026580040
2018-12-25 15:20:33,634 [salt.loaded.ext.module.maas:1001][INFO    ][20211] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2179.91944504s left)
2018-12-25 15:20:36,817 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152036793840
2018-12-25 15:20:36,842 [salt.minion      :1432][INFO    ][20362] Starting a new job with PID 20362
2018-12-25 15:20:36,867 [salt.minion      :1711][INFO    ][20362] Returning information for job: 20181225152036793840
2018-12-25 15:20:47,032 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152047011570
2018-12-25 15:20:47,060 [salt.minion      :1432][INFO    ][20395] Starting a new job with PID 20395
2018-12-25 15:20:47,083 [salt.minion      :1711][INFO    ][20395] Returning information for job: 20181225152047011570
2018-12-25 15:20:57,234 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152057216108
2018-12-25 15:20:57,250 [salt.minion      :1432][INFO    ][20402] Starting a new job with PID 20402
2018-12-25 15:20:57,273 [salt.minion      :1711][INFO    ][20402] Returning information for job: 20181225152057216108
2018-12-25 15:21:06,913 [salt.loaded.ext.module.maas:1001][INFO    ][20211] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2146.64113712s left)
2018-12-25 15:21:07,431 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152107410471
2018-12-25 15:21:07,455 [salt.minion      :1432][INFO    ][20415] Starting a new job with PID 20415
2018-12-25 15:21:07,481 [salt.minion      :1711][INFO    ][20415] Returning information for job: 20181225152107410471
2018-12-25 15:21:17,446 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152117423900
2018-12-25 15:21:17,470 [salt.minion      :1432][INFO    ][20438] Starting a new job with PID 20438
2018-12-25 15:21:17,495 [salt.minion      :1711][INFO    ][20438] Returning information for job: 20181225152117423900
2018-12-25 15:21:27,668 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152127649979
2018-12-25 15:21:27,692 [salt.minion      :1432][INFO    ][20444] Starting a new job with PID 20444
2018-12-25 15:21:27,713 [salt.minion      :1711][INFO    ][20444] Returning information for job: 20181225152127649979
2018-12-25 15:21:37,890 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152137868305
2018-12-25 15:21:37,911 [salt.minion      :1432][INFO    ][20484] Starting a new job with PID 20484
2018-12-25 15:21:37,931 [salt.minion      :1711][INFO    ][20484] Returning information for job: 20181225152137868305
2018-12-25 15:21:40,097 [salt.loaded.ext.module.maas:1001][INFO    ][20211] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2113.45667601s left)
2018-12-25 15:21:48,082 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152148064180
2018-12-25 15:21:48,107 [salt.minion      :1432][INFO    ][20508] Starting a new job with PID 20508
2018-12-25 15:21:48,134 [salt.minion      :1711][INFO    ][20508] Returning information for job: 20181225152148064180
2018-12-25 15:21:58,114 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152158089188
2018-12-25 15:21:58,143 [salt.minion      :1432][INFO    ][20517] Starting a new job with PID 20517
2018-12-25 15:21:58,165 [salt.minion      :1711][INFO    ][20517] Returning information for job: 20181225152158089188
2018-12-25 15:22:08,320 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152208299833
2018-12-25 15:22:08,351 [salt.minion      :1432][INFO    ][20572] Starting a new job with PID 20572
2018-12-25 15:22:08,381 [salt.minion      :1711][INFO    ][20572] Returning information for job: 20181225152208299833
2018-12-25 15:22:13,169 [salt.loaded.ext.module.maas:1001][INFO    ][20211] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2080.38486505s left)
2018-12-25 15:22:18,347 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152218323263
2018-12-25 15:22:18,378 [salt.minion      :1432][INFO    ][20616] Starting a new job with PID 20616
2018-12-25 15:22:18,400 [salt.minion      :1711][INFO    ][20616] Returning information for job: 20181225152218323263
2018-12-25 15:22:28,377 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152228354750
2018-12-25 15:22:28,401 [salt.minion      :1432][INFO    ][20650] Starting a new job with PID 20650
2018-12-25 15:22:28,428 [salt.minion      :1711][INFO    ][20650] Returning information for job: 20181225152228354750
2018-12-25 15:22:38,417 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152238388104
2018-12-25 15:22:38,445 [salt.minion      :1432][INFO    ][20675] Starting a new job with PID 20675
2018-12-25 15:22:38,477 [salt.minion      :1711][INFO    ][20675] Returning information for job: 20181225152238388104
2018-12-25 15:22:46,557 [salt.loaded.ext.module.maas:1001][INFO    ][20211] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2046.99679112s left)
2018-12-25 15:22:48,449 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152248429846
2018-12-25 15:22:48,473 [salt.minion      :1432][INFO    ][20858] Starting a new job with PID 20858
2018-12-25 15:22:48,501 [salt.minion      :1711][INFO    ][20858] Returning information for job: 20181225152248429846
2018-12-25 15:22:58,495 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152258471764
2018-12-25 15:22:58,523 [salt.minion      :1432][INFO    ][20875] Starting a new job with PID 20875
2018-12-25 15:22:58,549 [salt.minion      :1711][INFO    ][20875] Returning information for job: 20181225152258471764
2018-12-25 15:23:08,531 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152308513712
2018-12-25 15:23:08,779 [salt.minion      :1432][INFO    ][20887] Starting a new job with PID 20887
2018-12-25 15:23:08,969 [salt.minion      :1711][INFO    ][20887] Returning information for job: 20181225152308513712
2018-12-25 15:23:18,738 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152318717447
2018-12-25 15:23:18,786 [salt.minion      :1432][INFO    ][20970] Starting a new job with PID 20970
2018-12-25 15:23:18,897 [salt.minion      :1711][INFO    ][20970] Returning information for job: 20181225152318717447
2018-12-25 15:23:20,433 [salt.loaded.ext.module.maas:1001][INFO    ][20211] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2013.1211741s left)
2018-12-25 15:23:28,858 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152328839503
2018-12-25 15:23:28,886 [salt.minion      :1432][INFO    ][21016] Starting a new job with PID 21016
2018-12-25 15:23:28,915 [salt.minion      :1711][INFO    ][21016] Returning information for job: 20181225152328839503
2018-12-25 15:23:38,866 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152338848654
2018-12-25 15:23:38,887 [salt.minion      :1432][INFO    ][21052] Starting a new job with PID 21052
2018-12-25 15:23:38,919 [salt.minion      :1711][INFO    ][21052] Returning information for job: 20181225152338848654
2018-12-25 15:23:48,881 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152348859953
2018-12-25 15:23:48,903 [salt.minion      :1432][INFO    ][21158] Starting a new job with PID 21158
2018-12-25 15:23:48,927 [salt.minion      :1711][INFO    ][21158] Returning information for job: 20181225152348859953
2018-12-25 15:23:54,157 [salt.loaded.ext.module.maas:1001][INFO    ][20211] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1979.39765406s left)
2018-12-25 15:23:58,920 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152358898578
2018-12-25 15:23:58,960 [salt.minion      :1432][INFO    ][21201] Starting a new job with PID 21201
2018-12-25 15:23:59,095 [salt.minion      :1711][INFO    ][21201] Returning information for job: 20181225152358898578
2018-12-25 15:24:09,094 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152409072949
2018-12-25 15:24:09,117 [salt.minion      :1432][INFO    ][21263] Starting a new job with PID 21263
2018-12-25 15:24:09,142 [salt.minion      :1711][INFO    ][21263] Returning information for job: 20181225152409072949
2018-12-25 15:24:19,124 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152419102305
2018-12-25 15:24:19,151 [salt.minion      :1432][INFO    ][21356] Starting a new job with PID 21356
2018-12-25 15:24:19,179 [salt.minion      :1711][INFO    ][21356] Returning information for job: 20181225152419102305
2018-12-25 15:24:27,503 [salt.loaded.ext.module.maas:1001][INFO    ][20211] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1946.05083299s left)
2018-12-25 15:24:29,186 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152429164067
2018-12-25 15:24:29,219 [salt.minion      :1432][INFO    ][21378] Starting a new job with PID 21378
2018-12-25 15:24:29,253 [salt.minion      :1711][INFO    ][21378] Returning information for job: 20181225152429164067
2018-12-25 15:24:39,271 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152439249898
2018-12-25 15:24:39,298 [salt.minion      :1432][INFO    ][21399] Starting a new job with PID 21399
2018-12-25 15:24:39,323 [salt.minion      :1711][INFO    ][21399] Returning information for job: 20181225152439249898
2018-12-25 15:24:49,328 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152449310444
2018-12-25 15:24:49,350 [salt.minion      :1432][INFO    ][21696] Starting a new job with PID 21696
2018-12-25 15:24:49,456 [salt.minion      :1711][INFO    ][21696] Returning information for job: 20181225152449310444
2018-12-25 15:24:59,469 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152459448847
2018-12-25 15:24:59,491 [salt.minion      :1432][INFO    ][21768] Starting a new job with PID 21768
2018-12-25 15:24:59,531 [salt.minion      :1711][INFO    ][21768] Returning information for job: 20181225152459448847
2018-12-25 15:25:00,705 [salt.loaded.ext.module.maas:1001][INFO    ][20211] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1912.84874606s left)
2018-12-25 15:25:09,548 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152509527460
2018-12-25 15:25:09,572 [salt.minion      :1432][INFO    ][21788] Starting a new job with PID 21788
2018-12-25 15:25:09,598 [salt.minion      :1711][INFO    ][21788] Returning information for job: 20181225152509527460
2018-12-25 15:25:19,609 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152519590994
2018-12-25 15:25:19,628 [salt.minion      :1432][INFO    ][21890] Starting a new job with PID 21890
2018-12-25 15:25:19,654 [salt.minion      :1711][INFO    ][21890] Returning information for job: 20181225152519590994
2018-12-25 15:25:29,680 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152529657147
2018-12-25 15:25:29,707 [salt.minion      :1432][INFO    ][21898] Starting a new job with PID 21898
2018-12-25 15:25:29,730 [salt.minion      :1711][INFO    ][21898] Returning information for job: 20181225152529657147
2018-12-25 15:25:33,910 [salt.loaded.ext.module.maas:1001][INFO    ][20211] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1879.64427519s left)
2018-12-25 15:25:39,752 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152539730061
2018-12-25 15:25:39,773 [salt.minion      :1432][INFO    ][21930] Starting a new job with PID 21930
2018-12-25 15:25:39,830 [salt.minion      :1711][INFO    ][21930] Returning information for job: 20181225152539730061
2018-12-25 15:25:49,876 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152549848730
2018-12-25 15:25:49,903 [salt.minion      :1432][INFO    ][22135] Starting a new job with PID 22135
2018-12-25 15:25:49,929 [salt.minion      :1711][INFO    ][22135] Returning information for job: 20181225152549848730
2018-12-25 15:25:59,983 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152559958634
2018-12-25 15:26:00,007 [salt.minion      :1432][INFO    ][22142] Starting a new job with PID 22142
2018-12-25 15:26:00,031 [salt.minion      :1711][INFO    ][22142] Returning information for job: 20181225152559958634
2018-12-25 15:26:07,234 [salt.loaded.ext.module.maas:1001][INFO    ][20211] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1846.31957102s left)
2018-12-25 15:26:10,055 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152610033012
2018-12-25 15:26:10,086 [salt.minion      :1432][INFO    ][22159] Starting a new job with PID 22159
2018-12-25 15:26:10,130 [salt.minion      :1711][INFO    ][22159] Returning information for job: 20181225152610033012
2018-12-25 15:26:20,207 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152620184194
2018-12-25 15:26:20,231 [salt.minion      :1432][INFO    ][22209] Starting a new job with PID 22209
2018-12-25 15:26:20,255 [salt.minion      :1711][INFO    ][22209] Returning information for job: 20181225152620184194
2018-12-25 15:26:30,339 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152630310377
2018-12-25 15:26:30,365 [salt.minion      :1432][INFO    ][22223] Starting a new job with PID 22223
2018-12-25 15:26:30,393 [salt.minion      :1711][INFO    ][22223] Returning information for job: 20181225152630310377
2018-12-25 15:26:40,439 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152640421549
2018-12-25 15:26:40,463 [salt.minion      :1432][INFO    ][22249] Starting a new job with PID 22249
2018-12-25 15:26:40,494 [salt.minion      :1711][INFO    ][22249] Returning information for job: 20181225152640421549
2018-12-25 15:26:40,751 [salt.loaded.ext.module.maas:1001][INFO    ][20211] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1812.80276918s left)
2018-12-25 15:26:50,551 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152650530599
2018-12-25 15:26:50,577 [salt.minion      :1432][INFO    ][22415] Starting a new job with PID 22415
2018-12-25 15:26:50,606 [salt.minion      :1711][INFO    ][22415] Returning information for job: 20181225152650530599
2018-12-25 15:27:00,694 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152700665452
2018-12-25 15:27:00,718 [salt.minion      :1432][INFO    ][22422] Starting a new job with PID 22422
2018-12-25 15:27:00,743 [salt.minion      :1711][INFO    ][22422] Returning information for job: 20181225152700665452
2018-12-25 15:27:10,830 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152710806802
2018-12-25 15:27:10,863 [salt.minion      :1432][INFO    ][22438] Starting a new job with PID 22438
2018-12-25 15:27:10,888 [salt.minion      :1711][INFO    ][22438] Returning information for job: 20181225152710806802
2018-12-25 15:27:13,968 [salt.loaded.ext.module.maas:1001][INFO    ][20211] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1779.58610201s left)
2018-12-25 15:27:20,956 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152720932398
2018-12-25 15:27:20,982 [salt.minion      :1432][INFO    ][22533] Starting a new job with PID 22533
2018-12-25 15:27:21,009 [salt.minion      :1711][INFO    ][22533] Returning information for job: 20181225152720932398
2018-12-25 15:27:31,055 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152731038432
2018-12-25 15:27:31,073 [salt.minion      :1432][INFO    ][22545] Starting a new job with PID 22545
2018-12-25 15:27:31,095 [salt.minion      :1711][INFO    ][22545] Returning information for job: 20181225152731038432
2018-12-25 15:27:41,190 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152741164673
2018-12-25 15:27:41,217 [salt.minion      :1432][INFO    ][22716] Starting a new job with PID 22716
2018-12-25 15:27:41,239 [salt.minion      :1711][INFO    ][22716] Returning information for job: 20181225152741164673
2018-12-25 15:27:47,555 [salt.loaded.ext.module.maas:1001][INFO    ][20211] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1745.99835706s left)
2018-12-25 15:27:51,327 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152751301916
2018-12-25 15:27:51,361 [salt.minion      :1432][INFO    ][22854] Starting a new job with PID 22854
2018-12-25 15:27:51,387 [salt.minion      :1711][INFO    ][22854] Returning information for job: 20181225152751301916
2018-12-25 15:28:01,404 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152801383253
2018-12-25 15:28:01,445 [salt.minion      :1432][INFO    ][22881] Starting a new job with PID 22881
2018-12-25 15:28:01,468 [salt.minion      :1711][INFO    ][22881] Returning information for job: 20181225152801383253
2018-12-25 15:28:11,598 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152811575751
2018-12-25 15:28:11,626 [salt.minion      :1432][INFO    ][22893] Starting a new job with PID 22893
2018-12-25 15:28:11,651 [salt.minion      :1711][INFO    ][22893] Returning information for job: 20181225152811575751
2018-12-25 15:28:20,715 [salt.loaded.ext.module.maas:1001][INFO    ][20211] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1712.838902s left)
2018-12-25 15:28:21,755 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152821732262
2018-12-25 15:28:21,776 [salt.minion      :1432][INFO    ][22945] Starting a new job with PID 22945
2018-12-25 15:28:21,804 [salt.minion      :1711][INFO    ][22945] Returning information for job: 20181225152821732262
2018-12-25 15:28:31,931 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152831915080
2018-12-25 15:28:31,951 [salt.minion      :1432][INFO    ][22956] Starting a new job with PID 22956
2018-12-25 15:28:31,979 [salt.minion      :1711][INFO    ][22956] Returning information for job: 20181225152831915080
2018-12-25 15:28:42,006 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152841989275
2018-12-25 15:28:42,024 [salt.minion      :1432][INFO    ][22989] Starting a new job with PID 22989
2018-12-25 15:28:42,048 [salt.minion      :1711][INFO    ][22989] Returning information for job: 20181225152841989275
2018-12-25 15:28:52,159 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152852139779
2018-12-25 15:28:52,185 [salt.minion      :1432][INFO    ][23158] Starting a new job with PID 23158
2018-12-25 15:28:52,216 [salt.minion      :1711][INFO    ][23158] Returning information for job: 20181225152852139779
2018-12-25 15:28:54,056 [salt.loaded.ext.module.maas:1001][INFO    ][20211] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1679.49832797s left)
2018-12-25 15:29:02,362 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152902337685
2018-12-25 15:29:02,399 [salt.minion      :1432][INFO    ][23174] Starting a new job with PID 23174
2018-12-25 15:29:02,425 [salt.minion      :1711][INFO    ][23174] Returning information for job: 20181225152902337685
2018-12-25 15:29:12,591 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152912566321
2018-12-25 15:29:12,616 [salt.minion      :1432][INFO    ][23184] Starting a new job with PID 23184
2018-12-25 15:29:12,640 [salt.minion      :1711][INFO    ][23184] Returning information for job: 20181225152912566321
2018-12-25 15:29:22,819 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152922796444
2018-12-25 15:29:22,847 [salt.minion      :1432][INFO    ][23211] Starting a new job with PID 23211
2018-12-25 15:29:22,874 [salt.minion      :1711][INFO    ][23211] Returning information for job: 20181225152922796444
2018-12-25 15:29:27,598 [salt.loaded.ext.module.maas:1001][INFO    ][20211] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1645.95595312s left)
2018-12-25 15:29:33,018 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152932992608
2018-12-25 15:29:33,045 [salt.minion      :1432][INFO    ][23222] Starting a new job with PID 23222
2018-12-25 15:29:33,072 [salt.minion      :1711][INFO    ][23222] Returning information for job: 20181225152932992608
2018-12-25 15:29:43,208 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152943189816
2018-12-25 15:29:43,228 [salt.minion      :1432][INFO    ][23240] Starting a new job with PID 23240
2018-12-25 15:29:43,251 [salt.minion      :1711][INFO    ][23240] Returning information for job: 20181225152943189816
2018-12-25 15:29:53,419 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225152953395718
2018-12-25 15:29:53,445 [salt.minion      :1432][INFO    ][23310] Starting a new job with PID 23310
2018-12-25 15:29:53,474 [salt.minion      :1711][INFO    ][23310] Returning information for job: 20181225152953395718
2018-12-25 15:30:00,969 [salt.loaded.ext.module.maas:1001][INFO    ][20211] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1612.58486009s left)
2018-12-25 15:30:03,634 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225153003612298
2018-12-25 15:30:03,662 [salt.minion      :1432][INFO    ][23320] Starting a new job with PID 23320
2018-12-25 15:30:03,693 [salt.minion      :1711][INFO    ][23320] Returning information for job: 20181225153003612298
2018-12-25 15:30:13,681 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225153013663910
2018-12-25 15:30:13,708 [salt.minion      :1432][INFO    ][23330] Starting a new job with PID 23330
2018-12-25 15:30:13,733 [salt.minion      :1711][INFO    ][23330] Returning information for job: 20181225153013663910
2018-12-25 15:30:23,733 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225153023710709
2018-12-25 15:30:23,760 [salt.minion      :1432][INFO    ][23354] Starting a new job with PID 23354
2018-12-25 15:30:23,786 [salt.minion      :1711][INFO    ][23354] Returning information for job: 20181225153023710709
2018-12-25 15:30:33,749 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225153033732943
2018-12-25 15:30:33,775 [salt.minion      :1432][INFO    ][23365] Starting a new job with PID 23365
2018-12-25 15:30:33,800 [salt.minion      :1711][INFO    ][23365] Returning information for job: 20181225153033732943
2018-12-25 15:30:34,363 [salt.loaded.ext.module.maas:1001][INFO    ][20211] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1579.19084501s left)
2018-12-25 15:30:43,792 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225153043775525
2018-12-25 15:30:43,816 [salt.minion      :1432][INFO    ][23406] Starting a new job with PID 23406
2018-12-25 15:30:43,845 [salt.minion      :1711][INFO    ][23406] Returning information for job: 20181225153043775525
2018-12-25 15:30:53,982 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225153053964277
2018-12-25 15:30:54,004 [salt.minion      :1432][INFO    ][23440] Starting a new job with PID 23440
2018-12-25 15:30:54,033 [salt.minion      :1711][INFO    ][23440] Returning information for job: 20181225153053964277
2018-12-25 15:31:04,018 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225153103995467
2018-12-25 15:31:04,046 [salt.minion      :1432][INFO    ][23450] Starting a new job with PID 23450
2018-12-25 15:31:04,070 [salt.minion      :1711][INFO    ][23450] Returning information for job: 20181225153103995467
2018-12-25 15:31:07,764 [salt.loaded.ext.module.maas:1001][INFO    ][20211] Waiting status:Deployed for machines:['kvm01', 'kvm03']
sleep for:30s Timeout:2250s (1545.79022598s left)
2018-12-25 15:31:14,056 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225153114032500
2018-12-25 15:31:14,082 [salt.minion      :1432][INFO    ][23471] Starting a new job with PID 23471
2018-12-25 15:31:14,107 [salt.minion      :1711][INFO    ][23471] Returning information for job: 20181225153114032500
2018-12-25 15:31:24,137 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225153124115396
2018-12-25 15:31:24,164 [salt.minion      :1432][INFO    ][23561] Starting a new job with PID 23561
2018-12-25 15:31:24,188 [salt.minion      :1711][INFO    ][23561] Returning information for job: 20181225153124115396
2018-12-25 15:31:34,210 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225153134187417
2018-12-25 15:31:34,240 [salt.minion      :1432][INFO    ][23593] Starting a new job with PID 23593
2018-12-25 15:31:34,267 [salt.minion      :1711][INFO    ][23593] Returning information for job: 20181225153134187417
2018-12-25 15:31:40,952 [salt.loaded.ext.module.maas:1001][INFO    ][20211] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1512.60204506s left)
2018-12-25 15:31:44,320 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225153144295783
2018-12-25 15:31:44,356 [salt.minion      :1432][INFO    ][23628] Starting a new job with PID 23628
2018-12-25 15:31:44,388 [salt.minion      :1711][INFO    ][23628] Returning information for job: 20181225153144295783
2018-12-25 15:31:54,346 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225153154329890
2018-12-25 15:31:54,367 [salt.minion      :1432][INFO    ][23800] Starting a new job with PID 23800
2018-12-25 15:31:54,396 [salt.minion      :1711][INFO    ][23800] Returning information for job: 20181225153154329890
2018-12-25 15:32:04,442 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command saltutil.find_job with jid 20181225153204420951
2018-12-25 15:32:04,467 [salt.minion      :1432][INFO    ][23817] Starting a new job with PID 23817
2018-12-25 15:32:04,493 [salt.minion      :1711][INFO    ][23817] Returning information for job: 20181225153204420951
2018-12-25 15:32:14,249 [salt.state       :300 ][INFO    ][20211] {'ret': True}
2018-12-25 15:32:14,250 [salt.state       :1951][INFO    ][20211] Completed state [maas.wait_for_machine_status] at time 15:32:14.250314 duration_in_ms=770708.758
2018-12-25 15:32:14,257 [salt.minion      :1711][INFO    ][20211] Returning information for job: 20181225151915820855
2018-12-25 16:11:24,263 [salt.utils.schedule:1377][INFO    ][17328] Running scheduled job: __mine_interval
2018-12-25 16:42:12,566 [salt.minion      :1308][INFO    ][17328] User sudo_ubuntu Executing command cp.push_dir with jid 20181225164212549831
2018-12-25 16:42:12,601 [salt.minion      :1432][INFO    ][29177] Starting a new job with PID 29177
