2019-04-20 02:05:21,154 [salt.minion      :870 ][ERROR   ][379] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-20 02:06:11,196 [salt.minion      :870 ][ERROR   ][379] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-20 02:07:01,240 [salt.minion      :870 ][ERROR   ][379] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-20 02:07:51,287 [salt.minion      :870 ][ERROR   ][379] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-20 02:08:41,332 [salt.minion      :870 ][ERROR   ][379] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-20 02:09:31,376 [salt.minion      :870 ][ERROR   ][379] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-20 02:11:01,693 [salt.utils.decorators:613 ][WARNING ][2919] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-20 02:11:02,503 [salt.utils.decorators:613 ][WARNING ][2919] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-20 02:11:13,156 [salt.loaded.int.states.file:2298][WARNING ][3053] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-04-20 02:11:17,447 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3268] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-04-20 02:11:17,470 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3268] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-04-20 02:11:17,505 [salt.utils.parsers:1051][WARNING ][379] Minion received a SIGTERM. Exiting.
2019-04-20 02:11:18,660 [salt.cli.daemons :293 ][INFO    ][3463] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-04-20 02:11:18,833 [salt.cli.daemons :82  ][INFO    ][3463] Starting up the Salt Minion
2019-04-20 02:11:18,834 [salt.utils.event :1017][INFO    ][3463] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-04-20 02:11:20,012 [salt.minion      :976 ][INFO    ][3463] Creating minion process manager
2019-04-20 02:11:21,156 [salt.state       :2022][WARNING ][3272] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-04-20 02:11:22,546 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][3463] Executing command ['date', '+%z'] in directory '/root'
2019-04-20 02:11:22,574 [salt.utils.schedule:568 ][INFO    ][3463] Updating job settings for scheduled job: __mine_interval
2019-04-20 02:11:22,578 [salt.minion      :1108][INFO    ][3463] Added mine.update to scheduler
2019-04-20 02:11:22,583 [salt.minion      :1975][INFO    ][3463] Minion is starting as user 'root'
2019-04-20 02:11:22,602 [salt.minion      :2336][INFO    ][3463] Minion is ready to receive requests!
2019-04-20 02:11:24,356 [salt.utils.decorators:613 ][WARNING ][3272] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-20 02:11:29,396 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420021129384519
2019-04-20 02:11:29,413 [salt.minion      :1432][INFO    ][3970] Starting a new job with PID 3970
2019-04-20 02:11:29,442 [salt.minion      :1711][INFO    ][3970] Returning information for job: 20190420021129384519
2019-04-20 02:11:37,294 [salt.utils.decorators:613 ][WARNING ][3272] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-20 02:11:59,466 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420021159455963
2019-04-20 02:11:59,483 [salt.minion      :1432][INFO    ][4498] Starting a new job with PID 4498
2019-04-20 02:11:59,508 [salt.minion      :1711][INFO    ][4498] Returning information for job: 20190420021159455963
2019-04-20 02:12:02,980 [salt.utils.decorators:613 ][WARNING ][3272] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-20 02:12:03,929 [salt.utils.decorators:613 ][WARNING ][3272] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-20 02:12:05,769 [salt.loaded.ext.module.maasng:1008][WARNING ][3272] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-04-20 02:12:05,770 [salt.loaded.ext.module.maasng:1011][WARNING ][3272] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-04-20 02:12:06,431 [salt.loaded.ext.module.maasng:1235][WARNING ][3272] Ignoring parameter vlan:0
2019-04-20 02:12:07,806 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command state.apply with jid 20190420021207796392
2019-04-20 02:12:07,821 [salt.minion      :1432][INFO    ][4841] Starting a new job with PID 4841
2019-04-20 02:12:15,983 [salt.state       :915 ][INFO    ][4841] Loading fresh modules for state activity
2019-04-20 02:12:16,042 [salt.fileclient  :1219][INFO    ][4841] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-04-20 02:12:16,084 [salt.state       :1780][INFO    ][4841] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:12:16.084890
2019-04-20 02:12:16,085 [salt.state       :1813][INFO    ][4841] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-20 02:12:16,086 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4841] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-20 02:12:17,785 [salt.state       :300 ][INFO    ][4841] {'pid': 4879, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-20 02:12:17,786 [salt.state       :1951][INFO    ][4841] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:12:17.786391 duration_in_ms=1701.501
2019-04-20 02:12:17,790 [salt.state       :1780][INFO    ][4841] Running state [maas.process_machines] at time 02:12:17.790229
2019-04-20 02:12:17,791 [salt.state       :1813][INFO    ][4841] Executing state module.run for [maas.process_machines]
2019-04-20 02:12:17,793 [salt.utils.decorators:613 ][WARNING ][4841] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-20 02:12:17,854 [salt.loaded.ext.module.maas:412 ][WARNING ][4841] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-20 02:12:17,855 [salt.loaded.ext.module.maas:92  ][INFO    ][4841] machine hostname=cmp002 power_type=ipmi mac_addresses=9c:b6:54:8a:10:18 power_parameters_power_address=172.16.1.20 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-20 02:12:19,307 [salt.loaded.ext.module.maas:412 ][WARNING ][4841] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-20 02:12:19,308 [salt.loaded.ext.module.maas:92  ][INFO    ][4841] machine hostname=cmp001 power_type=ipmi mac_addresses=9c:b6:54:8a:95:a0 power_parameters_power_address=172.16.1.19 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-20 02:12:20,544 [salt.loaded.ext.module.maas:412 ][WARNING ][4841] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-20 02:12:20,544 [salt.loaded.ext.module.maas:92  ][INFO    ][4841] machine hostname=kvm01 power_type=ipmi mac_addresses=14:58:d0:54:e7:88 power_parameters_power_address=172.16.1.16 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-20 02:12:21,830 [salt.loaded.ext.module.maas:412 ][WARNING ][4841] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-20 02:12:21,835 [salt.loaded.ext.module.maas:92  ][INFO    ][4841] machine hostname=kvm03 power_type=ipmi mac_addresses=14:58:d0:54:7a:28 power_parameters_power_address=172.16.1.18 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-20 02:12:22,848 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420021222840342
2019-04-20 02:12:22,869 [salt.minion      :1432][INFO    ][5086] Starting a new job with PID 5086
2019-04-20 02:12:22,892 [salt.minion      :1711][INFO    ][5086] Returning information for job: 20190420021222840342
2019-04-20 02:12:23,040 [salt.loaded.ext.module.maas:412 ][WARNING ][4841] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-20 02:12:23,040 [salt.loaded.ext.module.maas:92  ][INFO    ][4841] machine hostname=kvm02 power_type=ipmi mac_addresses=14:58:d0:54:6a:60 power_parameters_power_address=172.16.1.17 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-20 02:12:24,320 [salt.state       :300 ][INFO    ][4841] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-04-20 02:12:24,321 [salt.state       :1951][INFO    ][4841] Completed state [maas.process_machines] at time 02:12:24.321056 duration_in_ms=6530.827
2019-04-20 02:12:24,323 [salt.minion      :1711][INFO    ][4841] Returning information for job: 20190420021207796392
2019-04-20 02:12:55,370 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command state.apply with jid 20190420021255358736
2019-04-20 02:12:55,390 [salt.minion      :1432][INFO    ][5223] Starting a new job with PID 5223
2019-04-20 02:13:03,510 [salt.state       :915 ][INFO    ][5223] Loading fresh modules for state activity
2019-04-20 02:13:03,566 [salt.fileclient  :1219][INFO    ][5223] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-04-20 02:13:03,615 [salt.state       :1780][INFO    ][5223] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:13:03.614800
2019-04-20 02:13:03,616 [salt.state       :1813][INFO    ][5223] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-20 02:13:03,619 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5223] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-20 02:13:05,316 [salt.state       :300 ][INFO    ][5223] {'pid': 5231, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-20 02:13:05,317 [salt.state       :1951][INFO    ][5223] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:13:05.317282 duration_in_ms=1702.482
2019-04-20 02:13:05,321 [salt.state       :1780][INFO    ][5223] Running state [maas.wait_for_machine_status] at time 02:13:05.321520
2019-04-20 02:13:05,322 [salt.state       :1813][INFO    ][5223] Executing state module.run for [maas.wait_for_machine_status]
2019-04-20 02:13:05,322 [salt.utils.decorators:613 ][WARNING ][5223] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-20 02:13:06,125 [salt.loaded.ext.module.maas:1023][INFO    ][5223] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.20861292s left)
2019-04-20 02:13:10,485 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420021310474528
2019-04-20 02:13:10,506 [salt.minion      :1432][INFO    ][5243] Starting a new job with PID 5243
2019-04-20 02:13:10,528 [salt.minion      :1711][INFO    ][5243] Returning information for job: 20190420021310474528
2019-04-20 02:13:36,939 [salt.loaded.ext.module.maas:1023][INFO    ][5223] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.39465308s left)
2019-04-20 02:13:40,544 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420021340537091
2019-04-20 02:13:40,557 [salt.minion      :1432][INFO    ][5319] Starting a new job with PID 5319
2019-04-20 02:13:40,576 [salt.minion      :1711][INFO    ][5319] Returning information for job: 20190420021340537091
2019-04-20 02:14:07,740 [salt.loaded.ext.module.maas:1023][INFO    ][5223] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.59402609s left)
2019-04-20 02:14:10,603 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420021410594604
2019-04-20 02:14:10,626 [salt.minion      :1432][INFO    ][5339] Starting a new job with PID 5339
2019-04-20 02:14:10,646 [salt.minion      :1711][INFO    ][5339] Returning information for job: 20190420021410594604
2019-04-20 02:14:38,562 [salt.loaded.ext.module.maas:1023][INFO    ][5223] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.77255988s left)
2019-04-20 02:14:40,828 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420021440814612
2019-04-20 02:14:40,849 [salt.minion      :1432][INFO    ][5420] Starting a new job with PID 5420
2019-04-20 02:14:40,871 [salt.minion      :1711][INFO    ][5420] Returning information for job: 20190420021440814612
2019-04-20 02:15:09,609 [salt.loaded.ext.module.maas:1023][INFO    ][5223] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.72505403s left)
2019-04-20 02:15:11,028 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420021511016368
2019-04-20 02:15:11,049 [salt.minion      :1432][INFO    ][5467] Starting a new job with PID 5467
2019-04-20 02:15:11,071 [salt.minion      :1711][INFO    ][5467] Returning information for job: 20190420021511016368
2019-04-20 02:15:40,775 [salt.loaded.ext.module.maas:1023][INFO    ][5223] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.55873609s left)
2019-04-20 02:15:41,250 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420021541233222
2019-04-20 02:15:41,266 [salt.minion      :1432][INFO    ][5719] Starting a new job with PID 5719
2019-04-20 02:15:41,291 [salt.minion      :1711][INFO    ][5719] Returning information for job: 20190420021541233222
2019-04-20 02:16:11,322 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420021611309230
2019-04-20 02:16:11,339 [salt.minion      :1432][INFO    ][5785] Starting a new job with PID 5785
2019-04-20 02:16:11,361 [salt.minion      :1711][INFO    ][5785] Returning information for job: 20190420021611309230
2019-04-20 02:16:12,032 [salt.loaded.ext.module.maas:1023][INFO    ][5223] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1313.301929s left)
2019-04-20 02:16:41,414 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420021641405600
2019-04-20 02:16:41,434 [salt.minion      :1432][INFO    ][6113] Starting a new job with PID 6113
2019-04-20 02:16:41,456 [salt.minion      :1711][INFO    ][6113] Returning information for job: 20190420021641405600
2019-04-20 02:16:43,505 [salt.loaded.ext.module.maas:1023][INFO    ][5223] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1281.82910395s left)
2019-04-20 02:17:11,466 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420021711452707
2019-04-20 02:17:11,485 [salt.minion      :1432][INFO    ][6247] Starting a new job with PID 6247
2019-04-20 02:17:11,510 [salt.minion      :1711][INFO    ][6247] Returning information for job: 20190420021711452707
2019-04-20 02:17:15,553 [salt.loaded.ext.module.maas:1023][INFO    ][5223] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1249.78181005s left)
2019-04-20 02:17:41,637 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420021741621058
2019-04-20 02:17:41,659 [salt.minion      :1432][INFO    ][6794] Starting a new job with PID 6794
2019-04-20 02:17:41,681 [salt.minion      :1711][INFO    ][6794] Returning information for job: 20190420021741621058
2019-04-20 02:17:47,857 [salt.loaded.ext.module.maas:1023][INFO    ][5223] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1217.47704101s left)
2019-04-20 02:18:11,697 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420021811683743
2019-04-20 02:18:11,719 [salt.minion      :1432][INFO    ][6829] Starting a new job with PID 6829
2019-04-20 02:18:11,742 [salt.minion      :1711][INFO    ][6829] Returning information for job: 20190420021811683743
2019-04-20 02:18:20,025 [salt.loaded.ext.module.maas:1023][INFO    ][5223] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1185.30902004s left)
2019-04-20 02:18:41,809 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420021841795709
2019-04-20 02:18:41,827 [salt.minion      :1432][INFO    ][7046] Starting a new job with PID 7046
2019-04-20 02:18:41,851 [salt.minion      :1711][INFO    ][7046] Returning information for job: 20190420021841795709
2019-04-20 02:18:52,874 [salt.state       :300 ][INFO    ][5223] {'ret': True}
2019-04-20 02:18:52,877 [salt.state       :1951][INFO    ][5223] Completed state [maas.wait_for_machine_status] at time 02:18:52.876948 duration_in_ms=347555.427
2019-04-20 02:18:52,880 [salt.minion      :1711][INFO    ][5223] Returning information for job: 20190420021255358736
2019-04-20 02:18:53,452 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command state.apply with jid 20190420021853437862
2019-04-20 02:18:53,468 [salt.minion      :1432][INFO    ][7081] Starting a new job with PID 7081
2019-04-20 02:19:01,626 [salt.state       :915 ][INFO    ][7081] Loading fresh modules for state activity
2019-04-20 02:19:01,677 [salt.fileclient  :1219][INFO    ][7081] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-04-20 02:19:01,781 [salt.state       :1780][INFO    ][7081] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:19:01.781899
2019-04-20 02:19:01,782 [salt.state       :1813][INFO    ][7081] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-20 02:19:01,783 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7081] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-20 02:19:03,515 [salt.state       :300 ][INFO    ][7081] {'pid': 7096, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-20 02:19:03,516 [salt.state       :1951][INFO    ][7081] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:19:03.516685 duration_in_ms=1734.786
2019-04-20 02:19:03,520 [salt.state       :1780][INFO    ][7081] Running state [maas_machines_storage_cmp002_lvm] at time 02:19:03.520641
2019-04-20 02:19:03,520 [salt.state       :1813][INFO    ][7081] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-04-20 02:19:04,613 [salt.loaded.ext.module.maasng:610 ][INFO    ][7081] f4wh7c
2019-04-20 02:19:04,613 [salt.loaded.ext.module.maasng:626 ][INFO    ][7081] sda
2019-04-20 02:19:05,118 [salt.loaded.ext.module.maasng:361 ][INFO    ][7081] f4wh7c
2019-04-20 02:19:05,197 [salt.loaded.ext.module.maasng:367 ][INFO    ][7081] [{u'size': 800109715456, u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'f4wh7c', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'partitions': [{u'size': 800101236736, u'uuid': u'f1c25595-32ab-4f8a-abb5-14ea190dca2b', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'f4wh7c', u'filesystem': {u'mount_options': None, u'uuid': u'25645c23-dd46-4333-9fbf-9e530da1253b', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/f4wh7c/blockdevices/1/partition/1', u'type': u'partition', u'id': 1, u'device_id': 1}], u'type': u'physical', u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/f4wh7c/blockdevices/1/'}, {u'size': 800097042432, u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'uuid': u'2d3205e7-9eee-47fc-a14f-56fb89415961', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'f4wh7c', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'f0fbcac7-6ee4-4fe0-b299-89328bea3867', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'partitions': [], u'type': u'virtual', u'id': 3, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/f4wh7c/blockdevices/3/'}]
2019-04-20 02:19:05,197 [salt.loaded.ext.module.maasng:632 ][INFO    ][7081] vgroot
2019-04-20 02:19:05,197 [salt.loaded.ext.module.maasng:635 ][INFO    ][7081] lvroot
2019-04-20 02:19:05,197 [salt.loaded.ext.module.maasng:639 ][INFO    ][7081] 107374182400
2019-04-20 02:19:05,669 [salt.loaded.ext.module.maasng:645 ][INFO    ][7081] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'min_hwe_kernel': u'ga-16.04', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'testing_status': 2, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'398085f7-6fbc-4c8d-8246-eb9c2a85a08a', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'f4wh7c', u'filesystem': {u'uuid': u'215bc7bf-e3a6-4475-9725-61cdcc398a11', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/f4wh7c/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'device_id': 1}], u'name': u'sda', u'system_id': u'f4wh7c', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'path': u'/dev/disk/by-dname/sda', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/f4wh7c/blockdevices/1/'}, u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'hostname': u'cmp002', u'storage': 800109.715456, u'status_action': u'', u'system_id': u'f4wh7c', u'power_state': u'off', u'memory': 65536, u'commissioning_status_name': u'Passed', u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'uuid': u'5cb255b5-3ffd-4b82-9e62-f23147aaefa2', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'partitions': [], u'name': u'vgroot-lvroot', u'system_id': u'f4wh7c', u'partition_table_type': None, u'filesystem': {u'uuid': u'68719b0f-4042-4cbf-add5-31bff745dd5d', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'block_size': 4096, u'used_size': 107374182400, u'id': 11, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/f4wh7c/blockdevices/11/'}], u'blockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'398085f7-6fbc-4c8d-8246-eb9c2a85a08a', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'f4wh7c', u'filesystem': {u'uuid': u'215bc7bf-e3a6-4475-9725-61cdcc398a11', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/f4wh7c/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'device_id': 1}], u'name': u'sda', u'system_id': u'f4wh7c', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'path': u'/dev/disk/by-dname/sda', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/f4wh7c/blockdevices/1/'}, {u'size': 107374182400, u'model': None, u'uuid': u'5cb255b5-3ffd-4b82-9e62-f23147aaefa2', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'partitions': [], u'name': u'vgroot-lvroot', u'system_id': u'f4wh7c', u'partition_table_type': None, u'filesystem': {u'uuid': u'68719b0f-4042-4cbf-add5-31bff745dd5d', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'path': u'/dev/disk/by-dname/lvroot', u'block_size': 4096, u'used_size': 107374182400, u'id': 11, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/f4wh7c/blockdevices/11/'}], u'status': 4, u'storage_test_status': 2, u'cpu_count': 40, u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'f4wh7c', u'id': 6}], u'current_installation_result_id': None, u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'node_type_name': u'Machine', u'interface_set': [{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'x8wpc7', 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.38'}], 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'x8wpc7', 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.38', u'id': 18, u'mode': u'dhcp'}], 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'x8wpc7', 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'children': [], u'effective_mtu': 1500, u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'system_id': u'f4wh7c', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/f4wh7c/interfaces/4/'}, {u'discovered': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'params': u'', u'mac_address': u'38:ea:a7:8f:07:50', u'parents': [], u'system_id': u'f4wh7c', u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/f4wh7c/interfaces/14/'}, {u'discovered': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'params': u'', u'mac_address': u'38:ea:a7:8f:07:51', u'parents': [], u'system_id': u'f4wh7c', u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/f4wh7c/interfaces/15/'}, {u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'x8wpc7', 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'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'x8wpc7', 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'id': 19, u'mode': u'link_up'}], 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'x8wpc7', 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'children': [], u'effective_mtu': 1500, u'params': u'', u'mac_address': u'9c:b6:54:8a:10:1c', u'parents': [], u'system_id': u'f4wh7c', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/f4wh7c/interfaces/11/'}, {u'discovered': None, u'name': u'ens2f0', u'links': [{u'id': 20, u'mode': u'link_up'}], 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': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'params': u'', u'mac_address': u'38:ea:a7:8f:12:48', u'parents': [], u'system_id': u'f4wh7c', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/f4wh7c/interfaces/12/'}, {u'discovered': None, u'name': u'ens2f1', u'links': [{u'id': 21, u'mode': u'link_up'}], 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': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'params': u'', u'mac_address': u'38:ea:a7:8f:12:49', u'parents': [], u'system_id': u'f4wh7c', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/f4wh7c/interfaces/13/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'bcaches': [], u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'398085f7-6fbc-4c8d-8246-eb9c2a85a08a', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'f4wh7c', u'filesystem': {u'uuid': u'215bc7bf-e3a6-4475-9725-61cdcc398a11', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/f4wh7c/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'device_id': 1}], u'name': u'sda', u'system_id': u'f4wh7c', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'path': u'/dev/disk/by-dname/sda', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/f4wh7c/blockdevices/1/'}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'disable_ipv4': False, u'commissioning_status': 2, u'architecture': u'amd64/generic', u'boot_interface': {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'x8wpc7', 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.38'}], 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'x8wpc7', 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.38', u'id': 18, u'mode': u'dhcp'}], 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'x8wpc7', 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'children': [], u'effective_mtu': 1500, u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'system_id': u'f4wh7c', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/f4wh7c/interfaces/4/'}, u'current_commissioning_result_id': 2, u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/f4wh7c/'}
2019-04-20 02:19:05,671 [salt.state       :300 ][INFO    ][7081] {'new': {'storage_layout': 'lvm'}}
2019-04-20 02:19:05,672 [salt.state       :1951][INFO    ][7081] Completed state [maas_machines_storage_cmp002_lvm] at time 02:19:05.671977 duration_in_ms=2151.335
2019-04-20 02:19:05,672 [salt.state       :1780][INFO    ][7081] Running state [maas_machines_storage_cmp001_lvm] at time 02:19:05.672327
2019-04-20 02:19:05,672 [salt.state       :1813][INFO    ][7081] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-04-20 02:19:06,647 [salt.loaded.ext.module.maasng:610 ][INFO    ][7081] 4bsbyd
2019-04-20 02:19:06,648 [salt.loaded.ext.module.maasng:626 ][INFO    ][7081] sda
2019-04-20 02:19:07,253 [salt.loaded.ext.module.maasng:361 ][INFO    ][7081] 4bsbyd
2019-04-20 02:19:07,324 [salt.loaded.ext.module.maasng:367 ][INFO    ][7081] [{u'partition_table_type': u'MBR', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'4bsbyd', u'resource_uri': u'/MAAS/api/2.0/nodes/4bsbyd/blockdevices/2/', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'partitions': [{u'size': 800101236736, u'uuid': u'83abbdf2-1837-43e0-b6c2-c6b1c1e66f80', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'4bsbyd', u'filesystem': {u'mount_options': None, u'uuid': u'275b3e28-0a53-49ec-a7e3-294fe0bdd757', u'label': None, u'mount_point': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/4bsbyd/blockdevices/2/partition/2'}], u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, {u'partition_table_type': None, u'block_size': 4096, u'uuid': u'b04e22c3-4807-49ee-a4e5-8e441f939eb4', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'filesystem': {u'mount_options': None, u'uuid': u'a14dd143-e5db-46f3-b99d-0e6a472faab9', u'label': u'root', u'mount_point': u'/', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'4bsbyd', u'resource_uri': u'/MAAS/api/2.0/nodes/4bsbyd/blockdevices/4/', u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'partitions': [], u'used_size': 800097042432, u'id': 4, u'serial': None, u'size': 800097042432}]
2019-04-20 02:19:07,325 [salt.loaded.ext.module.maasng:632 ][INFO    ][7081] vgroot
2019-04-20 02:19:07,325 [salt.loaded.ext.module.maasng:635 ][INFO    ][7081] lvroot
2019-04-20 02:19:07,325 [salt.loaded.ext.module.maasng:639 ][INFO    ][7081] 107374182400
2019-04-20 02:19:07,804 [salt.loaded.ext.module.maasng:645 ][INFO    ][7081] {u'hwe_kernel': u'', u'swap_size': None, u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.39', u'192.168.11.41'], 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'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'partition_table_type': u'MBR', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'4bsbyd', u'resource_uri': u'/MAAS/api/2.0/nodes/4bsbyd/blockdevices/2/', 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'partitions': [{u'size': 800101236736, u'uuid': u'154ca069-d006-49d4-b827-21a2224308b2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'4bsbyd', u'filesystem': {u'mount_options': None, u'uuid': u'17efd86a-463c-4bb9-9046-0e0467c28765', u'label': None, u'mount_point': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/4bsbyd/blockdevices/2/partition/7'}], u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, u'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'id': 1}, u'disable_ipv4': False, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'4bsbyd', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'partition_table_type': None, u'block_size': 4096, u'uuid': u'6396ff75-01ab-42d3-a426-777c5d8e7906', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'filesystem': {u'mount_options': None, u'uuid': u'af090641-740e-4315-b76e-166011405e59', u'label': u'root', u'mount_point': u'/', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'4bsbyd', u'resource_uri': u'/MAAS/api/2.0/nodes/4bsbyd/blockdevices/12/', u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'partitions': [], u'used_size': 107374182400, u'id': 12, u'serial': None, u'size': 107374182400}], u'min_hwe_kernel': u'ga-16.04', u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'power_state': u'off', u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'4bsbyd', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'commissioning_status_name': u'Passed', u'current_testing_result_id': 5, u'cpu_test_status': -1, u'storage_test_status': 2, u'other_test_status': -1, u'current_commissioning_result_id': 4, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'partition_table_type': u'MBR', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'4bsbyd', u'resource_uri': u'/MAAS/api/2.0/nodes/4bsbyd/blockdevices/2/', 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'partitions': [{u'size': 800101236736, u'uuid': u'154ca069-d006-49d4-b827-21a2224308b2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'4bsbyd', u'filesystem': {u'mount_options': None, u'uuid': u'17efd86a-463c-4bb9-9046-0e0467c28765', u'label': None, u'mount_point': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/4bsbyd/blockdevices/2/partition/7'}], u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'node_type': 0, u'commissioning_status': 2, u'architecture': u'amd64/generic', u'boot_interface': {u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'name': u'untagged', u'primary_rack': u'x8wpc7', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'name': u'untagged', u'primary_rack': u'x8wpc7', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'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': 24}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:95:a0', u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'name': u'untagged', u'primary_rack': u'x8wpc7', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'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'system_id': u'4bsbyd', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/4bsbyd/interfaces/5/'}, u'interface_set': [{u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'name': u'untagged', u'primary_rack': u'x8wpc7', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'name': u'untagged', u'primary_rack': u'x8wpc7', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'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': 24}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:95:a0', u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'name': u'untagged', u'primary_rack': u'x8wpc7', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'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'system_id': u'4bsbyd', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/4bsbyd/interfaces/5/'}, {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'children': [], u'discovered': None, u'system_id': u'4bsbyd', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/4bsbyd/interfaces/19/'}, {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'children': [], u'discovered': None, u'system_id': u'4bsbyd', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/4bsbyd/interfaces/20/'}, {u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'name': u'untagged', u'primary_rack': u'x8wpc7', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'name': u'untagged', u'primary_rack': u'x8wpc7', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'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'mac_address': u'9c:b6:54:8a:95:a4', u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'name': u'untagged', u'primary_rack': u'x8wpc7', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'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.41'}], u'system_id': u'4bsbyd', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/4bsbyd/interfaces/16/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 26}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:52:cc', u'enabled': True, u'children': [], u'discovered': None, u'system_id': u'4bsbyd', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/4bsbyd/interfaces/17/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 27}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:52:cd', u'enabled': True, u'children': [], u'discovered': None, u'system_id': u'4bsbyd', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/4bsbyd/interfaces/18/'}], u'address_ttl': None, u'blockdevice_set': [{u'partition_table_type': u'MBR', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'4bsbyd', u'resource_uri': u'/MAAS/api/2.0/nodes/4bsbyd/blockdevices/2/', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'partitions': [{u'size': 800101236736, u'uuid': u'154ca069-d006-49d4-b827-21a2224308b2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'4bsbyd', u'filesystem': {u'mount_options': None, u'uuid': u'17efd86a-463c-4bb9-9046-0e0467c28765', u'label': None, u'mount_point': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/4bsbyd/blockdevices/2/partition/7'}], u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, {u'partition_table_type': None, u'block_size': 4096, u'uuid': u'6396ff75-01ab-42d3-a426-777c5d8e7906', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'filesystem': {u'mount_options': None, u'uuid': u'af090641-740e-4315-b76e-166011405e59', u'label': u'root', u'mount_point': u'/', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'4bsbyd', u'resource_uri': u'/MAAS/api/2.0/nodes/4bsbyd/blockdevices/12/', u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'partitions': [], u'used_size': 107374182400, u'id': 12, u'serial': None, u'size': 107374182400}], u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/4bsbyd/'}
2019-04-20 02:19:07,805 [salt.state       :300 ][INFO    ][7081] {'new': {'storage_layout': 'lvm'}}
2019-04-20 02:19:07,806 [salt.state       :1951][INFO    ][7081] Completed state [maas_machines_storage_cmp001_lvm] at time 02:19:07.805990 duration_in_ms=2133.662
2019-04-20 02:19:07,808 [salt.minion      :1711][INFO    ][7081] Returning information for job: 20190420021853437862
2019-04-20 02:19:08,328 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command state.apply with jid 20190420021908320198
2019-04-20 02:19:08,340 [salt.minion      :1432][INFO    ][7128] Starting a new job with PID 7128
2019-04-20 02:19:09,420 [salt.state       :915 ][INFO    ][7128] Loading fresh modules for state activity
2019-04-20 02:19:09,467 [salt.fileclient  :1219][INFO    ][7128] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-04-20 02:19:09,510 [salt.state       :1780][INFO    ][7128] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:19:09.510246
2019-04-20 02:19:09,510 [salt.state       :1813][INFO    ][7128] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-20 02:19:09,512 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7128] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-20 02:19:11,281 [salt.state       :300 ][INFO    ][7128] {'pid': 7135, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-20 02:19:11,284 [salt.state       :1951][INFO    ][7128] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:19:11.282740 duration_in_ms=1772.493
2019-04-20 02:19:11,286 [salt.state       :1780][INFO    ][7128] Running state [maas.deploy_machines] at time 02:19:11.286660
2019-04-20 02:19:11,287 [salt.state       :1813][INFO    ][7128] Executing state module.run for [maas.deploy_machines]
2019-04-20 02:19:11,288 [salt.utils.decorators:613 ][WARNING ][7128] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-20 02:19:11,849 [salt.loaded.ext.module.maas:684 ][INFO    ][7128] deploymachines hwe_kernel=ga-16.04 system_id=f4wh7c distro_series=xenial
2019-04-20 02:19:14,202 [salt.loaded.ext.module.maas:684 ][INFO    ][7128] deploymachines hwe_kernel=ga-16.04 system_id=4bsbyd distro_series=xenial
2019-04-20 02:19:16,583 [salt.loaded.ext.module.maas:684 ][INFO    ][7128] deploymachines hwe_kernel=ga-16.04 system_id=dycasb distro_series=xenial
2019-04-20 02:19:18,932 [salt.loaded.ext.module.maas:684 ][INFO    ][7128] deploymachines hwe_kernel=ga-16.04 system_id=c3kg84 distro_series=xenial
2019-04-20 02:19:21,310 [salt.loaded.ext.module.maas:684 ][INFO    ][7128] deploymachines hwe_kernel=ga-16.04 system_id=mbsame distro_series=xenial
2019-04-20 02:19:23,365 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420021923357275
2019-04-20 02:19:23,384 [salt.minion      :1432][INFO    ][7403] Starting a new job with PID 7403
2019-04-20 02:19:23,407 [salt.minion      :1711][INFO    ][7403] Returning information for job: 20190420021923357275
2019-04-20 02:19:23,670 [salt.state       :300 ][INFO    ][7128] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-04-20 02:19:23,670 [salt.state       :1951][INFO    ][7128] Completed state [maas.deploy_machines] at time 02:19:23.670699 duration_in_ms=12384.039
2019-04-20 02:19:23,673 [salt.minion      :1711][INFO    ][7128] Returning information for job: 20190420021908320198
2019-04-20 02:19:24,259 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command state.apply with jid 20190420021924238670
2019-04-20 02:19:24,273 [salt.minion      :1432][INFO    ][7421] Starting a new job with PID 7421
2019-04-20 02:19:32,617 [salt.state       :915 ][INFO    ][7421] Loading fresh modules for state activity
2019-04-20 02:19:32,668 [salt.fileclient  :1219][INFO    ][7421] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-04-20 02:19:32,712 [salt.state       :1780][INFO    ][7421] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:19:32.712254
2019-04-20 02:19:32,712 [salt.state       :1813][INFO    ][7421] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-20 02:19:32,714 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7421] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-20 02:19:34,493 [salt.state       :300 ][INFO    ][7421] {'pid': 7489, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-20 02:19:34,493 [salt.state       :1951][INFO    ][7421] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:19:34.493699 duration_in_ms=1781.446
2019-04-20 02:19:34,496 [salt.state       :1780][INFO    ][7421] Running state [maas.wait_for_machine_status] at time 02:19:34.496329
2019-04-20 02:19:34,496 [salt.state       :1813][INFO    ][7421] Executing state module.run for [maas.wait_for_machine_status]
2019-04-20 02:19:34,497 [salt.utils.decorators:613 ][WARNING ][7421] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-20 02:19:37,430 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.07602s left)
2019-04-20 02:19:39,353 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420021939344359
2019-04-20 02:19:39,368 [salt.minion      :1432][INFO    ][7500] Starting a new job with PID 7500
2019-04-20 02:19:39,390 [salt.minion      :1711][INFO    ][7500] Returning information for job: 20190420021939344359
2019-04-20 02:20:09,401 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420022009392516
2019-04-20 02:20:09,415 [salt.minion      :1432][INFO    ][7529] Starting a new job with PID 7529
2019-04-20 02:20:09,436 [salt.minion      :1711][INFO    ][7529] Returning information for job: 20190420022009392516
2019-04-20 02:20:10,361 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.14475012s left)
2019-04-20 02:20:39,465 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420022039450634
2019-04-20 02:20:39,490 [salt.minion      :1432][INFO    ][7605] Starting a new job with PID 7605
2019-04-20 02:20:39,514 [salt.minion      :1711][INFO    ][7605] Returning information for job: 20190420022039450634
2019-04-20 02:20:43,145 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.36056209s left)
2019-04-20 02:21:09,527 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420022109516798
2019-04-20 02:21:09,545 [salt.minion      :1432][INFO    ][7626] Starting a new job with PID 7626
2019-04-20 02:21:09,564 [salt.minion      :1711][INFO    ][7626] Returning information for job: 20190420022109516798
2019-04-20 02:21:15,818 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2148.68783617s left)
2019-04-20 02:21:39,745 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420022139730738
2019-04-20 02:21:39,767 [salt.minion      :1432][INFO    ][7726] Starting a new job with PID 7726
2019-04-20 02:21:39,789 [salt.minion      :1711][INFO    ][7726] Returning information for job: 20190420022139730738
2019-04-20 02:21:48,580 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2115.92673302s left)
2019-04-20 02:22:09,936 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420022209927027
2019-04-20 02:22:09,955 [salt.minion      :1432][INFO    ][7795] Starting a new job with PID 7795
2019-04-20 02:22:09,979 [salt.minion      :1711][INFO    ][7795] Returning information for job: 20190420022209927027
2019-04-20 02:22:21,385 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2083.1208601s left)
2019-04-20 02:22:39,993 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420022239980891
2019-04-20 02:22:40,015 [salt.minion      :1432][INFO    ][7993] Starting a new job with PID 7993
2019-04-20 02:22:40,041 [salt.minion      :1711][INFO    ][7993] Returning information for job: 20190420022239980891
2019-04-20 02:22:54,449 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2050.0566721s left)
2019-04-20 02:23:10,082 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420022310068551
2019-04-20 02:23:10,101 [salt.minion      :1432][INFO    ][8020] Starting a new job with PID 8020
2019-04-20 02:23:10,128 [salt.minion      :1711][INFO    ][8020] Returning information for job: 20190420022310068551
2019-04-20 02:23:28,417 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2016.08868504s left)
2019-04-20 02:23:40,186 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420022340171846
2019-04-20 02:23:40,206 [salt.minion      :1432][INFO    ][8366] Starting a new job with PID 8366
2019-04-20 02:23:40,240 [salt.minion      :1711][INFO    ][8366] Returning information for job: 20190420022340171846
2019-04-20 02:24:01,424 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1983.08143497s left)
2019-04-20 02:24:10,277 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420022410269323
2019-04-20 02:24:10,293 [salt.minion      :1432][INFO    ][8462] Starting a new job with PID 8462
2019-04-20 02:24:10,315 [salt.minion      :1711][INFO    ][8462] Returning information for job: 20190420022410269323
2019-04-20 02:24:34,210 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1950.29566097s left)
2019-04-20 02:24:40,377 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420022440363924
2019-04-20 02:24:40,399 [salt.minion      :1432][INFO    ][8737] Starting a new job with PID 8737
2019-04-20 02:24:40,421 [salt.minion      :1711][INFO    ][8737] Returning information for job: 20190420022440363924
2019-04-20 02:25:07,224 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1917.28176904s left)
2019-04-20 02:25:10,473 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420022510464447
2019-04-20 02:25:10,495 [salt.minion      :1432][INFO    ][8767] Starting a new job with PID 8767
2019-04-20 02:25:10,518 [salt.minion      :1711][INFO    ][8767] Returning information for job: 20190420022510464447
2019-04-20 02:25:39,981 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1884.52463508s left)
2019-04-20 02:25:40,630 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420022540623446
2019-04-20 02:25:40,644 [salt.minion      :1432][INFO    ][9216] Starting a new job with PID 9216
2019-04-20 02:25:40,665 [salt.minion      :1711][INFO    ][9216] Returning information for job: 20190420022540623446
2019-04-20 02:26:10,728 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420022610720702
2019-04-20 02:26:10,741 [salt.minion      :1432][INFO    ][9241] Starting a new job with PID 9241
2019-04-20 02:26:10,763 [salt.minion      :1711][INFO    ][9241] Returning information for job: 20190420022610720702
2019-04-20 02:26:12,969 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1851.53685403s left)
2019-04-20 02:26:40,862 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420022640851603
2019-04-20 02:26:40,882 [salt.minion      :1432][INFO    ][9430] Starting a new job with PID 9430
2019-04-20 02:26:40,904 [salt.minion      :1711][INFO    ][9430] Returning information for job: 20190420022640851603
2019-04-20 02:26:45,834 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1818.67149496s left)
2019-04-20 02:27:10,976 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420022710961304
2019-04-20 02:27:10,999 [salt.minion      :1432][INFO    ][9455] Starting a new job with PID 9455
2019-04-20 02:27:11,023 [salt.minion      :1711][INFO    ][9455] Returning information for job: 20190420022710961304
2019-04-20 02:27:18,628 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1785.87936115s left)
2019-04-20 02:27:41,155 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420022741140660
2019-04-20 02:27:41,177 [salt.minion      :1432][INFO    ][9728] Starting a new job with PID 9728
2019-04-20 02:27:41,200 [salt.minion      :1711][INFO    ][9728] Returning information for job: 20190420022741140660
2019-04-20 02:27:51,444 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1753.06151509s left)
2019-04-20 02:28:11,273 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420022811256068
2019-04-20 02:28:11,290 [salt.minion      :1432][INFO    ][9774] Starting a new job with PID 9774
2019-04-20 02:28:11,317 [salt.minion      :1711][INFO    ][9774] Returning information for job: 20190420022811256068
2019-04-20 02:28:24,251 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1720.25510406s left)
2019-04-20 02:28:41,462 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420022841450579
2019-04-20 02:28:41,480 [salt.minion      :1432][INFO    ][10114] Starting a new job with PID 10114
2019-04-20 02:28:41,502 [salt.minion      :1711][INFO    ][10114] Returning information for job: 20190420022841450579
2019-04-20 02:28:57,105 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1687.40093517s left)
2019-04-20 02:29:11,588 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420022911573922
2019-04-20 02:29:11,610 [salt.minion      :1432][INFO    ][10141] Starting a new job with PID 10141
2019-04-20 02:29:11,632 [salt.minion      :1711][INFO    ][10141] Returning information for job: 20190420022911573922
2019-04-20 02:29:29,796 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1654.70997214s left)
2019-04-20 02:29:41,757 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420022941748810
2019-04-20 02:29:41,779 [salt.minion      :1432][INFO    ][10351] Starting a new job with PID 10351
2019-04-20 02:29:41,801 [salt.minion      :1711][INFO    ][10351] Returning information for job: 20190420022941748810
2019-04-20 02:30:02,574 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1621.931705s left)
2019-04-20 02:30:11,901 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420023011885633
2019-04-20 02:30:11,921 [salt.minion      :1432][INFO    ][10372] Starting a new job with PID 10372
2019-04-20 02:30:11,939 [salt.minion      :1711][INFO    ][10372] Returning information for job: 20190420023011885633
2019-04-20 02:30:35,513 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1588.99248695s left)
2019-04-20 02:30:42,078 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420023042057849
2019-04-20 02:30:42,097 [salt.minion      :1432][INFO    ][10473] Starting a new job with PID 10473
2019-04-20 02:30:42,122 [salt.minion      :1711][INFO    ][10473] Returning information for job: 20190420023042057849
2019-04-20 02:31:08,317 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1556.18835402s left)
2019-04-20 02:31:12,149 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420023112141069
2019-04-20 02:31:12,163 [salt.minion      :1432][INFO    ][10522] Starting a new job with PID 10522
2019-04-20 02:31:12,182 [salt.minion      :1711][INFO    ][10522] Returning information for job: 20190420023112141069
2019-04-20 02:31:41,108 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1523.39829516s left)
2019-04-20 02:31:42,326 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420023142312221
2019-04-20 02:31:42,347 [salt.minion      :1432][INFO    ][10942] Starting a new job with PID 10942
2019-04-20 02:31:42,371 [salt.minion      :1711][INFO    ][10942] Returning information for job: 20190420023142312221
2019-04-20 02:32:12,516 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420023212506611
2019-04-20 02:32:12,535 [salt.minion      :1432][INFO    ][10961] Starting a new job with PID 10961
2019-04-20 02:32:12,557 [salt.minion      :1711][INFO    ][10961] Returning information for job: 20190420023212506611
2019-04-20 02:32:13,818 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1490.68816996s left)
2019-04-20 02:32:42,704 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420023242692645
2019-04-20 02:32:42,727 [salt.minion      :1432][INFO    ][11094] Starting a new job with PID 11094
2019-04-20 02:32:42,750 [salt.minion      :1711][INFO    ][11094] Returning information for job: 20190420023242692645
2019-04-20 02:32:46,711 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1457.79485416s left)
2019-04-20 02:33:12,917 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420023312901807
2019-04-20 02:33:12,934 [salt.minion      :1432][INFO    ][11170] Starting a new job with PID 11170
2019-04-20 02:33:12,955 [salt.minion      :1711][INFO    ][11170] Returning information for job: 20190420023312901807
2019-04-20 02:33:19,584 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1424.92171097s left)
2019-04-20 02:33:43,039 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420023343023790
2019-04-20 02:33:43,062 [salt.minion      :1432][INFO    ][11425] Starting a new job with PID 11425
2019-04-20 02:33:43,082 [salt.minion      :1711][INFO    ][11425] Returning information for job: 20190420023343023790
2019-04-20 02:33:52,421 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1392.08481002s left)
2019-04-20 02:34:13,053 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420023413044253
2019-04-20 02:34:13,077 [salt.minion      :1432][INFO    ][11451] Starting a new job with PID 11451
2019-04-20 02:34:13,099 [salt.minion      :1711][INFO    ][11451] Returning information for job: 20190420023413044253
2019-04-20 02:34:26,035 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1358.47031307s left)
2019-04-20 02:34:43,089 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420023443080054
2019-04-20 02:34:43,112 [salt.minion      :1432][INFO    ][11647] Starting a new job with PID 11647
2019-04-20 02:34:43,133 [salt.minion      :1711][INFO    ][11647] Returning information for job: 20190420023443080054
2019-04-20 02:34:58,696 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1325.80975795s left)
2019-04-20 02:35:13,146 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420023513133415
2019-04-20 02:35:13,169 [salt.minion      :1432][INFO    ][11668] Starting a new job with PID 11668
2019-04-20 02:35:13,189 [salt.minion      :1711][INFO    ][11668] Returning information for job: 20190420023513133415
2019-04-20 02:35:31,509 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1292.9967041s left)
2019-04-20 02:35:43,175 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420023543166436
2019-04-20 02:35:43,197 [salt.minion      :1432][INFO    ][11749] Starting a new job with PID 11749
2019-04-20 02:35:43,217 [salt.minion      :1711][INFO    ][11749] Returning information for job: 20190420023543166436
2019-04-20 02:36:04,300 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1260.20557904s left)
2019-04-20 02:36:13,244 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420023613231280
2019-04-20 02:36:13,268 [salt.minion      :1432][INFO    ][11774] Starting a new job with PID 11774
2019-04-20 02:36:13,291 [salt.minion      :1711][INFO    ][11774] Returning information for job: 20190420023613231280
2019-04-20 02:36:36,928 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1227.57771897s left)
2019-04-20 02:36:43,280 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420023643272036
2019-04-20 02:36:43,303 [salt.minion      :1432][INFO    ][11855] Starting a new job with PID 11855
2019-04-20 02:36:43,327 [salt.minion      :1711][INFO    ][11855] Returning information for job: 20190420023643272036
2019-04-20 02:37:09,860 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1194.64614511s left)
2019-04-20 02:37:13,364 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420023713352674
2019-04-20 02:37:13,382 [salt.minion      :1432][INFO    ][11876] Starting a new job with PID 11876
2019-04-20 02:37:13,404 [salt.minion      :1711][INFO    ][11876] Returning information for job: 20190420023713352674
2019-04-20 02:37:42,609 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1161.89635801s left)
2019-04-20 02:37:43,450 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420023743442640
2019-04-20 02:37:43,464 [salt.minion      :1432][INFO    ][11957] Starting a new job with PID 11957
2019-04-20 02:37:43,487 [salt.minion      :1711][INFO    ][11957] Returning information for job: 20190420023743442640
2019-04-20 02:38:13,536 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420023813527715
2019-04-20 02:38:13,554 [salt.minion      :1432][INFO    ][11984] Starting a new job with PID 11984
2019-04-20 02:38:13,574 [salt.minion      :1711][INFO    ][11984] Returning information for job: 20190420023813527715
2019-04-20 02:38:15,322 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1129.18373609s left)
2019-04-20 02:38:43,667 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420023843660653
2019-04-20 02:38:43,688 [salt.minion      :1432][INFO    ][12064] Starting a new job with PID 12064
2019-04-20 02:38:43,716 [salt.minion      :1711][INFO    ][12064] Returning information for job: 20190420023843660653
2019-04-20 02:38:47,890 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1096.61543107s left)
2019-04-20 02:39:13,771 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420023913760982
2019-04-20 02:39:13,787 [salt.minion      :1432][INFO    ][12085] Starting a new job with PID 12085
2019-04-20 02:39:13,808 [salt.minion      :1711][INFO    ][12085] Returning information for job: 20190420023913760982
2019-04-20 02:39:20,657 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1063.84923315s left)
2019-04-20 02:39:43,929 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420023943919582
2019-04-20 02:39:43,950 [salt.minion      :1432][INFO    ][12166] Starting a new job with PID 12166
2019-04-20 02:39:43,974 [salt.minion      :1711][INFO    ][12166] Returning information for job: 20190420023943919582
2019-04-20 02:39:53,407 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1031.09889913s left)
2019-04-20 02:40:14,069 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420024014053009
2019-04-20 02:40:14,088 [salt.minion      :1432][INFO    ][12189] Starting a new job with PID 12189
2019-04-20 02:40:14,109 [salt.minion      :1711][INFO    ][12189] Returning information for job: 20190420024014053009
2019-04-20 02:40:26,238 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (998.267465115s left)
2019-04-20 02:40:44,248 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420024044233742
2019-04-20 02:40:44,266 [salt.minion      :1432][INFO    ][12272] Starting a new job with PID 12272
2019-04-20 02:40:44,288 [salt.minion      :1711][INFO    ][12272] Returning information for job: 20190420024044233742
2019-04-20 02:40:58,842 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (965.663442135s left)
2019-04-20 02:41:14,414 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420024114399735
2019-04-20 02:41:14,435 [salt.minion      :1432][INFO    ][12293] Starting a new job with PID 12293
2019-04-20 02:41:14,458 [salt.minion      :1711][INFO    ][12293] Returning information for job: 20190420024114399735
2019-04-20 02:41:31,593 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (932.912722111s left)
2019-04-20 02:41:44,432 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420024144421342
2019-04-20 02:41:44,455 [salt.minion      :1432][INFO    ][12396] Starting a new job with PID 12396
2019-04-20 02:41:44,480 [salt.minion      :1711][INFO    ][12396] Returning information for job: 20190420024144421342
2019-04-20 02:42:04,254 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (900.251839161s left)
2019-04-20 02:42:14,626 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420024214610404
2019-04-20 02:42:14,648 [salt.minion      :1432][INFO    ][12418] Starting a new job with PID 12418
2019-04-20 02:42:14,672 [salt.minion      :1711][INFO    ][12418] Returning information for job: 20190420024214610404
2019-04-20 02:42:37,054 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (867.451753139s left)
2019-04-20 02:42:44,851 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420024244838101
2019-04-20 02:42:44,869 [salt.minion      :1432][INFO    ][12501] Starting a new job with PID 12501
2019-04-20 02:42:44,894 [salt.minion      :1711][INFO    ][12501] Returning information for job: 20190420024244838101
2019-04-20 02:43:09,734 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (834.771928072s left)
2019-04-20 02:43:15,024 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420024315013890
2019-04-20 02:43:15,043 [salt.minion      :1432][INFO    ][12524] Starting a new job with PID 12524
2019-04-20 02:43:15,068 [salt.minion      :1711][INFO    ][12524] Returning information for job: 20190420024315013890
2019-04-20 02:43:42,620 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (801.886113167s left)
2019-04-20 02:43:45,061 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420024345048462
2019-04-20 02:43:45,081 [salt.minion      :1432][INFO    ][12607] Starting a new job with PID 12607
2019-04-20 02:43:45,109 [salt.minion      :1711][INFO    ][12607] Returning information for job: 20190420024345048462
2019-04-20 02:44:15,145 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420024415131744
2019-04-20 02:44:15,169 [salt.minion      :1432][INFO    ][12638] Starting a new job with PID 12638
2019-04-20 02:44:15,199 [salt.minion      :1711][INFO    ][12638] Returning information for job: 20190420024415131744
2019-04-20 02:44:15,336 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (769.169770956s left)
2019-04-20 02:44:45,178 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420024445166097
2019-04-20 02:44:45,198 [salt.minion      :1432][INFO    ][12706] Starting a new job with PID 12706
2019-04-20 02:44:45,223 [salt.minion      :1711][INFO    ][12706] Returning information for job: 20190420024445166097
2019-04-20 02:44:48,000 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (736.50616312s left)
2019-04-20 02:45:15,301 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420024515289370
2019-04-20 02:45:15,320 [salt.minion      :1432][INFO    ][12740] Starting a new job with PID 12740
2019-04-20 02:45:15,347 [salt.minion      :1711][INFO    ][12740] Returning information for job: 20190420024515289370
2019-04-20 02:45:20,757 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (703.748434067s left)
2019-04-20 02:45:45,367 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420024545358522
2019-04-20 02:45:45,385 [salt.minion      :1432][INFO    ][12812] Starting a new job with PID 12812
2019-04-20 02:45:45,409 [salt.minion      :1711][INFO    ][12812] Returning information for job: 20190420024545358522
2019-04-20 02:45:53,418 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (671.087763071s left)
2019-04-20 02:46:15,500 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420024615488359
2019-04-20 02:46:15,521 [salt.minion      :1432][INFO    ][12842] Starting a new job with PID 12842
2019-04-20 02:46:15,545 [salt.minion      :1711][INFO    ][12842] Returning information for job: 20190420024615488359
2019-04-20 02:46:26,282 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (638.224223137s left)
2019-04-20 02:46:45,637 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420024645624528
2019-04-20 02:46:45,658 [salt.minion      :1432][INFO    ][12917] Starting a new job with PID 12917
2019-04-20 02:46:45,681 [salt.minion      :1711][INFO    ][12917] Returning information for job: 20190420024645624528
2019-04-20 02:46:59,003 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (605.502892017s left)
2019-04-20 02:47:15,834 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420024715822085
2019-04-20 02:47:15,848 [salt.minion      :1432][INFO    ][12947] Starting a new job with PID 12947
2019-04-20 02:47:15,872 [salt.minion      :1711][INFO    ][12947] Returning information for job: 20190420024715822085
2019-04-20 02:47:31,733 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (572.77316618s left)
2019-04-20 02:47:45,968 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420024745957925
2019-04-20 02:47:45,991 [salt.minion      :1432][INFO    ][13022] Starting a new job with PID 13022
2019-04-20 02:47:46,016 [salt.minion      :1711][INFO    ][13022] Returning information for job: 20190420024745957925
2019-04-20 02:48:04,498 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (540.007539988s left)
2019-04-20 02:48:15,992 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420024815983905
2019-04-20 02:48:16,015 [salt.minion      :1432][INFO    ][13052] Starting a new job with PID 13052
2019-04-20 02:48:16,038 [salt.minion      :1711][INFO    ][13052] Returning information for job: 20190420024815983905
2019-04-20 02:48:37,156 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (507.349421024s left)
2019-04-20 02:48:46,169 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420024846157596
2019-04-20 02:48:46,187 [salt.minion      :1432][INFO    ][13123] Starting a new job with PID 13123
2019-04-20 02:48:46,210 [salt.minion      :1711][INFO    ][13123] Returning information for job: 20190420024846157596
2019-04-20 02:49:09,716 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (474.789740086s left)
2019-04-20 02:49:16,229 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420024916221432
2019-04-20 02:49:16,248 [salt.minion      :1432][INFO    ][13155] Starting a new job with PID 13155
2019-04-20 02:49:16,271 [salt.minion      :1711][INFO    ][13155] Returning information for job: 20190420024916221432
2019-04-20 02:49:42,569 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (441.936697006s left)
2019-04-20 02:49:46,257 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420024946250494
2019-04-20 02:49:46,272 [salt.minion      :1432][INFO    ][13227] Starting a new job with PID 13227
2019-04-20 02:49:46,293 [salt.minion      :1711][INFO    ][13227] Returning information for job: 20190420024946250494
2019-04-20 02:50:15,361 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (409.145120144s left)
2019-04-20 02:50:16,325 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420025016313082
2019-04-20 02:50:16,343 [salt.minion      :1432][INFO    ][13259] Starting a new job with PID 13259
2019-04-20 02:50:16,367 [salt.minion      :1711][INFO    ][13259] Returning information for job: 20190420025016313082
2019-04-20 02:50:46,441 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420025046432737
2019-04-20 02:50:46,462 [salt.minion      :1432][INFO    ][13334] Starting a new job with PID 13334
2019-04-20 02:50:46,487 [salt.minion      :1711][INFO    ][13334] Returning information for job: 20190420025046432737
2019-04-20 02:50:48,045 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (376.460484028s left)
2019-04-20 02:51:16,520 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420025116511459
2019-04-20 02:51:16,542 [salt.minion      :1432][INFO    ][13491] Starting a new job with PID 13491
2019-04-20 02:51:16,569 [salt.minion      :1711][INFO    ][13491] Returning information for job: 20190420025116511459
2019-04-20 02:51:20,688 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (343.817995071s left)
2019-04-20 02:51:46,673 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420025146656997
2019-04-20 02:51:46,690 [salt.minion      :1432][INFO    ][13580] Starting a new job with PID 13580
2019-04-20 02:51:46,714 [salt.minion      :1711][INFO    ][13580] Returning information for job: 20190420025146656997
2019-04-20 02:51:53,281 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (311.225025177s left)
2019-04-20 02:52:16,792 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420025216777598
2019-04-20 02:52:16,811 [salt.minion      :1432][INFO    ][13611] Starting a new job with PID 13611
2019-04-20 02:52:16,834 [salt.minion      :1711][INFO    ][13611] Returning information for job: 20190420025216777598
2019-04-20 02:52:26,200 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (278.305536985s left)
2019-04-20 02:52:46,992 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420025246984104
2019-04-20 02:52:47,010 [salt.minion      :1432][INFO    ][13683] Starting a new job with PID 13683
2019-04-20 02:52:47,034 [salt.minion      :1711][INFO    ][13683] Returning information for job: 20190420025246984104
2019-04-20 02:52:58,933 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (245.572728157s left)
2019-04-20 02:53:17,133 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420025317126242
2019-04-20 02:53:17,150 [salt.minion      :1432][INFO    ][13712] Starting a new job with PID 13712
2019-04-20 02:53:17,176 [salt.minion      :1711][INFO    ][13712] Returning information for job: 20190420025317126242
2019-04-20 02:53:31,676 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (212.829627991s left)
2019-04-20 02:53:47,356 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420025347343546
2019-04-20 02:53:47,377 [salt.minion      :1432][INFO    ][13789] Starting a new job with PID 13789
2019-04-20 02:53:47,398 [salt.minion      :1711][INFO    ][13789] Returning information for job: 20190420025347343546
2019-04-20 02:54:04,328 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (180.177845001s left)
2019-04-20 02:54:17,569 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420025417558349
2019-04-20 02:54:17,590 [salt.minion      :1432][INFO    ][13820] Starting a new job with PID 13820
2019-04-20 02:54:17,613 [salt.minion      :1711][INFO    ][13820] Returning information for job: 20190420025417558349
2019-04-20 02:54:37,006 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (147.500159979s left)
2019-04-20 02:54:47,616 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420025447605476
2019-04-20 02:54:47,639 [salt.minion      :1432][INFO    ][13892] Starting a new job with PID 13892
2019-04-20 02:54:47,663 [salt.minion      :1711][INFO    ][13892] Returning information for job: 20190420025447605476
2019-04-20 02:55:09,768 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (114.737926006s left)
2019-04-20 02:55:17,832 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420025517819196
2019-04-20 02:55:17,854 [salt.minion      :1432][INFO    ][13923] Starting a new job with PID 13923
2019-04-20 02:55:17,876 [salt.minion      :1711][INFO    ][13923] Returning information for job: 20190420025517819196
2019-04-20 02:55:42,454 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (82.051527977s left)
2019-04-20 02:55:47,913 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420025547901246
2019-04-20 02:55:47,934 [salt.minion      :1432][INFO    ][13995] Starting a new job with PID 13995
2019-04-20 02:55:47,958 [salt.minion      :1711][INFO    ][13995] Returning information for job: 20190420025547901246
2019-04-20 02:56:15,281 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (49.2249400616s left)
2019-04-20 02:56:18,029 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420025618020138
2019-04-20 02:56:18,044 [salt.minion      :1432][INFO    ][14024] Starting a new job with PID 14024
2019-04-20 02:56:18,066 [salt.minion      :1711][INFO    ][14024] Returning information for job: 20190420025618020138
2019-04-20 02:56:48,043 [salt.loaded.ext.module.maas:1023][INFO    ][7421] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (16.4633290768s left)
2019-04-20 02:56:48,097 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420025648084635
2019-04-20 02:56:48,118 [salt.minion      :1432][INFO    ][14099] Starting a new job with PID 14099
2019-04-20 02:56:48,141 [salt.minion      :1711][INFO    ][14099] Returning information for job: 20190420025648084635
2019-04-20 02:57:18,302 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420025718292397
2019-04-20 02:57:18,320 [salt.minion      :1432][INFO    ][14126] Starting a new job with PID 14126
2019-04-20 02:57:18,343 [salt.minion      :1711][INFO    ][14126] Returning information for job: 20190420025718292397
2019-04-20 02:57:20,791 [salt.state       :302 ][ERROR   ][7421] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-04-20 02:57:20,791 [salt.state       :1951][INFO    ][7421] Completed state [maas.wait_for_machine_status] at time 02:57:20.791875 duration_in_ms=2266295.542
2019-04-20 02:57:20,797 [salt.minion      :1711][INFO    ][7421] Returning information for job: 20190420021924238670
2019-04-20 02:57:31,567 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command pillar.get with jid 20190420025731555510
2019-04-20 02:57:31,590 [salt.minion      :1432][INFO    ][14195] Starting a new job with PID 14195
2019-04-20 02:57:31,597 [salt.minion      :1711][INFO    ][14195] Returning information for job: 20190420025731555510
2019-04-20 02:57:32,141 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command service.status with jid 20190420025732131759
2019-04-20 02:57:32,160 [salt.minion      :1432][INFO    ][14200] Starting a new job with PID 14200
2019-04-20 02:57:32,674 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][14200] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-04-20 02:57:32,709 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][14200] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-04-20 02:57:32,725 [salt.minion      :1711][INFO    ][14200] Returning information for job: 20190420025732131759
2019-04-20 02:57:33,261 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command state.apply with jid 20190420025733250748
2019-04-20 02:57:33,282 [salt.minion      :1432][INFO    ][14211] Starting a new job with PID 14211
2019-04-20 02:57:41,250 [salt.state       :915 ][INFO    ][14211] Loading fresh modules for state activity
2019-04-20 02:57:41,816 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14211] Executing command 'salt-minion --version' in directory '/root'
2019-04-20 02:57:42,102 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14211] Executing command 'salt-minion --version' in directory '/root'
2019-04-20 02:57:43,049 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14211] Executing command 'salt-minion --version' in directory '/root'
2019-04-20 02:57:43,330 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14211] Executing command 'salt-minion --version' in directory '/root'
2019-04-20 02:57:45,089 [salt.state       :1780][INFO    ][14211] Running state [salt-minion] at time 02:57:45.089801
2019-04-20 02:57:45,090 [salt.state       :1813][INFO    ][14211] Executing state pkg.installed for [salt-minion]
2019-04-20 02:57:45,091 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14211] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-20 02:57:45,201 [salt.state       :300 ][INFO    ][14211] All specified packages are already installed
2019-04-20 02:57:45,201 [salt.state       :1951][INFO    ][14211] Completed state [salt-minion] at time 02:57:45.201391 duration_in_ms=111.59
2019-04-20 02:57:45,201 [salt.state       :1780][INFO    ][14211] Running state [salt_minion_dependency_packages] at time 02:57:45.201717
2019-04-20 02:57:45,202 [salt.state       :1813][INFO    ][14211] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-04-20 02:57:45,211 [salt.state       :300 ][INFO    ][14211] All specified packages are already installed
2019-04-20 02:57:45,212 [salt.state       :1951][INFO    ][14211] Completed state [salt_minion_dependency_packages] at time 02:57:45.212006 duration_in_ms=10.289
2019-04-20 02:57:45,215 [salt.state       :1780][INFO    ][14211] Running state [/etc/salt/minion.d/minion.conf] at time 02:57:45.214991
2019-04-20 02:57:45,215 [salt.state       :1813][INFO    ][14211] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-04-20 02:57:45,483 [salt.state       :300 ][INFO    ][14211] File /etc/salt/minion.d/minion.conf is in the correct state
2019-04-20 02:57:45,483 [salt.state       :1951][INFO    ][14211] Completed state [/etc/salt/minion.d/minion.conf] at time 02:57:45.483255 duration_in_ms=268.264
2019-04-20 02:57:45,483 [salt.state       :1780][INFO    ][14211] Running state [python-netaddr] at time 02:57:45.483509
2019-04-20 02:57:45,483 [salt.state       :1813][INFO    ][14211] Executing state pkg.installed for [python-netaddr]
2019-04-20 02:57:45,492 [salt.state       :300 ][INFO    ][14211] All specified packages are already installed
2019-04-20 02:57:45,492 [salt.state       :1951][INFO    ][14211] Completed state [python-netaddr] at time 02:57:45.492919 duration_in_ms=9.41
2019-04-20 02:57:45,497 [salt.state       :1780][INFO    ][14211] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:57:45.497066
2019-04-20 02:57:45,497 [salt.state       :1813][INFO    ][14211] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-04-20 02:57:45,512 [salt.state       :300 ][INFO    ][14211] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-04-20 02:57:45,513 [salt.state       :1951][INFO    ][14211] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:57:45.513049 duration_in_ms=15.982
2019-04-20 02:57:45,514 [salt.state       :1780][INFO    ][14211] Running state [salt-minion] at time 02:57:45.514865
2019-04-20 02:57:45,515 [salt.state       :1813][INFO    ][14211] Executing state service.running for [salt-minion]
2019-04-20 02:57:45,516 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14211] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-04-20 02:57:45,558 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14211] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-04-20 02:57:45,574 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14211] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-04-20 02:57:45,593 [salt.state       :300 ][INFO    ][14211] The service salt-minion is already running
2019-04-20 02:57:45,593 [salt.state       :1951][INFO    ][14211] Completed state [salt-minion] at time 02:57:45.593577 duration_in_ms=78.711
2019-04-20 02:57:45,596 [salt.state       :1780][INFO    ][14211] Running state [/etc/salt/grains.d] at time 02:57:45.596327
2019-04-20 02:57:45,596 [salt.state       :1813][INFO    ][14211] Executing state file.directory for [/etc/salt/grains.d]
2019-04-20 02:57:45,598 [salt.state       :300 ][INFO    ][14211] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-04-20 02:57:45,598 [salt.state       :1951][INFO    ][14211] Completed state [/etc/salt/grains.d] at time 02:57:45.598739 duration_in_ms=2.412
2019-04-20 02:57:45,600 [salt.state       :1780][INFO    ][14211] Running state [/etc/salt/grains] at time 02:57:45.600015
2019-04-20 02:57:45,600 [salt.state       :1813][INFO    ][14211] Executing state file.managed for [/etc/salt/grains]
2019-04-20 02:57:45,601 [salt.state       :300 ][INFO    ][14211] File /etc/salt/grains exists with proper permissions. No changes made.
2019-04-20 02:57:45,601 [salt.state       :1951][INFO    ][14211] Completed state [/etc/salt/grains] at time 02:57:45.601626 duration_in_ms=1.612
2019-04-20 02:57:45,602 [salt.state       :1780][INFO    ][14211] Running state [/etc/salt/grains.d/placeholder] at time 02:57:45.602270
2019-04-20 02:57:45,602 [salt.state       :1813][INFO    ][14211] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-04-20 02:57:45,604 [salt.state       :300 ][INFO    ][14211] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-04-20 02:57:45,604 [salt.state       :1951][INFO    ][14211] Completed state [/etc/salt/grains.d/placeholder] at time 02:57:45.604631 duration_in_ms=2.361
2019-04-20 02:57:45,605 [salt.state       :1780][INFO    ][14211] Running state [/etc/salt/grains.d/sphinx] at time 02:57:45.605086
2019-04-20 02:57:45,605 [salt.state       :1813][INFO    ][14211] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-04-20 02:57:45,606 [salt.state       :300 ][INFO    ][14211] File /etc/salt/grains.d/sphinx is in the correct state
2019-04-20 02:57:45,606 [salt.state       :1951][INFO    ][14211] Completed state [/etc/salt/grains.d/sphinx] at time 02:57:45.606606 duration_in_ms=1.52
2019-04-20 02:57:45,609 [salt.state       :1780][INFO    ][14211] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:57:45.609161
2019-04-20 02:57:45,609 [salt.state       :1813][INFO    ][14211] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-04-20 02:57:45,609 [salt.state       :300 ][INFO    ][14211] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-04-20 02:57:45,609 [salt.state       :1951][INFO    ][14211] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:57:45.609913 duration_in_ms=0.753
2019-04-20 02:57:45,610 [salt.state       :1780][INFO    ][14211] Running state [/etc/salt/grains.d/dns_records] at time 02:57:45.610391
2019-04-20 02:57:45,610 [salt.state       :1813][INFO    ][14211] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-04-20 02:57:45,611 [salt.state       :300 ][INFO    ][14211] File /etc/salt/grains.d/dns_records is in the correct state
2019-04-20 02:57:45,611 [salt.state       :1951][INFO    ][14211] Completed state [/etc/salt/grains.d/dns_records] at time 02:57:45.611709 duration_in_ms=1.318
2019-04-20 02:57:45,612 [salt.state       :1780][INFO    ][14211] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:57:45.612621
2019-04-20 02:57:45,612 [salt.state       :1813][INFO    ][14211] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-04-20 02:57:45,613 [salt.state       :300 ][INFO    ][14211] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-04-20 02:57:45,613 [salt.state       :1951][INFO    ][14211] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:57:45.613365 duration_in_ms=0.743
2019-04-20 02:57:45,613 [salt.state       :1780][INFO    ][14211] Running state [/etc/salt/grains.d/salt] at time 02:57:45.613839
2019-04-20 02:57:45,614 [salt.state       :1813][INFO    ][14211] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-04-20 02:57:45,616 [salt.state       :300 ][INFO    ][14211] File /etc/salt/grains.d/salt is in the correct state
2019-04-20 02:57:45,616 [salt.state       :1951][INFO    ][14211] Completed state [/etc/salt/grains.d/salt] at time 02:57:45.616581 duration_in_ms=2.742
2019-04-20 02:57:45,617 [salt.state       :1780][INFO    ][14211] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:57:45.617461
2019-04-20 02:57:45,617 [salt.state       :1813][INFO    ][14211] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-04-20 02:57:45,618 [salt.state       :300 ][INFO    ][14211] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-04-20 02:57:45,618 [salt.state       :1951][INFO    ][14211] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:57:45.618217 duration_in_ms=0.755
2019-04-20 02:57:45,620 [salt.state       :1780][INFO    ][14211] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:57:45.620127
2019-04-20 02:57:45,620 [salt.state       :1813][INFO    ][14211] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-04-20 02:57:45,620 [salt.state       :300 ][INFO    ][14211] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-04-20 02:57:45,620 [salt.state       :1951][INFO    ][14211] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:57:45.620899 duration_in_ms=0.772
2019-04-20 02:57:45,621 [salt.state       :1780][INFO    ][14211] Running state [mine.update] at time 02:57:45.621552
2019-04-20 02:57:45,621 [salt.state       :1813][INFO    ][14211] Executing state module.wait for [mine.update]
2019-04-20 02:57:45,622 [salt.state       :300 ][INFO    ][14211] No changes made for mine.update
2019-04-20 02:57:45,622 [salt.state       :1951][INFO    ][14211] Completed state [mine.update] at time 02:57:45.622255 duration_in_ms=0.702
2019-04-20 02:57:45,622 [salt.state       :1780][INFO    ][14211] Running state [ca-certificates] at time 02:57:45.622495
2019-04-20 02:57:45,622 [salt.state       :1813][INFO    ][14211] Executing state pkg.installed for [ca-certificates]
2019-04-20 02:57:45,632 [salt.state       :300 ][INFO    ][14211] All specified packages are already installed
2019-04-20 02:57:45,632 [salt.state       :1951][INFO    ][14211] Completed state [ca-certificates] at time 02:57:45.632861 duration_in_ms=10.366
2019-04-20 02:57:45,633 [salt.state       :1780][INFO    ][14211] Running state [update-ca-certificates] at time 02:57:45.633546
2019-04-20 02:57:45,633 [salt.state       :1813][INFO    ][14211] Executing state cmd.wait for [update-ca-certificates]
2019-04-20 02:57:45,634 [salt.state       :300 ][INFO    ][14211] No changes made for update-ca-certificates
2019-04-20 02:57:45,634 [salt.state       :1951][INFO    ][14211] Completed state [update-ca-certificates] at time 02:57:45.634268 duration_in_ms=0.722
2019-04-20 02:57:45,634 [salt.state       :1780][INFO    ][14211] Running state [iptables] at time 02:57:45.634509
2019-04-20 02:57:45,634 [salt.state       :1813][INFO    ][14211] Executing state pkg.installed for [iptables]
2019-04-20 02:57:45,643 [salt.state       :300 ][INFO    ][14211] All specified packages are already installed
2019-04-20 02:57:45,644 [salt.state       :1951][INFO    ][14211] Completed state [iptables] at time 02:57:45.644036 duration_in_ms=9.527
2019-04-20 02:57:45,644 [salt.state       :1780][INFO    ][14211] Running state [iptables-persistent] at time 02:57:45.644271
2019-04-20 02:57:45,644 [salt.state       :1813][INFO    ][14211] Executing state pkg.installed for [iptables-persistent]
2019-04-20 02:57:45,653 [salt.state       :300 ][INFO    ][14211] All specified packages are already installed
2019-04-20 02:57:45,653 [salt.state       :1951][INFO    ][14211] Completed state [iptables-persistent] at time 02:57:45.653794 duration_in_ms=9.523
2019-04-20 02:57:45,654 [salt.state       :1780][INFO    ][14211] Running state [iptables_modules_v4_load] at time 02:57:45.654770
2019-04-20 02:57:45,655 [salt.state       :1813][INFO    ][14211] Executing state kmod.present for [iptables_modules_v4_load]
2019-04-20 02:57:45,655 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14211] Executing command 'lsmod' in directory '/root'
2019-04-20 02:57:45,677 [salt.state       :300 ][INFO    ][14211] Kernel modules iptable_filter, ip_tables are already present
2019-04-20 02:57:45,677 [salt.state       :1951][INFO    ][14211] Completed state [iptables_modules_v4_load] at time 02:57:45.677820 duration_in_ms=23.049
2019-04-20 02:57:45,679 [salt.state       :1780][INFO    ][14211] Running state [/etc/iptables/rules.v4] at time 02:57:45.679276
2019-04-20 02:57:45,679 [salt.state       :1813][INFO    ][14211] Executing state file.managed for [/etc/iptables/rules.v4]
2019-04-20 02:57:45,778 [salt.state       :300 ][INFO    ][14211] File /etc/iptables/rules.v4 is in the correct state
2019-04-20 02:57:45,778 [salt.state       :1951][INFO    ][14211] Completed state [/etc/iptables/rules.v4] at time 02:57:45.778725 duration_in_ms=99.449
2019-04-20 02:57:45,779 [salt.state       :1780][INFO    ][14211] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:57:45.779882
2019-04-20 02:57:45,780 [salt.state       :1813][INFO    ][14211] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-04-20 02:57:45,780 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14211] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-04-20 02:57:45,800 [salt.state       :300 ][INFO    ][14211] onlyif execution failed
2019-04-20 02:57:45,801 [salt.state       :1951][INFO    ][14211] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:57:45.801089 duration_in_ms=21.207
2019-04-20 02:57:45,803 [salt.state       :1780][INFO    ][14211] Running state [netfilter-persistent] at time 02:57:45.803059
2019-04-20 02:57:45,803 [salt.state       :1813][INFO    ][14211] Executing state service.running for [netfilter-persistent]
2019-04-20 02:57:45,805 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14211] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-04-20 02:57:45,827 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14211] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-04-20 02:57:45,847 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14211] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-04-20 02:57:45,863 [salt.state       :300 ][INFO    ][14211] The service netfilter-persistent is already running
2019-04-20 02:57:45,863 [salt.state       :1951][INFO    ][14211] Completed state [netfilter-persistent] at time 02:57:45.863835 duration_in_ms=60.775
2019-04-20 02:57:45,865 [salt.state       :1780][INFO    ][14211] Running state [iptables_extra.remove_stale_tables] at time 02:57:45.865032
2019-04-20 02:57:45,865 [salt.state       :1813][INFO    ][14211] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-04-20 02:57:45,865 [salt.state       :300 ][INFO    ][14211] No changes made for iptables_extra.remove_stale_tables
2019-04-20 02:57:45,866 [salt.state       :1951][INFO    ][14211] Completed state [iptables_extra.remove_stale_tables] at time 02:57:45.866185 duration_in_ms=1.153
2019-04-20 02:57:45,866 [salt.state       :1780][INFO    ][14211] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:57:45.866530
2019-04-20 02:57:45,868 [salt.state       :1813][INFO    ][14211] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-04-20 02:57:45,869 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14211] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-04-20 02:57:45,883 [salt.state       :300 ][INFO    ][14211] onlyif execution failed
2019-04-20 02:57:45,883 [salt.state       :1951][INFO    ][14211] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:57:45.883829 duration_in_ms=17.298
2019-04-20 02:57:45,885 [salt.state       :1780][INFO    ][14211] Running state [/etc/iptables/rules.v6] at time 02:57:45.885178
2019-04-20 02:57:45,885 [salt.state       :1813][INFO    ][14211] Executing state file.absent for [/etc/iptables/rules.v6]
2019-04-20 02:57:45,886 [salt.state       :300 ][INFO    ][14211] File /etc/iptables/rules.v6 is not present
2019-04-20 02:57:45,886 [salt.state       :1951][INFO    ][14211] Completed state [/etc/iptables/rules.v6] at time 02:57:45.886515 duration_in_ms=1.337
2019-04-20 02:57:45,887 [salt.state       :1780][INFO    ][14211] Running state [iptables_extra.flush_all] at time 02:57:45.887445
2019-04-20 02:57:45,887 [salt.state       :1813][INFO    ][14211] Executing state module.wait for [iptables_extra.flush_all]
2019-04-20 02:57:45,888 [salt.state       :300 ][INFO    ][14211] No changes made for iptables_extra.flush_all
2019-04-20 02:57:45,888 [salt.state       :1951][INFO    ][14211] Completed state [iptables_extra.flush_all] at time 02:57:45.888554 duration_in_ms=1.109
2019-04-20 02:57:45,895 [salt.minion      :1711][INFO    ][14211] Returning information for job: 20190420025733250748
2019-04-20 02:57:46,447 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command state.apply with jid 20190420025746432697
2019-04-20 02:57:46,464 [salt.minion      :1432][INFO    ][14291] Starting a new job with PID 14291
2019-04-20 02:57:47,603 [salt.state       :915 ][INFO    ][14291] Loading fresh modules for state activity
2019-04-20 02:57:48,461 [salt.state       :1780][INFO    ][14291] Running state [maas-rack-controller] at time 02:57:48.461210
2019-04-20 02:57:48,461 [salt.state       :1813][INFO    ][14291] Executing state pkg.installed for [maas-rack-controller]
2019-04-20 02:57:48,462 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14291] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-20 02:57:48,562 [salt.state       :300 ][INFO    ][14291] All specified packages are already installed
2019-04-20 02:57:48,562 [salt.state       :1951][INFO    ][14291] Completed state [maas-rack-controller] at time 02:57:48.562511 duration_in_ms=101.3
2019-04-20 02:57:48,562 [salt.state       :1780][INFO    ][14291] Running state [ipmitool] at time 02:57:48.562900
2019-04-20 02:57:48,563 [salt.state       :1813][INFO    ][14291] Executing state pkg.installed for [ipmitool]
2019-04-20 02:57:48,572 [salt.state       :300 ][INFO    ][14291] All specified packages are already installed
2019-04-20 02:57:48,573 [salt.state       :1951][INFO    ][14291] Completed state [ipmitool] at time 02:57:48.573027 duration_in_ms=10.127
2019-04-20 02:57:48,576 [salt.state       :1780][INFO    ][14291] Running state [/etc/maas/rackd.conf] at time 02:57:48.576908
2019-04-20 02:57:48,577 [salt.state       :1813][INFO    ][14291] Executing state file.line for [/etc/maas/rackd.conf]
2019-04-20 02:57:48,578 [salt.state       :300 ][INFO    ][14291] No changes needed to be made
2019-04-20 02:57:48,578 [salt.state       :1951][INFO    ][14291] Completed state [/etc/maas/rackd.conf] at time 02:57:48.578315 duration_in_ms=1.408
2019-04-20 02:57:48,578 [salt.state       :1780][INFO    ][14291] Running state [/etc/maas/rackd.conf] at time 02:57:48.578559
2019-04-20 02:57:48,578 [salt.state       :1813][INFO    ][14291] Executing state file.managed for [/etc/maas/rackd.conf]
2019-04-20 02:57:48,579 [salt.loaded.int.states.file:2298][WARNING ][14291] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-04-20 02:57:48,580 [salt.state       :300 ][INFO    ][14291] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-04-20 02:57:48,580 [salt.state       :1951][INFO    ][14291] Completed state [/etc/maas/rackd.conf] at time 02:57:48.580905 duration_in_ms=2.347
2019-04-20 02:57:48,581 [salt.state       :1780][INFO    ][14291] Running state [maas-rackd] at time 02:57:48.581905
2019-04-20 02:57:48,582 [salt.state       :1813][INFO    ][14291] Executing state service.running for [maas-rackd]
2019-04-20 02:57:48,582 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14291] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-04-20 02:57:48,617 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14291] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-04-20 02:57:48,633 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14291] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-04-20 02:57:48,649 [salt.state       :300 ][INFO    ][14291] The service maas-rackd is already running
2019-04-20 02:57:48,650 [salt.state       :1951][INFO    ][14291] Completed state [maas-rackd] at time 02:57:48.649978 duration_in_ms=68.073
2019-04-20 02:57:48,653 [salt.minion      :1711][INFO    ][14291] Returning information for job: 20190420025746432697
2019-04-20 02:57:49,188 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command state.apply with jid 20190420025749177805
2019-04-20 02:57:49,207 [salt.minion      :1432][INFO    ][14314] Starting a new job with PID 14314
2019-04-20 02:57:50,310 [salt.state       :915 ][INFO    ][14314] Loading fresh modules for state activity
2019-04-20 02:57:51,305 [salt.state       :1780][INFO    ][14314] Running state [maas-region-controller] at time 02:57:51.305565
2019-04-20 02:57:51,306 [salt.state       :1813][INFO    ][14314] Executing state pkg.installed for [maas-region-controller]
2019-04-20 02:57:51,306 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14314] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-20 02:57:51,406 [salt.state       :300 ][INFO    ][14314] All specified packages are already installed
2019-04-20 02:57:51,406 [salt.state       :1951][INFO    ][14314] Completed state [maas-region-controller] at time 02:57:51.406412 duration_in_ms=100.848
2019-04-20 02:57:51,406 [salt.state       :1780][INFO    ][14314] Running state [python-oauth] at time 02:57:51.406730
2019-04-20 02:57:51,407 [salt.state       :1813][INFO    ][14314] Executing state pkg.installed for [python-oauth]
2019-04-20 02:57:51,417 [salt.state       :300 ][INFO    ][14314] All specified packages are already installed
2019-04-20 02:57:51,417 [salt.state       :1951][INFO    ][14314] Completed state [python-oauth] at time 02:57:51.417301 duration_in_ms=10.571
2019-04-20 02:57:51,420 [salt.state       :1780][INFO    ][14314] Running state [/etc/maas/regiond.conf] at time 02:57:51.420136
2019-04-20 02:57:51,420 [salt.state       :1813][INFO    ][14314] Executing state file.replace for [/etc/maas/regiond.conf]
2019-04-20 02:57:51,425 [salt.state       :300 ][INFO    ][14314] No changes needed to be made
2019-04-20 02:57:51,426 [salt.state       :1951][INFO    ][14314] Completed state [/etc/maas/regiond.conf] at time 02:57:51.426087 duration_in_ms=5.951
2019-04-20 02:57:51,426 [salt.state       :1780][INFO    ][14314] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:57:51.426564
2019-04-20 02:57:51,426 [salt.state       :1813][INFO    ][14314] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-04-20 02:57:51,484 [salt.state       :300 ][INFO    ][14314] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-04-20 02:57:51,484 [salt.state       :1951][INFO    ][14314] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:57:51.484409 duration_in_ms=57.844
2019-04-20 02:57:51,484 [salt.state       :1780][INFO    ][14314] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:57:51.484894
2019-04-20 02:57:51,485 [salt.state       :1813][INFO    ][14314] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-04-20 02:57:51,488 [salt.state       :300 ][INFO    ][14314] No changes needed to be made
2019-04-20 02:57:51,488 [salt.state       :1951][INFO    ][14314] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:57:51.488824 duration_in_ms=3.929
2019-04-20 02:57:51,489 [salt.state       :1780][INFO    ][14314] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:57:51.489302
2019-04-20 02:57:51,489 [salt.state       :1813][INFO    ][14314] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-04-20 02:57:51,492 [salt.state       :300 ][INFO    ][14314] No changes needed to be made
2019-04-20 02:57:51,493 [salt.state       :1951][INFO    ][14314] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:57:51.493113 duration_in_ms=3.811
2019-04-20 02:57:51,493 [salt.state       :1780][INFO    ][14314] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:57:51.493588
2019-04-20 02:57:51,493 [salt.state       :1813][INFO    ][14314] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-04-20 02:57:51,516 [salt.state       :300 ][INFO    ][14314] No changes needed to be made
2019-04-20 02:57:51,517 [salt.state       :1951][INFO    ][14314] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:57:51.517139 duration_in_ms=23.551
2019-04-20 02:57:51,517 [salt.state       :1780][INFO    ][14314] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:57:51.517615
2019-04-20 02:57:51,517 [salt.state       :1813][INFO    ][14314] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-04-20 02:57:51,529 [salt.state       :300 ][INFO    ][14314] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-04-20 02:57:51,529 [salt.state       :1951][INFO    ][14314] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:57:51.529699 duration_in_ms=12.083
2019-04-20 02:57:51,533 [salt.state       :1780][INFO    ][14314] Running state [a2enmod headers] at time 02:57:51.533192
2019-04-20 02:57:51,533 [salt.state       :1813][INFO    ][14314] Executing state cmd.run for [a2enmod headers]
2019-04-20 02:57:51,534 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14314] Executing command 'a2enmod headers' in directory '/root'
2019-04-20 02:57:51,596 [salt.state       :300 ][INFO    ][14314] {'pid': 14333, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-04-20 02:57:51,597 [salt.state       :1951][INFO    ][14314] Completed state [a2enmod headers] at time 02:57:51.597531 duration_in_ms=64.339
2019-04-20 02:57:51,598 [salt.state       :1780][INFO    ][14314] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:57:51.597959
2019-04-20 02:57:51,598 [salt.state       :1813][INFO    ][14314] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-04-20 02:57:51,619 [salt.state       :300 ][INFO    ][14314] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-04-20 02:57:51,619 [salt.state       :1951][INFO    ][14314] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:57:51.619577 duration_in_ms=21.618
2019-04-20 02:57:51,620 [salt.state       :1780][INFO    ][14314] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:57:51.620161
2019-04-20 02:57:51,620 [salt.state       :1813][INFO    ][14314] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-04-20 02:57:51,677 [salt.state       :300 ][INFO    ][14314] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-04-20 02:57:51,677 [salt.state       :1951][INFO    ][14314] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:57:51.677823 duration_in_ms=57.662
2019-04-20 02:57:51,678 [salt.state       :1780][INFO    ][14314] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:57:51.678326
2019-04-20 02:57:51,678 [salt.state       :1813][INFO    ][14314] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-04-20 02:57:51,727 [salt.state       :300 ][INFO    ][14314] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-04-20 02:57:51,727 [salt.state       :1951][INFO    ][14314] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:57:51.727241 duration_in_ms=48.915
2019-04-20 02:57:51,727 [salt.state       :1780][INFO    ][14314] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:57:51.727755
2019-04-20 02:57:51,728 [salt.state       :1813][INFO    ][14314] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-04-20 02:57:51,797 [salt.state       :300 ][INFO    ][14314] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-04-20 02:57:51,797 [salt.state       :1951][INFO    ][14314] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:57:51.797521 duration_in_ms=69.766
2019-04-20 02:57:51,797 [salt.state       :1780][INFO    ][14314] Running state [/root/.pgpass] at time 02:57:51.797782
2019-04-20 02:57:51,798 [salt.state       :1813][INFO    ][14314] Executing state file.managed for [/root/.pgpass]
2019-04-20 02:57:51,837 [salt.state       :300 ][INFO    ][14314] File /root/.pgpass is in the correct state
2019-04-20 02:57:51,838 [salt.state       :1951][INFO    ][14314] Completed state [/root/.pgpass] at time 02:57:51.838097 duration_in_ms=40.315
2019-04-20 02:57:51,844 [salt.state       :1780][INFO    ][14314] Running state [maas-region syncdb --noinput] at time 02:57:51.844460
2019-04-20 02:57:51,844 [salt.state       :1813][INFO    ][14314] Executing state cmd.run for [maas-region syncdb --noinput]
2019-04-20 02:57:51,845 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14314] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-04-20 02:57:54,363 [salt.state       :300 ][INFO    ][14314] {'pid': 14346, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: metadataserver, maasserver, piston3, auth, contenttypes, sites, sessions\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-04-20 02:57:54,363 [salt.state       :1951][INFO    ][14314] Completed state [maas-region syncdb --noinput] at time 02:57:54.363871 duration_in_ms=2519.41
2019-04-20 02:57:54,364 [salt.state       :2022][WARNING ][14314] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-04-20 02:57:54,367 [salt.state       :1780][INFO    ][14314] Running state [maas-regiond] at time 02:57:54.367704
2019-04-20 02:57:54,368 [salt.state       :1813][INFO    ][14314] Executing state service.running for [maas-regiond]
2019-04-20 02:57:54,369 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14314] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-04-20 02:57:54,407 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14314] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-04-20 02:57:54,426 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14314] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-04-20 02:57:54,443 [salt.state       :300 ][INFO    ][14314] The service maas-regiond is already running
2019-04-20 02:57:54,444 [salt.state       :1951][INFO    ][14314] Completed state [maas-regiond] at time 02:57:54.444212 duration_in_ms=76.508
2019-04-20 02:57:54,446 [salt.state       :1780][INFO    ][14314] Running state [bind9] at time 02:57:54.446595
2019-04-20 02:57:54,449 [salt.state       :1813][INFO    ][14314] Executing state service.running for [bind9]
2019-04-20 02:57:54,449 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14314] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-04-20 02:57:54,468 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14314] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-04-20 02:57:54,490 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14314] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-04-20 02:57:54,509 [salt.state       :300 ][INFO    ][14314] The service bind9 is already running
2019-04-20 02:57:54,509 [salt.state       :1951][INFO    ][14314] Completed state [bind9] at time 02:57:54.509833 duration_in_ms=63.237
2019-04-20 02:57:54,512 [salt.state       :1780][INFO    ][14314] Running state [apache2] at time 02:57:54.511983
2019-04-20 02:57:54,512 [salt.state       :1813][INFO    ][14314] Executing state service.running for [apache2]
2019-04-20 02:57:54,513 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14314] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-04-20 02:57:54,530 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14314] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-04-20 02:57:54,551 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14314] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-04-20 02:57:54,574 [salt.state       :300 ][INFO    ][14314] The service apache2 is already running
2019-04-20 02:57:54,574 [salt.state       :1951][INFO    ][14314] Completed state [apache2] at time 02:57:54.574580 duration_in_ms=62.597
2019-04-20 02:57:54,576 [salt.state       :1780][INFO    ][14314] Running state [maasng.wait_for_http_code] at time 02:57:54.576430
2019-04-20 02:57:54,577 [salt.state       :1813][INFO    ][14314] Executing state module.run for [maasng.wait_for_http_code]
2019-04-20 02:57:54,577 [salt.utils.decorators:613 ][WARNING ][14314] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-20 02:57:54,707 [salt.state       :300 ][INFO    ][14314] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-04-20 02:57:54,708 [salt.state       :1951][INFO    ][14314] Completed state [maasng.wait_for_http_code] at time 02:57:54.708149 duration_in_ms=131.72
2019-04-20 02:57:54,708 [salt.state       :1780][INFO    ][14314] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:57:54.708948
2019-04-20 02:57:54,709 [salt.state       :1813][INFO    ][14314] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-04-20 02:57:54,709 [salt.state       :300 ][INFO    ][14314] /var/lib/maas/.setup_admin exists
2019-04-20 02:57:54,709 [salt.state       :1951][INFO    ][14314] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:57:54.709841 duration_in_ms=0.894
2019-04-20 02:57:54,710 [salt.state       :1780][INFO    ][14314] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:57:54.710508
2019-04-20 02:57:54,710 [salt.state       :1813][INFO    ][14314] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-20 02:57:54,712 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14314] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-20 02:57:56,411 [salt.state       :300 ][INFO    ][14314] {'pid': 14370, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-20 02:57:56,412 [salt.state       :1951][INFO    ][14314] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:57:56.412224 duration_in_ms=1701.714
2019-04-20 02:57:56,419 [salt.state       :1780][INFO    ][14314] Running state [maas_region_boot_source_resources_mirror] at time 02:57:56.419550
2019-04-20 02:57:56,419 [salt.state       :1813][INFO    ][14314] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-04-20 02:57:56,505 [salt.state       :300 ][INFO    ][14314] {'changes': {}}
2019-04-20 02:57:56,505 [salt.state       :1951][INFO    ][14314] Completed state [maas_region_boot_source_resources_mirror] at time 02:57:56.505901 duration_in_ms=86.351
2019-04-20 02:57:56,506 [salt.state       :1780][INFO    ][14314] Running state [maasng.boot_resources_import] at time 02:57:56.506739
2019-04-20 02:57:56,507 [salt.state       :1813][INFO    ][14314] Executing state module.run for [maasng.boot_resources_import]
2019-04-20 02:57:56,507 [salt.utils.decorators:613 ][WARNING ][14314] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-20 02:57:57,949 [salt.loaded.ext.module.maasng:1600][INFO    ][14314] Waiting boot-resources import done
sleep for:5s Left:899.0/900s
2019-04-20 02:58:03,044 [salt.state       :300 ][INFO    ][14314] {'ret': True}
2019-04-20 02:58:03,045 [salt.state       :1951][INFO    ][14314] Completed state [maasng.boot_resources_import] at time 02:58:03.045283 duration_in_ms=6538.543
2019-04-20 02:58:03,046 [salt.state       :1780][INFO    ][14314] Running state [maas_region_boot_sources_selection_xenial] at time 02:58:03.046060
2019-04-20 02:58:03,046 [salt.state       :1813][INFO    ][14314] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-04-20 02:58:03,213 [salt.state       :300 ][INFO    ][14314] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-04-20 02:58:03,213 [salt.state       :1951][INFO    ][14314] Completed state [maas_region_boot_sources_selection_xenial] at time 02:58:03.213913 duration_in_ms=167.852
2019-04-20 02:58:03,215 [salt.state       :1780][INFO    ][14314] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 02:58:03.215507
2019-04-20 02:58:03,215 [salt.state       :1813][INFO    ][14314] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-04-20 02:58:03,216 [salt.utils.decorators:613 ][WARNING ][14314] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-20 02:58:03,216 [salt.loaded.ext.module.maasng:1771][INFO    ][14314] boot-sources sync initiated for ALL Rack's
2019-04-20 02:58:04,143 [salt.state       :300 ][INFO    ][14314] {'ret': True}
2019-04-20 02:58:04,144 [salt.state       :1951][INFO    ][14314] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 02:58:04.143970 duration_in_ms=928.462
2019-04-20 02:58:04,145 [salt.state       :1780][INFO    ][14314] Running state [maas.process_maas_config] at time 02:58:04.145424
2019-04-20 02:58:04,145 [salt.state       :1813][INFO    ][14314] Executing state module.run for [maas.process_maas_config]
2019-04-20 02:58:04,146 [salt.utils.decorators:613 ][WARNING ][14314] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-20 02:58:04,147 [salt.loaded.ext.module.maas:92  ][INFO    ][14314] maasconfig name=enable_http_proxy value=True
2019-04-20 02:58:04,200 [salt.loaded.ext.module.maas:92  ][INFO    ][14314] maasconfig name=upstream_dns value=8.8.8.8
2019-04-20 02:58:04,248 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420025804235418
2019-04-20 02:58:04,250 [salt.loaded.ext.module.maas:92  ][INFO    ][14314] maasconfig name=commissioning_distro_series value=xenial
2019-04-20 02:58:04,270 [salt.minion      :1432][INFO    ][14546] Starting a new job with PID 14546
2019-04-20 02:58:04,297 [salt.minion      :1711][INFO    ][14546] Returning information for job: 20190420025804235418
2019-04-20 02:58:04,327 [salt.loaded.ext.module.maas:92  ][INFO    ][14314] maasconfig name=default_osystem value=ubuntu
2019-04-20 02:58:04,385 [salt.loaded.ext.module.maas:92  ][INFO    ][14314] maasconfig name=active_discovery_interval value=600
2019-04-20 02:58:04,437 [salt.loaded.ext.module.maas:92  ][INFO    ][14314] maasconfig name=dnssec_validation value=no
2019-04-20 02:58:04,487 [salt.loaded.ext.module.maas:92  ][INFO    ][14314] maasconfig name=maas_name value=mas01
2019-04-20 02:58:04,544 [salt.loaded.ext.module.maas:92  ][INFO    ][14314] maasconfig name=network_discovery value=enabled
2019-04-20 02:58:04,652 [salt.loaded.ext.module.maas:92  ][INFO    ][14314] maasconfig name=enable_third_party_drivers value=True
2019-04-20 02:58:04,701 [salt.loaded.ext.module.maas:92  ][INFO    ][14314] maasconfig name=default_storage_layout value=lvm
2019-04-20 02:58:05,603 [salt.loaded.ext.module.maas:92  ][INFO    ][14314] maasconfig name=ntp_external_only value=True
2019-04-20 02:58:05,658 [salt.loaded.ext.module.maas:92  ][INFO    ][14314] maasconfig name=disk_erase_with_secure_erase value=False
2019-04-20 02:58:05,705 [salt.loaded.ext.module.maas:92  ][INFO    ][14314] maasconfig name=default_distro_series value=xenial
2019-04-20 02:58:05,752 [salt.loaded.ext.module.maas:92  ][INFO    ][14314] maasconfig name=default_min_hwe_kernel value=ga-16.04
2019-04-20 02:58:05,880 [salt.state       :300 ][INFO    ][14314] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-04-20 02:58:05,880 [salt.state       :1951][INFO    ][14314] Completed state [maas.process_maas_config] at time 02:58:05.880513 duration_in_ms=1735.088
2019-04-20 02:58:05,881 [salt.state       :1780][INFO    ][14314] Running state [pxe_admin] at time 02:58:05.881350
2019-04-20 02:58:05,881 [salt.state       :1813][INFO    ][14314] Executing state maasng.fabric_present for [pxe_admin]
2019-04-20 02:58:05,937 [salt.loaded.ext.module.maasng:945 ][INFO    ][14314] [{u'name': u'fabric-0', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'name': u'fabric-1', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'name': u'pxe_admin', u'vlans': [{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'x8wpc7', 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'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-04-20 02:58:06,018 [salt.loaded.ext.module.maasng:1008][WARNING ][14314] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-04-20 02:58:06,018 [salt.loaded.ext.module.maasng:1011][WARNING ][14314] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-04-20 02:58:06,079 [salt.state       :300 ][INFO    ][14314] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-04-20 02:58:06,080 [salt.state       :1951][INFO    ][14314] Completed state [pxe_admin] at time 02:58:06.080284 duration_in_ms=198.933
2019-04-20 02:58:06,080 [salt.state       :1780][INFO    ][14314] Running state [vlan 0] at time 02:58:06.080816
2019-04-20 02:58:06,081 [salt.state       :1813][INFO    ][14314] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-04-20 02:58:06,130 [salt.loaded.ext.module.maasng:945 ][INFO    ][14314] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{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'x8wpc7', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-04-20 02:58:06,224 [salt.loaded.ext.module.maasng:945 ][INFO    ][14314] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{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'x8wpc7', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-04-20 02:58:06,448 [salt.loaded.ext.module.maasng:945 ][INFO    ][14314] [{u'name': u'fabric-0', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'name': u'fabric-1', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'name': u'pxe_admin', u'vlans': [{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'x8wpc7', 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'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-04-20 02:58:06,527 [salt.state       :300 ][INFO    ][14314] {'new': 'Vlan untagged was updated'}
2019-04-20 02:58:06,527 [salt.state       :1951][INFO    ][14314] Completed state [vlan 0] at time 02:58:06.527887 duration_in_ms=447.07
2019-04-20 02:58:06,528 [salt.state       :1780][INFO    ][14314] Running state [192.168.11.0/24] at time 02:58:06.528813
2019-04-20 02:58:06,529 [salt.state       :1813][INFO    ][14314] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-04-20 02:58:06,697 [salt.loaded.ext.module.maasng:945 ][INFO    ][14314] [{u'name': u'fabric-0', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'name': u'fabric-1', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'name': u'pxe_admin', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'x8wpc7', 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'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-04-20 02:58:06,698 [salt.loaded.ext.module.maasng:1235][WARNING ][14314] Ignoring parameter vlan:0
2019-04-20 02:58:06,766 [salt.state       :300 ][INFO    ][14314] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-04-20 02:58:06,766 [salt.state       :1951][INFO    ][14314] Completed state [192.168.11.0/24] at time 02:58:06.766288 duration_in_ms=237.474
2019-04-20 02:58:06,767 [salt.state       :1780][INFO    ][14314] Running state [maas_create_iprange_1] at time 02:58:06.767623
2019-04-20 02:58:06,767 [salt.state       :1813][INFO    ][14314] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-04-20 02:58:06,817 [salt.state       :300 ][INFO    ][14314] Iprange maas_create_iprange_1 already exist.
2019-04-20 02:58:06,817 [salt.state       :1951][INFO    ][14314] Completed state [maas_create_iprange_1] at time 02:58:06.817443 duration_in_ms=49.82
2019-04-20 02:58:06,817 [salt.state       :1780][INFO    ][14314] Running state [vlan 0] at time 02:58:06.817693
2019-04-20 02:58:06,817 [salt.state       :1813][INFO    ][14314] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-04-20 02:58:06,859 [salt.loaded.ext.module.maasng:945 ][INFO    ][14314] [{u'name': u'fabric-0', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'name': u'fabric-1', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'name': u'pxe_admin', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'x8wpc7', 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'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-04-20 02:58:06,935 [salt.loaded.ext.module.maasng:945 ][INFO    ][14314] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'x8wpc7', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-04-20 02:58:07,134 [salt.loaded.ext.module.maasng:945 ][INFO    ][14314] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'x8wpc7', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-04-20 02:58:07,212 [salt.state       :300 ][INFO    ][14314] {'new': 'Vlan untagged was updated'}
2019-04-20 02:58:07,212 [salt.state       :1951][INFO    ][14314] Completed state [vlan 0] at time 02:58:07.212231 duration_in_ms=394.538
2019-04-20 02:58:07,212 [salt.state       :1780][INFO    ][14314] Running state [opnfv] at time 02:58:07.212801
2019-04-20 02:58:07,213 [salt.state       :1813][INFO    ][14314] Executing state maasng.sshkey_present for [opnfv]
2019-04-20 02:58:07,273 [salt.loaded.ext.module.maasng:1903][INFO    ][14314] [{u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b', u'keysource': u''}]
2019-04-20 02:58:07,273 [salt.state       :300 ][INFO    ][14314] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-04-20 02:58:07,273 [salt.state       :1951][INFO    ][14314] Completed state [opnfv] at time 02:58:07.273660 duration_in_ms=60.859
2019-04-20 02:58:07,280 [salt.minion      :1711][INFO    ][14314] Returning information for job: 20190420025749177805
2019-04-20 02:58:07,890 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command state.apply with jid 20190420025807881756
2019-04-20 02:58:07,903 [salt.minion      :1432][INFO    ][14626] Starting a new job with PID 14626
2019-04-20 02:58:16,194 [salt.state       :915 ][INFO    ][14626] Loading fresh modules for state activity
2019-04-20 02:58:16,284 [salt.state       :1780][INFO    ][14626] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:58:16.284367
2019-04-20 02:58:16,284 [salt.state       :1813][INFO    ][14626] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-20 02:58:16,286 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14626] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-20 02:58:17,985 [salt.state       :300 ][INFO    ][14626] {'pid': 14666, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-20 02:58:17,986 [salt.state       :1951][INFO    ][14626] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:58:17.986111 duration_in_ms=1701.745
2019-04-20 02:58:17,987 [salt.state       :1780][INFO    ][14626] Running state [maas.process_machines] at time 02:58:17.987904
2019-04-20 02:58:17,988 [salt.state       :1813][INFO    ][14626] Executing state module.run for [maas.process_machines]
2019-04-20 02:58:17,988 [salt.utils.decorators:613 ][WARNING ][14626] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-20 02:58:18,504 [salt.loaded.ext.module.maas:412 ][WARNING ][14626] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-20 02:58:18,505 [salt.loaded.ext.module.maas:92  ][INFO    ][14626] machine hostname=cmp002 power_type=ipmi mac_addresses=['9c:b6:54:8a:10:18'] power_parameters_power_address=172.16.1.20 power_parameters_power_pass=Winter2017 system_id=f4wh7c architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-20 02:58:19,596 [salt.loaded.ext.module.maas:412 ][WARNING ][14626] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-20 02:58:19,597 [salt.loaded.ext.module.maas:92  ][INFO    ][14626] machine hostname=cmp001 power_type=ipmi mac_addresses=['9c:b6:54:8a:95:a0'] power_parameters_power_address=172.16.1.19 power_parameters_power_pass=Winter2017 system_id=4bsbyd architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-20 02:58:20,694 [salt.loaded.ext.module.maas:412 ][WARNING ][14626] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-20 02:58:20,696 [salt.loaded.ext.module.maas:92  ][INFO    ][14626] machine hostname=kvm01 power_type=ipmi mac_addresses=['14:58:d0:54:e7:88'] power_parameters_power_address=172.16.1.16 power_parameters_power_pass=Winter2017 system_id=dycasb architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-20 02:58:21,802 [salt.loaded.ext.module.maas:412 ][WARNING ][14626] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-20 02:58:21,804 [salt.loaded.ext.module.maas:92  ][INFO    ][14626] machine hostname=kvm03 power_type=ipmi mac_addresses=['14:58:d0:54:7a:28'] power_parameters_power_address=172.16.1.18 power_parameters_power_pass=Winter2017 system_id=c3kg84 architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-20 02:58:22,896 [salt.loaded.ext.module.maas:412 ][WARNING ][14626] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-20 02:58:22,896 [salt.loaded.ext.module.maas:92  ][INFO    ][14626] machine hostname=kvm02 power_type=ipmi mac_addresses=['14:58:d0:54:6a:60'] power_parameters_power_address=172.16.1.17 power_parameters_power_pass=Winter2017 system_id=mbsame architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-20 02:58:22,955 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420025822947765
2019-04-20 02:58:22,974 [salt.minion      :1432][INFO    ][14840] Starting a new job with PID 14840
2019-04-20 02:58:22,996 [salt.minion      :1711][INFO    ][14840] Returning information for job: 20190420025822947765
2019-04-20 02:58:24,058 [salt.state       :300 ][INFO    ][14626] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-04-20 02:58:24,059 [salt.state       :1951][INFO    ][14626] Completed state [maas.process_machines] at time 02:58:24.059593 duration_in_ms=6071.689
2019-04-20 02:58:24,062 [salt.minion      :1711][INFO    ][14626] Returning information for job: 20190420025807881756
2019-04-20 02:58:57,030 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command state.apply with jid 20190420025857016836
2019-04-20 02:58:57,048 [salt.minion      :1432][INFO    ][14953] Starting a new job with PID 14953
2019-04-20 02:59:05,111 [salt.state       :915 ][INFO    ][14953] Loading fresh modules for state activity
2019-04-20 02:59:05,225 [salt.state       :1780][INFO    ][14953] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:59:05.225774
2019-04-20 02:59:05,226 [salt.state       :1813][INFO    ][14953] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-20 02:59:05,228 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14953] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-20 02:59:06,930 [salt.state       :300 ][INFO    ][14953] {'pid': 14961, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-20 02:59:06,931 [salt.state       :1951][INFO    ][14953] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:59:06.931228 duration_in_ms=1705.453
2019-04-20 02:59:06,934 [salt.state       :1780][INFO    ][14953] Running state [maas.wait_for_machine_status] at time 02:59:06.934129
2019-04-20 02:59:06,934 [salt.state       :1813][INFO    ][14953] Executing state module.run for [maas.wait_for_machine_status]
2019-04-20 02:59:06,936 [salt.utils.decorators:613 ][WARNING ][14953] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-20 02:59:09,600 [salt.state       :300 ][INFO    ][14953] {'ret': True}
2019-04-20 02:59:09,600 [salt.state       :1951][INFO    ][14953] Completed state [maas.wait_for_machine_status] at time 02:59:09.600630 duration_in_ms=2666.5
2019-04-20 02:59:09,606 [salt.minion      :1711][INFO    ][14953] Returning information for job: 20190420025857016836
2019-04-20 02:59:10,137 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command state.apply with jid 20190420025910124744
2019-04-20 02:59:10,153 [salt.minion      :1432][INFO    ][14975] Starting a new job with PID 14975
2019-04-20 02:59:11,287 [salt.state       :915 ][INFO    ][14975] Loading fresh modules for state activity
2019-04-20 02:59:11,432 [salt.state       :1780][INFO    ][14975] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:59:11.432403
2019-04-20 02:59:11,432 [salt.state       :1813][INFO    ][14975] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-20 02:59:11,434 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14975] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-20 02:59:13,111 [salt.state       :300 ][INFO    ][14975] {'pid': 14982, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-20 02:59:13,112 [salt.state       :1951][INFO    ][14975] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:59:13.111922 duration_in_ms=1679.519
2019-04-20 02:59:13,113 [salt.state       :1780][INFO    ][14975] Running state [maas_machines_storage_cmp002_lvm] at time 02:59:13.113829
2019-04-20 02:59:13,114 [salt.state       :1813][INFO    ][14975] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-04-20 02:59:13,655 [salt.state       :300 ][INFO    ][14975] Machine cmp002 is not in Ready state.
2019-04-20 02:59:13,656 [salt.state       :1951][INFO    ][14975] Completed state [maas_machines_storage_cmp002_lvm] at time 02:59:13.656244 duration_in_ms=542.415
2019-04-20 02:59:13,657 [salt.state       :1780][INFO    ][14975] Running state [maas_machines_storage_cmp001_lvm] at time 02:59:13.656937
2019-04-20 02:59:13,657 [salt.state       :1813][INFO    ][14975] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-04-20 02:59:14,174 [salt.state       :300 ][INFO    ][14975] Machine cmp001 is not in Ready state.
2019-04-20 02:59:14,174 [salt.state       :1951][INFO    ][14975] Completed state [maas_machines_storage_cmp001_lvm] at time 02:59:14.174512 duration_in_ms=517.575
2019-04-20 02:59:14,178 [salt.minion      :1711][INFO    ][14975] Returning information for job: 20190420025910124744
2019-04-20 02:59:14,719 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command state.apply with jid 20190420025914705059
2019-04-20 02:59:14,735 [salt.minion      :1432][INFO    ][14992] Starting a new job with PID 14992
2019-04-20 02:59:15,875 [salt.state       :915 ][INFO    ][14992] Loading fresh modules for state activity
2019-04-20 02:59:15,964 [salt.state       :1780][INFO    ][14992] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:59:15.964875
2019-04-20 02:59:15,965 [salt.state       :1813][INFO    ][14992] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-20 02:59:15,967 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14992] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-20 02:59:17,661 [salt.state       :300 ][INFO    ][14992] {'pid': 15012, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-20 02:59:17,661 [salt.state       :1951][INFO    ][14992] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:59:17.661850 duration_in_ms=1696.975
2019-04-20 02:59:17,663 [salt.state       :1780][INFO    ][14992] Running state [maas.deploy_machines] at time 02:59:17.663876
2019-04-20 02:59:17,664 [salt.state       :1813][INFO    ][14992] Executing state module.run for [maas.deploy_machines]
2019-04-20 02:59:17,664 [salt.utils.decorators:613 ][WARNING ][14992] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-20 02:59:18,210 [salt.loaded.ext.module.maas:684 ][INFO    ][14992] deploymachines hwe_kernel=ga-16.04 system_id=mbsame distro_series=xenial
2019-04-20 02:59:20,496 [salt.state       :300 ][INFO    ][14992] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-04-20 02:59:20,496 [salt.state       :1951][INFO    ][14992] Completed state [maas.deploy_machines] at time 02:59:20.496475 duration_in_ms=2832.597
2019-04-20 02:59:20,503 [salt.minion      :1711][INFO    ][14992] Returning information for job: 20190420025914705059
2019-04-20 02:59:21,041 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command state.apply with jid 20190420025921030181
2019-04-20 02:59:21,060 [salt.minion      :1432][INFO    ][15072] Starting a new job with PID 15072
2019-04-20 02:59:29,281 [salt.state       :915 ][INFO    ][15072] Loading fresh modules for state activity
2019-04-20 02:59:29,368 [salt.state       :1780][INFO    ][15072] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:59:29.368421
2019-04-20 02:59:29,368 [salt.state       :1813][INFO    ][15072] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-20 02:59:29,370 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15072] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-20 02:59:31,122 [salt.state       :300 ][INFO    ][15072] {'pid': 15132, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-20 02:59:31,123 [salt.state       :1951][INFO    ][15072] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:59:31.122931 duration_in_ms=1754.511
2019-04-20 02:59:31,124 [salt.state       :1780][INFO    ][15072] Running state [maas.wait_for_machine_status] at time 02:59:31.124316
2019-04-20 02:59:31,124 [salt.state       :1813][INFO    ][15072] Executing state module.run for [maas.wait_for_machine_status]
2019-04-20 02:59:31,125 [salt.utils.decorators:613 ][WARNING ][15072] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-20 02:59:33,657 [salt.loaded.ext.module.maas:1023][INFO    ][15072] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.47951698s left)
2019-04-20 02:59:36,126 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420025936116835
2019-04-20 02:59:36,140 [salt.minion      :1432][INFO    ][15156] Starting a new job with PID 15156
2019-04-20 02:59:36,164 [salt.minion      :1711][INFO    ][15156] Returning information for job: 20190420025936116835
2019-04-20 03:00:06,320 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420030006269540
2019-04-20 03:00:06,336 [salt.minion      :1432][INFO    ][15178] Starting a new job with PID 15178
2019-04-20 03:00:06,359 [salt.minion      :1711][INFO    ][15178] Returning information for job: 20190420030006269540
2019-04-20 03:00:06,496 [salt.loaded.ext.module.maas:1023][INFO    ][15072] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.64085793s left)
2019-04-20 03:00:36,409 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420030036393307
2019-04-20 03:00:36,427 [salt.minion      :1432][INFO    ][15254] Starting a new job with PID 15254
2019-04-20 03:00:36,451 [salt.minion      :1711][INFO    ][15254] Returning information for job: 20190420030036393307
2019-04-20 03:00:39,122 [salt.loaded.ext.module.maas:1023][INFO    ][15072] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2182.01508188s left)
2019-04-20 03:01:06,453 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420030106443213
2019-04-20 03:01:06,476 [salt.minion      :1432][INFO    ][15275] Starting a new job with PID 15275
2019-04-20 03:01:06,499 [salt.minion      :1711][INFO    ][15275] Returning information for job: 20190420030106443213
2019-04-20 03:01:11,893 [salt.loaded.ext.module.maas:1023][INFO    ][15072] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2149.24438286s left)
2019-04-20 03:01:36,521 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420030136511887
2019-04-20 03:01:36,546 [salt.minion      :1432][INFO    ][15375] Starting a new job with PID 15375
2019-04-20 03:01:36,569 [salt.minion      :1711][INFO    ][15375] Returning information for job: 20190420030136511887
2019-04-20 03:01:44,621 [salt.loaded.ext.module.maas:1023][INFO    ][15072] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2116.5162499s left)
2019-04-20 03:02:06,578 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420030206564170
2019-04-20 03:02:06,599 [salt.minion      :1432][INFO    ][15402] Starting a new job with PID 15402
2019-04-20 03:02:06,626 [salt.minion      :1711][INFO    ][15402] Returning information for job: 20190420030206564170
2019-04-20 03:02:17,380 [salt.loaded.ext.module.maas:1023][INFO    ][15072] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2083.75688291s left)
2019-04-20 03:02:36,656 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420030236640483
2019-04-20 03:02:36,679 [salt.minion      :1432][INFO    ][15484] Starting a new job with PID 15484
2019-04-20 03:02:36,704 [salt.minion      :1711][INFO    ][15484] Returning information for job: 20190420030236640483
2019-04-20 03:02:49,960 [salt.loaded.ext.module.maas:1023][INFO    ][15072] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2051.17669988s left)
2019-04-20 03:03:06,715 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420030306703830
2019-04-20 03:03:06,738 [salt.minion      :1432][INFO    ][15505] Starting a new job with PID 15505
2019-04-20 03:03:06,761 [salt.minion      :1711][INFO    ][15505] Returning information for job: 20190420030306703830
2019-04-20 03:03:22,582 [salt.loaded.ext.module.maas:1023][INFO    ][15072] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2018.55520487s left)
2019-04-20 03:03:36,785 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420030336777259
2019-04-20 03:03:36,800 [salt.minion      :1432][INFO    ][15610] Starting a new job with PID 15610
2019-04-20 03:03:36,824 [salt.minion      :1711][INFO    ][15610] Returning information for job: 20190420030336777259
2019-04-20 03:03:55,305 [salt.loaded.ext.module.maas:1023][INFO    ][15072] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1985.83192086s left)
2019-04-20 03:04:06,836 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420030406821911
2019-04-20 03:04:06,851 [salt.minion      :1432][INFO    ][15660] Starting a new job with PID 15660
2019-04-20 03:04:06,877 [salt.minion      :1711][INFO    ][15660] Returning information for job: 20190420030406821911
2019-04-20 03:04:28,446 [salt.loaded.ext.module.maas:1023][INFO    ][15072] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1952.69131804s left)
2019-04-20 03:04:36,921 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420030436907860
2019-04-20 03:04:36,942 [salt.minion      :1432][INFO    ][15815] Starting a new job with PID 15815
2019-04-20 03:04:36,967 [salt.minion      :1711][INFO    ][15815] Returning information for job: 20190420030436907860
2019-04-20 03:05:01,252 [salt.loaded.ext.module.maas:1023][INFO    ][15072] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1919.88503599s left)
2019-04-20 03:05:07,001 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420030506985975
2019-04-20 03:05:07,025 [salt.minion      :1432][INFO    ][15842] Starting a new job with PID 15842
2019-04-20 03:05:07,051 [salt.minion      :1711][INFO    ][15842] Returning information for job: 20190420030506985975
2019-04-20 03:05:34,025 [salt.loaded.ext.module.maas:1023][INFO    ][15072] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1887.11191106s left)
2019-04-20 03:05:37,124 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420030537109713
2019-04-20 03:05:37,141 [salt.minion      :1432][INFO    ][16011] Starting a new job with PID 16011
2019-04-20 03:05:37,166 [salt.minion      :1711][INFO    ][16011] Returning information for job: 20190420030537109713
2019-04-20 03:06:06,630 [salt.loaded.ext.module.maas:1023][INFO    ][15072] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1854.50688696s left)
2019-04-20 03:06:07,207 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420030607197039
2019-04-20 03:06:07,224 [salt.minion      :1432][INFO    ][16034] Starting a new job with PID 16034
2019-04-20 03:06:07,247 [salt.minion      :1711][INFO    ][16034] Returning information for job: 20190420030607197039
2019-04-20 03:06:37,284 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420030637277206
2019-04-20 03:06:37,303 [salt.minion      :1432][INFO    ][16123] Starting a new job with PID 16123
2019-04-20 03:06:37,329 [salt.minion      :1711][INFO    ][16123] Returning information for job: 20190420030637277206
2019-04-20 03:06:39,324 [salt.loaded.ext.module.maas:1023][INFO    ][15072] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1821.81318903s left)
2019-04-20 03:07:07,388 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420030707375547
2019-04-20 03:07:07,412 [salt.minion      :1432][INFO    ][16144] Starting a new job with PID 16144
2019-04-20 03:07:07,438 [salt.minion      :1711][INFO    ][16144] Returning information for job: 20190420030707375547
2019-04-20 03:07:11,985 [salt.loaded.ext.module.maas:1023][INFO    ][15072] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1789.15218186s left)
2019-04-20 03:07:37,512 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420030737504623
2019-04-20 03:07:37,525 [salt.minion      :1432][INFO    ][16272] Starting a new job with PID 16272
2019-04-20 03:07:37,548 [salt.minion      :1711][INFO    ][16272] Returning information for job: 20190420030737504623
2019-04-20 03:07:44,976 [salt.loaded.ext.module.maas:1023][INFO    ][15072] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1756.16113687s left)
2019-04-20 03:08:07,639 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420030807626161
2019-04-20 03:08:07,662 [salt.minion      :1432][INFO    ][16293] Starting a new job with PID 16293
2019-04-20 03:08:07,687 [salt.minion      :1711][INFO    ][16293] Returning information for job: 20190420030807626161
2019-04-20 03:08:17,794 [salt.loaded.ext.module.maas:1023][INFO    ][15072] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1723.34281683s left)
2019-04-20 03:08:37,733 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420030837718264
2019-04-20 03:08:37,752 [salt.minion      :1432][INFO    ][16456] Starting a new job with PID 16456
2019-04-20 03:08:37,774 [salt.minion      :1711][INFO    ][16456] Returning information for job: 20190420030837718264
2019-04-20 03:08:50,554 [salt.loaded.ext.module.maas:1023][INFO    ][15072] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1690.58327699s left)
2019-04-20 03:09:07,752 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420030907738836
2019-04-20 03:09:07,772 [salt.minion      :1432][INFO    ][16483] Starting a new job with PID 16483
2019-04-20 03:09:07,801 [salt.minion      :1711][INFO    ][16483] Returning information for job: 20190420030907738836
2019-04-20 03:09:23,288 [salt.loaded.ext.module.maas:1023][INFO    ][15072] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1657.84937596s left)
2019-04-20 03:09:37,865 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420030937851208
2019-04-20 03:09:37,881 [salt.minion      :1432][INFO    ][16629] Starting a new job with PID 16629
2019-04-20 03:09:37,908 [salt.minion      :1711][INFO    ][16629] Returning information for job: 20190420030937851208
2019-04-20 03:09:56,051 [salt.loaded.ext.module.maas:1023][INFO    ][15072] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1625.08560395s left)
2019-04-20 03:10:08,028 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420031008014083
2019-04-20 03:10:08,049 [salt.minion      :1432][INFO    ][16652] Starting a new job with PID 16652
2019-04-20 03:10:08,076 [salt.minion      :1711][INFO    ][16652] Returning information for job: 20190420031008014083
2019-04-20 03:10:28,888 [salt.loaded.ext.module.maas:1023][INFO    ][15072] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1592.24916792s left)
2019-04-20 03:10:38,188 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420031038171674
2019-04-20 03:10:38,212 [salt.minion      :1432][INFO    ][16732] Starting a new job with PID 16732
2019-04-20 03:10:38,236 [salt.minion      :1711][INFO    ][16732] Returning information for job: 20190420031038171674
2019-04-20 03:11:01,668 [salt.loaded.ext.module.maas:1023][INFO    ][15072] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1559.46893501s left)
2019-04-20 03:11:08,369 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420031108352674
2019-04-20 03:11:08,388 [salt.minion      :1432][INFO    ][16755] Starting a new job with PID 16755
2019-04-20 03:11:08,413 [salt.minion      :1711][INFO    ][16755] Returning information for job: 20190420031108352674
2019-04-20 03:11:23,605 [salt.utils.schedule:1377][INFO    ][3463] Running scheduled job: __mine_interval
2019-04-20 03:11:34,401 [salt.loaded.ext.module.maas:1023][INFO    ][15072] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1526.73577285s left)
2019-04-20 03:11:38,516 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420031138508269
2019-04-20 03:11:38,529 [salt.minion      :1432][INFO    ][17009] Starting a new job with PID 17009
2019-04-20 03:11:38,550 [salt.minion      :1711][INFO    ][17009] Returning information for job: 20190420031138508269
2019-04-20 03:12:07,121 [salt.loaded.ext.module.maas:1023][INFO    ][15072] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1494.016047s left)
2019-04-20 03:12:08,687 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420031208678423
2019-04-20 03:12:08,702 [salt.minion      :1432][INFO    ][17035] Starting a new job with PID 17035
2019-04-20 03:12:08,728 [salt.minion      :1711][INFO    ][17035] Returning information for job: 20190420031208678423
2019-04-20 03:12:38,735 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420031238728150
2019-04-20 03:12:38,750 [salt.minion      :1432][INFO    ][17113] Starting a new job with PID 17113
2019-04-20 03:12:38,774 [salt.minion      :1711][INFO    ][17113] Returning information for job: 20190420031238728150
2019-04-20 03:12:39,918 [salt.loaded.ext.module.maas:1023][INFO    ][15072] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1461.21887803s left)
2019-04-20 03:13:08,925 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420031308916764
2019-04-20 03:13:08,946 [salt.minion      :1432][INFO    ][17154] Starting a new job with PID 17154
2019-04-20 03:13:08,968 [salt.minion      :1711][INFO    ][17154] Returning information for job: 20190420031308916764
2019-04-20 03:13:12,761 [salt.loaded.ext.module.maas:1023][INFO    ][15072] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1428.37627101s left)
2019-04-20 03:13:39,057 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command saltutil.find_job with jid 20190420031339043649
2019-04-20 03:13:39,073 [salt.minion      :1432][INFO    ][17306] Starting a new job with PID 17306
2019-04-20 03:13:39,095 [salt.minion      :1711][INFO    ][17306] Returning information for job: 20190420031339043649
2019-04-20 03:13:45,418 [salt.state       :300 ][INFO    ][15072] {'ret': True}
2019-04-20 03:13:45,419 [salt.state       :1951][INFO    ][15072] Completed state [maas.wait_for_machine_status] at time 03:13:45.419262 duration_in_ms=854294.942
2019-04-20 03:13:45,425 [salt.minion      :1711][INFO    ][15072] Returning information for job: 20190420025921030181
2019-04-20 04:11:23,605 [salt.utils.schedule:1377][INFO    ][3463] Running scheduled job: __mine_interval
2019-04-20 05:06:03,720 [salt.minion      :1308][INFO    ][3463] User sudo_ubuntu Executing command cp.push_dir with jid 20190420050603713214
2019-04-20 05:06:03,734 [salt.minion      :1432][INFO    ][28620] Starting a new job with PID 28620
