2019-06-12 11:49:44,118 [salt.utils.decorators:613 ][WARNING ][2062] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-12 11:49:45,021 [salt.utils.decorators:613 ][WARNING ][2062] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-12 11:49:48,779 [salt.loaded.int.states.file:2298][WARNING ][2384] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-06-12 11:50:06,912 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2763] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-06-12 11:50:06,943 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2763] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-06-12 11:50:07,004 [salt.utils.parsers:1051][WARNING ][382] Minion received a SIGTERM. Exiting.
2019-06-12 11:50:08,113 [salt.cli.daemons :293 ][INFO    ][2873] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-06-12 11:50:08,291 [salt.cli.daemons :82  ][INFO    ][2873] Starting up the Salt Minion
2019-06-12 11:50:08,291 [salt.utils.event :1017][INFO    ][2873] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-06-12 11:50:09,514 [salt.minion      :976 ][INFO    ][2873] Creating minion process manager
2019-06-12 11:50:11,591 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][2873] Executing command ['date', '+%z'] in directory '/root'
2019-06-12 11:50:11,614 [salt.utils.schedule:568 ][INFO    ][2873] Updating job settings for scheduled job: __mine_interval
2019-06-12 11:50:11,616 [salt.minion      :1108][INFO    ][2873] Added mine.update to scheduler
2019-06-12 11:50:11,622 [salt.minion      :1975][INFO    ][2873] Minion is starting as user 'root'
2019-06-12 11:50:11,647 [salt.minion      :2336][INFO    ][2873] Minion is ready to receive requests!
2019-06-12 11:50:18,438 [salt.state       :2022][WARNING ][2769] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-06-12 11:50:19,626 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612115019614547
2019-06-12 11:50:19,641 [salt.minion      :1432][INFO    ][3114] Starting a new job with PID 3114
2019-06-12 11:50:19,673 [salt.minion      :1711][INFO    ][3114] Returning information for job: 20190612115019614547
2019-06-12 11:50:21,552 [salt.utils.decorators:613 ][WARNING ][2769] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-12 11:50:35,352 [salt.utils.decorators:613 ][WARNING ][2769] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-12 11:50:49,779 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612115049758694
2019-06-12 11:50:49,807 [salt.minion      :1432][INFO    ][3915] Starting a new job with PID 3915
2019-06-12 11:50:49,833 [salt.minion      :1711][INFO    ][3915] Returning information for job: 20190612115049758694
2019-06-12 11:51:01,205 [salt.utils.decorators:613 ][WARNING ][2769] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-12 11:51:02,249 [salt.utils.decorators:613 ][WARNING ][2769] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-12 11:51:04,547 [salt.loaded.ext.module.maasng:1008][WARNING ][2769] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-06-12 11:51:04,547 [salt.loaded.ext.module.maasng:1011][WARNING ][2769] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-06-12 11:51:05,348 [salt.loaded.ext.module.maasng:1235][WARNING ][2769] Ignoring parameter vlan:0
2019-06-12 11:51:06,349 [salt.utils.decorators:613 ][WARNING ][2769] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-12 11:51:07,369 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command state.apply with jid 20190612115107354816
2019-06-12 11:51:07,397 [salt.minion      :1432][INFO    ][4320] Starting a new job with PID 4320
2019-06-12 11:51:15,574 [salt.state       :915 ][INFO    ][4320] Loading fresh modules for state activity
2019-06-12 11:51:15,640 [salt.fileclient  :1219][INFO    ][4320] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-06-12 11:51:15,680 [salt.state       :1780][INFO    ][4320] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:51:15.680801
2019-06-12 11:51:15,681 [salt.state       :1813][INFO    ][4320] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-12 11:51:15,683 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4320] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-12 11:51:17,556 [salt.state       :300 ][INFO    ][4320] {'pid': 4344, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-12 11:51:17,557 [salt.state       :1951][INFO    ][4320] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:51:17.557191 duration_in_ms=1876.39
2019-06-12 11:51:17,559 [salt.state       :1780][INFO    ][4320] Running state [maas.process_machines] at time 11:51:17.559674
2019-06-12 11:51:17,560 [salt.state       :1813][INFO    ][4320] Executing state module.run for [maas.process_machines]
2019-06-12 11:51:17,560 [salt.utils.decorators:613 ][WARNING ][4320] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-12 11:51:17,616 [salt.loaded.ext.module.maas:412 ][WARNING ][4320] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-12 11:51:17,617 [salt.loaded.ext.module.maas:92  ][INFO    ][4320] machine hostname=cmp002 power_type=ipmi mac_addresses=9c:b6:54:8a:10:18 power_parameters_power_address=172.16.1.20 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-12 11:51:19,184 [salt.loaded.ext.module.maas:412 ][WARNING ][4320] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-12 11:51:19,185 [salt.loaded.ext.module.maas:92  ][INFO    ][4320] machine hostname=cmp001 power_type=ipmi mac_addresses=9c:b6:54:8a:95:a0 power_parameters_power_address=172.16.1.19 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-12 11:51:20,683 [salt.loaded.ext.module.maas:412 ][WARNING ][4320] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-12 11:51:20,683 [salt.loaded.ext.module.maas:92  ][INFO    ][4320] machine hostname=kvm01 power_type=ipmi mac_addresses=14:58:d0:54:e7:88 power_parameters_power_address=172.16.1.16 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-12 11:51:22,228 [salt.loaded.ext.module.maas:412 ][WARNING ][4320] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-12 11:51:22,229 [salt.loaded.ext.module.maas:92  ][INFO    ][4320] machine hostname=kvm03 power_type=ipmi mac_addresses=14:58:d0:54:7a:28 power_parameters_power_address=172.16.1.18 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-12 11:51:22,446 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612115122435468
2019-06-12 11:51:22,472 [salt.minion      :1432][INFO    ][4540] Starting a new job with PID 4540
2019-06-12 11:51:22,501 [salt.minion      :1711][INFO    ][4540] Returning information for job: 20190612115122435468
2019-06-12 11:51:23,664 [salt.loaded.ext.module.maas:412 ][WARNING ][4320] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-12 11:51:23,664 [salt.loaded.ext.module.maas:92  ][INFO    ][4320] machine hostname=kvm02 power_type=ipmi mac_addresses=14:58:d0:54:6a:60 power_parameters_power_address=172.16.1.17 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-12 11:51:25,084 [salt.state       :300 ][INFO    ][4320] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-06-12 11:51:25,084 [salt.state       :1951][INFO    ][4320] Completed state [maas.process_machines] at time 11:51:25.084645 duration_in_ms=7524.97
2019-06-12 11:51:25,088 [salt.minion      :1711][INFO    ][4320] Returning information for job: 20190612115107354816
2019-06-12 11:51:56,464 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command state.apply with jid 20190612115156454736
2019-06-12 11:51:56,488 [salt.minion      :1432][INFO    ][4662] Starting a new job with PID 4662
2019-06-12 11:52:04,255 [salt.state       :915 ][INFO    ][4662] Loading fresh modules for state activity
2019-06-12 11:52:04,315 [salt.fileclient  :1219][INFO    ][4662] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-06-12 11:52:04,376 [salt.state       :1780][INFO    ][4662] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:52:04.376888
2019-06-12 11:52:04,377 [salt.state       :1813][INFO    ][4662] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-12 11:52:04,379 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4662] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-12 11:52:06,364 [salt.state       :300 ][INFO    ][4662] {'pid': 4669, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-12 11:52:06,365 [salt.state       :1951][INFO    ][4662] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:52:06.365508 duration_in_ms=1988.619
2019-06-12 11:52:06,370 [salt.state       :1780][INFO    ][4662] Running state [maas.wait_for_machine_status] at time 11:52:06.370209
2019-06-12 11:52:06,370 [salt.state       :1813][INFO    ][4662] Executing state module.run for [maas.wait_for_machine_status]
2019-06-12 11:52:06,371 [salt.utils.decorators:613 ][WARNING ][4662] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-12 11:52:07,244 [salt.loaded.ext.module.maas:1023][INFO    ][4662] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.14017987s left)
2019-06-12 11:52:11,552 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612115211541502
2019-06-12 11:52:11,578 [salt.minion      :1432][INFO    ][4694] Starting a new job with PID 4694
2019-06-12 11:52:11,604 [salt.minion      :1711][INFO    ][4694] Returning information for job: 20190612115211541502
2019-06-12 11:52:38,224 [salt.loaded.ext.module.maas:1023][INFO    ][4662] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.15984702s left)
2019-06-12 11:52:41,633 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612115241620168
2019-06-12 11:52:41,663 [salt.minion      :1432][INFO    ][4739] Starting a new job with PID 4739
2019-06-12 11:52:41,692 [salt.minion      :1711][INFO    ][4739] Returning information for job: 20190612115241620168
2019-06-12 11:53:09,100 [salt.loaded.ext.module.maas:1023][INFO    ][4662] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.2846601s left)
2019-06-12 11:53:11,756 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612115311742243
2019-06-12 11:53:11,783 [salt.minion      :1432][INFO    ][4769] Starting a new job with PID 4769
2019-06-12 11:53:11,815 [salt.minion      :1711][INFO    ][4769] Returning information for job: 20190612115311742243
2019-06-12 11:53:39,928 [salt.loaded.ext.module.maas:1023][INFO    ][4662] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.45672989s left)
2019-06-12 11:53:41,839 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612115341824129
2019-06-12 11:53:41,867 [salt.minion      :1432][INFO    ][4820] Starting a new job with PID 4820
2019-06-12 11:53:41,896 [salt.minion      :1711][INFO    ][4820] Returning information for job: 20190612115341824129
2019-06-12 11:54:11,073 [salt.loaded.ext.module.maas:1023][INFO    ][4662] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.31160998s left)
2019-06-12 11:54:11,937 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612115411925144
2019-06-12 11:54:11,964 [salt.minion      :1432][INFO    ][4883] Starting a new job with PID 4883
2019-06-12 11:54:11,991 [salt.minion      :1711][INFO    ][4883] Returning information for job: 20190612115411925144
2019-06-12 11:54:42,040 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612115442023711
2019-06-12 11:54:42,060 [salt.minion      :1432][INFO    ][5038] Starting a new job with PID 5038
2019-06-12 11:54:42,090 [salt.minion      :1711][INFO    ][5038] Returning information for job: 20190612115442023711
2019-06-12 11:54:42,249 [salt.loaded.ext.module.maas:1023][INFO    ][4662] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.1351819s left)
2019-06-12 11:55:12,145 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612115512126451
2019-06-12 11:55:12,175 [salt.minion      :1432][INFO    ][5110] Starting a new job with PID 5110
2019-06-12 11:55:12,204 [salt.minion      :1711][INFO    ][5110] Returning information for job: 20190612115512126451
2019-06-12 11:55:13,540 [salt.loaded.ext.module.maas:1023][INFO    ][4662] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1312.84404898s left)
2019-06-12 11:55:42,280 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612115542264486
2019-06-12 11:55:42,295 [salt.minion      :1432][INFO    ][5454] Starting a new job with PID 5454
2019-06-12 11:55:42,320 [salt.minion      :1711][INFO    ][5454] Returning information for job: 20190612115542264486
2019-06-12 11:55:44,781 [salt.loaded.ext.module.maas:1023][INFO    ][4662] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1281.60318589s left)
2019-06-12 11:56:12,388 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612115612374200
2019-06-12 11:56:12,405 [salt.minion      :1432][INFO    ][5557] Starting a new job with PID 5557
2019-06-12 11:56:12,433 [salt.minion      :1711][INFO    ][5557] Returning information for job: 20190612115612374200
2019-06-12 11:56:16,624 [salt.loaded.ext.module.maas:1023][INFO    ][4662] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1249.75995493s left)
2019-06-12 11:56:42,561 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612115642548399
2019-06-12 11:56:42,584 [salt.minion      :1432][INFO    ][5902] Starting a new job with PID 5902
2019-06-12 11:56:42,615 [salt.minion      :1711][INFO    ][5902] Returning information for job: 20190612115642548399
2019-06-12 11:56:48,640 [salt.loaded.ext.module.maas:1023][INFO    ][4662] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1217.74426389s left)
2019-06-12 11:57:12,688 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612115712671978
2019-06-12 11:57:12,716 [salt.minion      :1432][INFO    ][6040] Starting a new job with PID 6040
2019-06-12 11:57:12,743 [salt.minion      :1711][INFO    ][6040] Returning information for job: 20190612115712671978
2019-06-12 11:57:20,828 [salt.loaded.ext.module.maas:1023][INFO    ][4662] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1185.55616403s left)
2019-06-12 11:57:42,825 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612115742809826
2019-06-12 11:57:42,852 [salt.minion      :1432][INFO    ][6267] Starting a new job with PID 6267
2019-06-12 11:57:42,882 [salt.minion      :1711][INFO    ][6267] Returning information for job: 20190612115742809826
2019-06-12 11:57:53,311 [salt.loaded.ext.module.maas:1023][INFO    ][4662] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1153.07369494s left)
2019-06-12 11:58:12,968 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612115812953391
2019-06-12 11:58:12,997 [salt.minion      :1432][INFO    ][6417] Starting a new job with PID 6417
2019-06-12 11:58:13,029 [salt.minion      :1711][INFO    ][6417] Returning information for job: 20190612115812953391
2019-06-12 11:58:26,389 [salt.state       :300 ][INFO    ][4662] {'ret': True}
2019-06-12 11:58:26,391 [salt.state       :1951][INFO    ][4662] Completed state [maas.wait_for_machine_status] at time 11:58:26.391699 duration_in_ms=380021.488
2019-06-12 11:58:26,394 [salt.minion      :1711][INFO    ][4662] Returning information for job: 20190612115156454736
2019-06-12 11:58:27,148 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command state.apply with jid 20190612115827137002
2019-06-12 11:58:27,167 [salt.minion      :1432][INFO    ][6522] Starting a new job with PID 6522
2019-06-12 11:58:35,063 [salt.state       :915 ][INFO    ][6522] Loading fresh modules for state activity
2019-06-12 11:58:35,134 [salt.fileclient  :1219][INFO    ][6522] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-06-12 11:58:35,239 [salt.state       :1780][INFO    ][6522] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:58:35.239676
2019-06-12 11:58:35,240 [salt.state       :1813][INFO    ][6522] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-12 11:58:35,242 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6522] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-12 11:58:37,204 [salt.state       :300 ][INFO    ][6522] {'pid': 6539, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-12 11:58:37,205 [salt.state       :1951][INFO    ][6522] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:58:37.205230 duration_in_ms=1965.554
2019-06-12 11:58:37,209 [salt.state       :1780][INFO    ][6522] Running state [maas_machines_storage_cmp002_lvm] at time 11:58:37.209775
2019-06-12 11:58:37,210 [salt.state       :1813][INFO    ][6522] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-06-12 11:58:38,379 [salt.loaded.ext.module.maasng:610 ][INFO    ][6522] 8qp7xg
2019-06-12 11:58:38,379 [salt.loaded.ext.module.maasng:626 ][INFO    ][6522] sda
2019-06-12 11:58:38,962 [salt.loaded.ext.module.maasng:361 ][INFO    ][6522] 8qp7xg
2019-06-12 11:58:39,060 [salt.loaded.ext.module.maasng:367 ][INFO    ][6522] [{u'size': 800109715456, u'block_size': 4096, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'id': 1, u'filesystem': None, u'name': u'sda', u'system_id': u'8qp7xg', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/8qp7xg/blockdevices/1/', u'type': u'physical', u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'38050b82-504d-4606-bc17-34f0add0f9ee', u'resource_uri': u'/MAAS/api/2.0/nodes/8qp7xg/blockdevices/1/partition/1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'8qp7xg', u'filesystem': {u'label': None, u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'uuid': u'a5db7fc3-82a9-460b-85b4-900a23e1a575'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 1, u'size': 800101236736}]}, {u'size': 800097042432, u'block_size': 4096, u'uuid': u'934c27f4-d21e-4ff6-b4d6-42dfd5ea335a', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 800097042432, u'id': 3, u'filesystem': {u'label': u'root', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'uuid': u'd604b951-2106-45ac-bb97-5ebff2912cde'}, u'name': u'vgroot-lvroot', u'system_id': u'8qp7xg', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/8qp7xg/blockdevices/3/', u'type': u'virtual', u'model': None, u'partitions': []}]
2019-06-12 11:58:39,061 [salt.loaded.ext.module.maasng:632 ][INFO    ][6522] vgroot
2019-06-12 11:58:39,062 [salt.loaded.ext.module.maasng:635 ][INFO    ][6522] lvroot
2019-06-12 11:58:39,062 [salt.loaded.ext.module.maasng:639 ][INFO    ][6522] 107374182400
2019-06-12 11:58:39,684 [salt.loaded.ext.module.maasng:645 ][INFO    ][6522] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'testing_status_name': u'Passed', u'memory_test_status': -1, u'disable_ipv4': False, u'cpu_count': 40, u'owner_data': {}, u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'min_hwe_kernel': u'hwe-16.04', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'boot_disk': {u'partition_table_type': u'MBR', u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'7e0e4355-86f2-4c67-a625-11c9e16db15c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'8qp7xg', u'device_id': 1, u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'2238fbfc-f42c-48c9-8476-9e201b6b736a', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/8qp7xg/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'size': 800101236736}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'8qp7xg', u'resource_uri': u'/MAAS/api/2.0/nodes/8qp7xg/blockdevices/1/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}, u'fqdn': u'cmp002.maas', u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'8qp7xg', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'partition_table_type': None, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'7bfa34a4-14c8-440c-850f-9ef4e18a8157', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'8qp7xg', u'resource_uri': u'/MAAS/api/2.0/nodes/8qp7xg/blockdevices/11/', u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'f74e4637-c016-4cdf-a6f7-e5490bc950c4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 11, u'serial': None, u'size': 107374182400}], u'blockdevice_set': [{u'size': 800109715456, u'partition_table_type': u'MBR', u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'8qp7xg', u'resource_uri': u'/MAAS/api/2.0/nodes/8qp7xg/blockdevices/1/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'7e0e4355-86f2-4c67-a625-11c9e16db15c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'8qp7xg', u'device_id': 1, u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'2238fbfc-f42c-48c9-8476-9e201b6b736a', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/8qp7xg/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'size': 800101236736}]}, {u'size': 107374182400, u'partition_table_type': None, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'uuid': u'7bfa34a4-14c8-440c-850f-9ef4e18a8157', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'8qp7xg', u'resource_uri': u'/MAAS/api/2.0/nodes/8qp7xg/blockdevices/11/', u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'f74e4637-c016-4cdf-a6f7-e5490bc950c4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 11, u'serial': None, u'partitions': []}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'power_type': u'ipmi', u'physicalblockdevice_set': [{u'partition_table_type': u'MBR', u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'7e0e4355-86f2-4c67-a625-11c9e16db15c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'8qp7xg', u'device_id': 1, u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'2238fbfc-f42c-48c9-8476-9e201b6b736a', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/8qp7xg/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'size': 800101236736}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'8qp7xg', u'resource_uri': u'/MAAS/api/2.0/nodes/8qp7xg/blockdevices/1/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}], u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'8qp7xg', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'commissioning_status_name': u'Passed', u'current_testing_result_id': 3, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', 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'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'8qwgpg', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'8qwgpg', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 18}], u'tags': [u'sriov'], u'effective_mtu': 1500, 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'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'8qwgpg', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'8qp7xg', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/8qp7xg/interfaces/4/'}, u'interface_set': [{u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'8qwgpg', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'8qwgpg', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 18}], u'tags': [u'sriov'], u'effective_mtu': 1500, 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'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'8qwgpg', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'8qp7xg', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/8qp7xg/interfaces/4/'}, {u'vlan': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:07:51', u'system_id': u'8qp7xg', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/8qp7xg/interfaces/13/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 19}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:12:48', u'system_id': u'8qp7xg', u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/8qp7xg/interfaces/14/'}, {u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'8qwgpg', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'8qwgpg', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'mode': u'link_up', u'id': 20}], u'tags': [u'sriov'], u'effective_mtu': 1500, 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'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'8qwgpg', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.40'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:1c', u'system_id': u'8qp7xg', u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/8qp7xg/interfaces/15/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 21}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:12:49', u'system_id': u'8qp7xg', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/8qp7xg/interfaces/11/'}, {u'vlan': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:07:50', u'system_id': u'8qp7xg', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/8qp7xg/interfaces/12/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/8qp7xg/'}
2019-06-12 11:58:39,685 [salt.state       :300 ][INFO    ][6522] {'new': {'storage_layout': 'lvm'}}
2019-06-12 11:58:39,687 [salt.state       :1951][INFO    ][6522] Completed state [maas_machines_storage_cmp002_lvm] at time 11:58:39.685678 duration_in_ms=2475.902
2019-06-12 11:58:39,688 [salt.state       :1780][INFO    ][6522] Running state [maas_machines_storage_cmp001_lvm] at time 11:58:39.688017
2019-06-12 11:58:39,688 [salt.state       :1813][INFO    ][6522] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-06-12 11:58:40,954 [salt.loaded.ext.module.maasng:610 ][INFO    ][6522] hwahxa
2019-06-12 11:58:40,955 [salt.loaded.ext.module.maasng:626 ][INFO    ][6522] sda
2019-06-12 11:58:41,491 [salt.loaded.ext.module.maasng:361 ][INFO    ][6522] hwahxa
2019-06-12 11:58:41,578 [salt.loaded.ext.module.maasng:367 ][INFO    ][6522] [{u'size': 800109715456, u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'hwahxa', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/hwahxa/blockdevices/2/', u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'3ae66643-9d59-4fa6-935d-ca3f588ce6c8', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hwahxa', u'device_id': 2, u'filesystem': {u'uuid': u'720d3ee5-3c35-4f41-975f-23b72181b6a3', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/hwahxa/blockdevices/2/partition/2', u'type': u'partition', u'id': 2, u'size': 800101236736}]}, {u'size': 800097042432, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'filesystem': {u'uuid': u'0d8bef29-438e-428d-aab4-34d4f5363a4c', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'label': u'root'}, u'uuid': u'502aa732-fa7a-4a91-8788-e7e943e11eb8', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'hwahxa', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/hwahxa/blockdevices/4/', u'type': u'virtual', u'id': 4, u'serial': None, u'partitions': []}]
2019-06-12 11:58:41,579 [salt.loaded.ext.module.maasng:632 ][INFO    ][6522] vgroot
2019-06-12 11:58:41,579 [salt.loaded.ext.module.maasng:635 ][INFO    ][6522] lvroot
2019-06-12 11:58:41,579 [salt.loaded.ext.module.maasng:639 ][INFO    ][6522] 107374182400
2019-06-12 11:58:42,177 [salt.loaded.ext.module.maasng:645 ][INFO    ][6522] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'memory_test_status': -1, u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'8qwgpg', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'id': 26, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'8qwgpg', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'8qwgpg', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'mac_address': u'9c:b6:54:8a:95:a0', u'params': u'', u'system_id': u'hwahxa', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/hwahxa/interfaces/5/'}, u'ip_addresses': [u'192.168.11.39', u'192.168.11.44'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'zone': {u'description': u'', u'id': 1, u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'node_type': 0, u'testing_status': 2, u'system_id': u'hwahxa', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'uuid': u'94554075-e844-4a9c-ac30-98395fdae482', u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/hwahxa/blockdevices/12/', u'used_size': 107374182400, u'tags': [], u'filesystem': {u'uuid': u'eda29e6c-db54-41e1-9446-6b24b6c53578', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'hwahxa', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 12, u'serial': None, u'partitions': []}], u'architecture': u'amd64/generic', 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'hwahxa', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'size': 800109715456, u'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/hwahxa/blockdevices/2/', u'used_size': 800106479616, u'tags': [u'ssd'], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'hwahxa', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'8b60d45d-0113-4a07-b634-d89e004851ea', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hwahxa', u'device_id': 2, u'filesystem': {u'uuid': u'1982cc4c-1482-4696-811e-213e6d247d55', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/hwahxa/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}]}, u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'8qwgpg', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'id': 26, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'8qwgpg', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'8qwgpg', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'mac_address': u'9c:b6:54:8a:95:a0', u'params': u'', u'system_id': u'hwahxa', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/hwahxa/interfaces/5/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'id': 16, u'discovered': None, u'parents': [], u'mac_address': u'38:ea:a7:8f:1f:d5', u'params': u'', u'system_id': u'hwahxa', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/hwahxa/interfaces/16/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'id': 20, u'discovered': None, u'parents': [], u'mac_address': u'38:ea:a7:8f:1f:d4', u'params': u'', u'system_id': u'hwahxa', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/hwahxa/interfaces/20/'}, {u'name': u'ens2f1', u'links': [{u'id': 27, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'effective_mtu': 1500, u'id': 17, u'discovered': None, u'parents': [], u'mac_address': u'38:ea:a7:8f:52:cd', u'params': u'', u'system_id': u'hwahxa', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/hwahxa/interfaces/17/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'8qwgpg', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 28, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'8qwgpg', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 18, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'8qwgpg', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.44'}], u'parents': [], u'mac_address': u'9c:b6:54:8a:95:a4', u'params': u'', u'system_id': u'hwahxa', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/hwahxa/interfaces/18/'}, {u'name': u'ens2f0', u'links': [{u'id': 29, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'effective_mtu': 1500, u'id': 19, u'discovered': None, u'parents': [], u'mac_address': u'38:ea:a7:8f:52:cc', u'params': u'', u'system_id': u'hwahxa', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/hwahxa/interfaces/19/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 800109715456, u'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/hwahxa/blockdevices/2/', u'used_size': 800106479616, u'tags': [u'ssd'], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'hwahxa', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'8b60d45d-0113-4a07-b634-d89e004851ea', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hwahxa', u'device_id': 2, u'filesystem': {u'uuid': u'1982cc4c-1482-4696-811e-213e6d247d55', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/hwahxa/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}]}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'disable_ipv4': False, u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'commissioning_status_name': u'Passed', u'current_commissioning_result_id': 4, u'address_ttl': None, u'blockdevice_set': [{u'size': 800109715456, u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'hwahxa', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/hwahxa/blockdevices/2/', u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'8b60d45d-0113-4a07-b634-d89e004851ea', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hwahxa', u'device_id': 2, u'filesystem': {u'uuid': u'1982cc4c-1482-4696-811e-213e6d247d55', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/hwahxa/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}]}, {u'size': 107374182400, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'filesystem': {u'uuid': u'eda29e6c-db54-41e1-9446-6b24b6c53578', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'label': u'root'}, u'uuid': u'94554075-e844-4a9c-ac30-98395fdae482', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'hwahxa', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/hwahxa/blockdevices/12/', u'type': u'virtual', u'id': 12, u'serial': None, u'partitions': []}], u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/hwahxa/'}
2019-06-12 11:58:42,181 [salt.state       :300 ][INFO    ][6522] {'new': {'storage_layout': 'lvm'}}
2019-06-12 11:58:42,184 [salt.state       :1951][INFO    ][6522] Completed state [maas_machines_storage_cmp001_lvm] at time 11:58:42.182563 duration_in_ms=2493.693
2019-06-12 11:58:42,187 [salt.minion      :1711][INFO    ][6522] Returning information for job: 20190612115827137002
2019-06-12 11:58:42,922 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command state.apply with jid 20190612115842905297
2019-06-12 11:58:42,947 [salt.minion      :1432][INFO    ][6561] Starting a new job with PID 6561
2019-06-12 11:58:44,118 [salt.state       :915 ][INFO    ][6561] Loading fresh modules for state activity
2019-06-12 11:58:44,182 [salt.fileclient  :1219][INFO    ][6561] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-06-12 11:58:44,235 [salt.state       :1780][INFO    ][6561] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:58:44.235151
2019-06-12 11:58:44,235 [salt.state       :1813][INFO    ][6561] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-12 11:58:44,237 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6561] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-12 11:58:46,235 [salt.state       :300 ][INFO    ][6561] {'pid': 6568, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-12 11:58:46,236 [salt.state       :1951][INFO    ][6561] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:58:46.236017 duration_in_ms=2000.865
2019-06-12 11:58:46,239 [salt.state       :1780][INFO    ][6561] Running state [maas.deploy_machines] at time 11:58:46.239718
2019-06-12 11:58:46,240 [salt.state       :1813][INFO    ][6561] Executing state module.run for [maas.deploy_machines]
2019-06-12 11:58:46,242 [salt.utils.decorators:613 ][WARNING ][6561] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-12 11:58:46,816 [salt.loaded.ext.module.maas:684 ][INFO    ][6561] deploymachines hwe_kernel=hwe-16.04 system_id=8qp7xg distro_series=xenial
2019-06-12 11:58:49,395 [salt.loaded.ext.module.maas:684 ][INFO    ][6561] deploymachines hwe_kernel=hwe-16.04 system_id=hwahxa distro_series=xenial
2019-06-12 11:58:51,943 [salt.loaded.ext.module.maas:684 ][INFO    ][6561] deploymachines hwe_kernel=hwe-16.04 system_id=h3qhg6 distro_series=xenial
2019-06-12 11:58:54,448 [salt.loaded.ext.module.maas:684 ][INFO    ][6561] deploymachines hwe_kernel=hwe-16.04 system_id=me8r3f distro_series=xenial
2019-06-12 11:58:56,842 [salt.loaded.ext.module.maas:684 ][INFO    ][6561] deploymachines hwe_kernel=hwe-16.04 system_id=nrbfpc distro_series=xenial
2019-06-12 11:58:57,958 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612115857948550
2019-06-12 11:58:57,982 [salt.minion      :1432][INFO    ][6821] Starting a new job with PID 6821
2019-06-12 11:58:58,014 [salt.minion      :1711][INFO    ][6821] Returning information for job: 20190612115857948550
2019-06-12 11:58:59,286 [salt.state       :300 ][INFO    ][6561] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-06-12 11:58:59,286 [salt.state       :1951][INFO    ][6561] Completed state [maas.deploy_machines] at time 11:58:59.286714 duration_in_ms=13046.996
2019-06-12 11:58:59,289 [salt.minion      :1711][INFO    ][6561] Returning information for job: 20190612115842905297
2019-06-12 11:59:00,009 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command state.apply with jid 20190612115859995524
2019-06-12 11:59:00,030 [salt.minion      :1432][INFO    ][6848] Starting a new job with PID 6848
2019-06-12 11:59:07,747 [salt.state       :915 ][INFO    ][6848] Loading fresh modules for state activity
2019-06-12 11:59:07,809 [salt.fileclient  :1219][INFO    ][6848] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-06-12 11:59:07,869 [salt.state       :1780][INFO    ][6848] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:59:07.868950
2019-06-12 11:59:07,869 [salt.state       :1813][INFO    ][6848] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-12 11:59:07,871 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6848] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-12 11:59:09,716 [salt.state       :300 ][INFO    ][6848] {'pid': 6874, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-12 11:59:09,718 [salt.state       :1951][INFO    ][6848] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:59:09.717614 duration_in_ms=1848.663
2019-06-12 11:59:09,721 [salt.state       :1780][INFO    ][6848] Running state [maas.wait_for_machine_status] at time 11:59:09.721534
2019-06-12 11:59:09,724 [salt.state       :1813][INFO    ][6848] Executing state module.run for [maas.wait_for_machine_status]
2019-06-12 11:59:09,725 [salt.utils.decorators:613 ][WARNING ][6848] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-12 11:59:12,851 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.88432002s left)
2019-06-12 11:59:15,059 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612115915044390
2019-06-12 11:59:15,090 [salt.minion      :1432][INFO    ][6885] Starting a new job with PID 6885
2019-06-12 11:59:15,121 [salt.minion      :1711][INFO    ][6885] Returning information for job: 20190612115915044390
2019-06-12 11:59:45,163 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612115945148956
2019-06-12 11:59:45,188 [salt.minion      :1432][INFO    ][6930] Starting a new job with PID 6930
2019-06-12 11:59:45,220 [salt.minion      :1711][INFO    ][6930] Returning information for job: 20190612115945148956
2019-06-12 11:59:45,804 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.93181896s left)
2019-06-12 12:00:15,275 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612120015257420
2019-06-12 12:00:15,300 [salt.minion      :1432][INFO    ][6965] Starting a new job with PID 6965
2019-06-12 12:00:15,328 [salt.minion      :1711][INFO    ][6965] Returning information for job: 20190612120015257420
2019-06-12 12:00:18,802 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2180.93345904s left)
2019-06-12 12:00:45,369 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612120045347455
2019-06-12 12:00:45,402 [salt.minion      :1432][INFO    ][7025] Starting a new job with PID 7025
2019-06-12 12:00:45,441 [salt.minion      :1711][INFO    ][7025] Returning information for job: 20190612120045347455
2019-06-12 12:00:51,791 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2147.94485712s left)
2019-06-12 12:01:15,476 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612120115465064
2019-06-12 12:01:15,497 [salt.minion      :1432][INFO    ][7055] Starting a new job with PID 7055
2019-06-12 12:01:15,531 [salt.minion      :1711][INFO    ][7055] Returning information for job: 20190612120115465064
2019-06-12 12:01:24,765 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2114.97099805s left)
2019-06-12 12:01:45,597 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612120145577091
2019-06-12 12:01:45,624 [salt.minion      :1432][INFO    ][7141] Starting a new job with PID 7141
2019-06-12 12:01:45,677 [salt.minion      :1711][INFO    ][7141] Returning information for job: 20190612120145577091
2019-06-12 12:01:57,906 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2081.8302331s left)
2019-06-12 12:02:15,712 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612120215694979
2019-06-12 12:02:15,732 [salt.minion      :1432][INFO    ][7207] Starting a new job with PID 7207
2019-06-12 12:02:15,763 [salt.minion      :1711][INFO    ][7207] Returning information for job: 20190612120215694979
2019-06-12 12:02:30,874 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2048.86237812s left)
2019-06-12 12:02:45,847 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612120245829666
2019-06-12 12:02:45,875 [salt.minion      :1432][INFO    ][7449] Starting a new job with PID 7449
2019-06-12 12:02:45,908 [salt.minion      :1711][INFO    ][7449] Returning information for job: 20190612120245829666
2019-06-12 12:03:03,928 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2015.80732012s left)
2019-06-12 12:03:15,977 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612120315963789
2019-06-12 12:03:15,995 [salt.minion      :1432][INFO    ][7523] Starting a new job with PID 7523
2019-06-12 12:03:16,023 [salt.minion      :1711][INFO    ][7523] Returning information for job: 20190612120315963789
2019-06-12 12:03:36,880 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1982.85549212s left)
2019-06-12 12:03:46,090 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612120346076011
2019-06-12 12:03:46,123 [salt.minion      :1432][INFO    ][7776] Starting a new job with PID 7776
2019-06-12 12:03:46,153 [salt.minion      :1711][INFO    ][7776] Returning information for job: 20190612120346076011
2019-06-12 12:04:09,848 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1949.88814807s left)
2019-06-12 12:04:16,246 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612120416233103
2019-06-12 12:04:16,272 [salt.minion      :1432][INFO    ][7824] Starting a new job with PID 7824
2019-06-12 12:04:16,312 [salt.minion      :1711][INFO    ][7824] Returning information for job: 20190612120416233103
2019-06-12 12:04:42,928 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1916.808043s left)
2019-06-12 12:04:46,463 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612120446444748
2019-06-12 12:04:46,494 [salt.minion      :1432][INFO    ][8171] Starting a new job with PID 8171
2019-06-12 12:04:46,528 [salt.minion      :1711][INFO    ][8171] Returning information for job: 20190612120446444748
2019-06-12 12:05:15,971 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1883.76500702s left)
2019-06-12 12:05:16,631 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612120516613523
2019-06-12 12:05:16,659 [salt.minion      :1432][INFO    ][8209] Starting a new job with PID 8209
2019-06-12 12:05:16,688 [salt.minion      :1711][INFO    ][8209] Returning information for job: 20190612120516613523
2019-06-12 12:05:46,783 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612120546769258
2019-06-12 12:05:46,807 [salt.minion      :1432][INFO    ][8480] Starting a new job with PID 8480
2019-06-12 12:05:46,838 [salt.minion      :1711][INFO    ][8480] Returning information for job: 20190612120546769258
2019-06-12 12:05:48,853 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1850.88299298s left)
2019-06-12 12:06:16,928 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612120616916243
2019-06-12 12:06:16,953 [salt.minion      :1432][INFO    ][8519] Starting a new job with PID 8519
2019-06-12 12:06:16,988 [salt.minion      :1711][INFO    ][8519] Returning information for job: 20190612120616916243
2019-06-12 12:06:22,307 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1817.42888808s left)
2019-06-12 12:06:47,132 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612120647116582
2019-06-12 12:06:47,161 [salt.minion      :1432][INFO    ][8839] Starting a new job with PID 8839
2019-06-12 12:06:47,198 [salt.minion      :1711][INFO    ][8839] Returning information for job: 20190612120647116582
2019-06-12 12:06:55,289 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1784.44715214s left)
2019-06-12 12:07:17,307 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612120717296832
2019-06-12 12:07:17,332 [salt.minion      :1432][INFO    ][8896] Starting a new job with PID 8896
2019-06-12 12:07:17,363 [salt.minion      :1711][INFO    ][8896] Returning information for job: 20190612120717296832
2019-06-12 12:07:28,069 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1751.66682601s left)
2019-06-12 12:07:47,491 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612120747477440
2019-06-12 12:07:47,514 [salt.minion      :1432][INFO    ][9155] Starting a new job with PID 9155
2019-06-12 12:07:47,545 [salt.minion      :1711][INFO    ][9155] Returning information for job: 20190612120747477440
2019-06-12 12:08:01,012 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1718.72356701s left)
2019-06-12 12:08:17,660 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612120817649091
2019-06-12 12:08:17,688 [salt.minion      :1432][INFO    ][9213] Starting a new job with PID 9213
2019-06-12 12:08:17,719 [salt.minion      :1711][INFO    ][9213] Returning information for job: 20190612120817649091
2019-06-12 12:08:34,000 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1685.73551893s left)
2019-06-12 12:08:47,869 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612120847856512
2019-06-12 12:08:47,891 [salt.minion      :1432][INFO    ][9334] Starting a new job with PID 9334
2019-06-12 12:08:47,922 [salt.minion      :1711][INFO    ][9334] Returning information for job: 20190612120847856512
2019-06-12 12:09:06,992 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1652.74410701s left)
2019-06-12 12:09:18,043 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612120918032288
2019-06-12 12:09:18,071 [salt.minion      :1432][INFO    ][9390] Starting a new job with PID 9390
2019-06-12 12:09:18,102 [salt.minion      :1711][INFO    ][9390] Returning information for job: 20190612120918032288
2019-06-12 12:09:39,892 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1619.84339905s left)
2019-06-12 12:09:48,260 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612120948247158
2019-06-12 12:09:48,291 [salt.minion      :1432][INFO    ][9596] Starting a new job with PID 9596
2019-06-12 12:09:48,322 [salt.minion      :1711][INFO    ][9596] Returning information for job: 20190612120948247158
2019-06-12 12:10:12,653 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1587.08290005s left)
2019-06-12 12:10:18,450 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612121018435346
2019-06-12 12:10:18,478 [salt.minion      :1432][INFO    ][9760] Starting a new job with PID 9760
2019-06-12 12:10:18,515 [salt.minion      :1711][INFO    ][9760] Returning information for job: 20190612121018435346
2019-06-12 12:10:45,430 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1554.30554295s left)
2019-06-12 12:10:48,679 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612121048663473
2019-06-12 12:10:48,708 [salt.minion      :1432][INFO    ][9815] Starting a new job with PID 9815
2019-06-12 12:10:48,740 [salt.minion      :1711][INFO    ][9815] Returning information for job: 20190612121048663473
2019-06-12 12:11:18,752 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1520.98373604s left)
2019-06-12 12:11:18,896 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612121118889231
2019-06-12 12:11:18,913 [salt.minion      :1432][INFO    ][9879] Starting a new job with PID 9879
2019-06-12 12:11:18,944 [salt.minion      :1711][INFO    ][9879] Returning information for job: 20190612121118889231
2019-06-12 12:11:48,921 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612121148911386
2019-06-12 12:11:48,943 [salt.minion      :1432][INFO    ][10023] Starting a new job with PID 10023
2019-06-12 12:11:48,970 [salt.minion      :1711][INFO    ][10023] Returning information for job: 20190612121148911386
2019-06-12 12:11:51,603 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1488.13252401s left)
2019-06-12 12:12:19,003 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612121218989105
2019-06-12 12:12:19,035 [salt.minion      :1432][INFO    ][10070] Starting a new job with PID 10070
2019-06-12 12:12:19,065 [salt.minion      :1711][INFO    ][10070] Returning information for job: 20190612121218989105
2019-06-12 12:12:25,374 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1454.36177397s left)
2019-06-12 12:12:49,105 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612121249088953
2019-06-12 12:12:49,132 [salt.minion      :1432][INFO    ][10297] Starting a new job with PID 10297
2019-06-12 12:12:49,163 [salt.minion      :1711][INFO    ][10297] Returning information for job: 20190612121249088953
2019-06-12 12:12:58,292 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1421.44421005s left)
2019-06-12 12:13:19,138 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612121319125133
2019-06-12 12:13:19,169 [salt.minion      :1432][INFO    ][10325] Starting a new job with PID 10325
2019-06-12 12:13:19,201 [salt.minion      :1711][INFO    ][10325] Returning information for job: 20190612121319125133
2019-06-12 12:13:31,209 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1388.52719998s left)
2019-06-12 12:13:49,230 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612121349214776
2019-06-12 12:13:49,258 [salt.minion      :1432][INFO    ][10372] Starting a new job with PID 10372
2019-06-12 12:13:49,292 [salt.minion      :1711][INFO    ][10372] Returning information for job: 20190612121349214776
2019-06-12 12:14:03,939 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1355.79626608s left)
2019-06-12 12:14:19,311 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612121419295074
2019-06-12 12:14:19,331 [salt.minion      :1432][INFO    ][10403] Starting a new job with PID 10403
2019-06-12 12:14:19,357 [salt.minion      :1711][INFO    ][10403] Returning information for job: 20190612121419295074
2019-06-12 12:14:36,976 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1322.75958109s left)
2019-06-12 12:14:49,381 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612121449370330
2019-06-12 12:14:49,414 [salt.minion      :1432][INFO    ][10452] Starting a new job with PID 10452
2019-06-12 12:14:49,441 [salt.minion      :1711][INFO    ][10452] Returning information for job: 20190612121449370330
2019-06-12 12:15:09,868 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1289.86787915s left)
2019-06-12 12:15:19,507 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612121519489372
2019-06-12 12:15:19,536 [salt.minion      :1432][INFO    ][10485] Starting a new job with PID 10485
2019-06-12 12:15:19,567 [salt.minion      :1711][INFO    ][10485] Returning information for job: 20190612121519489372
2019-06-12 12:15:42,676 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1257.06010294s left)
2019-06-12 12:15:49,616 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612121549605113
2019-06-12 12:15:49,644 [salt.minion      :1432][INFO    ][10531] Starting a new job with PID 10531
2019-06-12 12:15:49,677 [salt.minion      :1711][INFO    ][10531] Returning information for job: 20190612121549605113
2019-06-12 12:16:15,405 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1224.33074093s left)
2019-06-12 12:16:19,772 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612121619757449
2019-06-12 12:16:19,800 [salt.minion      :1432][INFO    ][10566] Starting a new job with PID 10566
2019-06-12 12:16:19,831 [salt.minion      :1711][INFO    ][10566] Returning information for job: 20190612121619757449
2019-06-12 12:16:48,142 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1191.59345794s left)
2019-06-12 12:16:49,914 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612121649899596
2019-06-12 12:16:49,941 [salt.minion      :1432][INFO    ][10613] Starting a new job with PID 10613
2019-06-12 12:16:49,977 [salt.minion      :1711][INFO    ][10613] Returning information for job: 20190612121649899596
2019-06-12 12:17:20,094 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612121720076709
2019-06-12 12:17:20,117 [salt.minion      :1432][INFO    ][10643] Starting a new job with PID 10643
2019-06-12 12:17:20,145 [salt.minion      :1711][INFO    ][10643] Returning information for job: 20190612121720076709
2019-06-12 12:17:21,004 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1158.73220396s left)
2019-06-12 12:17:50,248 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612121750231079
2019-06-12 12:17:50,274 [salt.minion      :1432][INFO    ][10687] Starting a new job with PID 10687
2019-06-12 12:17:50,306 [salt.minion      :1711][INFO    ][10687] Returning information for job: 20190612121750231079
2019-06-12 12:17:53,840 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1125.89621902s left)
2019-06-12 12:18:20,453 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612121820435071
2019-06-12 12:18:20,481 [salt.minion      :1432][INFO    ][10716] Starting a new job with PID 10716
2019-06-12 12:18:20,513 [salt.minion      :1711][INFO    ][10716] Returning information for job: 20190612121820435071
2019-06-12 12:18:26,689 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1093.04666114s left)
2019-06-12 12:18:50,647 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612121850631877
2019-06-12 12:18:50,676 [salt.minion      :1432][INFO    ][10766] Starting a new job with PID 10766
2019-06-12 12:18:50,717 [salt.minion      :1711][INFO    ][10766] Returning information for job: 20190612121850631877
2019-06-12 12:18:59,471 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1060.26493406s left)
2019-06-12 12:19:20,880 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612121920864159
2019-06-12 12:19:20,904 [salt.minion      :1432][INFO    ][10797] Starting a new job with PID 10797
2019-06-12 12:19:20,944 [salt.minion      :1711][INFO    ][10797] Returning information for job: 20190612121920864159
2019-06-12 12:19:32,328 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1027.40762997s left)
2019-06-12 12:19:50,920 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612121950908822
2019-06-12 12:19:50,937 [salt.minion      :1432][INFO    ][10845] Starting a new job with PID 10845
2019-06-12 12:19:50,972 [salt.minion      :1711][INFO    ][10845] Returning information for job: 20190612121950908822
2019-06-12 12:20:05,050 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (994.685516119s left)
2019-06-12 12:20:20,967 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612122020952043
2019-06-12 12:20:20,992 [salt.minion      :1432][INFO    ][10885] Starting a new job with PID 10885
2019-06-12 12:20:21,021 [salt.minion      :1711][INFO    ][10885] Returning information for job: 20190612122020952043
2019-06-12 12:20:38,164 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (961.571830034s left)
2019-06-12 12:20:51,033 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612122051021623
2019-06-12 12:20:51,056 [salt.minion      :1432][INFO    ][10944] Starting a new job with PID 10944
2019-06-12 12:20:51,087 [salt.minion      :1711][INFO    ][10944] Returning information for job: 20190612122051021623
2019-06-12 12:21:10,889 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (928.846740007s left)
2019-06-12 12:21:21,069 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612122121058498
2019-06-12 12:21:21,095 [salt.minion      :1432][INFO    ][10975] Starting a new job with PID 10975
2019-06-12 12:21:21,134 [salt.minion      :1711][INFO    ][10975] Returning information for job: 20190612122121058498
2019-06-12 12:21:43,759 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (895.976799965s left)
2019-06-12 12:21:51,193 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612122151176011
2019-06-12 12:21:51,224 [salt.minion      :1432][INFO    ][11022] Starting a new job with PID 11022
2019-06-12 12:21:51,255 [salt.minion      :1711][INFO    ][11022] Returning information for job: 20190612122151176011
2019-06-12 12:22:16,619 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (863.116374016s left)
2019-06-12 12:22:21,299 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612122221283313
2019-06-12 12:22:21,326 [salt.minion      :1432][INFO    ][11052] Starting a new job with PID 11052
2019-06-12 12:22:21,358 [salt.minion      :1711][INFO    ][11052] Returning information for job: 20190612122221283313
2019-06-12 12:22:49,505 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (830.231086016s left)
2019-06-12 12:22:51,453 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612122251436548
2019-06-12 12:22:51,478 [salt.minion      :1432][INFO    ][11101] Starting a new job with PID 11101
2019-06-12 12:22:51,511 [salt.minion      :1711][INFO    ][11101] Returning information for job: 20190612122251436548
2019-06-12 12:23:21,584 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612122321570922
2019-06-12 12:23:21,609 [salt.minion      :1432][INFO    ][11138] Starting a new job with PID 11138
2019-06-12 12:23:21,643 [salt.minion      :1711][INFO    ][11138] Returning information for job: 20190612122321570922
2019-06-12 12:23:22,328 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (797.408177137s left)
2019-06-12 12:23:51,760 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612122351746123
2019-06-12 12:23:51,786 [salt.minion      :1432][INFO    ][11185] Starting a new job with PID 11185
2019-06-12 12:23:51,816 [salt.minion      :1711][INFO    ][11185] Returning information for job: 20190612122351746123
2019-06-12 12:23:55,213 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (764.522908926s left)
2019-06-12 12:24:21,957 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612122421944137
2019-06-12 12:24:21,982 [salt.minion      :1432][INFO    ][11232] Starting a new job with PID 11232
2019-06-12 12:24:22,017 [salt.minion      :1711][INFO    ][11232] Returning information for job: 20190612122421944137
2019-06-12 12:24:27,934 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (731.801600933s left)
2019-06-12 12:24:52,187 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612122452173259
2019-06-12 12:24:52,212 [salt.minion      :1432][INFO    ][11262] Starting a new job with PID 11262
2019-06-12 12:24:52,241 [salt.minion      :1711][INFO    ][11262] Returning information for job: 20190612122452173259
2019-06-12 12:25:00,670 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (699.065382957s left)
2019-06-12 12:25:22,399 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612122522387140
2019-06-12 12:25:22,424 [salt.minion      :1432][INFO    ][11312] Starting a new job with PID 11312
2019-06-12 12:25:22,455 [salt.minion      :1711][INFO    ][11312] Returning information for job: 20190612122522387140
2019-06-12 12:25:33,407 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (666.329249144s left)
2019-06-12 12:25:52,635 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612122552619132
2019-06-12 12:25:52,664 [salt.minion      :1432][INFO    ][11341] Starting a new job with PID 11341
2019-06-12 12:25:52,695 [salt.minion      :1711][INFO    ][11341] Returning information for job: 20190612122552619132
2019-06-12 12:26:06,504 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (633.231501102s left)
2019-06-12 12:26:22,692 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612122622683995
2019-06-12 12:26:22,714 [salt.minion      :1432][INFO    ][11389] Starting a new job with PID 11389
2019-06-12 12:26:22,744 [salt.minion      :1711][INFO    ][11389] Returning information for job: 20190612122622683995
2019-06-12 12:26:39,241 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (600.49503994s left)
2019-06-12 12:26:52,769 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612122652752313
2019-06-12 12:26:52,798 [salt.minion      :1432][INFO    ][11419] Starting a new job with PID 11419
2019-06-12 12:26:52,832 [salt.minion      :1711][INFO    ][11419] Returning information for job: 20190612122652752313
2019-06-12 12:27:12,076 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (567.659937143s left)
2019-06-12 12:27:22,880 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612122722868061
2019-06-12 12:27:22,909 [salt.minion      :1432][INFO    ][11471] Starting a new job with PID 11471
2019-06-12 12:27:22,937 [salt.minion      :1711][INFO    ][11471] Returning information for job: 20190612122722868061
2019-06-12 12:27:44,879 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (534.856904984s left)
2019-06-12 12:27:52,966 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612122752954401
2019-06-12 12:27:52,991 [salt.minion      :1432][INFO    ][11500] Starting a new job with PID 11500
2019-06-12 12:27:53,023 [salt.minion      :1711][INFO    ][11500] Returning information for job: 20190612122752954401
2019-06-12 12:28:17,736 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (502.000093937s left)
2019-06-12 12:28:23,137 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612122823123936
2019-06-12 12:28:23,161 [salt.minion      :1432][INFO    ][11545] Starting a new job with PID 11545
2019-06-12 12:28:23,193 [salt.minion      :1711][INFO    ][11545] Returning information for job: 20190612122823123936
2019-06-12 12:28:50,569 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (469.167258978s left)
2019-06-12 12:28:53,239 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612122853220177
2019-06-12 12:28:53,265 [salt.minion      :1432][INFO    ][11575] Starting a new job with PID 11575
2019-06-12 12:28:53,294 [salt.minion      :1711][INFO    ][11575] Returning information for job: 20190612122853220177
2019-06-12 12:29:23,455 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612122923441367
2019-06-12 12:29:23,479 [salt.minion      :1432][INFO    ][11625] Starting a new job with PID 11625
2019-06-12 12:29:23,512 [salt.minion      :1711][INFO    ][11625] Returning information for job: 20190612122923441367
2019-06-12 12:29:23,709 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (436.026767969s left)
2019-06-12 12:29:53,635 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612122953621220
2019-06-12 12:29:53,662 [salt.minion      :1432][INFO    ][11647] Starting a new job with PID 11647
2019-06-12 12:29:53,692 [salt.minion      :1711][INFO    ][11647] Returning information for job: 20190612122953621220
2019-06-12 12:29:56,538 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (403.198123932s left)
2019-06-12 12:30:23,653 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612123023640437
2019-06-12 12:30:23,681 [salt.minion      :1432][INFO    ][11837] Starting a new job with PID 11837
2019-06-12 12:30:23,717 [salt.minion      :1711][INFO    ][11837] Returning information for job: 20190612123023640437
2019-06-12 12:30:29,280 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (370.455857038s left)
2019-06-12 12:30:53,729 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612123053712368
2019-06-12 12:30:53,759 [salt.minion      :1432][INFO    ][11870] Starting a new job with PID 11870
2019-06-12 12:30:53,790 [salt.minion      :1711][INFO    ][11870] Returning information for job: 20190612123053712368
2019-06-12 12:31:02,168 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (337.567574978s left)
2019-06-12 12:31:23,801 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612123123788882
2019-06-12 12:31:23,825 [salt.minion      :1432][INFO    ][11923] Starting a new job with PID 11923
2019-06-12 12:31:23,855 [salt.minion      :1711][INFO    ][11923] Returning information for job: 20190612123123788882
2019-06-12 12:31:34,912 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (304.82417202s left)
2019-06-12 12:31:53,898 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612123153883796
2019-06-12 12:31:53,925 [salt.minion      :1432][INFO    ][11945] Starting a new job with PID 11945
2019-06-12 12:31:53,956 [salt.minion      :1711][INFO    ][11945] Returning information for job: 20190612123153883796
2019-06-12 12:32:07,789 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (271.94701314s left)
2019-06-12 12:32:24,030 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612123224015489
2019-06-12 12:32:24,055 [salt.minion      :1432][INFO    ][12006] Starting a new job with PID 12006
2019-06-12 12:32:24,088 [salt.minion      :1711][INFO    ][12006] Returning information for job: 20190612123224015489
2019-06-12 12:32:40,663 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (239.072484016s left)
2019-06-12 12:32:54,199 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612123254188055
2019-06-12 12:32:54,217 [salt.minion      :1432][INFO    ][12027] Starting a new job with PID 12027
2019-06-12 12:32:54,248 [salt.minion      :1711][INFO    ][12027] Returning information for job: 20190612123254188055
2019-06-12 12:33:13,515 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (206.220422029s left)
2019-06-12 12:33:24,316 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612123324301153
2019-06-12 12:33:24,342 [salt.minion      :1432][INFO    ][12081] Starting a new job with PID 12081
2019-06-12 12:33:24,372 [salt.minion      :1711][INFO    ][12081] Returning information for job: 20190612123324301153
2019-06-12 12:33:46,436 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (173.300135136s left)
2019-06-12 12:33:54,486 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612123354473496
2019-06-12 12:33:54,513 [salt.minion      :1432][INFO    ][12103] Starting a new job with PID 12103
2019-06-12 12:33:54,543 [salt.minion      :1711][INFO    ][12103] Returning information for job: 20190612123354473496
2019-06-12 12:34:19,199 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (140.536569118s left)
2019-06-12 12:34:24,645 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612123424634645
2019-06-12 12:34:24,672 [salt.minion      :1432][INFO    ][12156] Starting a new job with PID 12156
2019-06-12 12:34:24,708 [salt.minion      :1711][INFO    ][12156] Returning information for job: 20190612123424634645
2019-06-12 12:34:51,959 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (107.77641201s left)
2019-06-12 12:34:54,867 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612123454848720
2019-06-12 12:34:54,897 [salt.minion      :1432][INFO    ][12178] Starting a new job with PID 12178
2019-06-12 12:34:54,929 [salt.minion      :1711][INFO    ][12178] Returning information for job: 20190612123454848720
2019-06-12 12:35:24,828 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (74.9079051018s left)
2019-06-12 12:35:25,072 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612123525058935
2019-06-12 12:35:25,096 [salt.minion      :1432][INFO    ][12233] Starting a new job with PID 12233
2019-06-12 12:35:25,126 [salt.minion      :1711][INFO    ][12233] Returning information for job: 20190612123525058935
2019-06-12 12:35:55,180 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612123555164825
2019-06-12 12:35:55,204 [salt.minion      :1432][INFO    ][12250] Starting a new job with PID 12250
2019-06-12 12:35:55,235 [salt.minion      :1711][INFO    ][12250] Returning information for job: 20190612123555164825
2019-06-12 12:35:57,749 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (41.9874680042s left)
2019-06-12 12:36:25,201 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612123625189035
2019-06-12 12:36:25,224 [salt.minion      :1432][INFO    ][12303] Starting a new job with PID 12303
2019-06-12 12:36:25,258 [salt.minion      :1711][INFO    ][12303] Returning information for job: 20190612123625189035
2019-06-12 12:36:30,499 [salt.loaded.ext.module.maas:1023][INFO    ][6848] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (9.2368490696s left)
2019-06-12 12:36:55,303 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612123655289669
2019-06-12 12:36:55,336 [salt.minion      :1432][INFO    ][12326] Starting a new job with PID 12326
2019-06-12 12:36:55,367 [salt.minion      :1711][INFO    ][12326] Returning information for job: 20190612123655289669
2019-06-12 12:37:03,456 [salt.state       :302 ][ERROR   ][6848] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-06-12 12:37:03,457 [salt.state       :1951][INFO    ][6848] Completed state [maas.wait_for_machine_status] at time 12:37:03.457044 duration_in_ms=2273735.503
2019-06-12 12:37:03,467 [salt.minion      :1711][INFO    ][6848] Returning information for job: 20190612115859995524
2019-06-12 12:37:14,443 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command pillar.get with jid 20190612123714424999
2019-06-12 12:37:14,472 [salt.minion      :1432][INFO    ][12352] Starting a new job with PID 12352
2019-06-12 12:37:14,483 [salt.minion      :1711][INFO    ][12352] Returning information for job: 20190612123714424999
2019-06-12 12:37:15,197 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command service.status with jid 20190612123715180158
2019-06-12 12:37:15,228 [salt.minion      :1432][INFO    ][12357] Starting a new job with PID 12357
2019-06-12 12:37:16,008 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12357] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-06-12 12:37:16,052 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12357] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-06-12 12:37:16,073 [salt.minion      :1711][INFO    ][12357] Returning information for job: 20190612123715180158
2019-06-12 12:37:16,784 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command state.apply with jid 20190612123716770170
2019-06-12 12:37:16,811 [salt.minion      :1432][INFO    ][12368] Starting a new job with PID 12368
2019-06-12 12:37:24,895 [salt.state       :915 ][INFO    ][12368] Loading fresh modules for state activity
2019-06-12 12:37:25,557 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12368] Executing command 'salt-minion --version' in directory '/root'
2019-06-12 12:37:25,902 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12368] Executing command 'salt-minion --version' in directory '/root'
2019-06-12 12:37:26,991 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12368] Executing command 'salt-minion --version' in directory '/root'
2019-06-12 12:37:27,336 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12368] Executing command 'salt-minion --version' in directory '/root'
2019-06-12 12:37:29,643 [salt.state       :1780][INFO    ][12368] Running state [salt-minion] at time 12:37:29.643637
2019-06-12 12:37:29,644 [salt.state       :1813][INFO    ][12368] Executing state pkg.installed for [salt-minion]
2019-06-12 12:37:29,644 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12368] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-12 12:37:29,766 [salt.state       :300 ][INFO    ][12368] All specified packages are already installed
2019-06-12 12:37:29,767 [salt.state       :1951][INFO    ][12368] Completed state [salt-minion] at time 12:37:29.767043 duration_in_ms=123.407
2019-06-12 12:37:29,767 [salt.state       :1780][INFO    ][12368] Running state [salt_minion_dependency_packages] at time 12:37:29.767422
2019-06-12 12:37:29,767 [salt.state       :1813][INFO    ][12368] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-06-12 12:37:29,778 [salt.state       :300 ][INFO    ][12368] All specified packages are already installed
2019-06-12 12:37:29,779 [salt.state       :1951][INFO    ][12368] Completed state [salt_minion_dependency_packages] at time 12:37:29.778959 duration_in_ms=11.537
2019-06-12 12:37:29,782 [salt.state       :1780][INFO    ][12368] Running state [/etc/salt/minion.d/minion.conf] at time 12:37:29.782231
2019-06-12 12:37:29,782 [salt.state       :1813][INFO    ][12368] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-06-12 12:37:30,060 [salt.state       :300 ][INFO    ][12368] File /etc/salt/minion.d/minion.conf is in the correct state
2019-06-12 12:37:30,061 [salt.state       :1951][INFO    ][12368] Completed state [/etc/salt/minion.d/minion.conf] at time 12:37:30.061009 duration_in_ms=278.778
2019-06-12 12:37:30,061 [salt.state       :1780][INFO    ][12368] Running state [python-netaddr] at time 12:37:30.061331
2019-06-12 12:37:30,061 [salt.state       :1813][INFO    ][12368] Executing state pkg.installed for [python-netaddr]
2019-06-12 12:37:30,071 [salt.state       :300 ][INFO    ][12368] All specified packages are already installed
2019-06-12 12:37:30,071 [salt.state       :1951][INFO    ][12368] Completed state [python-netaddr] at time 12:37:30.071335 duration_in_ms=10.004
2019-06-12 12:37:30,075 [salt.state       :1780][INFO    ][12368] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 12:37:30.075316
2019-06-12 12:37:30,075 [salt.state       :1813][INFO    ][12368] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-06-12 12:37:30,094 [salt.state       :300 ][INFO    ][12368] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-06-12 12:37:30,095 [salt.state       :1951][INFO    ][12368] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 12:37:30.095227 duration_in_ms=19.91
2019-06-12 12:37:30,097 [salt.state       :1780][INFO    ][12368] Running state [salt-minion] at time 12:37:30.097215
2019-06-12 12:37:30,099 [salt.state       :1813][INFO    ][12368] Executing state service.running for [salt-minion]
2019-06-12 12:37:30,100 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12368] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-06-12 12:37:30,142 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12368] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-06-12 12:37:30,161 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12368] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-06-12 12:37:30,182 [salt.state       :300 ][INFO    ][12368] The service salt-minion is already running
2019-06-12 12:37:30,183 [salt.state       :1951][INFO    ][12368] Completed state [salt-minion] at time 12:37:30.183219 duration_in_ms=86.003
2019-06-12 12:37:30,185 [salt.state       :1780][INFO    ][12368] Running state [/etc/salt/grains.d] at time 12:37:30.185263
2019-06-12 12:37:30,187 [salt.state       :1813][INFO    ][12368] Executing state file.directory for [/etc/salt/grains.d]
2019-06-12 12:37:30,189 [salt.state       :300 ][INFO    ][12368] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-06-12 12:37:30,190 [salt.state       :1951][INFO    ][12368] Completed state [/etc/salt/grains.d] at time 12:37:30.190186 duration_in_ms=4.923
2019-06-12 12:37:30,191 [salt.state       :1780][INFO    ][12368] Running state [/etc/salt/grains] at time 12:37:30.191261
2019-06-12 12:37:30,191 [salt.state       :1813][INFO    ][12368] Executing state file.managed for [/etc/salt/grains]
2019-06-12 12:37:30,192 [salt.state       :300 ][INFO    ][12368] File /etc/salt/grains exists with proper permissions. No changes made.
2019-06-12 12:37:30,193 [salt.state       :1951][INFO    ][12368] Completed state [/etc/salt/grains] at time 12:37:30.193001 duration_in_ms=1.739
2019-06-12 12:37:30,195 [salt.state       :1780][INFO    ][12368] Running state [/etc/salt/grains.d/placeholder] at time 12:37:30.195585
2019-06-12 12:37:30,195 [salt.state       :1813][INFO    ][12368] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-06-12 12:37:30,196 [salt.state       :300 ][INFO    ][12368] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-06-12 12:37:30,196 [salt.state       :1951][INFO    ][12368] Completed state [/etc/salt/grains.d/placeholder] at time 12:37:30.196708 duration_in_ms=1.123
2019-06-12 12:37:30,197 [salt.state       :1780][INFO    ][12368] Running state [/etc/salt/grains.d/sphinx] at time 12:37:30.197214
2019-06-12 12:37:30,197 [salt.state       :1813][INFO    ][12368] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-06-12 12:37:30,198 [salt.state       :300 ][INFO    ][12368] File /etc/salt/grains.d/sphinx is in the correct state
2019-06-12 12:37:30,199 [salt.state       :1951][INFO    ][12368] Completed state [/etc/salt/grains.d/sphinx] at time 12:37:30.199051 duration_in_ms=1.837
2019-06-12 12:37:30,201 [salt.state       :1780][INFO    ][12368] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 12:37:30.201157
2019-06-12 12:37:30,201 [salt.state       :1813][INFO    ][12368] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-06-12 12:37:30,201 [salt.state       :300 ][INFO    ][12368] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-06-12 12:37:30,202 [salt.state       :1951][INFO    ][12368] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 12:37:30.202024 duration_in_ms=0.867
2019-06-12 12:37:30,202 [salt.state       :1780][INFO    ][12368] Running state [/etc/salt/grains.d/dns_records] at time 12:37:30.202541
2019-06-12 12:37:30,202 [salt.state       :1813][INFO    ][12368] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-06-12 12:37:30,203 [salt.state       :300 ][INFO    ][12368] File /etc/salt/grains.d/dns_records is in the correct state
2019-06-12 12:37:30,204 [salt.state       :1951][INFO    ][12368] Completed state [/etc/salt/grains.d/dns_records] at time 12:37:30.204119 duration_in_ms=1.578
2019-06-12 12:37:30,205 [salt.state       :1780][INFO    ][12368] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 12:37:30.205070
2019-06-12 12:37:30,205 [salt.state       :1813][INFO    ][12368] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-06-12 12:37:30,207 [salt.state       :300 ][INFO    ][12368] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-06-12 12:37:30,208 [salt.state       :1951][INFO    ][12368] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 12:37:30.208175 duration_in_ms=3.105
2019-06-12 12:37:30,208 [salt.state       :1780][INFO    ][12368] Running state [/etc/salt/grains.d/salt] at time 12:37:30.208686
2019-06-12 12:37:30,209 [salt.state       :1813][INFO    ][12368] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-06-12 12:37:30,210 [salt.state       :300 ][INFO    ][12368] File /etc/salt/grains.d/salt is in the correct state
2019-06-12 12:37:30,210 [salt.state       :1951][INFO    ][12368] Completed state [/etc/salt/grains.d/salt] at time 12:37:30.210343 duration_in_ms=1.658
2019-06-12 12:37:30,211 [salt.state       :1780][INFO    ][12368] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 12:37:30.211257
2019-06-12 12:37:30,211 [salt.state       :1813][INFO    ][12368] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-06-12 12:37:30,211 [salt.state       :300 ][INFO    ][12368] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-06-12 12:37:30,212 [salt.state       :1951][INFO    ][12368] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 12:37:30.212089 duration_in_ms=0.832
2019-06-12 12:37:30,214 [salt.state       :1780][INFO    ][12368] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 12:37:30.214034
2019-06-12 12:37:30,214 [salt.state       :1813][INFO    ][12368] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-06-12 12:37:30,214 [salt.state       :300 ][INFO    ][12368] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-06-12 12:37:30,214 [salt.state       :1951][INFO    ][12368] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 12:37:30.214898 duration_in_ms=0.865
2019-06-12 12:37:30,215 [salt.state       :1780][INFO    ][12368] Running state [mine.update] at time 12:37:30.215586
2019-06-12 12:37:30,215 [salt.state       :1813][INFO    ][12368] Executing state module.wait for [mine.update]
2019-06-12 12:37:30,216 [salt.state       :300 ][INFO    ][12368] No changes made for mine.update
2019-06-12 12:37:30,216 [salt.state       :1951][INFO    ][12368] Completed state [mine.update] at time 12:37:30.216372 duration_in_ms=0.786
2019-06-12 12:37:30,216 [salt.state       :1780][INFO    ][12368] Running state [ca-certificates] at time 12:37:30.216654
2019-06-12 12:37:30,216 [salt.state       :1813][INFO    ][12368] Executing state pkg.installed for [ca-certificates]
2019-06-12 12:37:30,227 [salt.state       :300 ][INFO    ][12368] All specified packages are already installed
2019-06-12 12:37:30,227 [salt.state       :1951][INFO    ][12368] Completed state [ca-certificates] at time 12:37:30.227853 duration_in_ms=11.199
2019-06-12 12:37:30,228 [salt.state       :1780][INFO    ][12368] Running state [update-ca-certificates] at time 12:37:30.228586
2019-06-12 12:37:30,228 [salt.state       :1813][INFO    ][12368] Executing state cmd.wait for [update-ca-certificates]
2019-06-12 12:37:30,229 [salt.state       :300 ][INFO    ][12368] No changes made for update-ca-certificates
2019-06-12 12:37:30,229 [salt.state       :1951][INFO    ][12368] Completed state [update-ca-certificates] at time 12:37:30.229410 duration_in_ms=0.824
2019-06-12 12:37:30,230 [salt.state       :1780][INFO    ][12368] Running state [iptables] at time 12:37:30.229699
2019-06-12 12:37:30,230 [salt.state       :1813][INFO    ][12368] Executing state pkg.installed for [iptables]
2019-06-12 12:37:30,239 [salt.state       :300 ][INFO    ][12368] All specified packages are already installed
2019-06-12 12:37:30,240 [salt.state       :1951][INFO    ][12368] Completed state [iptables] at time 12:37:30.240194 duration_in_ms=10.495
2019-06-12 12:37:30,240 [salt.state       :1780][INFO    ][12368] Running state [iptables-persistent] at time 12:37:30.240484
2019-06-12 12:37:30,240 [salt.state       :1813][INFO    ][12368] Executing state pkg.installed for [iptables-persistent]
2019-06-12 12:37:30,250 [salt.state       :300 ][INFO    ][12368] All specified packages are already installed
2019-06-12 12:37:30,250 [salt.state       :1951][INFO    ][12368] Completed state [iptables-persistent] at time 12:37:30.250700 duration_in_ms=10.216
2019-06-12 12:37:30,251 [salt.state       :1780][INFO    ][12368] Running state [iptables_modules_v4_load] at time 12:37:30.251748
2019-06-12 12:37:30,252 [salt.state       :1813][INFO    ][12368] Executing state kmod.present for [iptables_modules_v4_load]
2019-06-12 12:37:30,252 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12368] Executing command 'lsmod' in directory '/root'
2019-06-12 12:37:30,280 [salt.state       :300 ][INFO    ][12368] Kernel modules iptable_filter, ip_tables are already present
2019-06-12 12:37:30,281 [salt.state       :1951][INFO    ][12368] Completed state [iptables_modules_v4_load] at time 12:37:30.281458 duration_in_ms=29.708
2019-06-12 12:37:30,283 [salt.state       :1780][INFO    ][12368] Running state [/etc/iptables/rules.v4] at time 12:37:30.283159
2019-06-12 12:37:30,283 [salt.state       :1813][INFO    ][12368] Executing state file.managed for [/etc/iptables/rules.v4]
2019-06-12 12:37:30,397 [salt.state       :300 ][INFO    ][12368] File /etc/iptables/rules.v4 is in the correct state
2019-06-12 12:37:30,398 [salt.state       :1951][INFO    ][12368] Completed state [/etc/iptables/rules.v4] at time 12:37:30.397977 duration_in_ms=114.819
2019-06-12 12:37:30,399 [salt.state       :1780][INFO    ][12368] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 12:37:30.399105
2019-06-12 12:37:30,399 [salt.state       :1813][INFO    ][12368] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-06-12 12:37:30,400 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12368] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-06-12 12:37:30,425 [salt.state       :300 ][INFO    ][12368] onlyif execution failed
2019-06-12 12:37:30,426 [salt.state       :1951][INFO    ][12368] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 12:37:30.426151 duration_in_ms=27.045
2019-06-12 12:37:30,428 [salt.state       :1780][INFO    ][12368] Running state [netfilter-persistent] at time 12:37:30.428428
2019-06-12 12:37:30,429 [salt.state       :1813][INFO    ][12368] Executing state service.running for [netfilter-persistent]
2019-06-12 12:37:30,431 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12368] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-06-12 12:37:30,462 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12368] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-06-12 12:37:30,486 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12368] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-06-12 12:37:30,509 [salt.state       :300 ][INFO    ][12368] The service netfilter-persistent is already running
2019-06-12 12:37:30,510 [salt.state       :1951][INFO    ][12368] Completed state [netfilter-persistent] at time 12:37:30.510169 duration_in_ms=81.741
2019-06-12 12:37:30,511 [salt.state       :1780][INFO    ][12368] Running state [iptables_extra.remove_stale_tables] at time 12:37:30.511647
2019-06-12 12:37:30,512 [salt.state       :1813][INFO    ][12368] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-06-12 12:37:30,512 [salt.state       :300 ][INFO    ][12368] No changes made for iptables_extra.remove_stale_tables
2019-06-12 12:37:30,513 [salt.state       :1951][INFO    ][12368] Completed state [iptables_extra.remove_stale_tables] at time 12:37:30.513136 duration_in_ms=1.49
2019-06-12 12:37:30,513 [salt.state       :1780][INFO    ][12368] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 12:37:30.513610
2019-06-12 12:37:30,514 [salt.state       :1813][INFO    ][12368] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-06-12 12:37:30,515 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12368] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-06-12 12:37:30,536 [salt.state       :300 ][INFO    ][12368] onlyif execution failed
2019-06-12 12:37:30,537 [salt.state       :1951][INFO    ][12368] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 12:37:30.537030 duration_in_ms=23.419
2019-06-12 12:37:30,539 [salt.state       :1780][INFO    ][12368] Running state [/etc/iptables/rules.v6] at time 12:37:30.539003
2019-06-12 12:37:30,539 [salt.state       :1813][INFO    ][12368] Executing state file.absent for [/etc/iptables/rules.v6]
2019-06-12 12:37:30,540 [salt.state       :300 ][INFO    ][12368] File /etc/iptables/rules.v6 is not present
2019-06-12 12:37:30,540 [salt.state       :1951][INFO    ][12368] Completed state [/etc/iptables/rules.v6] at time 12:37:30.540758 duration_in_ms=1.755
2019-06-12 12:37:30,544 [salt.state       :1780][INFO    ][12368] Running state [iptables_extra.flush_all] at time 12:37:30.544311
2019-06-12 12:37:30,544 [salt.state       :1813][INFO    ][12368] Executing state module.wait for [iptables_extra.flush_all]
2019-06-12 12:37:30,545 [salt.state       :300 ][INFO    ][12368] No changes made for iptables_extra.flush_all
2019-06-12 12:37:30,545 [salt.state       :1951][INFO    ][12368] Completed state [iptables_extra.flush_all] at time 12:37:30.545386 duration_in_ms=1.075
2019-06-12 12:37:30,549 [salt.minion      :1711][INFO    ][12368] Returning information for job: 20190612123716770170
2019-06-12 12:37:31,266 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command state.apply with jid 20190612123731255697
2019-06-12 12:37:31,292 [salt.minion      :1432][INFO    ][12477] Starting a new job with PID 12477
2019-06-12 12:37:32,444 [salt.state       :915 ][INFO    ][12477] Loading fresh modules for state activity
2019-06-12 12:37:33,762 [salt.state       :1780][INFO    ][12477] Running state [maas-rack-controller] at time 12:37:33.762825
2019-06-12 12:37:33,763 [salt.state       :1813][INFO    ][12477] Executing state pkg.installed for [maas-rack-controller]
2019-06-12 12:37:33,764 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12477] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-12 12:37:33,886 [salt.state       :300 ][INFO    ][12477] All specified packages are already installed
2019-06-12 12:37:33,887 [salt.state       :1951][INFO    ][12477] Completed state [maas-rack-controller] at time 12:37:33.886912 duration_in_ms=124.086
2019-06-12 12:37:33,887 [salt.state       :1780][INFO    ][12477] Running state [ipmitool] at time 12:37:33.887276
2019-06-12 12:37:33,887 [salt.state       :1813][INFO    ][12477] Executing state pkg.installed for [ipmitool]
2019-06-12 12:37:33,896 [salt.state       :300 ][INFO    ][12477] All specified packages are already installed
2019-06-12 12:37:33,897 [salt.state       :1951][INFO    ][12477] Completed state [ipmitool] at time 12:37:33.897099 duration_in_ms=9.823
2019-06-12 12:37:33,900 [salt.state       :1780][INFO    ][12477] Running state [/etc/maas/rackd.conf] at time 12:37:33.900161
2019-06-12 12:37:33,900 [salt.state       :1813][INFO    ][12477] Executing state file.line for [/etc/maas/rackd.conf]
2019-06-12 12:37:33,901 [salt.state       :300 ][INFO    ][12477] No changes needed to be made
2019-06-12 12:37:33,902 [salt.state       :1951][INFO    ][12477] Completed state [/etc/maas/rackd.conf] at time 12:37:33.901679 duration_in_ms=1.518
2019-06-12 12:37:33,902 [salt.state       :1780][INFO    ][12477] Running state [/etc/maas/rackd.conf] at time 12:37:33.902569
2019-06-12 12:37:33,902 [salt.state       :1813][INFO    ][12477] Executing state file.managed for [/etc/maas/rackd.conf]
2019-06-12 12:37:33,903 [salt.loaded.int.states.file:2298][WARNING ][12477] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-06-12 12:37:33,904 [salt.state       :300 ][INFO    ][12477] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-06-12 12:37:33,904 [salt.state       :1951][INFO    ][12477] Completed state [/etc/maas/rackd.conf] at time 12:37:33.904276 duration_in_ms=1.707
2019-06-12 12:37:33,905 [salt.state       :1780][INFO    ][12477] Running state [maas-rackd] at time 12:37:33.905355
2019-06-12 12:37:33,905 [salt.state       :1813][INFO    ][12477] Executing state service.running for [maas-rackd]
2019-06-12 12:37:33,906 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12477] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-06-12 12:37:33,949 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12477] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-06-12 12:37:33,975 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12477] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-06-12 12:37:34,000 [salt.state       :300 ][INFO    ][12477] The service maas-rackd is already running
2019-06-12 12:37:34,001 [salt.state       :1951][INFO    ][12477] Completed state [maas-rackd] at time 12:37:34.001455 duration_in_ms=96.099
2019-06-12 12:37:34,004 [salt.minion      :1711][INFO    ][12477] Returning information for job: 20190612123731255697
2019-06-12 12:37:34,700 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command state.apply with jid 20190612123734688448
2019-06-12 12:37:34,727 [salt.minion      :1432][INFO    ][12500] Starting a new job with PID 12500
2019-06-12 12:37:35,891 [salt.state       :915 ][INFO    ][12500] Loading fresh modules for state activity
2019-06-12 12:37:37,337 [salt.state       :1780][INFO    ][12500] Running state [maas-region-controller] at time 12:37:37.337441
2019-06-12 12:37:37,338 [salt.state       :1813][INFO    ][12500] Executing state pkg.installed for [maas-region-controller]
2019-06-12 12:37:37,339 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12500] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-12 12:37:37,448 [salt.state       :300 ][INFO    ][12500] All specified packages are already installed
2019-06-12 12:37:37,448 [salt.state       :1951][INFO    ][12500] Completed state [maas-region-controller] at time 12:37:37.448865 duration_in_ms=111.427
2019-06-12 12:37:37,450 [salt.state       :1780][INFO    ][12500] Running state [python-oauth] at time 12:37:37.450170
2019-06-12 12:37:37,450 [salt.state       :1813][INFO    ][12500] Executing state pkg.installed for [python-oauth]
2019-06-12 12:37:37,464 [salt.state       :300 ][INFO    ][12500] All specified packages are already installed
2019-06-12 12:37:37,464 [salt.state       :1951][INFO    ][12500] Completed state [python-oauth] at time 12:37:37.464701 duration_in_ms=14.531
2019-06-12 12:37:37,468 [salt.state       :1780][INFO    ][12500] Running state [/etc/maas/regiond.conf] at time 12:37:37.468320
2019-06-12 12:37:37,468 [salt.state       :1813][INFO    ][12500] Executing state file.replace for [/etc/maas/regiond.conf]
2019-06-12 12:37:37,479 [salt.state       :300 ][INFO    ][12500] No changes needed to be made
2019-06-12 12:37:37,479 [salt.state       :1951][INFO    ][12500] Completed state [/etc/maas/regiond.conf] at time 12:37:37.479729 duration_in_ms=11.409
2019-06-12 12:37:37,480 [salt.state       :1780][INFO    ][12500] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 12:37:37.480300
2019-06-12 12:37:37,480 [salt.state       :1813][INFO    ][12500] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-06-12 12:37:37,555 [salt.state       :300 ][INFO    ][12500] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-06-12 12:37:37,555 [salt.state       :1951][INFO    ][12500] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 12:37:37.555768 duration_in_ms=75.468
2019-06-12 12:37:37,556 [salt.state       :1780][INFO    ][12500] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 12:37:37.556306
2019-06-12 12:37:37,556 [salt.state       :1813][INFO    ][12500] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-06-12 12:37:37,560 [salt.state       :300 ][INFO    ][12500] No changes needed to be made
2019-06-12 12:37:37,561 [salt.state       :1951][INFO    ][12500] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 12:37:37.561117 duration_in_ms=4.811
2019-06-12 12:37:37,561 [salt.state       :1780][INFO    ][12500] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 12:37:37.561637
2019-06-12 12:37:37,562 [salt.state       :1813][INFO    ][12500] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-06-12 12:37:37,566 [salt.state       :300 ][INFO    ][12500] No changes needed to be made
2019-06-12 12:37:37,567 [salt.state       :1951][INFO    ][12500] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 12:37:37.566943 duration_in_ms=5.306
2019-06-12 12:37:37,567 [salt.state       :1780][INFO    ][12500] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 12:37:37.567459
2019-06-12 12:37:37,567 [salt.state       :1813][INFO    ][12500] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-06-12 12:37:37,592 [salt.state       :300 ][INFO    ][12500] No changes needed to be made
2019-06-12 12:37:37,592 [salt.state       :1951][INFO    ][12500] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 12:37:37.592538 duration_in_ms=25.079
2019-06-12 12:37:37,593 [salt.state       :1780][INFO    ][12500] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 12:37:37.593064
2019-06-12 12:37:37,593 [salt.state       :1813][INFO    ][12500] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-06-12 12:37:37,611 [salt.state       :300 ][INFO    ][12500] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-06-12 12:37:37,611 [salt.state       :1951][INFO    ][12500] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 12:37:37.611885 duration_in_ms=18.821
2019-06-12 12:37:37,613 [salt.state       :1780][INFO    ][12500] Running state [a2enmod headers] at time 12:37:37.613092
2019-06-12 12:37:37,613 [salt.state       :1813][INFO    ][12500] Executing state cmd.run for [a2enmod headers]
2019-06-12 12:37:37,614 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12500] Executing command 'a2enmod headers' in directory '/root'
2019-06-12 12:37:37,688 [salt.state       :300 ][INFO    ][12500] {'pid': 12523, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-06-12 12:37:37,689 [salt.state       :1951][INFO    ][12500] Completed state [a2enmod headers] at time 12:37:37.689465 duration_in_ms=76.372
2019-06-12 12:37:37,690 [salt.state       :1780][INFO    ][12500] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 12:37:37.690480
2019-06-12 12:37:37,691 [salt.state       :1813][INFO    ][12500] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-06-12 12:37:37,721 [salt.state       :300 ][INFO    ][12500] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-06-12 12:37:37,721 [salt.state       :1951][INFO    ][12500] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 12:37:37.721509 duration_in_ms=31.029
2019-06-12 12:37:37,722 [salt.state       :1780][INFO    ][12500] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 12:37:37.722487
2019-06-12 12:37:37,722 [salt.state       :1813][INFO    ][12500] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-06-12 12:37:37,791 [salt.state       :300 ][INFO    ][12500] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-06-12 12:37:37,791 [salt.state       :1951][INFO    ][12500] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 12:37:37.791387 duration_in_ms=68.899
2019-06-12 12:37:37,792 [salt.state       :1780][INFO    ][12500] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 12:37:37.792213
2019-06-12 12:37:37,792 [salt.state       :1813][INFO    ][12500] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-06-12 12:37:37,863 [salt.state       :300 ][INFO    ][12500] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-06-12 12:37:37,863 [salt.state       :1951][INFO    ][12500] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 12:37:37.863531 duration_in_ms=71.319
2019-06-12 12:37:37,864 [salt.state       :1780][INFO    ][12500] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 12:37:37.864146
2019-06-12 12:37:37,864 [salt.state       :1813][INFO    ][12500] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-06-12 12:37:37,943 [salt.state       :300 ][INFO    ][12500] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-06-12 12:37:37,943 [salt.state       :1951][INFO    ][12500] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 12:37:37.943465 duration_in_ms=79.319
2019-06-12 12:37:37,943 [salt.state       :1780][INFO    ][12500] Running state [/root/.pgpass] at time 12:37:37.943768
2019-06-12 12:37:37,944 [salt.state       :1813][INFO    ][12500] Executing state file.managed for [/root/.pgpass]
2019-06-12 12:37:37,993 [salt.state       :300 ][INFO    ][12500] File /root/.pgpass is in the correct state
2019-06-12 12:37:37,993 [salt.state       :1951][INFO    ][12500] Completed state [/root/.pgpass] at time 12:37:37.993533 duration_in_ms=49.764
2019-06-12 12:37:37,999 [salt.state       :1780][INFO    ][12500] Running state [maas-region syncdb --noinput] at time 12:37:37.999010
2019-06-12 12:37:37,999 [salt.state       :1813][INFO    ][12500] Executing state cmd.run for [maas-region syncdb --noinput]
2019-06-12 12:37:37,999 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12500] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-06-12 12:37:40,756 [salt.state       :300 ][INFO    ][12500] {'pid': 12536, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: maasserver, contenttypes, piston3, metadataserver, sessions, auth, sites\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-06-12 12:37:40,757 [salt.state       :1951][INFO    ][12500] Completed state [maas-region syncdb --noinput] at time 12:37:40.757426 duration_in_ms=2758.414
2019-06-12 12:37:40,759 [salt.state       :2022][WARNING ][12500] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-06-12 12:37:40,763 [salt.state       :1780][INFO    ][12500] Running state [maas-regiond] at time 12:37:40.763010
2019-06-12 12:37:40,763 [salt.state       :1813][INFO    ][12500] Executing state service.running for [maas-regiond]
2019-06-12 12:37:40,765 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12500] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-06-12 12:37:40,811 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12500] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-06-12 12:37:40,836 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12500] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-06-12 12:37:40,861 [salt.state       :300 ][INFO    ][12500] The service maas-regiond is already running
2019-06-12 12:37:40,864 [salt.state       :1951][INFO    ][12500] Completed state [maas-regiond] at time 12:37:40.863970 duration_in_ms=100.944
2019-06-12 12:37:40,867 [salt.state       :1780][INFO    ][12500] Running state [bind9] at time 12:37:40.867685
2019-06-12 12:37:40,868 [salt.state       :1813][INFO    ][12500] Executing state service.running for [bind9]
2019-06-12 12:37:40,869 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12500] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-06-12 12:37:40,896 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12500] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-06-12 12:37:40,921 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12500] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-06-12 12:37:40,944 [salt.state       :300 ][INFO    ][12500] The service bind9 is already running
2019-06-12 12:37:40,945 [salt.state       :1951][INFO    ][12500] Completed state [bind9] at time 12:37:40.945424 duration_in_ms=77.737
2019-06-12 12:37:40,950 [salt.state       :1780][INFO    ][12500] Running state [apache2] at time 12:37:40.950464
2019-06-12 12:37:40,951 [salt.state       :1813][INFO    ][12500] Executing state service.running for [apache2]
2019-06-12 12:37:40,952 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12500] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-06-12 12:37:40,976 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12500] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-06-12 12:37:40,998 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12500] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-06-12 12:37:41,024 [salt.state       :300 ][INFO    ][12500] The service apache2 is already running
2019-06-12 12:37:41,025 [salt.state       :1951][INFO    ][12500] Completed state [apache2] at time 12:37:41.025320 duration_in_ms=74.856
2019-06-12 12:37:41,028 [salt.state       :1780][INFO    ][12500] Running state [maasng.wait_for_http_code] at time 12:37:41.027946
2019-06-12 12:37:41,028 [salt.state       :1813][INFO    ][12500] Executing state module.run for [maasng.wait_for_http_code]
2019-06-12 12:37:41,029 [salt.utils.decorators:613 ][WARNING ][12500] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-12 12:37:41,178 [salt.state       :300 ][INFO    ][12500] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-06-12 12:37:41,179 [salt.state       :1951][INFO    ][12500] Completed state [maasng.wait_for_http_code] at time 12:37:41.178991 duration_in_ms=151.045
2019-06-12 12:37:41,180 [salt.state       :1780][INFO    ][12500] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 12:37:41.180229
2019-06-12 12:37:41,180 [salt.state       :1813][INFO    ][12500] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-06-12 12:37:41,181 [salt.state       :300 ][INFO    ][12500] /var/lib/maas/.setup_admin exists
2019-06-12 12:37:41,182 [salt.state       :1951][INFO    ][12500] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 12:37:41.182405 duration_in_ms=2.176
2019-06-12 12:37:41,183 [salt.state       :1780][INFO    ][12500] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 12:37:41.183409
2019-06-12 12:37:41,183 [salt.state       :1813][INFO    ][12500] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-12 12:37:41,184 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12500] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-12 12:37:43,025 [salt.state       :300 ][INFO    ][12500] {'pid': 12557, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-12 12:37:43,026 [salt.state       :1951][INFO    ][12500] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 12:37:43.026622 duration_in_ms=1843.212
2019-06-12 12:37:43,035 [salt.state       :1780][INFO    ][12500] Running state [maas_region_boot_source_resources_mirror] at time 12:37:43.035099
2019-06-12 12:37:43,035 [salt.state       :1813][INFO    ][12500] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-06-12 12:37:43,133 [salt.state       :300 ][INFO    ][12500] {'changes': {}}
2019-06-12 12:37:43,134 [salt.state       :1951][INFO    ][12500] Completed state [maas_region_boot_source_resources_mirror] at time 12:37:43.134085 duration_in_ms=98.985
2019-06-12 12:37:43,135 [salt.state       :1780][INFO    ][12500] Running state [maasng.boot_resources_import] at time 12:37:43.135216
2019-06-12 12:37:43,135 [salt.state       :1813][INFO    ][12500] Executing state module.run for [maasng.boot_resources_import]
2019-06-12 12:37:43,136 [salt.utils.decorators:613 ][WARNING ][12500] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-12 12:37:43,223 [salt.loaded.ext.module.maasng:1600][INFO    ][12500] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-06-12 12:37:48,272 [salt.loaded.ext.module.maasng:1600][INFO    ][12500] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-06-12 12:37:49,833 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612123749816743
2019-06-12 12:37:49,863 [salt.minion      :1432][INFO    ][12584] Starting a new job with PID 12584
2019-06-12 12:37:49,894 [salt.minion      :1711][INFO    ][12584] Returning information for job: 20190612123749816743
2019-06-12 12:37:53,376 [salt.state       :300 ][INFO    ][12500] {'ret': True}
2019-06-12 12:37:53,376 [salt.state       :1951][INFO    ][12500] Completed state [maasng.boot_resources_import] at time 12:37:53.376780 duration_in_ms=10241.562
2019-06-12 12:37:53,379 [salt.state       :1780][INFO    ][12500] Running state [maas_region_boot_sources_selection_xenial] at time 12:37:53.379613
2019-06-12 12:37:53,380 [salt.state       :1813][INFO    ][12500] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-06-12 12:37:53,577 [salt.state       :300 ][INFO    ][12500] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-06-12 12:37:53,578 [salt.state       :1951][INFO    ][12500] Completed state [maas_region_boot_sources_selection_xenial] at time 12:37:53.578090 duration_in_ms=198.477
2019-06-12 12:37:53,579 [salt.state       :1780][INFO    ][12500] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 12:37:53.579640
2019-06-12 12:37:53,580 [salt.state       :1813][INFO    ][12500] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-06-12 12:37:53,580 [salt.utils.decorators:613 ][WARNING ][12500] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-12 12:37:53,581 [salt.loaded.ext.module.maasng:1771][INFO    ][12500] boot-sources sync initiated for ALL Rack's
2019-06-12 12:37:54,647 [salt.state       :300 ][INFO    ][12500] {'ret': True}
2019-06-12 12:37:54,648 [salt.state       :1951][INFO    ][12500] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 12:37:54.648347 duration_in_ms=1068.707
2019-06-12 12:37:54,651 [salt.state       :1780][INFO    ][12500] Running state [maas.process_maas_config] at time 12:37:54.651321
2019-06-12 12:37:54,651 [salt.state       :1813][INFO    ][12500] Executing state module.run for [maas.process_maas_config]
2019-06-12 12:37:54,652 [salt.utils.decorators:613 ][WARNING ][12500] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-12 12:37:54,652 [salt.loaded.ext.module.maas:92  ][INFO    ][12500] maasconfig name=enable_http_proxy value=True
2019-06-12 12:37:54,708 [salt.loaded.ext.module.maas:92  ][INFO    ][12500] maasconfig name=upstream_dns value=8.8.8.8
2019-06-12 12:37:56,048 [salt.loaded.ext.module.maas:92  ][INFO    ][12500] maasconfig name=commissioning_distro_series value=xenial
2019-06-12 12:37:56,113 [salt.loaded.ext.module.maas:92  ][INFO    ][12500] maasconfig name=default_osystem value=ubuntu
2019-06-12 12:37:56,172 [salt.loaded.ext.module.maas:92  ][INFO    ][12500] maasconfig name=active_discovery_interval value=600
2019-06-12 12:37:56,233 [salt.loaded.ext.module.maas:92  ][INFO    ][12500] maasconfig name=dnssec_validation value=no
2019-06-12 12:37:56,284 [salt.loaded.ext.module.maas:92  ][INFO    ][12500] maasconfig name=maas_name value=mas01
2019-06-12 12:37:56,333 [salt.loaded.ext.module.maas:92  ][INFO    ][12500] maasconfig name=network_discovery value=enabled
2019-06-12 12:37:56,432 [salt.loaded.ext.module.maas:92  ][INFO    ][12500] maasconfig name=enable_third_party_drivers value=True
2019-06-12 12:37:56,492 [salt.loaded.ext.module.maas:92  ][INFO    ][12500] maasconfig name=default_storage_layout value=lvm
2019-06-12 12:37:56,556 [salt.loaded.ext.module.maas:92  ][INFO    ][12500] maasconfig name=ntp_external_only value=True
2019-06-12 12:37:56,610 [salt.loaded.ext.module.maas:92  ][INFO    ][12500] maasconfig name=disk_erase_with_secure_erase value=False
2019-06-12 12:37:56,668 [salt.loaded.ext.module.maas:92  ][INFO    ][12500] maasconfig name=default_distro_series value=xenial
2019-06-12 12:37:56,741 [salt.loaded.ext.module.maas:92  ][INFO    ][12500] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-06-12 12:37:56,876 [salt.state       :300 ][INFO    ][12500] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-06-12 12:37:56,876 [salt.state       :1951][INFO    ][12500] Completed state [maas.process_maas_config] at time 12:37:56.876700 duration_in_ms=2225.379
2019-06-12 12:37:56,878 [salt.state       :1780][INFO    ][12500] Running state [pxe_admin] at time 12:37:56.877682
2019-06-12 12:37:56,879 [salt.state       :1813][INFO    ][12500] Executing state maasng.fabric_present for [pxe_admin]
2019-06-12 12:37:56,938 [salt.loaded.ext.module.maasng:945 ][INFO    ][12500] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'8qwgpg', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}]
2019-06-12 12:37:57,011 [salt.loaded.ext.module.maasng:1008][WARNING ][12500] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-06-12 12:37:57,012 [salt.loaded.ext.module.maasng:1011][WARNING ][12500] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-06-12 12:37:57,075 [salt.state       :300 ][INFO    ][12500] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-06-12 12:37:57,075 [salt.state       :1951][INFO    ][12500] Completed state [pxe_admin] at time 12:37:57.075871 duration_in_ms=198.187
2019-06-12 12:37:57,076 [salt.state       :1780][INFO    ][12500] Running state [vlan 0] at time 12:37:57.076391
2019-06-12 12:37:57,076 [salt.state       :1813][INFO    ][12500] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-06-12 12:37:57,139 [salt.loaded.ext.module.maasng:945 ][INFO    ][12500] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'8qwgpg', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}]
2019-06-12 12:37:57,256 [salt.loaded.ext.module.maasng:945 ][INFO    ][12500] [{u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'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'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0, u'class_type': None, u'name': u'fabric-0'}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2, u'class_type': None, u'name': u'fabric-2'}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'8qwgpg', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1, u'class_type': u'', u'name': u'pxe_admin'}]
2019-06-12 12:37:57,530 [salt.loaded.ext.module.maasng:945 ][INFO    ][12500] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'8qwgpg', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-06-12 12:37:57,618 [salt.state       :300 ][INFO    ][12500] {'new': 'Vlan untagged was updated'}
2019-06-12 12:37:57,619 [salt.state       :1951][INFO    ][12500] Completed state [vlan 0] at time 12:37:57.619241 duration_in_ms=542.848
2019-06-12 12:37:57,620 [salt.state       :1780][INFO    ][12500] Running state [192.168.11.0/24] at time 12:37:57.620796
2019-06-12 12:37:57,621 [salt.state       :1813][INFO    ][12500] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-06-12 12:37:57,822 [salt.loaded.ext.module.maasng:945 ][INFO    ][12500] [{u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'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'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0, u'class_type': None, u'name': u'fabric-0'}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2, u'class_type': None, u'name': u'fabric-2'}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'8qwgpg', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1, u'class_type': u'', u'name': u'pxe_admin'}]
2019-06-12 12:37:57,822 [salt.loaded.ext.module.maasng:1235][WARNING ][12500] Ignoring parameter vlan:0
2019-06-12 12:37:57,888 [salt.state       :300 ][INFO    ][12500] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-06-12 12:37:57,889 [salt.state       :1951][INFO    ][12500] Completed state [192.168.11.0/24] at time 12:37:57.888987 duration_in_ms=268.192
2019-06-12 12:37:57,891 [salt.state       :1780][INFO    ][12500] Running state [maas_create_iprange_1] at time 12:37:57.891723
2019-06-12 12:37:57,892 [salt.state       :1813][INFO    ][12500] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-06-12 12:37:57,936 [salt.state       :300 ][INFO    ][12500] Iprange maas_create_iprange_1 already exist.
2019-06-12 12:37:57,936 [salt.state       :1951][INFO    ][12500] Completed state [maas_create_iprange_1] at time 12:37:57.936556 duration_in_ms=44.833
2019-06-12 12:37:57,936 [salt.state       :1780][INFO    ][12500] Running state [vlan 0] at time 12:37:57.936862
2019-06-12 12:37:57,937 [salt.state       :1813][INFO    ][12500] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-06-12 12:37:57,983 [salt.loaded.ext.module.maasng:945 ][INFO    ][12500] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'8qwgpg', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}]
2019-06-12 12:37:58,081 [salt.loaded.ext.module.maasng:945 ][INFO    ][12500] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'8qwgpg', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}]
2019-06-12 12:37:58,338 [salt.loaded.ext.module.maasng:945 ][INFO    ][12500] [{u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'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'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0, u'class_type': None, u'name': u'fabric-0'}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2, u'class_type': None, u'name': u'fabric-2'}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'8qwgpg', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1, u'class_type': u'', u'name': u'pxe_admin'}]
2019-06-12 12:37:58,444 [salt.state       :300 ][INFO    ][12500] {'new': 'Vlan untagged was updated'}
2019-06-12 12:37:58,444 [salt.state       :1951][INFO    ][12500] Completed state [vlan 0] at time 12:37:58.444366 duration_in_ms=507.503
2019-06-12 12:37:58,445 [salt.state       :1780][INFO    ][12500] Running state [opnfv] at time 12:37:58.445386
2019-06-12 12:37:58,447 [salt.state       :1813][INFO    ][12500] Executing state maasng.sshkey_present for [opnfv]
2019-06-12 12:37:58,499 [salt.loaded.ext.module.maasng:1903][INFO    ][12500] [{u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b', u'keysource': u''}]
2019-06-12 12:37:58,499 [salt.state       :300 ][INFO    ][12500] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-06-12 12:37:58,500 [salt.state       :1951][INFO    ][12500] Completed state [opnfv] at time 12:37:58.500025 duration_in_ms=54.639
2019-06-12 12:37:58,500 [salt.state       :1780][INFO    ][12500] Running state [maas.process_tags] at time 12:37:58.500742
2019-06-12 12:37:58,501 [salt.state       :1813][INFO    ][12500] Executing state module.run for [maas.process_tags]
2019-06-12 12:37:58,501 [salt.utils.decorators:613 ][WARNING ][12500] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-12 12:37:58,553 [salt.loaded.ext.module.maas:92  ][INFO    ][12500] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-06-12 12:37:58,616 [salt.state       :300 ][INFO    ][12500] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-06-12 12:37:58,617 [salt.state       :1951][INFO    ][12500] Completed state [maas.process_tags] at time 12:37:58.617169 duration_in_ms=116.426
2019-06-12 12:37:58,620 [salt.minion      :1711][INFO    ][12500] Returning information for job: 20190612123734688448
2019-06-12 12:37:59,383 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command state.apply with jid 20190612123759371312
2019-06-12 12:37:59,405 [salt.minion      :1432][INFO    ][12943] Starting a new job with PID 12943
2019-06-12 12:38:07,186 [salt.state       :915 ][INFO    ][12943] Loading fresh modules for state activity
2019-06-12 12:38:07,301 [salt.state       :1780][INFO    ][12943] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 12:38:07.300998
2019-06-12 12:38:07,302 [salt.state       :1813][INFO    ][12943] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-12 12:38:07,306 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12943] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-12 12:38:09,167 [salt.state       :300 ][INFO    ][12943] {'pid': 12979, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-12 12:38:09,168 [salt.state       :1951][INFO    ][12943] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 12:38:09.168366 duration_in_ms=1867.369
2019-06-12 12:38:09,172 [salt.state       :1780][INFO    ][12943] Running state [maas.process_machines] at time 12:38:09.172844
2019-06-12 12:38:09,173 [salt.state       :1813][INFO    ][12943] Executing state module.run for [maas.process_machines]
2019-06-12 12:38:09,174 [salt.utils.decorators:613 ][WARNING ][12943] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-12 12:38:09,723 [salt.loaded.ext.module.maas:412 ][WARNING ][12943] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-12 12:38:09,725 [salt.loaded.ext.module.maas:92  ][INFO    ][12943] 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=8qp7xg architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-12 12:38:10,994 [salt.loaded.ext.module.maas:412 ][WARNING ][12943] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-12 12:38:10,995 [salt.loaded.ext.module.maas:92  ][INFO    ][12943] 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=hwahxa architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-12 12:38:12,223 [salt.loaded.ext.module.maas:412 ][WARNING ][12943] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-12 12:38:12,224 [salt.loaded.ext.module.maas:92  ][INFO    ][12943] 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=h3qhg6 architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-12 12:38:13,604 [salt.loaded.ext.module.maas:412 ][WARNING ][12943] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-12 12:38:13,606 [salt.loaded.ext.module.maas:92  ][INFO    ][12943] 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=me8r3f architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-12 12:38:14,501 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612123814490643
2019-06-12 12:38:14,527 [salt.minion      :1432][INFO    ][13151] Starting a new job with PID 13151
2019-06-12 12:38:14,564 [salt.minion      :1711][INFO    ][13151] Returning information for job: 20190612123814490643
2019-06-12 12:38:14,859 [salt.loaded.ext.module.maas:412 ][WARNING ][12943] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-12 12:38:14,860 [salt.loaded.ext.module.maas:92  ][INFO    ][12943] 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=nrbfpc architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-12 12:38:16,083 [salt.state       :300 ][INFO    ][12943] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-06-12 12:38:16,084 [salt.state       :1951][INFO    ][12943] Completed state [maas.process_machines] at time 12:38:16.084268 duration_in_ms=6911.422
2019-06-12 12:38:16,088 [salt.minion      :1711][INFO    ][12943] Returning information for job: 20190612123759371312
2019-06-12 12:38:50,060 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command state.apply with jid 20190612123850047104
2019-06-12 12:38:50,092 [salt.minion      :1432][INFO    ][13238] Starting a new job with PID 13238
2019-06-12 12:38:57,850 [salt.state       :915 ][INFO    ][13238] Loading fresh modules for state activity
2019-06-12 12:38:57,960 [salt.state       :1780][INFO    ][13238] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 12:38:57.960593
2019-06-12 12:38:57,961 [salt.state       :1813][INFO    ][13238] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-12 12:38:57,963 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13238] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-12 12:38:59,929 [salt.state       :300 ][INFO    ][13238] {'pid': 13253, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-12 12:38:59,931 [salt.state       :1951][INFO    ][13238] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 12:38:59.930783 duration_in_ms=1970.189
2019-06-12 12:38:59,934 [salt.state       :1780][INFO    ][13238] Running state [maas.wait_for_machine_status] at time 12:38:59.934300
2019-06-12 12:38:59,935 [salt.state       :1813][INFO    ][13238] Executing state module.run for [maas.wait_for_machine_status]
2019-06-12 12:38:59,936 [salt.utils.decorators:613 ][WARNING ][13238] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-12 12:39:02,716 [salt.state       :300 ][INFO    ][13238] {'ret': True}
2019-06-12 12:39:02,717 [salt.state       :1951][INFO    ][13238] Completed state [maas.wait_for_machine_status] at time 12:39:02.717193 duration_in_ms=2782.891
2019-06-12 12:39:02,721 [salt.minion      :1711][INFO    ][13238] Returning information for job: 20190612123850047104
2019-06-12 12:39:03,438 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command state.apply with jid 20190612123903425658
2019-06-12 12:39:03,465 [salt.minion      :1432][INFO    ][13266] Starting a new job with PID 13266
2019-06-12 12:39:04,599 [salt.state       :915 ][INFO    ][13266] Loading fresh modules for state activity
2019-06-12 12:39:04,772 [salt.state       :1780][INFO    ][13266] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 12:39:04.772116
2019-06-12 12:39:04,772 [salt.state       :1813][INFO    ][13266] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-12 12:39:04,775 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13266] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-12 12:39:06,654 [salt.state       :300 ][INFO    ][13266] {'pid': 13273, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-12 12:39:06,656 [salt.state       :1951][INFO    ][13266] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 12:39:06.655918 duration_in_ms=1883.8
2019-06-12 12:39:06,660 [salt.state       :1780][INFO    ][13266] Running state [maas_machines_storage_cmp002_lvm] at time 12:39:06.660391
2019-06-12 12:39:06,661 [salt.state       :1813][INFO    ][13266] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-06-12 12:39:07,216 [salt.state       :300 ][INFO    ][13266] Machine cmp002 is not in Ready state.
2019-06-12 12:39:07,217 [salt.state       :1951][INFO    ][13266] Completed state [maas_machines_storage_cmp002_lvm] at time 12:39:07.217004 duration_in_ms=556.613
2019-06-12 12:39:07,217 [salt.state       :1780][INFO    ][13266] Running state [maas_machines_storage_cmp001_lvm] at time 12:39:07.217439
2019-06-12 12:39:07,217 [salt.state       :1813][INFO    ][13266] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-06-12 12:39:07,756 [salt.state       :300 ][INFO    ][13266] Machine cmp001 is not in Ready state.
2019-06-12 12:39:07,756 [salt.state       :1951][INFO    ][13266] Completed state [maas_machines_storage_cmp001_lvm] at time 12:39:07.756603 duration_in_ms=539.163
2019-06-12 12:39:07,760 [salt.minion      :1711][INFO    ][13266] Returning information for job: 20190612123903425658
2019-06-12 12:39:08,461 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command state.apply with jid 20190612123908452622
2019-06-12 12:39:08,484 [salt.minion      :1432][INFO    ][13297] Starting a new job with PID 13297
2019-06-12 12:39:09,628 [salt.state       :915 ][INFO    ][13297] Loading fresh modules for state activity
2019-06-12 12:39:09,748 [salt.state       :1780][INFO    ][13297] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 12:39:09.748450
2019-06-12 12:39:09,748 [salt.state       :1813][INFO    ][13297] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-12 12:39:09,751 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13297] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-12 12:39:11,616 [salt.state       :300 ][INFO    ][13297] {'pid': 13304, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-12 12:39:11,616 [salt.state       :1951][INFO    ][13297] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 12:39:11.616595 duration_in_ms=1868.146
2019-06-12 12:39:11,618 [salt.state       :1780][INFO    ][13297] Running state [maas.deploy_machines] at time 12:39:11.618590
2019-06-12 12:39:11,619 [salt.state       :1813][INFO    ][13297] Executing state module.run for [maas.deploy_machines]
2019-06-12 12:39:11,619 [salt.utils.decorators:613 ][WARNING ][13297] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-12 12:39:12,187 [salt.loaded.ext.module.maas:684 ][INFO    ][13297] deploymachines hwe_kernel=hwe-16.04 system_id=nrbfpc distro_series=xenial
2019-06-12 12:39:14,624 [salt.state       :300 ][INFO    ][13297] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-06-12 12:39:14,624 [salt.state       :1951][INFO    ][13297] Completed state [maas.deploy_machines] at time 12:39:14.624651 duration_in_ms=3006.06
2019-06-12 12:39:14,629 [salt.minion      :1711][INFO    ][13297] Returning information for job: 20190612123908452622
2019-06-12 12:39:15,344 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command state.apply with jid 20190612123915329323
2019-06-12 12:39:15,373 [salt.minion      :1432][INFO    ][13361] Starting a new job with PID 13361
2019-06-12 12:39:23,310 [salt.state       :915 ][INFO    ][13361] Loading fresh modules for state activity
2019-06-12 12:39:23,415 [salt.state       :1780][INFO    ][13361] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 12:39:23.415527
2019-06-12 12:39:23,415 [salt.state       :1813][INFO    ][13361] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-12 12:39:23,417 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13361] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-12 12:39:25,312 [salt.state       :300 ][INFO    ][13361] {'pid': 13395, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-12 12:39:25,313 [salt.state       :1951][INFO    ][13361] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 12:39:25.313298 duration_in_ms=1897.771
2019-06-12 12:39:25,316 [salt.state       :1780][INFO    ][13361] Running state [maas.wait_for_machine_status] at time 12:39:25.316751
2019-06-12 12:39:25,317 [salt.state       :1813][INFO    ][13361] Executing state module.run for [maas.wait_for_machine_status]
2019-06-12 12:39:25,319 [salt.utils.decorators:613 ][WARNING ][13361] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-12 12:39:28,210 [salt.loaded.ext.module.maas:1023][INFO    ][13361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.13084912s left)
2019-06-12 12:39:30,392 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612123930382640
2019-06-12 12:39:30,415 [salt.minion      :1432][INFO    ][13417] Starting a new job with PID 13417
2019-06-12 12:39:30,448 [salt.minion      :1711][INFO    ][13417] Returning information for job: 20190612123930382640
2019-06-12 12:40:00,504 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612124000444480
2019-06-12 12:40:00,532 [salt.minion      :1432][INFO    ][13438] Starting a new job with PID 13438
2019-06-12 12:40:00,560 [salt.minion      :1711][INFO    ][13438] Returning information for job: 20190612124000444480
2019-06-12 12:40:01,079 [salt.loaded.ext.module.maas:1023][INFO    ][13361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.26191211s left)
2019-06-12 12:40:30,623 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612124030606571
2019-06-12 12:40:30,652 [salt.minion      :1432][INFO    ][13511] Starting a new job with PID 13511
2019-06-12 12:40:30,684 [salt.minion      :1711][INFO    ][13511] Returning information for job: 20190612124030606571
2019-06-12 12:40:33,909 [salt.loaded.ext.module.maas:1023][INFO    ][13361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2181.43224716s left)
2019-06-12 12:41:00,729 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612124100714355
2019-06-12 12:41:00,761 [salt.minion      :1432][INFO    ][13533] Starting a new job with PID 13533
2019-06-12 12:41:00,795 [salt.minion      :1711][INFO    ][13533] Returning information for job: 20190612124100714355
2019-06-12 12:41:06,785 [salt.loaded.ext.module.maas:1023][INFO    ][13361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2148.55612612s left)
2019-06-12 12:41:30,829 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612124130818983
2019-06-12 12:41:30,857 [salt.minion      :1432][INFO    ][13592] Starting a new job with PID 13592
2019-06-12 12:41:30,887 [salt.minion      :1711][INFO    ][13592] Returning information for job: 20190612124130818983
2019-06-12 12:41:39,527 [salt.loaded.ext.module.maas:1023][INFO    ][13361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2115.81399012s left)
2019-06-12 12:42:00,917 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612124200901668
2019-06-12 12:42:00,942 [salt.minion      :1432][INFO    ][13612] Starting a new job with PID 13612
2019-06-12 12:42:00,970 [salt.minion      :1711][INFO    ][13612] Returning information for job: 20190612124200901668
2019-06-12 12:42:12,331 [salt.loaded.ext.module.maas:1023][INFO    ][13361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2083.0099082s left)
2019-06-12 12:42:31,016 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612124231002088
2019-06-12 12:42:31,048 [salt.minion      :1432][INFO    ][13671] Starting a new job with PID 13671
2019-06-12 12:42:31,079 [salt.minion      :1711][INFO    ][13671] Returning information for job: 20190612124231002088
2019-06-12 12:42:45,039 [salt.loaded.ext.module.maas:1023][INFO    ][13361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2050.30241013s left)
2019-06-12 12:43:01,110 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612124301098285
2019-06-12 12:43:01,138 [salt.minion      :1432][INFO    ][13691] Starting a new job with PID 13691
2019-06-12 12:43:01,170 [salt.minion      :1711][INFO    ][13691] Returning information for job: 20190612124301098285
2019-06-12 12:43:18,130 [salt.loaded.ext.module.maas:1023][INFO    ][13361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2017.21121407s left)
2019-06-12 12:43:31,221 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612124331209783
2019-06-12 12:43:31,251 [salt.minion      :1432][INFO    ][13750] Starting a new job with PID 13750
2019-06-12 12:43:31,275 [salt.minion      :1711][INFO    ][13750] Returning information for job: 20190612124331209783
2019-06-12 12:43:50,967 [salt.loaded.ext.module.maas:1023][INFO    ][13361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1984.37424016s left)
2019-06-12 12:44:01,331 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612124401315946
2019-06-12 12:44:01,360 [salt.minion      :1432][INFO    ][13796] Starting a new job with PID 13796
2019-06-12 12:44:01,383 [salt.minion      :1711][INFO    ][13796] Returning information for job: 20190612124401315946
2019-06-12 12:44:24,333 [salt.loaded.ext.module.maas:1023][INFO    ][13361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1951.00803018s left)
2019-06-12 12:44:31,461 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612124431445516
2019-06-12 12:44:31,487 [salt.minion      :1432][INFO    ][13951] Starting a new job with PID 13951
2019-06-12 12:44:31,510 [salt.minion      :1711][INFO    ][13951] Returning information for job: 20190612124431445516
2019-06-12 12:44:57,219 [salt.loaded.ext.module.maas:1023][INFO    ][13361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1918.12173104s left)
2019-06-12 12:45:01,568 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612124501556714
2019-06-12 12:45:01,590 [salt.minion      :1432][INFO    ][13975] Starting a new job with PID 13975
2019-06-12 12:45:01,614 [salt.minion      :1711][INFO    ][13975] Returning information for job: 20190612124501556714
2019-06-12 12:45:30,029 [salt.loaded.ext.module.maas:1023][INFO    ][13361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1885.31275606s left)
2019-06-12 12:45:31,691 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612124531677122
2019-06-12 12:45:31,721 [salt.minion      :1432][INFO    ][14124] Starting a new job with PID 14124
2019-06-12 12:45:31,743 [salt.minion      :1711][INFO    ][14124] Returning information for job: 20190612124531677122
2019-06-12 12:46:01,808 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612124601796427
2019-06-12 12:46:01,831 [salt.minion      :1432][INFO    ][14146] Starting a new job with PID 14146
2019-06-12 12:46:01,854 [salt.minion      :1711][INFO    ][14146] Returning information for job: 20190612124601796427
2019-06-12 12:46:03,033 [salt.loaded.ext.module.maas:1023][INFO    ][13361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1852.30839705s left)
2019-06-12 12:46:31,940 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612124631928788
2019-06-12 12:46:31,969 [salt.minion      :1432][INFO    ][14253] Starting a new job with PID 14253
2019-06-12 12:46:31,996 [salt.minion      :1711][INFO    ][14253] Returning information for job: 20190612124631928788
2019-06-12 12:46:36,088 [salt.loaded.ext.module.maas:1023][INFO    ][13361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1819.25314403s left)
2019-06-12 12:47:02,108 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612124702094999
2019-06-12 12:47:02,139 [salt.minion      :1432][INFO    ][14274] Starting a new job with PID 14274
2019-06-12 12:47:02,160 [salt.minion      :1711][INFO    ][14274] Returning information for job: 20190612124702094999
2019-06-12 12:47:09,012 [salt.loaded.ext.module.maas:1023][INFO    ][13361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1786.32884598s left)
2019-06-12 12:47:32,261 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612124732243465
2019-06-12 12:47:32,290 [salt.minion      :1432][INFO    ][14474] Starting a new job with PID 14474
2019-06-12 12:47:32,314 [salt.minion      :1711][INFO    ][14474] Returning information for job: 20190612124732243465
2019-06-12 12:47:41,974 [salt.loaded.ext.module.maas:1023][INFO    ][13361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1753.36733913s left)
2019-06-12 12:48:02,439 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612124802423478
2019-06-12 12:48:02,463 [salt.minion      :1432][INFO    ][14493] Starting a new job with PID 14493
2019-06-12 12:48:02,489 [salt.minion      :1711][INFO    ][14493] Returning information for job: 20190612124802423478
2019-06-12 12:48:14,755 [salt.loaded.ext.module.maas:1023][INFO    ][13361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1720.58576512s left)
2019-06-12 12:48:32,598 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612124832582400
2019-06-12 12:48:32,627 [salt.minion      :1432][INFO    ][14549] Starting a new job with PID 14549
2019-06-12 12:48:32,651 [salt.minion      :1711][INFO    ][14549] Returning information for job: 20190612124832582400
2019-06-12 12:48:47,607 [salt.loaded.ext.module.maas:1023][INFO    ][13361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1687.73429513s left)
2019-06-12 12:49:02,770 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612124902758296
2019-06-12 12:49:02,795 [salt.minion      :1432][INFO    ][14569] Starting a new job with PID 14569
2019-06-12 12:49:02,823 [salt.minion      :1711][INFO    ][14569] Returning information for job: 20190612124902758296
2019-06-12 12:49:20,569 [salt.loaded.ext.module.maas:1023][INFO    ][13361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1654.77204108s left)
2019-06-12 12:49:32,940 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612124932923518
2019-06-12 12:49:32,965 [salt.minion      :1432][INFO    ][14628] Starting a new job with PID 14628
2019-06-12 12:49:32,994 [salt.minion      :1711][INFO    ][14628] Returning information for job: 20190612124932923518
2019-06-12 12:49:53,555 [salt.loaded.ext.module.maas:1023][INFO    ][13361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1621.78601003s left)
2019-06-12 12:50:03,128 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612125003111282
2019-06-12 12:50:03,161 [salt.minion      :1432][INFO    ][14650] Starting a new job with PID 14650
2019-06-12 12:50:03,186 [salt.minion      :1711][INFO    ][14650] Returning information for job: 20190612125003111282
2019-06-12 12:50:12,650 [salt.utils.schedule:1377][INFO    ][2873] Running scheduled job: __mine_interval
2019-06-12 12:50:26,488 [salt.loaded.ext.module.maas:1023][INFO    ][13361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1588.85331106s left)
2019-06-12 12:50:33,316 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612125033302886
2019-06-12 12:50:33,342 [salt.minion      :1432][INFO    ][14878] Starting a new job with PID 14878
2019-06-12 12:50:33,364 [salt.minion      :1711][INFO    ][14878] Returning information for job: 20190612125033302886
2019-06-12 12:50:59,302 [salt.loaded.ext.module.maas:1023][INFO    ][13361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1556.03955698s left)
2019-06-12 12:51:03,500 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612125103484951
2019-06-12 12:51:03,532 [salt.minion      :1432][INFO    ][14900] Starting a new job with PID 14900
2019-06-12 12:51:03,552 [salt.minion      :1711][INFO    ][14900] Returning information for job: 20190612125103484951
2019-06-12 12:51:32,299 [salt.loaded.ext.module.maas:1023][INFO    ][13361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1523.04256701s left)
2019-06-12 12:51:33,700 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612125133690603
2019-06-12 12:51:33,726 [salt.minion      :1432][INFO    ][14954] Starting a new job with PID 14954
2019-06-12 12:51:33,750 [salt.minion      :1711][INFO    ][14954] Returning information for job: 20190612125133690603
2019-06-12 12:52:03,920 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command saltutil.find_job with jid 20190612125203909487
2019-06-12 12:52:03,944 [salt.minion      :1432][INFO    ][15006] Starting a new job with PID 15006
2019-06-12 12:52:03,970 [salt.minion      :1711][INFO    ][15006] Returning information for job: 20190612125203909487
2019-06-12 12:52:05,128 [salt.state       :300 ][INFO    ][13361] {'ret': True}
2019-06-12 12:52:05,128 [salt.state       :1951][INFO    ][13361] Completed state [maas.wait_for_machine_status] at time 12:52:05.128625 duration_in_ms=759811.872
2019-06-12 12:52:05,135 [salt.minion      :1711][INFO    ][13361] Returning information for job: 20190612123915329323
2019-06-12 13:50:12,650 [salt.utils.schedule:1377][INFO    ][2873] Running scheduled job: __mine_interval
2019-06-12 14:16:18,055 [salt.minion      :1308][INFO    ][2873] User sudo_ubuntu Executing command cp.push_dir with jid 20190612141618041539
2019-06-12 14:16:18,080 [salt.minion      :1432][INFO    ][21051] Starting a new job with PID 21051
