2019-03-25 05:09:06,574 [salt.utils.decorators:613 ][WARNING ][2024] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-25 05:09:07,705 [salt.utils.decorators:613 ][WARNING ][2024] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-25 05:09:11,008 [salt.loaded.int.states.file:2298][WARNING ][2493] 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-03-25 05:09:29,911 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2920] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-03-25 05:09:29,935 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2920] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-03-25 05:09:29,964 [salt.utils.parsers:1051][WARNING ][382] Minion received a SIGTERM. Exiting.
2019-03-25 05:09:31,084 [salt.cli.daemons :293 ][INFO    ][3032] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-03-25 05:09:31,257 [salt.cli.daemons :82  ][INFO    ][3032] Starting up the Salt Minion
2019-03-25 05:09:31,257 [salt.utils.event :1017][INFO    ][3032] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-03-25 05:09:32,448 [salt.minion      :976 ][INFO    ][3032] Creating minion process manager
2019-03-25 05:09:34,376 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][3032] Executing command ['date', '+%z'] in directory '/root'
2019-03-25 05:09:34,399 [salt.utils.schedule:568 ][INFO    ][3032] Updating job settings for scheduled job: __mine_interval
2019-03-25 05:09:34,400 [salt.minion      :1108][INFO    ][3032] Added mine.update to scheduler
2019-03-25 05:09:34,405 [salt.minion      :1975][INFO    ][3032] Minion is starting as user 'root'
2019-03-25 05:09:34,425 [salt.minion      :2336][INFO    ][3032] Minion is ready to receive requests!
2019-03-25 05:09:40,669 [salt.state       :2022][WARNING ][2923] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-03-25 05:09:41,949 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325050941941543
2019-03-25 05:09:41,965 [salt.minion      :1432][INFO    ][3450] Starting a new job with PID 3450
2019-03-25 05:09:42,000 [salt.minion      :1711][INFO    ][3450] Returning information for job: 20190325050941941543
2019-03-25 05:09:43,787 [salt.utils.decorators:613 ][WARNING ][2923] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-25 05:09:58,211 [salt.utils.decorators:613 ][WARNING ][2923] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-25 05:10:12,058 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325051012045833
2019-03-25 05:10:12,077 [salt.minion      :1432][INFO    ][4228] Starting a new job with PID 4228
2019-03-25 05:10:12,105 [salt.minion      :1711][INFO    ][4228] Returning information for job: 20190325051012045833
2019-03-25 05:10:23,890 [salt.utils.decorators:613 ][WARNING ][2923] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-25 05:10:24,844 [salt.utils.decorators:613 ][WARNING ][2923] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-25 05:10:27,348 [salt.loaded.ext.module.maasng:1008][WARNING ][2923] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-03-25 05:10:27,349 [salt.loaded.ext.module.maasng:1011][WARNING ][2923] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-03-25 05:10:28,086 [salt.loaded.ext.module.maasng:1235][WARNING ][2923] Ignoring parameter vlan:0
2019-03-25 05:10:28,877 [salt.utils.decorators:613 ][WARNING ][2923] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-25 05:10:29,904 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command state.apply with jid 20190325051029894809
2019-03-25 05:10:29,917 [salt.minion      :1432][INFO    ][4625] Starting a new job with PID 4625
2019-03-25 05:10:37,707 [salt.state       :915 ][INFO    ][4625] Loading fresh modules for state activity
2019-03-25 05:10:37,770 [salt.fileclient  :1219][INFO    ][4625] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-03-25 05:10:37,817 [salt.state       :1780][INFO    ][4625] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:10:37.817024
2019-03-25 05:10:37,817 [salt.state       :1813][INFO    ][4625] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-25 05:10:37,820 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4625] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-25 05:10:39,705 [salt.state       :300 ][INFO    ][4625] {'pid': 4650, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-25 05:10:39,705 [salt.state       :1951][INFO    ][4625] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:10:39.705669 duration_in_ms=1888.646
2019-03-25 05:10:39,707 [salt.state       :1780][INFO    ][4625] Running state [maas.process_machines] at time 05:10:39.707550
2019-03-25 05:10:39,707 [salt.state       :1813][INFO    ][4625] Executing state module.run for [maas.process_machines]
2019-03-25 05:10:39,708 [salt.utils.decorators:613 ][WARNING ][4625] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-25 05:10:39,761 [salt.loaded.ext.module.maas:412 ][WARNING ][4625] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-25 05:10:39,761 [salt.loaded.ext.module.maas:92  ][INFO    ][4625] 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-03-25 05:10:40,938 [salt.loaded.ext.module.maas:412 ][WARNING ][4625] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-25 05:10:40,939 [salt.loaded.ext.module.maas:92  ][INFO    ][4625] 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-03-25 05:10:42,069 [salt.loaded.ext.module.maas:412 ][WARNING ][4625] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-25 05:10:42,069 [salt.loaded.ext.module.maas:92  ][INFO    ][4625] 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-03-25 05:10:43,282 [salt.loaded.ext.module.maas:412 ][WARNING ][4625] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-25 05:10:43,283 [salt.loaded.ext.module.maas:92  ][INFO    ][4625] 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-03-25 05:10:44,526 [salt.loaded.ext.module.maas:412 ][WARNING ][4625] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-25 05:10:44,527 [salt.loaded.ext.module.maas:92  ][INFO    ][4625] 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-03-25 05:10:45,023 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325051045012298
2019-03-25 05:10:45,045 [salt.minion      :1432][INFO    ][4928] Starting a new job with PID 4928
2019-03-25 05:10:45,077 [salt.minion      :1711][INFO    ][4928] Returning information for job: 20190325051045012298
2019-03-25 05:10:45,779 [salt.state       :300 ][INFO    ][4625] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-03-25 05:10:45,780 [salt.state       :1951][INFO    ][4625] Completed state [maas.process_machines] at time 05:10:45.780393 duration_in_ms=6072.842
2019-03-25 05:10:45,787 [salt.minion      :1711][INFO    ][4625] Returning information for job: 20190325051029894809
2019-03-25 05:11:17,132 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command state.apply with jid 20190325051117116546
2019-03-25 05:11:17,154 [salt.minion      :1432][INFO    ][4997] Starting a new job with PID 4997
2019-03-25 05:11:24,731 [salt.state       :915 ][INFO    ][4997] Loading fresh modules for state activity
2019-03-25 05:11:24,787 [salt.fileclient  :1219][INFO    ][4997] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-03-25 05:11:24,836 [salt.state       :1780][INFO    ][4997] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:11:24.836595
2019-03-25 05:11:24,836 [salt.state       :1813][INFO    ][4997] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-25 05:11:24,838 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4997] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-25 05:11:26,683 [salt.state       :300 ][INFO    ][4997] {'pid': 5004, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-25 05:11:26,685 [salt.state       :1951][INFO    ][4997] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:11:26.684828 duration_in_ms=1848.232
2019-03-25 05:11:26,688 [salt.state       :1780][INFO    ][4997] Running state [maas.wait_for_machine_status] at time 05:11:26.687935
2019-03-25 05:11:26,688 [salt.state       :1813][INFO    ][4997] Executing state module.run for [maas.wait_for_machine_status]
2019-03-25 05:11:26,689 [salt.utils.decorators:613 ][WARNING ][4997] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-25 05:11:27,458 [salt.loaded.ext.module.maas:1023][INFO    ][4997] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.24402523s left)
2019-03-25 05:11:32,154 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325051132139915
2019-03-25 05:11:32,173 [salt.minion      :1432][INFO    ][5024] Starting a new job with PID 5024
2019-03-25 05:11:32,200 [salt.minion      :1711][INFO    ][5024] Returning information for job: 20190325051132139915
2019-03-25 05:11:58,242 [salt.loaded.ext.module.maas:1023][INFO    ][4997] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.46017814s left)
2019-03-25 05:12:02,181 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325051202165297
2019-03-25 05:12:02,205 [salt.minion      :1432][INFO    ][5093] Starting a new job with PID 5093
2019-03-25 05:12:02,233 [salt.minion      :1711][INFO    ][5093] Returning information for job: 20190325051202165297
2019-03-25 05:12:28,992 [salt.loaded.ext.module.maas:1023][INFO    ][4997] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.70996308s left)
2019-03-25 05:12:32,269 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325051232260266
2019-03-25 05:12:32,288 [salt.minion      :1432][INFO    ][5122] Starting a new job with PID 5122
2019-03-25 05:12:32,315 [salt.minion      :1711][INFO    ][5122] Returning information for job: 20190325051232260266
2019-03-25 05:12:59,756 [salt.loaded.ext.module.maas:1023][INFO    ][4997] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.94623923s left)
2019-03-25 05:13:02,329 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325051302321293
2019-03-25 05:13:02,351 [salt.minion      :1432][INFO    ][5197] Starting a new job with PID 5197
2019-03-25 05:13:02,381 [salt.minion      :1711][INFO    ][5197] Returning information for job: 20190325051302321293
2019-03-25 05:13:30,790 [salt.loaded.ext.module.maas:1023][INFO    ][4997] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.91158319s left)
2019-03-25 05:13:32,399 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325051332381091
2019-03-25 05:13:32,421 [salt.minion      :1432][INFO    ][5254] Starting a new job with PID 5254
2019-03-25 05:13:32,448 [salt.minion      :1711][INFO    ][5254] Returning information for job: 20190325051332381091
2019-03-25 05:14:01,778 [salt.loaded.ext.module.maas:1023][INFO    ][4997] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.92393112s left)
2019-03-25 05:14:02,448 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325051402438125
2019-03-25 05:14:02,467 [salt.minion      :1432][INFO    ][5434] Starting a new job with PID 5434
2019-03-25 05:14:02,494 [salt.minion      :1711][INFO    ][5434] Returning information for job: 20190325051402438125
2019-03-25 05:14:32,672 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325051432657464
2019-03-25 05:14:32,690 [salt.minion      :1432][INFO    ][5511] Starting a new job with PID 5511
2019-03-25 05:14:32,722 [salt.minion      :1711][INFO    ][5511] Returning information for job: 20190325051432657464
2019-03-25 05:14:32,896 [salt.loaded.ext.module.maas:1023][INFO    ][4997] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1313.80593514s left)
2019-03-25 05:15:02,721 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325051502710477
2019-03-25 05:15:02,735 [salt.minion      :1432][INFO    ][5865] Starting a new job with PID 5865
2019-03-25 05:15:02,762 [salt.minion      :1711][INFO    ][5865] Returning information for job: 20190325051502710477
2019-03-25 05:15:04,161 [salt.loaded.ext.module.maas:1023][INFO    ][4997] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1282.54042006s left)
2019-03-25 05:15:32,798 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325051532785795
2019-03-25 05:15:32,815 [salt.minion      :1432][INFO    ][5974] Starting a new job with PID 5974
2019-03-25 05:15:32,843 [salt.minion      :1711][INFO    ][5974] Returning information for job: 20190325051532785795
2019-03-25 05:15:35,904 [salt.loaded.ext.module.maas:1023][INFO    ][4997] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1250.79831123s left)
2019-03-25 05:16:02,961 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325051602944000
2019-03-25 05:16:02,982 [salt.minion      :1432][INFO    ][6368] Starting a new job with PID 6368
2019-03-25 05:16:03,012 [salt.minion      :1711][INFO    ][6368] Returning information for job: 20190325051602944000
2019-03-25 05:16:07,880 [salt.loaded.ext.module.maas:1023][INFO    ][4997] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1218.82204008s left)
2019-03-25 05:16:33,055 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325051633042579
2019-03-25 05:16:33,073 [salt.minion      :1432][INFO    ][6521] Starting a new job with PID 6521
2019-03-25 05:16:33,102 [salt.minion      :1711][INFO    ][6521] Returning information for job: 20190325051633042579
2019-03-25 05:16:40,091 [salt.loaded.ext.module.maas:1023][INFO    ][4997] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1186.61107302s left)
2019-03-25 05:17:03,179 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325051703168471
2019-03-25 05:17:03,200 [salt.minion      :1432][INFO    ][6745] Starting a new job with PID 6745
2019-03-25 05:17:03,225 [salt.minion      :1711][INFO    ][6745] Returning information for job: 20190325051703168471
2019-03-25 05:17:12,513 [salt.loaded.ext.module.maas:1023][INFO    ][4997] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1154.18913412s left)
2019-03-25 05:17:33,212 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325051733201051
2019-03-25 05:17:33,237 [salt.minion      :1432][INFO    ][6903] Starting a new job with PID 6903
2019-03-25 05:17:33,269 [salt.minion      :1711][INFO    ][6903] Returning information for job: 20190325051733201051
2019-03-25 05:17:45,757 [salt.state       :300 ][INFO    ][4997] {'ret': True}
2019-03-25 05:17:45,758 [salt.state       :1951][INFO    ][4997] Completed state [maas.wait_for_machine_status] at time 05:17:45.757953 duration_in_ms=379070.016
2019-03-25 05:17:45,763 [salt.minion      :1711][INFO    ][4997] Returning information for job: 20190325051117116546
2019-03-25 05:17:46,446 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command state.apply with jid 20190325051746435529
2019-03-25 05:17:46,464 [salt.minion      :1432][INFO    ][7023] Starting a new job with PID 7023
2019-03-25 05:17:54,344 [salt.state       :915 ][INFO    ][7023] Loading fresh modules for state activity
2019-03-25 05:17:54,396 [salt.fileclient  :1219][INFO    ][7023] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-03-25 05:17:54,508 [salt.state       :1780][INFO    ][7023] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:17:54.508279
2019-03-25 05:17:54,508 [salt.state       :1813][INFO    ][7023] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-25 05:17:54,510 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7023] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-25 05:17:56,329 [salt.state       :300 ][INFO    ][7023] {'pid': 7051, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-25 05:17:56,330 [salt.state       :1951][INFO    ][7023] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:17:56.330213 duration_in_ms=1821.933
2019-03-25 05:17:56,335 [salt.state       :1780][INFO    ][7023] Running state [maas_machines_storage_cmp002_lvm] at time 05:17:56.335581
2019-03-25 05:17:56,336 [salt.state       :1813][INFO    ][7023] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-03-25 05:17:57,491 [salt.loaded.ext.module.maasng:610 ][INFO    ][7023] mbs8qg
2019-03-25 05:17:57,491 [salt.loaded.ext.module.maasng:626 ][INFO    ][7023] sda
2019-03-25 05:17:58,061 [salt.loaded.ext.module.maasng:361 ][INFO    ][7023] mbs8qg
2019-03-25 05:17:58,151 [salt.loaded.ext.module.maasng:367 ][INFO    ][7023] [{u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'3796419d-e573-4bad-aa09-b019dd8a8892', u'resource_uri': u'/MAAS/api/2.0/nodes/mbs8qg/blockdevices/1/partition/1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'mbs8qg', u'filesystem': {u'uuid': u'f56ecf22-cb42-45dd-85a5-27182cac15a3', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 1, u'device_id': 1}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'mbs8qg', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/mbs8qg/blockdevices/1/', u'type': u'physical', u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}, {u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'partitions': [], u'uuid': u'cd473a05-c3e4-4b91-9177-bf8991a5916c', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'mbs8qg', u'partition_table_type': None, u'filesystem': {u'uuid': u'df544709-7727-4166-9fd5-92708f4d287f', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/mbs8qg/blockdevices/3/', u'type': u'virtual', u'id': 3, u'serial': None, u'size': 800097042432}]
2019-03-25 05:17:58,152 [salt.loaded.ext.module.maasng:632 ][INFO    ][7023] vgroot
2019-03-25 05:17:58,152 [salt.loaded.ext.module.maasng:635 ][INFO    ][7023] lvroot
2019-03-25 05:17:58,152 [salt.loaded.ext.module.maasng:639 ][INFO    ][7023] 107374182400
2019-03-25 05:17:58,730 [salt.loaded.ext.module.maasng:645 ][INFO    ][7023] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'boot_interface': {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'6gyfys', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'6gyfys', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 19}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:10:18', 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'6gyfys', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'mbs8qg', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/mbs8qg/interfaces/4/'}, 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'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'mbs8qg', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/mbs8qg/blockdevices/1/', u'type': u'physical', u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'size': 800101236736, u'uuid': u'864e916d-0f3a-4f82-b29f-47f4f7e42e73', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'mbs8qg', u'filesystem': {u'label': None, u'uuid': u'83a6479c-65c7-485f-9aa6-53d37c19c001', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/mbs8qg/blockdevices/1/partition/6'}]}, u'zone': {u'description': u'', u'id': 1, u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'hostname': u'cmp002', u'storage': 800109.715456, u'node_type': 0, u'testing_status': 2, u'system_id': u'mbs8qg', 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'physicalblockdevice_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'mbs8qg', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/mbs8qg/blockdevices/1/', u'type': u'physical', u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'size': 800101236736, u'uuid': u'864e916d-0f3a-4f82-b29f-47f4f7e42e73', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'mbs8qg', u'filesystem': {u'label': None, u'uuid': u'83a6479c-65c7-485f-9aa6-53d37c19c001', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/mbs8qg/blockdevices/1/partition/6'}]}], u'blockdevice_set': [{u'size': 800109715456, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'864e916d-0f3a-4f82-b29f-47f4f7e42e73', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'mbs8qg', u'filesystem': {u'label': None, u'uuid': u'83a6479c-65c7-485f-9aa6-53d37c19c001', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/mbs8qg/blockdevices/1/partition/6'}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'mbs8qg', 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'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/mbs8qg/blockdevices/1/'}, {u'size': 107374182400, u'uuid': u'2be81282-9900-4c2e-a185-8a39d33d97b0', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'label': u'root', u'uuid': u'31e3a00e-2a1c-46b4-91b0-0f5dbec0f519', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'mbs8qg', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 11, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/mbs8qg/blockdevices/11/'}], u'status': 4, u'bcaches': [], 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'mbs8qg', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'node_type_name': u'Machine', u'interface_set': [{u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'6gyfys', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'6gyfys', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 19}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:10:18', 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'6gyfys', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'mbs8qg', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/mbs8qg/interfaces/4/'}, {u'vlan': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:07:51', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'mbs8qg', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/mbs8qg/interfaces/13/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f1', u'links': [{u'id': 20, u'mode': u'link_up'}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:12:49', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'mbs8qg', u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/mbs8qg/interfaces/14/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'6gyfys', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'6gyfys', 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': 21, u'mode': u'link_up'}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:10:1c', 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'6gyfys', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.40'}], u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'mbs8qg', u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/mbs8qg/interfaces/15/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f0', u'links': [{u'id': 22, u'mode': u'link_up'}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:12:48', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'mbs8qg', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/mbs8qg/interfaces/12/'}, {u'vlan': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:07:50', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'mbs8qg', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/mbs8qg/interfaces/11/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'size': 107374182400, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'filesystem': {u'label': u'root', u'uuid': u'31e3a00e-2a1c-46b4-91b0-0f5dbec0f519', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'uuid': u'2be81282-9900-4c2e-a185-8a39d33d97b0', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'mbs8qg', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/mbs8qg/blockdevices/11/', u'type': u'virtual', u'id': 11, u'serial': None, u'partitions': []}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/mbs8qg/', u'distro_series': u'', u'memory_test_status': -1}
2019-03-25 05:17:58,732 [salt.state       :300 ][INFO    ][7023] {'new': {'storage_layout': 'lvm'}}
2019-03-25 05:17:58,733 [salt.state       :1951][INFO    ][7023] Completed state [maas_machines_storage_cmp002_lvm] at time 05:17:58.733288 duration_in_ms=2397.705
2019-03-25 05:17:58,734 [salt.state       :1780][INFO    ][7023] Running state [maas_machines_storage_cmp001_lvm] at time 05:17:58.733916
2019-03-25 05:17:58,734 [salt.state       :1813][INFO    ][7023] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-03-25 05:17:59,860 [salt.loaded.ext.module.maasng:610 ][INFO    ][7023] etfwkg
2019-03-25 05:17:59,860 [salt.loaded.ext.module.maasng:626 ][INFO    ][7023] sda
2019-03-25 05:18:00,429 [salt.loaded.ext.module.maasng:361 ][INFO    ][7023] etfwkg
2019-03-25 05:18:00,515 [salt.loaded.ext.module.maasng:367 ][INFO    ][7023] [{u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'6bb60ac7-d716-4d72-a26f-aec0215adc13', u'resource_uri': u'/MAAS/api/2.0/nodes/etfwkg/blockdevices/2/partition/2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'etfwkg', u'filesystem': {u'uuid': u'bd0993e3-2e6c-4f0a-8cc4-61b61a4d931a', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 2, u'device_id': 2}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'etfwkg', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/etfwkg/blockdevices/2/', u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, {u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'partitions': [], u'uuid': u'bd8fced0-3295-46e6-a6ac-003c5356f696', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'etfwkg', u'partition_table_type': None, u'filesystem': {u'uuid': u'48f963e5-c4b8-41f7-ad4f-d1c7edb2685c', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/etfwkg/blockdevices/4/', u'type': u'virtual', u'id': 4, u'serial': None, u'size': 800097042432}]
2019-03-25 05:18:00,515 [salt.loaded.ext.module.maasng:632 ][INFO    ][7023] vgroot
2019-03-25 05:18:00,516 [salt.loaded.ext.module.maasng:635 ][INFO    ][7023] lvroot
2019-03-25 05:18:00,516 [salt.loaded.ext.module.maasng:639 ][INFO    ][7023] 107374182400
2019-03-25 05:18:01,117 [salt.loaded.ext.module.maasng:645 ][INFO    ][7023] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'swap_size': None, u'disable_ipv4': False, u'cpu_count': 40, u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'model': u'LOGICAL VOLUME', u'partition_table_type': u'MBR', u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'8a840a71-14f5-44d2-849f-8cb7b74b4ffb', u'resource_uri': u'/MAAS/api/2.0/nodes/etfwkg/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'etfwkg', u'filesystem': {u'uuid': u'ef300d94-a027-44da-aca1-d19c1ca47b68', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 7, u'device_id': 2}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'etfwkg', u'resource_uri': u'/MAAS/api/2.0/nodes/etfwkg/blockdevices/2/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'type': u'physical', u'id': 2, u'size': 800109715456}, u'blockdevice_set': [{u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'8a840a71-14f5-44d2-849f-8cb7b74b4ffb', u'resource_uri': u'/MAAS/api/2.0/nodes/etfwkg/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'etfwkg', u'filesystem': {u'uuid': u'ef300d94-a027-44da-aca1-d19c1ca47b68', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 7, u'device_id': 2}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'etfwkg', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/etfwkg/blockdevices/2/', u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, {u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'uuid': u'41c3fa70-a436-45d0-8556-1da549b32704', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'etfwkg', u'partition_table_type': None, u'filesystem': {u'uuid': u'210a453d-ff91-4ff4-8512-c7876a0e0016', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/etfwkg/blockdevices/12/', u'type': u'virtual', u'id': 12, u'serial': None, u'size': 107374182400}], u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'description': u''}, u'resource_uri': u'/MAAS/api/2.0/machines/etfwkg/', u'current_commissioning_result_id': 4, u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'etfwkg', 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'model': None, u'partition_table_type': None, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'uuid': u'41c3fa70-a436-45d0-8556-1da549b32704', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'etfwkg', u'resource_uri': u'/MAAS/api/2.0/nodes/etfwkg/blockdevices/12/', u'filesystem': {u'uuid': u'210a453d-ff91-4ff4-8512-c7876a0e0016', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 12, u'size': 107374182400}], u'architecture': u'amd64/generic', u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'raids': [], u'owner_data': {}, u'ip_addresses': [u'192.168.11.39', u'192.168.11.44'], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'etfwkg', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_interface': {u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'6gyfys', 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'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'6gyfys', 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.39', u'id': 25, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:95:a0', u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'6gyfys', 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.39'}], u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'etfwkg', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/etfwkg/interfaces/5/'}, 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'model': u'LOGICAL VOLUME', u'partition_table_type': u'MBR', u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'8a840a71-14f5-44d2-849f-8cb7b74b4ffb', u'resource_uri': u'/MAAS/api/2.0/nodes/etfwkg/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'etfwkg', u'filesystem': {u'uuid': u'ef300d94-a027-44da-aca1-d19c1ca47b68', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 7, u'device_id': 2}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'etfwkg', u'resource_uri': u'/MAAS/api/2.0/nodes/etfwkg/blockdevices/2/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'type': u'physical', u'id': 2, u'size': 800109715456}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'node_type': 0, u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'commissioning_status_name': u'Passed', u'interface_set': [{u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'6gyfys', 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'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'6gyfys', 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.39', u'id': 25, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:95:a0', u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'6gyfys', 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.39'}], u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'etfwkg', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/etfwkg/interfaces/5/'}, {u'vlan': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:1f:d4', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'etfwkg', u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/etfwkg/interfaces/19/'}, {u'vlan': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:1f:d5', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'etfwkg', u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/etfwkg/interfaces/20/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'name': u'ens2f0', u'links': [{u'id': 26, u'mode': u'link_up'}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:52:cc', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'etfwkg', u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/etfwkg/interfaces/16/'}, {u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'6gyfys', 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'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'6gyfys', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 27, u'mode': u'link_up'}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:95:a4', u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'6gyfys', 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.44'}], u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'etfwkg', u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/etfwkg/interfaces/17/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'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'id': 28, u'mode': u'link_up'}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:52:cd', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'etfwkg', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/etfwkg/interfaces/18/'}], u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'node_type_name': u'Machine'}
2019-03-25 05:18:01,120 [salt.state       :300 ][INFO    ][7023] {'new': {'storage_layout': 'lvm'}}
2019-03-25 05:18:01,121 [salt.state       :1951][INFO    ][7023] Completed state [maas_machines_storage_cmp001_lvm] at time 05:18:01.121003 duration_in_ms=2387.086
2019-03-25 05:18:01,124 [salt.minion      :1711][INFO    ][7023] Returning information for job: 20190325051746435529
2019-03-25 05:18:01,812 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command state.apply with jid 20190325051801799508
2019-03-25 05:18:01,833 [salt.minion      :1432][INFO    ][7074] Starting a new job with PID 7074
2019-03-25 05:18:02,738 [salt.state       :915 ][INFO    ][7074] Loading fresh modules for state activity
2019-03-25 05:18:02,801 [salt.fileclient  :1219][INFO    ][7074] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-03-25 05:18:02,851 [salt.state       :1780][INFO    ][7074] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:18:02.851454
2019-03-25 05:18:02,851 [salt.state       :1813][INFO    ][7074] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-25 05:18:02,853 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7074] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-25 05:18:04,741 [salt.state       :300 ][INFO    ][7074] {'pid': 7081, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-25 05:18:04,742 [salt.state       :1951][INFO    ][7074] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:18:04.741991 duration_in_ms=1890.535
2019-03-25 05:18:04,746 [salt.state       :1780][INFO    ][7074] Running state [maas.deploy_machines] at time 05:18:04.746288
2019-03-25 05:18:04,747 [salt.state       :1813][INFO    ][7074] Executing state module.run for [maas.deploy_machines]
2019-03-25 05:18:04,748 [salt.utils.decorators:613 ][WARNING ][7074] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-25 05:18:05,333 [salt.loaded.ext.module.maas:684 ][INFO    ][7074] deploymachines hwe_kernel=hwe-16.04 system_id=mbs8qg distro_series=xenial
2019-03-25 05:18:07,699 [salt.loaded.ext.module.maas:684 ][INFO    ][7074] deploymachines hwe_kernel=hwe-16.04 system_id=etfwkg distro_series=xenial
2019-03-25 05:18:10,022 [salt.loaded.ext.module.maas:684 ][INFO    ][7074] deploymachines hwe_kernel=hwe-16.04 system_id=mcwn3w distro_series=xenial
2019-03-25 05:18:12,325 [salt.loaded.ext.module.maas:684 ][INFO    ][7074] deploymachines hwe_kernel=hwe-16.04 system_id=7pa33a distro_series=xenial
2019-03-25 05:18:14,701 [salt.loaded.ext.module.maas:684 ][INFO    ][7074] deploymachines hwe_kernel=hwe-16.04 system_id=kaer4c distro_series=xenial
2019-03-25 05:18:16,894 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325051816886576
2019-03-25 05:18:16,914 [salt.minion      :1432][INFO    ][7345] Starting a new job with PID 7345
2019-03-25 05:18:16,942 [salt.minion      :1711][INFO    ][7345] Returning information for job: 20190325051816886576
2019-03-25 05:18:17,026 [salt.state       :300 ][INFO    ][7074] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-03-25 05:18:17,027 [salt.state       :1951][INFO    ][7074] Completed state [maas.deploy_machines] at time 05:18:17.027741 duration_in_ms=12281.453
2019-03-25 05:18:17,030 [salt.minion      :1711][INFO    ][7074] Returning information for job: 20190325051801799508
2019-03-25 05:18:17,732 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command state.apply with jid 20190325051817720574
2019-03-25 05:18:17,749 [salt.minion      :1432][INFO    ][7361] Starting a new job with PID 7361
2019-03-25 05:18:25,709 [salt.state       :915 ][INFO    ][7361] Loading fresh modules for state activity
2019-03-25 05:18:25,759 [salt.fileclient  :1219][INFO    ][7361] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-03-25 05:18:25,802 [salt.state       :1780][INFO    ][7361] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:18:25.801878
2019-03-25 05:18:25,802 [salt.state       :1813][INFO    ][7361] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-25 05:18:25,807 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7361] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-25 05:18:27,732 [salt.state       :300 ][INFO    ][7361] {'pid': 7383, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-25 05:18:27,733 [salt.state       :1951][INFO    ][7361] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:18:27.733564 duration_in_ms=1931.688
2019-03-25 05:18:27,739 [salt.state       :1780][INFO    ][7361] Running state [maas.wait_for_machine_status] at time 05:18:27.739447
2019-03-25 05:18:27,739 [salt.state       :1813][INFO    ][7361] Executing state module.run for [maas.wait_for_machine_status]
2019-03-25 05:18:27,740 [salt.utils.decorators:613 ][WARNING ][7361] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-25 05:18:30,521 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.23244905s left)
2019-03-25 05:18:32,803 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325051832783609
2019-03-25 05:18:32,826 [salt.minion      :1432][INFO    ][7405] Starting a new job with PID 7405
2019-03-25 05:18:32,853 [salt.minion      :1711][INFO    ][7405] Returning information for job: 20190325051832783609
2019-03-25 05:19:02,868 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325051902857344
2019-03-25 05:19:02,887 [salt.minion      :1432][INFO    ][7474] Starting a new job with PID 7474
2019-03-25 05:19:02,916 [salt.minion      :1711][INFO    ][7474] Returning information for job: 20190325051902857344
2019-03-25 05:19:03,438 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.31503606s left)
2019-03-25 05:19:32,930 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325051932920211
2019-03-25 05:19:32,953 [salt.minion      :1432][INFO    ][7504] Starting a new job with PID 7504
2019-03-25 05:19:32,984 [salt.minion      :1711][INFO    ][7504] Returning information for job: 20190325051932920211
2019-03-25 05:19:36,322 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.43116808s left)
2019-03-25 05:20:02,997 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325052002986095
2019-03-25 05:20:03,017 [salt.minion      :1432][INFO    ][7595] Starting a new job with PID 7595
2019-03-25 05:20:03,043 [salt.minion      :1711][INFO    ][7595] Returning information for job: 20190325052002986095
2019-03-25 05:20:09,065 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2148.68845701s left)
2019-03-25 05:20:33,186 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325052033177169
2019-03-25 05:20:33,199 [salt.minion      :1432][INFO    ][7621] Starting a new job with PID 7621
2019-03-25 05:20:33,224 [salt.minion      :1711][INFO    ][7621] Returning information for job: 20190325052033177169
2019-03-25 05:20:41,975 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2115.77821302s left)
2019-03-25 05:21:03,384 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325052103375083
2019-03-25 05:21:03,405 [salt.minion      :1432][INFO    ][7707] Starting a new job with PID 7707
2019-03-25 05:21:03,432 [salt.minion      :1711][INFO    ][7707] Returning information for job: 20190325052103375083
2019-03-25 05:21:14,806 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2082.94733s left)
2019-03-25 05:21:33,590 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325052133581786
2019-03-25 05:21:33,610 [salt.minion      :1432][INFO    ][7781] Starting a new job with PID 7781
2019-03-25 05:21:33,638 [salt.minion      :1711][INFO    ][7781] Returning information for job: 20190325052133581786
2019-03-25 05:21:47,948 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2049.80588198s left)
2019-03-25 05:22:03,621 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325052203612695
2019-03-25 05:22:03,636 [salt.minion      :1432][INFO    ][8023] Starting a new job with PID 8023
2019-03-25 05:22:03,666 [salt.minion      :1711][INFO    ][8023] Returning information for job: 20190325052203612695
2019-03-25 05:22:20,897 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2016.85648918s left)
2019-03-25 05:22:33,686 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325052233679388
2019-03-25 05:22:33,701 [salt.minion      :1432][INFO    ][8096] Starting a new job with PID 8096
2019-03-25 05:22:33,728 [salt.minion      :1711][INFO    ][8096] Returning information for job: 20190325052233679388
2019-03-25 05:22:53,781 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1983.97298098s left)
2019-03-25 05:23:03,784 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325052303768818
2019-03-25 05:23:03,804 [salt.minion      :1432][INFO    ][8388] Starting a new job with PID 8388
2019-03-25 05:23:03,830 [salt.minion      :1711][INFO    ][8388] Returning information for job: 20190325052303768818
2019-03-25 05:23:26,577 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1951.1767211s left)
2019-03-25 05:23:33,822 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325052333808300
2019-03-25 05:23:33,841 [salt.minion      :1432][INFO    ][8429] Starting a new job with PID 8429
2019-03-25 05:23:33,872 [salt.minion      :1711][INFO    ][8429] Returning information for job: 20190325052333808300
2019-03-25 05:23:59,558 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1918.19567204s left)
2019-03-25 05:24:03,948 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325052403937980
2019-03-25 05:24:03,966 [salt.minion      :1432][INFO    ][8818] Starting a new job with PID 8818
2019-03-25 05:24:04,002 [salt.minion      :1711][INFO    ][8818] Returning information for job: 20190325052403937980
2019-03-25 05:24:32,521 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1885.232903s left)
2019-03-25 05:24:34,052 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325052434042438
2019-03-25 05:24:34,068 [salt.minion      :1432][INFO    ][8870] Starting a new job with PID 8870
2019-03-25 05:24:34,098 [salt.minion      :1711][INFO    ][8870] Returning information for job: 20190325052434042438
2019-03-25 05:25:04,219 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325052504205985
2019-03-25 05:25:04,237 [salt.minion      :1432][INFO    ][9291] Starting a new job with PID 9291
2019-03-25 05:25:04,272 [salt.minion      :1711][INFO    ][9291] Returning information for job: 20190325052504205985
2019-03-25 05:25:05,563 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1852.18998814s left)
2019-03-25 05:25:34,353 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325052534341282
2019-03-25 05:25:34,375 [salt.minion      :1432][INFO    ][9330] Starting a new job with PID 9330
2019-03-25 05:25:34,405 [salt.minion      :1711][INFO    ][9330] Returning information for job: 20190325052534341282
2019-03-25 05:25:38,420 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1819.33383703s left)
2019-03-25 05:26:04,492 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325052604478415
2019-03-25 05:26:04,506 [salt.minion      :1432][INFO    ][9505] Starting a new job with PID 9505
2019-03-25 05:26:04,532 [salt.minion      :1711][INFO    ][9505] Returning information for job: 20190325052604478415
2019-03-25 05:26:11,342 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1786.411659s left)
2019-03-25 05:26:34,586 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325052634572317
2019-03-25 05:26:34,606 [salt.minion      :1432][INFO    ][9554] Starting a new job with PID 9554
2019-03-25 05:26:34,641 [salt.minion      :1711][INFO    ][9554] Returning information for job: 20190325052634572317
2019-03-25 05:26:44,340 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1753.41334105s left)
2019-03-25 05:27:04,757 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325052704748475
2019-03-25 05:27:04,772 [salt.minion      :1432][INFO    ][9789] Starting a new job with PID 9789
2019-03-25 05:27:04,798 [salt.minion      :1711][INFO    ][9789] Returning information for job: 20190325052704748475
2019-03-25 05:27:17,102 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1720.65131998s left)
2019-03-25 05:27:34,783 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325052734766667
2019-03-25 05:27:34,807 [salt.minion      :1432][INFO    ][9835] Starting a new job with PID 9835
2019-03-25 05:27:34,836 [salt.minion      :1711][INFO    ][9835] Returning information for job: 20190325052734766667
2019-03-25 05:27:49,946 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1687.80785108s left)
2019-03-25 05:28:04,861 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325052804846406
2019-03-25 05:28:04,885 [salt.minion      :1432][INFO    ][10104] Starting a new job with PID 10104
2019-03-25 05:28:04,916 [salt.minion      :1711][INFO    ][10104] Returning information for job: 20190325052804846406
2019-03-25 05:28:23,076 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1654.67746902s left)
2019-03-25 05:28:35,005 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325052834992038
2019-03-25 05:28:35,026 [salt.minion      :1432][INFO    ][10133] Starting a new job with PID 10133
2019-03-25 05:28:35,054 [salt.minion      :1711][INFO    ][10133] Returning information for job: 20190325052834992038
2019-03-25 05:28:55,882 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1621.87188101s left)
2019-03-25 05:29:05,190 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325052905176339
2019-03-25 05:29:05,214 [salt.minion      :1432][INFO    ][10282] Starting a new job with PID 10282
2019-03-25 05:29:05,245 [salt.minion      :1711][INFO    ][10282] Returning information for job: 20190325052905176339
2019-03-25 05:29:28,883 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1588.87066913s left)
2019-03-25 05:29:35,347 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325052935334221
2019-03-25 05:29:35,371 [salt.minion      :1432][INFO    ][10444] Starting a new job with PID 10444
2019-03-25 05:29:35,400 [salt.minion      :1711][INFO    ][10444] Returning information for job: 20190325052935334221
2019-03-25 05:30:01,652 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1556.10152698s left)
2019-03-25 05:30:05,525 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325053005513355
2019-03-25 05:30:05,544 [salt.minion      :1432][INFO    ][10549] Starting a new job with PID 10549
2019-03-25 05:30:05,570 [salt.minion      :1711][INFO    ][10549] Returning information for job: 20190325053005513355
2019-03-25 05:30:34,517 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1523.23629308s left)
2019-03-25 05:30:35,737 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325053035722447
2019-03-25 05:30:35,758 [salt.minion      :1432][INFO    ][10635] Starting a new job with PID 10635
2019-03-25 05:30:35,785 [salt.minion      :1711][INFO    ][10635] Returning information for job: 20190325053035722447
2019-03-25 05:31:05,765 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325053105755229
2019-03-25 05:31:05,781 [salt.minion      :1432][INFO    ][10968] Starting a new job with PID 10968
2019-03-25 05:31:05,813 [salt.minion      :1711][INFO    ][10968] Returning information for job: 20190325053105755229
2019-03-25 05:31:07,337 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1490.41648102s left)
2019-03-25 05:31:35,953 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325053135939218
2019-03-25 05:31:35,975 [salt.minion      :1432][INFO    ][10994] Starting a new job with PID 10994
2019-03-25 05:31:36,002 [salt.minion      :1711][INFO    ][10994] Returning information for job: 20190325053135939218
2019-03-25 05:31:40,249 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1457.50467396s left)
2019-03-25 05:32:06,174 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325053206162321
2019-03-25 05:32:06,198 [salt.minion      :1432][INFO    ][11066] Starting a new job with PID 11066
2019-03-25 05:32:06,227 [salt.minion      :1711][INFO    ][11066] Returning information for job: 20190325053206162321
2019-03-25 05:32:12,994 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1424.75946617s left)
2019-03-25 05:32:36,368 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325053236359715
2019-03-25 05:32:36,382 [salt.minion      :1432][INFO    ][11097] Starting a new job with PID 11097
2019-03-25 05:32:36,409 [salt.minion      :1711][INFO    ][11097] Returning information for job: 20190325053236359715
2019-03-25 05:32:46,247 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1391.50651598s left)
2019-03-25 05:33:06,389 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325053306376339
2019-03-25 05:33:06,410 [salt.minion      :1432][INFO    ][11169] Starting a new job with PID 11169
2019-03-25 05:33:06,438 [salt.minion      :1711][INFO    ][11169] Returning information for job: 20190325053306376339
2019-03-25 05:33:19,018 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1358.73595095s left)
2019-03-25 05:33:36,432 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325053336424912
2019-03-25 05:33:36,454 [salt.minion      :1432][INFO    ][11199] Starting a new job with PID 11199
2019-03-25 05:33:36,482 [salt.minion      :1711][INFO    ][11199] Returning information for job: 20190325053336424912
2019-03-25 05:33:51,923 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1325.83029199s left)
2019-03-25 05:34:06,461 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325053406445985
2019-03-25 05:34:06,478 [salt.minion      :1432][INFO    ][11273] Starting a new job with PID 11273
2019-03-25 05:34:06,505 [salt.minion      :1711][INFO    ][11273] Returning information for job: 20190325053406445985
2019-03-25 05:34:24,749 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1293.00413895s left)
2019-03-25 05:34:36,520 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325053436511864
2019-03-25 05:34:36,536 [salt.minion      :1432][INFO    ][11312] Starting a new job with PID 11312
2019-03-25 05:34:36,562 [salt.minion      :1711][INFO    ][11312] Returning information for job: 20190325053436511864
2019-03-25 05:34:57,413 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1260.34096599s left)
2019-03-25 05:35:06,660 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325053506652906
2019-03-25 05:35:06,673 [salt.minion      :1432][INFO    ][11391] Starting a new job with PID 11391
2019-03-25 05:35:06,702 [salt.minion      :1711][INFO    ][11391] Returning information for job: 20190325053506652906
2019-03-25 05:35:30,461 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1227.29208517s left)
2019-03-25 05:35:36,728 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325053536713925
2019-03-25 05:35:36,750 [salt.minion      :1432][INFO    ][11423] Starting a new job with PID 11423
2019-03-25 05:35:36,779 [salt.minion      :1711][INFO    ][11423] Returning information for job: 20190325053536713925
2019-03-25 05:36:03,128 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1194.62580109s left)
2019-03-25 05:36:06,794 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325053606783577
2019-03-25 05:36:06,814 [salt.minion      :1432][INFO    ][11494] Starting a new job with PID 11494
2019-03-25 05:36:06,842 [salt.minion      :1711][INFO    ][11494] Returning information for job: 20190325053606783577
2019-03-25 05:36:35,772 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1161.98116612s left)
2019-03-25 05:36:36,900 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325053636885580
2019-03-25 05:36:36,920 [salt.minion      :1432][INFO    ][11526] Starting a new job with PID 11526
2019-03-25 05:36:36,949 [salt.minion      :1711][INFO    ][11526] Returning information for job: 20190325053636885580
2019-03-25 05:37:06,973 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325053706962818
2019-03-25 05:37:06,995 [salt.minion      :1432][INFO    ][11598] Starting a new job with PID 11598
2019-03-25 05:37:07,028 [salt.minion      :1711][INFO    ][11598] Returning information for job: 20190325053706962818
2019-03-25 05:37:08,425 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1129.32855201s left)
2019-03-25 05:37:37,114 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325053737102560
2019-03-25 05:37:37,136 [salt.minion      :1432][INFO    ][11625] Starting a new job with PID 11625
2019-03-25 05:37:37,168 [salt.minion      :1711][INFO    ][11625] Returning information for job: 20190325053737102560
2019-03-25 05:37:41,059 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1096.69407511s left)
2019-03-25 05:38:07,224 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325053807209801
2019-03-25 05:38:07,245 [salt.minion      :1432][INFO    ][11697] Starting a new job with PID 11697
2019-03-25 05:38:07,274 [salt.minion      :1711][INFO    ][11697] Returning information for job: 20190325053807209801
2019-03-25 05:38:13,694 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1064.05920815s left)
2019-03-25 05:38:37,386 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325053837377405
2019-03-25 05:38:37,404 [salt.minion      :1432][INFO    ][11727] Starting a new job with PID 11727
2019-03-25 05:38:37,433 [salt.minion      :1711][INFO    ][11727] Returning information for job: 20190325053837377405
2019-03-25 05:38:46,760 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1030.99328303s left)
2019-03-25 05:39:07,545 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325053907537375
2019-03-25 05:39:07,560 [salt.minion      :1432][INFO    ][11798] Starting a new job with PID 11798
2019-03-25 05:39:07,585 [salt.minion      :1711][INFO    ][11798] Returning information for job: 20190325053907537375
2019-03-25 05:39:19,522 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (998.231487989s left)
2019-03-25 05:39:37,665 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325053937651557
2019-03-25 05:39:37,687 [salt.minion      :1432][INFO    ][11840] Starting a new job with PID 11840
2019-03-25 05:39:37,716 [salt.minion      :1711][INFO    ][11840] Returning information for job: 20190325053937651557
2019-03-25 05:39:52,316 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (965.437428951s left)
2019-03-25 05:40:07,859 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325054007844257
2019-03-25 05:40:07,880 [salt.minion      :1432][INFO    ][11930] Starting a new job with PID 11930
2019-03-25 05:40:07,906 [salt.minion      :1711][INFO    ][11930] Returning information for job: 20190325054007844257
2019-03-25 05:40:24,941 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (932.812498093s left)
2019-03-25 05:40:38,025 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325054038008779
2019-03-25 05:40:38,047 [salt.minion      :1432][INFO    ][11962] Starting a new job with PID 11962
2019-03-25 05:40:38,074 [salt.minion      :1711][INFO    ][11962] Returning information for job: 20190325054038008779
2019-03-25 05:40:57,764 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (899.99002099s left)
2019-03-25 05:41:08,054 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325054108040679
2019-03-25 05:41:08,071 [salt.minion      :1432][INFO    ][12033] Starting a new job with PID 12033
2019-03-25 05:41:08,105 [salt.minion      :1711][INFO    ][12033] Returning information for job: 20190325054108040679
2019-03-25 05:41:30,453 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (867.300781012s left)
2019-03-25 05:41:38,256 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325054138247849
2019-03-25 05:41:38,275 [salt.minion      :1432][INFO    ][12063] Starting a new job with PID 12063
2019-03-25 05:41:38,301 [salt.minion      :1711][INFO    ][12063] Returning information for job: 20190325054138247849
2019-03-25 05:42:03,058 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (834.695343018s left)
2019-03-25 05:42:08,312 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325054208302657
2019-03-25 05:42:08,328 [salt.minion      :1432][INFO    ][12134] Starting a new job with PID 12134
2019-03-25 05:42:08,359 [salt.minion      :1711][INFO    ][12134] Returning information for job: 20190325054208302657
2019-03-25 05:42:35,942 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (801.811414957s left)
2019-03-25 05:42:38,543 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325054238528842
2019-03-25 05:42:38,565 [salt.minion      :1432][INFO    ][12164] Starting a new job with PID 12164
2019-03-25 05:42:38,594 [salt.minion      :1711][INFO    ][12164] Returning information for job: 20190325054238528842
2019-03-25 05:43:08,617 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325054308606838
2019-03-25 05:43:08,637 [salt.minion      :1432][INFO    ][12238] Starting a new job with PID 12238
2019-03-25 05:43:08,668 [salt.minion      :1711][INFO    ][12238] Returning information for job: 20190325054308606838
2019-03-25 05:43:08,689 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (769.064213037s left)
2019-03-25 05:43:38,696 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325054338686963
2019-03-25 05:43:38,714 [salt.minion      :1432][INFO    ][12267] Starting a new job with PID 12267
2019-03-25 05:43:38,747 [salt.minion      :1711][INFO    ][12267] Returning information for job: 20190325054338686963
2019-03-25 05:43:41,481 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (736.272828102s left)
2019-03-25 05:44:08,746 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325054408732672
2019-03-25 05:44:08,768 [salt.minion      :1432][INFO    ][12338] Starting a new job with PID 12338
2019-03-25 05:44:08,802 [salt.minion      :1711][INFO    ][12338] Returning information for job: 20190325054408732672
2019-03-25 05:44:14,222 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (703.532171965s left)
2019-03-25 05:44:38,836 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325054438827613
2019-03-25 05:44:38,852 [salt.minion      :1432][INFO    ][12368] Starting a new job with PID 12368
2019-03-25 05:44:38,881 [salt.minion      :1711][INFO    ][12368] Returning information for job: 20190325054438827613
2019-03-25 05:44:46,742 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (671.011739016s left)
2019-03-25 05:45:09,023 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325054509009420
2019-03-25 05:45:09,046 [salt.minion      :1432][INFO    ][12445] Starting a new job with PID 12445
2019-03-25 05:45:09,077 [salt.minion      :1711][INFO    ][12445] Returning information for job: 20190325054509009420
2019-03-25 05:45:19,423 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (638.330402136s left)
2019-03-25 05:45:39,202 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325054539185192
2019-03-25 05:45:39,223 [salt.minion      :1432][INFO    ][12475] Starting a new job with PID 12475
2019-03-25 05:45:39,258 [salt.minion      :1711][INFO    ][12475] Returning information for job: 20190325054539185192
2019-03-25 05:45:52,127 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (605.626521111s left)
2019-03-25 05:46:09,348 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325054609333350
2019-03-25 05:46:09,367 [salt.minion      :1432][INFO    ][12549] Starting a new job with PID 12549
2019-03-25 05:46:09,397 [salt.minion      :1711][INFO    ][12549] Returning information for job: 20190325054609333350
2019-03-25 05:46:24,766 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (572.987262964s left)
2019-03-25 05:46:39,376 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325054639360126
2019-03-25 05:46:39,395 [salt.minion      :1432][INFO    ][12579] Starting a new job with PID 12579
2019-03-25 05:46:39,423 [salt.minion      :1711][INFO    ][12579] Returning information for job: 20190325054639360126
2019-03-25 05:46:57,580 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (540.17515111s left)
2019-03-25 05:47:09,570 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325054709561013
2019-03-25 05:47:09,584 [salt.minion      :1432][INFO    ][12651] Starting a new job with PID 12651
2019-03-25 05:47:09,612 [salt.minion      :1711][INFO    ][12651] Returning information for job: 20190325054709561013
2019-03-25 05:47:30,226 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (507.52761817s left)
2019-03-25 05:47:39,731 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325054739716746
2019-03-25 05:47:39,751 [salt.minion      :1432][INFO    ][12681] Starting a new job with PID 12681
2019-03-25 05:47:39,781 [salt.minion      :1711][INFO    ][12681] Returning information for job: 20190325054739716746
2019-03-25 05:48:02,928 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (474.825795174s left)
2019-03-25 05:48:09,942 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325054809928004
2019-03-25 05:48:09,962 [salt.minion      :1432][INFO    ][12756] Starting a new job with PID 12756
2019-03-25 05:48:09,993 [salt.minion      :1711][INFO    ][12756] Returning information for job: 20190325054809928004
2019-03-25 05:48:35,716 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (442.039157152s left)
2019-03-25 05:48:40,054 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325054840039530
2019-03-25 05:48:40,076 [salt.minion      :1432][INFO    ][12786] Starting a new job with PID 12786
2019-03-25 05:48:40,106 [salt.minion      :1711][INFO    ][12786] Returning information for job: 20190325054840039530
2019-03-25 05:49:08,585 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (409.168775082s left)
2019-03-25 05:49:10,074 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325054910058221
2019-03-25 05:49:10,097 [salt.minion      :1432][INFO    ][12860] Starting a new job with PID 12860
2019-03-25 05:49:10,128 [salt.minion      :1711][INFO    ][12860] Returning information for job: 20190325054910058221
2019-03-25 05:49:40,222 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325054940211783
2019-03-25 05:49:40,241 [salt.minion      :1432][INFO    ][13029] Starting a new job with PID 13029
2019-03-25 05:49:40,272 [salt.minion      :1711][INFO    ][13029] Returning information for job: 20190325054940211783
2019-03-25 05:49:41,394 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (376.359796047s left)
2019-03-25 05:50:10,256 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325055010243788
2019-03-25 05:50:10,278 [salt.minion      :1432][INFO    ][13108] Starting a new job with PID 13108
2019-03-25 05:50:10,309 [salt.minion      :1711][INFO    ][13108] Returning information for job: 20190325055010243788
2019-03-25 05:50:14,058 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (343.695224047s left)
2019-03-25 05:50:40,425 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325055040411715
2019-03-25 05:50:40,449 [salt.minion      :1432][INFO    ][13138] Starting a new job with PID 13138
2019-03-25 05:50:40,475 [salt.minion      :1711][INFO    ][13138] Returning information for job: 20190325055040411715
2019-03-25 05:50:46,722 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (311.031358004s left)
2019-03-25 05:51:10,566 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325055110558372
2019-03-25 05:51:10,582 [salt.minion      :1432][INFO    ][13210] Starting a new job with PID 13210
2019-03-25 05:51:10,609 [salt.minion      :1711][INFO    ][13210] Returning information for job: 20190325055110558372
2019-03-25 05:51:19,375 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (278.378393173s left)
2019-03-25 05:51:40,729 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325055140714433
2019-03-25 05:51:40,751 [salt.minion      :1432][INFO    ][13240] Starting a new job with PID 13240
2019-03-25 05:51:40,781 [salt.minion      :1711][INFO    ][13240] Returning information for job: 20190325055140714433
2019-03-25 05:51:52,168 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (245.585049152s left)
2019-03-25 05:52:10,948 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325055210934488
2019-03-25 05:52:10,967 [salt.minion      :1432][INFO    ][13314] Starting a new job with PID 13314
2019-03-25 05:52:10,996 [salt.minion      :1711][INFO    ][13314] Returning information for job: 20190325055210934488
2019-03-25 05:52:24,931 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (212.822487116s left)
2019-03-25 05:52:41,072 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325055241065164
2019-03-25 05:52:41,095 [salt.minion      :1432][INFO    ][13348] Starting a new job with PID 13348
2019-03-25 05:52:41,123 [salt.minion      :1711][INFO    ][13348] Returning information for job: 20190325055241065164
2019-03-25 05:52:57,790 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (179.963444948s left)
2019-03-25 05:53:11,096 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325055311088634
2019-03-25 05:53:11,119 [salt.minion      :1432][INFO    ][13421] Starting a new job with PID 13421
2019-03-25 05:53:11,150 [salt.minion      :1711][INFO    ][13421] Returning information for job: 20190325055311088634
2019-03-25 05:53:30,419 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (147.334465027s left)
2019-03-25 05:53:41,327 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325055341317679
2019-03-25 05:53:41,348 [salt.minion      :1432][INFO    ][13451] Starting a new job with PID 13451
2019-03-25 05:53:41,378 [salt.minion      :1711][INFO    ][13451] Returning information for job: 20190325055341317679
2019-03-25 05:54:03,094 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (114.659954071s left)
2019-03-25 05:54:11,393 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325055411378021
2019-03-25 05:54:11,412 [salt.minion      :1432][INFO    ][13525] Starting a new job with PID 13525
2019-03-25 05:54:11,435 [salt.minion      :1711][INFO    ][13525] Returning information for job: 20190325055411378021
2019-03-25 05:54:35,782 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (81.971047163s left)
2019-03-25 05:54:41,432 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325055441419971
2019-03-25 05:54:41,455 [salt.minion      :1432][INFO    ][13555] Starting a new job with PID 13555
2019-03-25 05:54:41,478 [salt.minion      :1711][INFO    ][13555] Returning information for job: 20190325055441419971
2019-03-25 05:55:08,509 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (49.2442040443s left)
2019-03-25 05:55:11,501 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325055511487339
2019-03-25 05:55:11,520 [salt.minion      :1432][INFO    ][13629] Starting a new job with PID 13629
2019-03-25 05:55:11,544 [salt.minion      :1711][INFO    ][13629] Returning information for job: 20190325055511487339
2019-03-25 05:55:41,237 [salt.loaded.ext.module.maas:1023][INFO    ][7361] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (16.5163781643s left)
2019-03-25 05:55:41,552 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325055541543237
2019-03-25 05:55:41,574 [salt.minion      :1432][INFO    ][13659] Starting a new job with PID 13659
2019-03-25 05:55:41,597 [salt.minion      :1711][INFO    ][13659] Returning information for job: 20190325055541543237
2019-03-25 05:56:11,734 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325055611726570
2019-03-25 05:56:11,750 [salt.minion      :1432][INFO    ][13727] Starting a new job with PID 13727
2019-03-25 05:56:11,774 [salt.minion      :1711][INFO    ][13727] Returning information for job: 20190325055611726570
2019-03-25 05:56:14,024 [salt.state       :302 ][ERROR   ][7361] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-03-25 05:56:14,025 [salt.state       :1951][INFO    ][7361] Completed state [maas.wait_for_machine_status] at time 05:56:14.025247 duration_in_ms=2266285.795
2019-03-25 05:56:14,029 [salt.minion      :1711][INFO    ][7361] Returning information for job: 20190325051817720574
2019-03-25 05:56:24,774 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command pillar.get with jid 20190325055624765936
2019-03-25 05:56:24,788 [salt.minion      :1432][INFO    ][13744] Starting a new job with PID 13744
2019-03-25 05:56:24,792 [salt.minion      :1711][INFO    ][13744] Returning information for job: 20190325055624765936
2019-03-25 05:56:25,318 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command service.status with jid 20190325055625311521
2019-03-25 05:56:25,332 [salt.minion      :1432][INFO    ][13749] Starting a new job with PID 13749
2019-03-25 05:56:25,844 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13749] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-03-25 05:56:25,867 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13749] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-03-25 05:56:25,879 [salt.minion      :1711][INFO    ][13749] Returning information for job: 20190325055625311521
2019-03-25 05:56:26,414 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command state.apply with jid 20190325055626406586
2019-03-25 05:56:26,432 [salt.minion      :1432][INFO    ][13760] Starting a new job with PID 13760
2019-03-25 05:56:34,136 [salt.state       :915 ][INFO    ][13760] Loading fresh modules for state activity
2019-03-25 05:56:34,719 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13760] Executing command 'salt-minion --version' in directory '/root'
2019-03-25 05:56:35,013 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13760] Executing command 'salt-minion --version' in directory '/root'
2019-03-25 05:56:35,960 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13760] Executing command 'salt-minion --version' in directory '/root'
2019-03-25 05:56:36,239 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13760] Executing command 'salt-minion --version' in directory '/root'
2019-03-25 05:56:37,870 [salt.state       :1780][INFO    ][13760] Running state [salt-minion] at time 05:56:37.870882
2019-03-25 05:56:37,871 [salt.state       :1813][INFO    ][13760] Executing state pkg.installed for [salt-minion]
2019-03-25 05:56:37,872 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13760] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-03-25 05:56:37,946 [salt.state       :300 ][INFO    ][13760] All specified packages are already installed
2019-03-25 05:56:37,948 [salt.state       :1951][INFO    ][13760] Completed state [salt-minion] at time 05:56:37.948492 duration_in_ms=77.656
2019-03-25 05:56:37,949 [salt.state       :1780][INFO    ][13760] Running state [salt_minion_dependency_packages] at time 05:56:37.948942
2019-03-25 05:56:37,949 [salt.state       :1813][INFO    ][13760] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-03-25 05:56:37,958 [salt.state       :300 ][INFO    ][13760] All specified packages are already installed
2019-03-25 05:56:37,958 [salt.state       :1951][INFO    ][13760] Completed state [salt_minion_dependency_packages] at time 05:56:37.958266 duration_in_ms=9.324
2019-03-25 05:56:37,962 [salt.state       :1780][INFO    ][13760] Running state [/etc/salt/minion.d/minion.conf] at time 05:56:37.962324
2019-03-25 05:56:37,962 [salt.state       :1813][INFO    ][13760] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-03-25 05:56:38,273 [salt.state       :300 ][INFO    ][13760] File /etc/salt/minion.d/minion.conf is in the correct state
2019-03-25 05:56:38,273 [salt.state       :1951][INFO    ][13760] Completed state [/etc/salt/minion.d/minion.conf] at time 05:56:38.273293 duration_in_ms=310.968
2019-03-25 05:56:38,273 [salt.state       :1780][INFO    ][13760] Running state [python-netaddr] at time 05:56:38.273674
2019-03-25 05:56:38,274 [salt.state       :1813][INFO    ][13760] Executing state pkg.installed for [python-netaddr]
2019-03-25 05:56:38,284 [salt.state       :300 ][INFO    ][13760] All specified packages are already installed
2019-03-25 05:56:38,284 [salt.state       :1951][INFO    ][13760] Completed state [python-netaddr] at time 05:56:38.284479 duration_in_ms=10.806
2019-03-25 05:56:38,287 [salt.state       :1780][INFO    ][13760] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 05:56:38.287695
2019-03-25 05:56:38,288 [salt.state       :1813][INFO    ][13760] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-03-25 05:56:38,298 [salt.state       :300 ][INFO    ][13760] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-03-25 05:56:38,299 [salt.state       :1951][INFO    ][13760] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 05:56:38.299052 duration_in_ms=11.356
2019-03-25 05:56:38,300 [salt.state       :1780][INFO    ][13760] Running state [salt-minion] at time 05:56:38.300320
2019-03-25 05:56:38,300 [salt.state       :1813][INFO    ][13760] Executing state service.running for [salt-minion]
2019-03-25 05:56:38,301 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13760] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-03-25 05:56:38,327 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13760] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-03-25 05:56:38,340 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13760] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-03-25 05:56:38,353 [salt.state       :300 ][INFO    ][13760] The service salt-minion is already running
2019-03-25 05:56:38,353 [salt.state       :1951][INFO    ][13760] Completed state [salt-minion] at time 05:56:38.353763 duration_in_ms=53.442
2019-03-25 05:56:38,358 [salt.state       :1780][INFO    ][13760] Running state [/etc/salt/grains.d] at time 05:56:38.358784
2019-03-25 05:56:38,359 [salt.state       :1813][INFO    ][13760] Executing state file.directory for [/etc/salt/grains.d]
2019-03-25 05:56:38,360 [salt.state       :300 ][INFO    ][13760] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-03-25 05:56:38,360 [salt.state       :1951][INFO    ][13760] Completed state [/etc/salt/grains.d] at time 05:56:38.360203 duration_in_ms=1.419
2019-03-25 05:56:38,360 [salt.state       :1780][INFO    ][13760] Running state [/etc/salt/grains] at time 05:56:38.360855
2019-03-25 05:56:38,361 [salt.state       :1813][INFO    ][13760] Executing state file.managed for [/etc/salt/grains]
2019-03-25 05:56:38,361 [salt.state       :300 ][INFO    ][13760] File /etc/salt/grains exists with proper permissions. No changes made.
2019-03-25 05:56:38,361 [salt.state       :1951][INFO    ][13760] Completed state [/etc/salt/grains] at time 05:56:38.361808 duration_in_ms=0.953
2019-03-25 05:56:38,362 [salt.state       :1780][INFO    ][13760] Running state [/etc/salt/grains.d/placeholder] at time 05:56:38.362267
2019-03-25 05:56:38,362 [salt.state       :1813][INFO    ][13760] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-03-25 05:56:38,363 [salt.state       :300 ][INFO    ][13760] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-03-25 05:56:38,363 [salt.state       :1951][INFO    ][13760] Completed state [/etc/salt/grains.d/placeholder] at time 05:56:38.363755 duration_in_ms=1.487
2019-03-25 05:56:38,364 [salt.state       :1780][INFO    ][13760] Running state [/etc/salt/grains.d/sphinx] at time 05:56:38.364208
2019-03-25 05:56:38,364 [salt.state       :1813][INFO    ][13760] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-03-25 05:56:38,365 [salt.state       :300 ][INFO    ][13760] File /etc/salt/grains.d/sphinx is in the correct state
2019-03-25 05:56:38,365 [salt.state       :1951][INFO    ][13760] Completed state [/etc/salt/grains.d/sphinx] at time 05:56:38.365761 duration_in_ms=1.553
2019-03-25 05:56:38,370 [salt.state       :1780][INFO    ][13760] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 05:56:38.370727
2019-03-25 05:56:38,371 [salt.state       :1813][INFO    ][13760] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-03-25 05:56:38,371 [salt.state       :300 ][INFO    ][13760] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-03-25 05:56:38,371 [salt.state       :1951][INFO    ][13760] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 05:56:38.371501 duration_in_ms=0.774
2019-03-25 05:56:38,372 [salt.state       :1780][INFO    ][13760] Running state [/etc/salt/grains.d/dns_records] at time 05:56:38.371972
2019-03-25 05:56:38,372 [salt.state       :1813][INFO    ][13760] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-03-25 05:56:38,373 [salt.state       :300 ][INFO    ][13760] File /etc/salt/grains.d/dns_records is in the correct state
2019-03-25 05:56:38,373 [salt.state       :1951][INFO    ][13760] Completed state [/etc/salt/grains.d/dns_records] at time 05:56:38.373291 duration_in_ms=1.319
2019-03-25 05:56:38,374 [salt.state       :1780][INFO    ][13760] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 05:56:38.374214
2019-03-25 05:56:38,374 [salt.state       :1813][INFO    ][13760] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-03-25 05:56:38,374 [salt.state       :300 ][INFO    ][13760] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-03-25 05:56:38,375 [salt.state       :1951][INFO    ][13760] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 05:56:38.375025 duration_in_ms=0.811
2019-03-25 05:56:38,375 [salt.state       :1780][INFO    ][13760] Running state [/etc/salt/grains.d/salt] at time 05:56:38.375502
2019-03-25 05:56:38,375 [salt.state       :1813][INFO    ][13760] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-03-25 05:56:38,376 [salt.state       :300 ][INFO    ][13760] File /etc/salt/grains.d/salt is in the correct state
2019-03-25 05:56:38,376 [salt.state       :1951][INFO    ][13760] Completed state [/etc/salt/grains.d/salt] at time 05:56:38.376853 duration_in_ms=1.351
2019-03-25 05:56:38,377 [salt.state       :1780][INFO    ][13760] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 05:56:38.377722
2019-03-25 05:56:38,378 [salt.state       :1813][INFO    ][13760] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-03-25 05:56:38,378 [salt.state       :300 ][INFO    ][13760] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-03-25 05:56:38,378 [salt.state       :1951][INFO    ][13760] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 05:56:38.378480 duration_in_ms=0.758
2019-03-25 05:56:38,382 [salt.state       :1780][INFO    ][13760] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 05:56:38.382329
2019-03-25 05:56:38,382 [salt.state       :1813][INFO    ][13760] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-03-25 05:56:38,383 [salt.state       :300 ][INFO    ][13760] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-03-25 05:56:38,383 [salt.state       :1951][INFO    ][13760] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 05:56:38.383406 duration_in_ms=1.077
2019-03-25 05:56:38,384 [salt.state       :1780][INFO    ][13760] Running state [mine.update] at time 05:56:38.384057
2019-03-25 05:56:38,384 [salt.state       :1813][INFO    ][13760] Executing state module.wait for [mine.update]
2019-03-25 05:56:38,384 [salt.state       :300 ][INFO    ][13760] No changes made for mine.update
2019-03-25 05:56:38,384 [salt.state       :1951][INFO    ][13760] Completed state [mine.update] at time 05:56:38.384748 duration_in_ms=0.69
2019-03-25 05:56:38,385 [salt.state       :1780][INFO    ][13760] Running state [ca-certificates] at time 05:56:38.384984
2019-03-25 05:56:38,385 [salt.state       :1813][INFO    ][13760] Executing state pkg.installed for [ca-certificates]
2019-03-25 05:56:38,396 [salt.state       :300 ][INFO    ][13760] All specified packages are already installed
2019-03-25 05:56:38,396 [salt.state       :1951][INFO    ][13760] Completed state [ca-certificates] at time 05:56:38.396619 duration_in_ms=11.635
2019-03-25 05:56:38,397 [salt.state       :1780][INFO    ][13760] Running state [update-ca-certificates] at time 05:56:38.397302
2019-03-25 05:56:38,397 [salt.state       :1813][INFO    ][13760] Executing state cmd.wait for [update-ca-certificates]
2019-03-25 05:56:38,397 [salt.state       :300 ][INFO    ][13760] No changes made for update-ca-certificates
2019-03-25 05:56:38,398 [salt.state       :1951][INFO    ][13760] Completed state [update-ca-certificates] at time 05:56:38.398017 duration_in_ms=0.716
2019-03-25 05:56:38,398 [salt.state       :1780][INFO    ][13760] Running state [iptables] at time 05:56:38.398255
2019-03-25 05:56:38,398 [salt.state       :1813][INFO    ][13760] Executing state pkg.installed for [iptables]
2019-03-25 05:56:38,408 [salt.state       :300 ][INFO    ][13760] All specified packages are already installed
2019-03-25 05:56:38,409 [salt.state       :1951][INFO    ][13760] Completed state [iptables] at time 05:56:38.409079 duration_in_ms=10.824
2019-03-25 05:56:38,409 [salt.state       :1780][INFO    ][13760] Running state [iptables-persistent] at time 05:56:38.409316
2019-03-25 05:56:38,409 [salt.state       :1813][INFO    ][13760] Executing state pkg.installed for [iptables-persistent]
2019-03-25 05:56:38,419 [salt.state       :300 ][INFO    ][13760] All specified packages are already installed
2019-03-25 05:56:38,419 [salt.state       :1951][INFO    ][13760] Completed state [iptables-persistent] at time 05:56:38.419666 duration_in_ms=10.35
2019-03-25 05:56:38,420 [salt.state       :1780][INFO    ][13760] Running state [iptables_modules_v4_load] at time 05:56:38.420629
2019-03-25 05:56:38,420 [salt.state       :1813][INFO    ][13760] Executing state kmod.present for [iptables_modules_v4_load]
2019-03-25 05:56:38,421 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13760] Executing command 'lsmod' in directory '/root'
2019-03-25 05:56:38,443 [salt.state       :300 ][INFO    ][13760] Kernel modules iptable_filter, ip_tables are already present
2019-03-25 05:56:38,444 [salt.state       :1951][INFO    ][13760] Completed state [iptables_modules_v4_load] at time 05:56:38.443853 duration_in_ms=23.223
2019-03-25 05:56:38,445 [salt.state       :1780][INFO    ][13760] Running state [/etc/iptables/rules.v4] at time 05:56:38.445286
2019-03-25 05:56:38,445 [salt.state       :1813][INFO    ][13760] Executing state file.managed for [/etc/iptables/rules.v4]
2019-03-25 05:56:38,549 [salt.state       :300 ][INFO    ][13760] File /etc/iptables/rules.v4 is in the correct state
2019-03-25 05:56:38,549 [salt.state       :1951][INFO    ][13760] Completed state [/etc/iptables/rules.v4] at time 05:56:38.549574 duration_in_ms=104.289
2019-03-25 05:56:38,550 [salt.state       :1780][INFO    ][13760] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 05:56:38.550692
2019-03-25 05:56:38,551 [salt.state       :1813][INFO    ][13760] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-03-25 05:56:38,551 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13760] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-03-25 05:56:38,564 [salt.state       :300 ][INFO    ][13760] onlyif execution failed
2019-03-25 05:56:38,565 [salt.state       :1951][INFO    ][13760] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 05:56:38.565164 duration_in_ms=14.472
2019-03-25 05:56:38,566 [salt.state       :1780][INFO    ][13760] Running state [netfilter-persistent] at time 05:56:38.566237
2019-03-25 05:56:38,566 [salt.state       :1813][INFO    ][13760] Executing state service.running for [netfilter-persistent]
2019-03-25 05:56:38,567 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13760] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-03-25 05:56:38,580 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13760] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-03-25 05:56:38,598 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13760] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-03-25 05:56:38,612 [salt.state       :300 ][INFO    ][13760] The service netfilter-persistent is already running
2019-03-25 05:56:38,613 [salt.state       :1951][INFO    ][13760] Completed state [netfilter-persistent] at time 05:56:38.613242 duration_in_ms=47.005
2019-03-25 05:56:38,614 [salt.state       :1780][INFO    ][13760] Running state [iptables_extra.remove_stale_tables] at time 05:56:38.614127
2019-03-25 05:56:38,614 [salt.state       :1813][INFO    ][13760] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-03-25 05:56:38,614 [salt.state       :300 ][INFO    ][13760] No changes made for iptables_extra.remove_stale_tables
2019-03-25 05:56:38,615 [salt.state       :1951][INFO    ][13760] Completed state [iptables_extra.remove_stale_tables] at time 05:56:38.614972 duration_in_ms=0.845
2019-03-25 05:56:38,615 [salt.state       :1780][INFO    ][13760] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 05:56:38.615223
2019-03-25 05:56:38,615 [salt.state       :1813][INFO    ][13760] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-03-25 05:56:38,616 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13760] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-03-25 05:56:38,629 [salt.state       :300 ][INFO    ][13760] onlyif execution failed
2019-03-25 05:56:38,629 [salt.state       :1951][INFO    ][13760] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 05:56:38.629521 duration_in_ms=14.298
2019-03-25 05:56:38,630 [salt.state       :1780][INFO    ][13760] Running state [/etc/iptables/rules.v6] at time 05:56:38.630466
2019-03-25 05:56:38,630 [salt.state       :1813][INFO    ][13760] Executing state file.absent for [/etc/iptables/rules.v6]
2019-03-25 05:56:38,633 [salt.state       :300 ][INFO    ][13760] File /etc/iptables/rules.v6 is not present
2019-03-25 05:56:38,633 [salt.state       :1951][INFO    ][13760] Completed state [/etc/iptables/rules.v6] at time 05:56:38.633355 duration_in_ms=2.889
2019-03-25 05:56:38,634 [salt.state       :1780][INFO    ][13760] Running state [iptables_extra.flush_all] at time 05:56:38.634045
2019-03-25 05:56:38,634 [salt.state       :1813][INFO    ][13760] Executing state module.wait for [iptables_extra.flush_all]
2019-03-25 05:56:38,634 [salt.state       :300 ][INFO    ][13760] No changes made for iptables_extra.flush_all
2019-03-25 05:56:38,634 [salt.state       :1951][INFO    ][13760] Completed state [iptables_extra.flush_all] at time 05:56:38.634783 duration_in_ms=0.738
2019-03-25 05:56:38,637 [salt.minion      :1711][INFO    ][13760] Returning information for job: 20190325055626406586
2019-03-25 05:56:39,157 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command state.apply with jid 20190325055639149677
2019-03-25 05:56:39,174 [salt.minion      :1432][INFO    ][13849] Starting a new job with PID 13849
2019-03-25 05:56:40,010 [salt.state       :915 ][INFO    ][13849] Loading fresh modules for state activity
2019-03-25 05:56:40,854 [salt.state       :1780][INFO    ][13849] Running state [maas-rack-controller] at time 05:56:40.854472
2019-03-25 05:56:40,855 [salt.state       :1813][INFO    ][13849] Executing state pkg.installed for [maas-rack-controller]
2019-03-25 05:56:40,855 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13849] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-03-25 05:56:40,939 [salt.state       :300 ][INFO    ][13849] All specified packages are already installed
2019-03-25 05:56:40,940 [salt.state       :1951][INFO    ][13849] Completed state [maas-rack-controller] at time 05:56:40.939929 duration_in_ms=85.457
2019-03-25 05:56:40,940 [salt.state       :1780][INFO    ][13849] Running state [ipmitool] at time 05:56:40.940363
2019-03-25 05:56:40,940 [salt.state       :1813][INFO    ][13849] Executing state pkg.installed for [ipmitool]
2019-03-25 05:56:40,951 [salt.state       :300 ][INFO    ][13849] All specified packages are already installed
2019-03-25 05:56:40,951 [salt.state       :1951][INFO    ][13849] Completed state [ipmitool] at time 05:56:40.951387 duration_in_ms=11.024
2019-03-25 05:56:40,955 [salt.state       :1780][INFO    ][13849] Running state [/etc/maas/rackd.conf] at time 05:56:40.955745
2019-03-25 05:56:40,956 [salt.state       :1813][INFO    ][13849] Executing state file.line for [/etc/maas/rackd.conf]
2019-03-25 05:56:40,957 [salt.state       :300 ][INFO    ][13849] No changes needed to be made
2019-03-25 05:56:40,957 [salt.state       :1951][INFO    ][13849] Completed state [/etc/maas/rackd.conf] at time 05:56:40.957454 duration_in_ms=1.709
2019-03-25 05:56:40,957 [salt.state       :1780][INFO    ][13849] Running state [/etc/maas/rackd.conf] at time 05:56:40.957695
2019-03-25 05:56:40,957 [salt.state       :1813][INFO    ][13849] Executing state file.managed for [/etc/maas/rackd.conf]
2019-03-25 05:56:40,958 [salt.loaded.int.states.file:2298][WARNING ][13849] 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-03-25 05:56:40,960 [salt.state       :300 ][INFO    ][13849] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-03-25 05:56:40,960 [salt.state       :1951][INFO    ][13849] Completed state [/etc/maas/rackd.conf] at time 05:56:40.960452 duration_in_ms=2.757
2019-03-25 05:56:40,962 [salt.state       :1780][INFO    ][13849] Running state [maas-rackd] at time 05:56:40.962374
2019-03-25 05:56:40,962 [salt.state       :1813][INFO    ][13849] Executing state service.running for [maas-rackd]
2019-03-25 05:56:40,963 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13849] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-03-25 05:56:40,991 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13849] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-03-25 05:56:41,005 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13849] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-03-25 05:56:41,024 [salt.state       :300 ][INFO    ][13849] The service maas-rackd is already running
2019-03-25 05:56:41,024 [salt.state       :1951][INFO    ][13849] Completed state [maas-rackd] at time 05:56:41.024613 duration_in_ms=62.239
2019-03-25 05:56:41,026 [salt.minion      :1711][INFO    ][13849] Returning information for job: 20190325055639149677
2019-03-25 05:56:41,542 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command state.apply with jid 20190325055641533957
2019-03-25 05:56:41,558 [salt.minion      :1432][INFO    ][13872] Starting a new job with PID 13872
2019-03-25 05:56:42,411 [salt.state       :915 ][INFO    ][13872] Loading fresh modules for state activity
2019-03-25 05:56:43,438 [salt.state       :1780][INFO    ][13872] Running state [maas-region-controller] at time 05:56:43.438398
2019-03-25 05:56:43,438 [salt.state       :1813][INFO    ][13872] Executing state pkg.installed for [maas-region-controller]
2019-03-25 05:56:43,439 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13872] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-03-25 05:56:43,539 [salt.state       :300 ][INFO    ][13872] All specified packages are already installed
2019-03-25 05:56:43,539 [salt.state       :1951][INFO    ][13872] Completed state [maas-region-controller] at time 05:56:43.539576 duration_in_ms=101.179
2019-03-25 05:56:43,539 [salt.state       :1780][INFO    ][13872] Running state [python-oauth] at time 05:56:43.539894
2019-03-25 05:56:43,540 [salt.state       :1813][INFO    ][13872] Executing state pkg.installed for [python-oauth]
2019-03-25 05:56:43,548 [salt.state       :300 ][INFO    ][13872] All specified packages are already installed
2019-03-25 05:56:43,549 [salt.state       :1951][INFO    ][13872] Completed state [python-oauth] at time 05:56:43.549103 duration_in_ms=9.209
2019-03-25 05:56:43,552 [salt.state       :1780][INFO    ][13872] Running state [/etc/maas/regiond.conf] at time 05:56:43.552907
2019-03-25 05:56:43,553 [salt.state       :1813][INFO    ][13872] Executing state file.replace for [/etc/maas/regiond.conf]
2019-03-25 05:56:43,557 [salt.state       :300 ][INFO    ][13872] No changes needed to be made
2019-03-25 05:56:43,558 [salt.state       :1951][INFO    ][13872] Completed state [/etc/maas/regiond.conf] at time 05:56:43.558142 duration_in_ms=5.236
2019-03-25 05:56:43,558 [salt.state       :1780][INFO    ][13872] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:56:43.558618
2019-03-25 05:56:43,559 [salt.state       :1813][INFO    ][13872] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-03-25 05:56:43,615 [salt.state       :300 ][INFO    ][13872] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-03-25 05:56:43,616 [salt.state       :1951][INFO    ][13872] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:56:43.616140 duration_in_ms=57.522
2019-03-25 05:56:43,616 [salt.state       :1780][INFO    ][13872] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:56:43.616685
2019-03-25 05:56:43,616 [salt.state       :1813][INFO    ][13872] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-03-25 05:56:43,620 [salt.state       :300 ][INFO    ][13872] No changes needed to be made
2019-03-25 05:56:43,620 [salt.state       :1951][INFO    ][13872] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:56:43.620854 duration_in_ms=4.169
2019-03-25 05:56:43,621 [salt.state       :1780][INFO    ][13872] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:56:43.621336
2019-03-25 05:56:43,621 [salt.state       :1813][INFO    ][13872] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-03-25 05:56:43,625 [salt.state       :300 ][INFO    ][13872] No changes needed to be made
2019-03-25 05:56:43,626 [salt.state       :1951][INFO    ][13872] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:56:43.626110 duration_in_ms=4.773
2019-03-25 05:56:43,626 [salt.state       :1780][INFO    ][13872] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:56:43.626601
2019-03-25 05:56:43,627 [salt.state       :1813][INFO    ][13872] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-03-25 05:56:43,656 [salt.state       :300 ][INFO    ][13872] No changes needed to be made
2019-03-25 05:56:43,656 [salt.state       :1951][INFO    ][13872] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:56:43.656574 duration_in_ms=29.973
2019-03-25 05:56:43,657 [salt.state       :1780][INFO    ][13872] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:56:43.657081
2019-03-25 05:56:43,657 [salt.state       :1813][INFO    ][13872] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-03-25 05:56:43,668 [salt.state       :300 ][INFO    ][13872] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-03-25 05:56:43,668 [salt.state       :1951][INFO    ][13872] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:56:43.668638 duration_in_ms=11.557
2019-03-25 05:56:43,669 [salt.state       :1780][INFO    ][13872] Running state [a2enmod headers] at time 05:56:43.669794
2019-03-25 05:56:43,670 [salt.state       :1813][INFO    ][13872] Executing state cmd.run for [a2enmod headers]
2019-03-25 05:56:43,670 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13872] Executing command 'a2enmod headers' in directory '/root'
2019-03-25 05:56:43,749 [salt.state       :300 ][INFO    ][13872] {'pid': 13892, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-03-25 05:56:43,750 [salt.state       :1951][INFO    ][13872] Completed state [a2enmod headers] at time 05:56:43.749971 duration_in_ms=80.176
2019-03-25 05:56:43,750 [salt.state       :1780][INFO    ][13872] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:56:43.750594
2019-03-25 05:56:43,751 [salt.state       :1813][INFO    ][13872] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-03-25 05:56:43,771 [salt.state       :300 ][INFO    ][13872] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-03-25 05:56:43,771 [salt.state       :1951][INFO    ][13872] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:56:43.771487 duration_in_ms=20.894
2019-03-25 05:56:43,772 [salt.state       :1780][INFO    ][13872] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:56:43.772080
2019-03-25 05:56:43,772 [salt.state       :1813][INFO    ][13872] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-03-25 05:56:43,822 [salt.state       :300 ][INFO    ][13872] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-03-25 05:56:43,822 [salt.state       :1951][INFO    ][13872] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:56:43.822747 duration_in_ms=50.667
2019-03-25 05:56:43,823 [salt.state       :1780][INFO    ][13872] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:56:43.823573
2019-03-25 05:56:43,823 [salt.state       :1813][INFO    ][13872] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-03-25 05:56:43,880 [salt.state       :300 ][INFO    ][13872] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-03-25 05:56:43,880 [salt.state       :1951][INFO    ][13872] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:56:43.880241 duration_in_ms=56.668
2019-03-25 05:56:43,880 [salt.state       :1780][INFO    ][13872] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:56:43.880737
2019-03-25 05:56:43,881 [salt.state       :1813][INFO    ][13872] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-03-25 05:56:43,965 [salt.state       :300 ][INFO    ][13872] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-03-25 05:56:43,965 [salt.state       :1951][INFO    ][13872] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:56:43.965881 duration_in_ms=85.145
2019-03-25 05:56:43,966 [salt.state       :1780][INFO    ][13872] Running state [/root/.pgpass] at time 05:56:43.966142
2019-03-25 05:56:43,966 [salt.state       :1813][INFO    ][13872] Executing state file.managed for [/root/.pgpass]
2019-03-25 05:56:44,005 [salt.state       :300 ][INFO    ][13872] File /root/.pgpass is in the correct state
2019-03-25 05:56:44,005 [salt.state       :1951][INFO    ][13872] Completed state [/root/.pgpass] at time 05:56:44.005252 duration_in_ms=39.11
2019-03-25 05:56:44,013 [salt.state       :1780][INFO    ][13872] Running state [maas-region syncdb --noinput] at time 05:56:44.013105
2019-03-25 05:56:44,013 [salt.state       :1813][INFO    ][13872] Executing state cmd.run for [maas-region syncdb --noinput]
2019-03-25 05:56:44,014 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13872] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-03-25 05:56:46,775 [salt.state       :300 ][INFO    ][13872] {'pid': 13914, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: sessions, piston3, maasserver, contenttypes, auth, sites, metadataserver\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-03-25 05:56:46,776 [salt.state       :1951][INFO    ][13872] Completed state [maas-region syncdb --noinput] at time 05:56:46.776146 duration_in_ms=2763.039
2019-03-25 05:56:46,776 [salt.state       :2022][WARNING ][13872] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-03-25 05:56:46,780 [salt.state       :1780][INFO    ][13872] Running state [maas-regiond] at time 05:56:46.780025
2019-03-25 05:56:46,780 [salt.state       :1813][INFO    ][13872] Executing state service.running for [maas-regiond]
2019-03-25 05:56:46,782 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13872] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-03-25 05:56:46,821 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13872] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-03-25 05:56:46,842 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13872] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-03-25 05:56:46,859 [salt.state       :300 ][INFO    ][13872] The service maas-regiond is already running
2019-03-25 05:56:46,860 [salt.state       :1951][INFO    ][13872] Completed state [maas-regiond] at time 05:56:46.860211 duration_in_ms=80.187
2019-03-25 05:56:46,864 [salt.state       :1780][INFO    ][13872] Running state [bind9] at time 05:56:46.862792
2019-03-25 05:56:46,865 [salt.state       :1813][INFO    ][13872] Executing state service.running for [bind9]
2019-03-25 05:56:46,866 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13872] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-03-25 05:56:46,885 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13872] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-03-25 05:56:46,905 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13872] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-03-25 05:56:46,927 [salt.state       :300 ][INFO    ][13872] The service bind9 is already running
2019-03-25 05:56:46,927 [salt.state       :1951][INFO    ][13872] Completed state [bind9] at time 05:56:46.927728 duration_in_ms=64.936
2019-03-25 05:56:46,930 [salt.state       :1780][INFO    ][13872] Running state [apache2] at time 05:56:46.930681
2019-03-25 05:56:46,931 [salt.state       :1813][INFO    ][13872] Executing state service.running for [apache2]
2019-03-25 05:56:46,932 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13872] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-03-25 05:56:46,953 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13872] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-03-25 05:56:46,970 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13872] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-03-25 05:56:46,994 [salt.state       :300 ][INFO    ][13872] The service apache2 is already running
2019-03-25 05:56:46,998 [salt.state       :1951][INFO    ][13872] Completed state [apache2] at time 05:56:46.997924 duration_in_ms=67.242
2019-03-25 05:56:47,000 [salt.state       :1780][INFO    ][13872] Running state [maasng.wait_for_http_code] at time 05:56:47.000005
2019-03-25 05:56:47,000 [salt.state       :1813][INFO    ][13872] Executing state module.run for [maasng.wait_for_http_code]
2019-03-25 05:56:47,001 [salt.utils.decorators:613 ][WARNING ][13872] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-25 05:56:47,166 [salt.state       :300 ][INFO    ][13872] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-03-25 05:56:47,166 [salt.state       :1951][INFO    ][13872] Completed state [maasng.wait_for_http_code] at time 05:56:47.166701 duration_in_ms=166.696
2019-03-25 05:56:47,168 [salt.state       :1780][INFO    ][13872] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:56:47.168106
2019-03-25 05:56:47,168 [salt.state       :1813][INFO    ][13872] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-03-25 05:56:47,168 [salt.state       :300 ][INFO    ][13872] /var/lib/maas/.setup_admin exists
2019-03-25 05:56:47,169 [salt.state       :1951][INFO    ][13872] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:56:47.168988 duration_in_ms=0.882
2019-03-25 05:56:47,169 [salt.state       :1780][INFO    ][13872] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:56:47.169649
2019-03-25 05:56:47,169 [salt.state       :1813][INFO    ][13872] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-25 05:56:47,170 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13872] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-25 05:56:48,954 [salt.state       :300 ][INFO    ][13872] {'pid': 13971, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-25 05:56:48,954 [salt.state       :1951][INFO    ][13872] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:56:48.954772 duration_in_ms=1785.122
2019-03-25 05:56:48,960 [salt.state       :1780][INFO    ][13872] Running state [maas_region_boot_source_resources_mirror] at time 05:56:48.960010
2019-03-25 05:56:48,960 [salt.state       :1813][INFO    ][13872] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-03-25 05:56:49,055 [salt.state       :300 ][INFO    ][13872] {'changes': {}}
2019-03-25 05:56:49,056 [salt.state       :1951][INFO    ][13872] Completed state [maas_region_boot_source_resources_mirror] at time 05:56:49.055942 duration_in_ms=95.932
2019-03-25 05:56:49,056 [salt.state       :1780][INFO    ][13872] Running state [maasng.boot_resources_import] at time 05:56:49.056889
2019-03-25 05:56:49,057 [salt.state       :1813][INFO    ][13872] Executing state module.run for [maasng.boot_resources_import]
2019-03-25 05:56:49,057 [salt.utils.decorators:613 ][WARNING ][13872] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-25 05:56:49,148 [salt.loaded.ext.module.maasng:1600][INFO    ][13872] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-03-25 05:56:54,201 [salt.loaded.ext.module.maasng:1600][INFO    ][13872] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-03-25 05:56:56,662 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325055656653567
2019-03-25 05:56:56,688 [salt.minion      :1432][INFO    ][14141] Starting a new job with PID 14141
2019-03-25 05:56:56,713 [salt.minion      :1711][INFO    ][14141] Returning information for job: 20190325055656653567
2019-03-25 05:56:59,277 [salt.state       :300 ][INFO    ][13872] {'ret': True}
2019-03-25 05:56:59,277 [salt.state       :1951][INFO    ][13872] Completed state [maasng.boot_resources_import] at time 05:56:59.277329 duration_in_ms=10220.439
2019-03-25 05:56:59,278 [salt.state       :1780][INFO    ][13872] Running state [maas_region_boot_sources_selection_xenial] at time 05:56:59.278008
2019-03-25 05:56:59,278 [salt.state       :1813][INFO    ][13872] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-03-25 05:56:59,454 [salt.state       :300 ][INFO    ][13872] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-03-25 05:56:59,454 [salt.state       :1951][INFO    ][13872] Completed state [maas_region_boot_sources_selection_xenial] at time 05:56:59.454774 duration_in_ms=176.766
2019-03-25 05:56:59,455 [salt.state       :1780][INFO    ][13872] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 05:56:59.455583
2019-03-25 05:56:59,455 [salt.state       :1813][INFO    ][13872] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-03-25 05:56:59,456 [salt.utils.decorators:613 ][WARNING ][13872] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-25 05:56:59,456 [salt.loaded.ext.module.maasng:1771][INFO    ][13872] boot-sources sync initiated for ALL Rack's
2019-03-25 05:57:00,416 [salt.state       :300 ][INFO    ][13872] {'ret': True}
2019-03-25 05:57:00,417 [salt.state       :1951][INFO    ][13872] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 05:57:00.417329 duration_in_ms=961.745
2019-03-25 05:57:00,419 [salt.state       :1780][INFO    ][13872] Running state [maas.process_maas_config] at time 05:57:00.419762
2019-03-25 05:57:00,420 [salt.state       :1813][INFO    ][13872] Executing state module.run for [maas.process_maas_config]
2019-03-25 05:57:00,420 [salt.utils.decorators:613 ][WARNING ][13872] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-25 05:57:00,421 [salt.loaded.ext.module.maas:92  ][INFO    ][13872] maasconfig name=enable_http_proxy value=True
2019-03-25 05:57:00,471 [salt.loaded.ext.module.maas:92  ][INFO    ][13872] maasconfig name=upstream_dns value=8.8.8.8
2019-03-25 05:57:00,536 [salt.loaded.ext.module.maas:92  ][INFO    ][13872] maasconfig name=commissioning_distro_series value=xenial
2019-03-25 05:57:01,786 [salt.loaded.ext.module.maas:92  ][INFO    ][13872] maasconfig name=default_osystem value=ubuntu
2019-03-25 05:57:01,852 [salt.loaded.ext.module.maas:92  ][INFO    ][13872] maasconfig name=active_discovery_interval value=600
2019-03-25 05:57:01,906 [salt.loaded.ext.module.maas:92  ][INFO    ][13872] maasconfig name=dnssec_validation value=no
2019-03-25 05:57:01,956 [salt.loaded.ext.module.maas:92  ][INFO    ][13872] maasconfig name=maas_name value=mas01
2019-03-25 05:57:01,999 [salt.loaded.ext.module.maas:92  ][INFO    ][13872] maasconfig name=network_discovery value=enabled
2019-03-25 05:57:02,119 [salt.loaded.ext.module.maas:92  ][INFO    ][13872] maasconfig name=enable_third_party_drivers value=True
2019-03-25 05:57:02,162 [salt.loaded.ext.module.maas:92  ][INFO    ][13872] maasconfig name=default_storage_layout value=lvm
2019-03-25 05:57:02,206 [salt.loaded.ext.module.maas:92  ][INFO    ][13872] maasconfig name=ntp_external_only value=True
2019-03-25 05:57:02,250 [salt.loaded.ext.module.maas:92  ][INFO    ][13872] maasconfig name=disk_erase_with_secure_erase value=False
2019-03-25 05:57:02,293 [salt.loaded.ext.module.maas:92  ][INFO    ][13872] maasconfig name=default_distro_series value=xenial
2019-03-25 05:57:02,361 [salt.loaded.ext.module.maas:92  ][INFO    ][13872] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-03-25 05:57:02,471 [salt.state       :300 ][INFO    ][13872] {'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-03-25 05:57:02,472 [salt.state       :1951][INFO    ][13872] Completed state [maas.process_maas_config] at time 05:57:02.472124 duration_in_ms=2052.34
2019-03-25 05:57:02,472 [salt.state       :1780][INFO    ][13872] Running state [pxe_admin] at time 05:57:02.472795
2019-03-25 05:57:02,473 [salt.state       :1813][INFO    ][13872] Executing state maasng.fabric_present for [pxe_admin]
2019-03-25 05:57:02,528 [salt.loaded.ext.module.maasng:945 ][INFO    ][13872] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'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'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'6gyfys', 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'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-03-25 05:57:02,591 [salt.loaded.ext.module.maasng:1008][WARNING ][13872] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-03-25 05:57:02,592 [salt.loaded.ext.module.maasng:1011][WARNING ][13872] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-03-25 05:57:02,657 [salt.state       :300 ][INFO    ][13872] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-03-25 05:57:02,658 [salt.state       :1951][INFO    ][13872] Completed state [pxe_admin] at time 05:57:02.658049 duration_in_ms=185.254
2019-03-25 05:57:02,658 [salt.state       :1780][INFO    ][13872] Running state [vlan 0] at time 05:57:02.658382
2019-03-25 05:57:02,658 [salt.state       :1813][INFO    ][13872] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-03-25 05:57:02,702 [salt.loaded.ext.module.maasng:945 ][INFO    ][13872] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'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'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'6gyfys', 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'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-03-25 05:57:02,794 [salt.loaded.ext.module.maasng:945 ][INFO    ][13872] [{u'id': 0, 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'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'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'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'6gyfys', 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'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-03-25 05:57:03,027 [salt.loaded.ext.module.maasng:945 ][INFO    ][13872] [{u'id': 0, 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'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'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'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'6gyfys', 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'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-03-25 05:57:03,120 [salt.state       :300 ][INFO    ][13872] {'new': 'Vlan untagged was updated'}
2019-03-25 05:57:03,120 [salt.state       :1951][INFO    ][13872] Completed state [vlan 0] at time 05:57:03.120607 duration_in_ms=462.224
2019-03-25 05:57:03,121 [salt.state       :1780][INFO    ][13872] Running state [192.168.11.0/24] at time 05:57:03.121863
2019-03-25 05:57:03,122 [salt.state       :1813][INFO    ][13872] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-03-25 05:57:03,320 [salt.loaded.ext.module.maasng:945 ][INFO    ][13872] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'6gyfys', 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-03-25 05:57:03,321 [salt.loaded.ext.module.maasng:1235][WARNING ][13872] Ignoring parameter vlan:0
2019-03-25 05:57:03,385 [salt.state       :300 ][INFO    ][13872] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-03-25 05:57:03,386 [salt.state       :1951][INFO    ][13872] Completed state [192.168.11.0/24] at time 05:57:03.386177 duration_in_ms=264.314
2019-03-25 05:57:03,388 [salt.state       :1780][INFO    ][13872] Running state [maas_create_iprange_1] at time 05:57:03.388530
2019-03-25 05:57:03,388 [salt.state       :1813][INFO    ][13872] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-03-25 05:57:03,431 [salt.state       :300 ][INFO    ][13872] Iprange maas_create_iprange_1 already exist.
2019-03-25 05:57:03,432 [salt.state       :1951][INFO    ][13872] Completed state [maas_create_iprange_1] at time 05:57:03.431714 duration_in_ms=43.184
2019-03-25 05:57:03,432 [salt.state       :1780][INFO    ][13872] Running state [vlan 0] at time 05:57:03.432281
2019-03-25 05:57:03,432 [salt.state       :1813][INFO    ][13872] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-03-25 05:57:03,478 [salt.loaded.ext.module.maasng:945 ][INFO    ][13872] [{u'id': 0, 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'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'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'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'6gyfys', 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'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-03-25 05:57:03,571 [salt.loaded.ext.module.maasng:945 ][INFO    ][13872] [{u'id': 0, 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'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'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'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'6gyfys', 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'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-03-25 05:57:03,787 [salt.loaded.ext.module.maasng:945 ][INFO    ][13872] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'6gyfys', 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-03-25 05:57:03,908 [salt.state       :300 ][INFO    ][13872] {'new': 'Vlan untagged was updated'}
2019-03-25 05:57:03,908 [salt.state       :1951][INFO    ][13872] Completed state [vlan 0] at time 05:57:03.908521 duration_in_ms=476.24
2019-03-25 05:57:03,909 [salt.state       :1780][INFO    ][13872] Running state [opnfv] at time 05:57:03.909234
2019-03-25 05:57:03,909 [salt.state       :1813][INFO    ][13872] Executing state maasng.sshkey_present for [opnfv]
2019-03-25 05:57:03,949 [salt.loaded.ext.module.maasng:1903][INFO    ][13872] [{u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCwbsLV1UY13y2+2b7H9h6zOFMrcnumZvKOz6oqAXgttZjoKci01cqGRU8OMjob/9xeAOP2Cmu7HOBpRxda00X57yYx4UidSgEtlbOi7LLj2DuvJScix+rgPAd19aukdCiTuMmyLZQPMzVrdBxqzupJ5zqS3djSCllnQ2myEwswcSBn0Dpusgt3bnwdsCgI6B6ZgL9aR9DLnyMPMVroI6ZZH7aP7syIy1sPEsiJd3smWEULgeRElsuK+4I3YKG1Z1J2n80lmtR2gVD5tuKLFRRxnbjAqzyCEXQBTUVRc13SAFrH0b5bzFaqfxY1wdQPzP+dBSA+t25KcjnpTAAtL86H', u'keysource': u''}]
2019-03-25 05:57:03,949 [salt.state       :300 ][INFO    ][13872] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCwbsLV1UY13y2+2b7H9h6zOFMrcnumZvKOz6oqAXgttZjoKci01cqGRU8OMjob/9xeAOP2Cmu7HOBpRxda00X57yYx4UidSgEtlbOi7LLj2DuvJScix+rgPAd19aukdCiTuMmyLZQPMzVrdBxqzupJ5zqS3djSCllnQ2myEwswcSBn0Dpusgt3bnwdsCgI6B6ZgL9aR9DLnyMPMVroI6ZZH7aP7syIy1sPEsiJd3smWEULgeRElsuK+4I3YKG1Z1J2n80lmtR2gVD5tuKLFRRxnbjAqzyCEXQBTUVRc13SAFrH0b5bzFaqfxY1wdQPzP+dBSA+t25KcjnpTAAtL86H already exist for user opnfv.
2019-03-25 05:57:03,950 [salt.state       :1951][INFO    ][13872] Completed state [opnfv] at time 05:57:03.949971 duration_in_ms=40.737
2019-03-25 05:57:03,950 [salt.state       :1780][INFO    ][13872] Running state [maas.process_tags] at time 05:57:03.950691
2019-03-25 05:57:03,954 [salt.state       :1813][INFO    ][13872] Executing state module.run for [maas.process_tags]
2019-03-25 05:57:03,954 [salt.utils.decorators:613 ][WARNING ][13872] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-25 05:57:03,993 [salt.loaded.ext.module.maas:92  ][INFO    ][13872] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-03-25 05:57:04,044 [salt.state       :300 ][INFO    ][13872] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-03-25 05:57:04,044 [salt.state       :1951][INFO    ][13872] Completed state [maas.process_tags] at time 05:57:04.044595 duration_in_ms=93.903
2019-03-25 05:57:04,049 [salt.minion      :1711][INFO    ][13872] Returning information for job: 20190325055641533957
2019-03-25 05:57:04,632 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command state.apply with jid 20190325055704624003
2019-03-25 05:57:04,646 [salt.minion      :1432][INFO    ][14372] Starting a new job with PID 14372
2019-03-25 05:57:12,379 [salt.state       :915 ][INFO    ][14372] Loading fresh modules for state activity
2019-03-25 05:57:12,472 [salt.state       :1780][INFO    ][14372] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:57:12.472134
2019-03-25 05:57:12,472 [salt.state       :1813][INFO    ][14372] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-25 05:57:12,474 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14372] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-25 05:57:14,356 [salt.state       :300 ][INFO    ][14372] {'pid': 14395, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-25 05:57:14,357 [salt.state       :1951][INFO    ][14372] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:57:14.357208 duration_in_ms=1885.075
2019-03-25 05:57:14,359 [salt.state       :1780][INFO    ][14372] Running state [maas.process_machines] at time 05:57:14.359490
2019-03-25 05:57:14,360 [salt.state       :1813][INFO    ][14372] Executing state module.run for [maas.process_machines]
2019-03-25 05:57:14,360 [salt.utils.decorators:613 ][WARNING ][14372] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-25 05:57:14,917 [salt.loaded.ext.module.maas:412 ][WARNING ][14372] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-25 05:57:14,918 [salt.loaded.ext.module.maas:92  ][INFO    ][14372] 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=mbs8qg architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-25 05:57:15,949 [salt.loaded.ext.module.maas:412 ][WARNING ][14372] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-25 05:57:15,950 [salt.loaded.ext.module.maas:92  ][INFO    ][14372] 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=etfwkg architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-25 05:57:16,929 [salt.loaded.ext.module.maas:412 ][WARNING ][14372] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-25 05:57:16,929 [salt.loaded.ext.module.maas:92  ][INFO    ][14372] 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=mcwn3w architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-25 05:57:18,031 [salt.loaded.ext.module.maas:412 ][WARNING ][14372] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-25 05:57:18,032 [salt.loaded.ext.module.maas:92  ][INFO    ][14372] 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=7pa33a architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-25 05:57:19,124 [salt.loaded.ext.module.maas:412 ][WARNING ][14372] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-25 05:57:19,124 [salt.loaded.ext.module.maas:92  ][INFO    ][14372] 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=kaer4c architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-25 05:57:19,691 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325055719676365
2019-03-25 05:57:19,712 [salt.minion      :1432][INFO    ][14613] Starting a new job with PID 14613
2019-03-25 05:57:19,736 [salt.minion      :1711][INFO    ][14613] Returning information for job: 20190325055719676365
2019-03-25 05:57:20,114 [salt.state       :300 ][INFO    ][14372] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-03-25 05:57:20,114 [salt.state       :1951][INFO    ][14372] Completed state [maas.process_machines] at time 05:57:20.114580 duration_in_ms=5755.09
2019-03-25 05:57:20,117 [salt.minion      :1711][INFO    ][14372] Returning information for job: 20190325055704624003
2019-03-25 05:57:53,099 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command state.apply with jid 20190325055753089720
2019-03-25 05:57:53,121 [salt.minion      :1432][INFO    ][14695] Starting a new job with PID 14695
2019-03-25 05:58:00,839 [salt.state       :915 ][INFO    ][14695] Loading fresh modules for state activity
2019-03-25 05:58:00,941 [salt.state       :1780][INFO    ][14695] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:58:00.941759
2019-03-25 05:58:00,942 [salt.state       :1813][INFO    ][14695] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-25 05:58:00,944 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14695] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-25 05:58:02,739 [salt.state       :300 ][INFO    ][14695] {'pid': 14707, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-25 05:58:02,740 [salt.state       :1951][INFO    ][14695] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:58:02.740399 duration_in_ms=1798.64
2019-03-25 05:58:02,743 [salt.state       :1780][INFO    ][14695] Running state [maas.wait_for_machine_status] at time 05:58:02.743780
2019-03-25 05:58:02,744 [salt.state       :1813][INFO    ][14695] Executing state module.run for [maas.wait_for_machine_status]
2019-03-25 05:58:02,744 [salt.utils.decorators:613 ][WARNING ][14695] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-25 05:58:05,506 [salt.state       :300 ][INFO    ][14695] {'ret': True}
2019-03-25 05:58:05,508 [salt.state       :1951][INFO    ][14695] Completed state [maas.wait_for_machine_status] at time 05:58:05.507941 duration_in_ms=2764.161
2019-03-25 05:58:05,510 [salt.minion      :1711][INFO    ][14695] Returning information for job: 20190325055753089720
2019-03-25 05:58:06,046 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command state.apply with jid 20190325055806035708
2019-03-25 05:58:06,069 [salt.minion      :1432][INFO    ][14720] Starting a new job with PID 14720
2019-03-25 05:58:06,985 [salt.state       :915 ][INFO    ][14720] Loading fresh modules for state activity
2019-03-25 05:58:07,130 [salt.state       :1780][INFO    ][14720] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:58:07.130671
2019-03-25 05:58:07,131 [salt.state       :1813][INFO    ][14720] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-25 05:58:07,132 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14720] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-25 05:58:08,922 [salt.state       :300 ][INFO    ][14720] {'pid': 14727, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-25 05:58:08,922 [salt.state       :1951][INFO    ][14720] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:58:08.922591 duration_in_ms=1791.92
2019-03-25 05:58:08,924 [salt.state       :1780][INFO    ][14720] Running state [maas_machines_storage_cmp002_lvm] at time 05:58:08.924725
2019-03-25 05:58:08,925 [salt.state       :1813][INFO    ][14720] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-03-25 05:58:09,467 [salt.state       :300 ][INFO    ][14720] Machine cmp002 is not in Ready state.
2019-03-25 05:58:09,468 [salt.state       :1951][INFO    ][14720] Completed state [maas_machines_storage_cmp002_lvm] at time 05:58:09.468181 duration_in_ms=543.453
2019-03-25 05:58:09,468 [salt.state       :1780][INFO    ][14720] Running state [maas_machines_storage_cmp001_lvm] at time 05:58:09.468563
2019-03-25 05:58:09,468 [salt.state       :1813][INFO    ][14720] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-03-25 05:58:09,973 [salt.state       :300 ][INFO    ][14720] Machine cmp001 is not in Ready state.
2019-03-25 05:58:09,974 [salt.state       :1951][INFO    ][14720] Completed state [maas_machines_storage_cmp001_lvm] at time 05:58:09.974202 duration_in_ms=505.638
2019-03-25 05:58:09,978 [salt.minion      :1711][INFO    ][14720] Returning information for job: 20190325055806035708
2019-03-25 05:58:10,524 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command state.apply with jid 20190325055810512013
2019-03-25 05:58:10,544 [salt.minion      :1432][INFO    ][14737] Starting a new job with PID 14737
2019-03-25 05:58:11,416 [salt.state       :915 ][INFO    ][14737] Loading fresh modules for state activity
2019-03-25 05:58:11,505 [salt.state       :1780][INFO    ][14737] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:58:11.505194
2019-03-25 05:58:11,505 [salt.state       :1813][INFO    ][14737] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-25 05:58:11,507 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14737] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-25 05:58:13,302 [salt.state       :300 ][INFO    ][14737] {'pid': 14744, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-25 05:58:13,303 [salt.state       :1951][INFO    ][14737] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:58:13.303401 duration_in_ms=1798.208
2019-03-25 05:58:13,306 [salt.state       :1780][INFO    ][14737] Running state [maas.deploy_machines] at time 05:58:13.306504
2019-03-25 05:58:13,307 [salt.state       :1813][INFO    ][14737] Executing state module.run for [maas.deploy_machines]
2019-03-25 05:58:13,308 [salt.utils.decorators:613 ][WARNING ][14737] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-25 05:58:13,985 [salt.loaded.ext.module.maas:684 ][INFO    ][14737] deploymachines hwe_kernel=hwe-16.04 system_id=kaer4c distro_series=xenial
2019-03-25 05:58:16,306 [salt.state       :300 ][INFO    ][14737] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-03-25 05:58:16,306 [salt.state       :1951][INFO    ][14737] Completed state [maas.deploy_machines] at time 05:58:16.306411 duration_in_ms=2999.906
2019-03-25 05:58:16,309 [salt.minion      :1711][INFO    ][14737] Returning information for job: 20190325055810512013
2019-03-25 05:58:16,856 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command state.apply with jid 20190325055816840734
2019-03-25 05:58:16,877 [salt.minion      :1432][INFO    ][14809] Starting a new job with PID 14809
2019-03-25 05:58:24,667 [salt.state       :915 ][INFO    ][14809] Loading fresh modules for state activity
2019-03-25 05:58:24,744 [salt.state       :1780][INFO    ][14809] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:58:24.744508
2019-03-25 05:58:24,744 [salt.state       :1813][INFO    ][14809] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-25 05:58:24,746 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14809] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-25 05:58:26,409 [salt.state       :300 ][INFO    ][14809] {'pid': 14820, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-25 05:58:26,410 [salt.state       :1951][INFO    ][14809] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:58:26.410405 duration_in_ms=1665.895
2019-03-25 05:58:26,417 [salt.state       :1780][INFO    ][14809] Running state [maas.wait_for_machine_status] at time 05:58:26.417253
2019-03-25 05:58:26,417 [salt.state       :1813][INFO    ][14809] Executing state module.run for [maas.wait_for_machine_status]
2019-03-25 05:58:26,418 [salt.utils.decorators:613 ][WARNING ][14809] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-25 05:58:28,819 [salt.loaded.ext.module.maas:1023][INFO    ][14809] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.60585713s left)
2019-03-25 05:58:31,977 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325055831961356
2019-03-25 05:58:32,003 [salt.minion      :1432][INFO    ][14844] Starting a new job with PID 14844
2019-03-25 05:58:32,026 [salt.minion      :1711][INFO    ][14844] Returning information for job: 20190325055831961356
2019-03-25 05:59:01,378 [salt.loaded.ext.module.maas:1023][INFO    ][14809] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2215.04680896s left)
2019-03-25 05:59:02,195 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325055902146080
2019-03-25 05:59:02,218 [salt.minion      :1432][INFO    ][14919] Starting a new job with PID 14919
2019-03-25 05:59:02,243 [salt.minion      :1711][INFO    ][14919] Returning information for job: 20190325055902146080
2019-03-25 05:59:32,220 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325055932211728
2019-03-25 05:59:32,240 [salt.minion      :1432][INFO    ][14951] Starting a new job with PID 14951
2019-03-25 05:59:32,263 [salt.minion      :1711][INFO    ][14951] Returning information for job: 20190325055932211728
2019-03-25 05:59:34,170 [salt.loaded.ext.module.maas:1023][INFO    ][14809] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2182.25474095s left)
2019-03-25 06:00:02,234 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325060002226788
2019-03-25 06:00:02,254 [salt.minion      :1432][INFO    ][15036] Starting a new job with PID 15036
2019-03-25 06:00:02,275 [salt.minion      :1711][INFO    ][15036] Returning information for job: 20190325060002226788
2019-03-25 06:00:06,825 [salt.loaded.ext.module.maas:1023][INFO    ][14809] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2149.59938693s left)
2019-03-25 06:00:32,425 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325060032417457
2019-03-25 06:00:32,447 [salt.minion      :1432][INFO    ][15065] Starting a new job with PID 15065
2019-03-25 06:00:32,472 [salt.minion      :1711][INFO    ][15065] Returning information for job: 20190325060032417457
2019-03-25 06:00:39,617 [salt.loaded.ext.module.maas:1023][INFO    ][14809] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2116.80779815s left)
2019-03-25 06:01:02,621 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325060102613099
2019-03-25 06:01:02,635 [salt.minion      :1432][INFO    ][15144] Starting a new job with PID 15144
2019-03-25 06:01:02,657 [salt.minion      :1711][INFO    ][15144] Returning information for job: 20190325060102613099
2019-03-25 06:01:12,265 [salt.loaded.ext.module.maas:1023][INFO    ][14809] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2084.16006112s left)
2019-03-25 06:01:32,805 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325060132793448
2019-03-25 06:01:32,828 [salt.minion      :1432][INFO    ][15173] Starting a new job with PID 15173
2019-03-25 06:01:32,852 [salt.minion      :1711][INFO    ][15173] Returning information for job: 20190325060132793448
2019-03-25 06:01:45,040 [salt.loaded.ext.module.maas:1023][INFO    ][14809] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2051.38438296s left)
2019-03-25 06:02:02,997 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325060202989020
2019-03-25 06:02:03,022 [salt.minion      :1432][INFO    ][15246] Starting a new job with PID 15246
2019-03-25 06:02:03,044 [salt.minion      :1711][INFO    ][15246] Returning information for job: 20190325060202989020
2019-03-25 06:02:17,813 [salt.loaded.ext.module.maas:1023][INFO    ][14809] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2018.61206794s left)
2019-03-25 06:02:33,198 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325060233189750
2019-03-25 06:02:33,214 [salt.minion      :1432][INFO    ][15300] Starting a new job with PID 15300
2019-03-25 06:02:33,235 [salt.minion      :1711][INFO    ][15300] Returning information for job: 20190325060233189750
2019-03-25 06:02:50,709 [salt.loaded.ext.module.maas:1023][INFO    ][14809] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1985.71617699s left)
2019-03-25 06:03:03,266 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325060303257259
2019-03-25 06:03:03,287 [salt.minion      :1432][INFO    ][15381] Starting a new job with PID 15381
2019-03-25 06:03:03,313 [salt.minion      :1711][INFO    ][15381] Returning information for job: 20190325060303257259
2019-03-25 06:03:23,565 [salt.loaded.ext.module.maas:1023][INFO    ][14809] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1952.85991406s left)
2019-03-25 06:03:33,348 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325060333334052
2019-03-25 06:03:33,369 [salt.minion      :1432][INFO    ][15436] Starting a new job with PID 15436
2019-03-25 06:03:33,395 [salt.minion      :1711][INFO    ][15436] Returning information for job: 20190325060333334052
2019-03-25 06:03:56,508 [salt.loaded.ext.module.maas:1023][INFO    ][14809] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1919.91690302s left)
2019-03-25 06:04:03,402 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325060403392995
2019-03-25 06:04:03,427 [salt.minion      :1432][INFO    ][15656] Starting a new job with PID 15656
2019-03-25 06:04:03,446 [salt.minion      :1711][INFO    ][15656] Returning information for job: 20190325060403392995
2019-03-25 06:04:29,285 [salt.loaded.ext.module.maas:1023][INFO    ][14809] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1887.14010096s left)
2019-03-25 06:04:33,600 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325060433591517
2019-03-25 06:04:33,620 [salt.minion      :1432][INFO    ][15685] Starting a new job with PID 15685
2019-03-25 06:04:33,644 [salt.minion      :1711][INFO    ][15685] Returning information for job: 20190325060433591517
2019-03-25 06:05:02,191 [salt.loaded.ext.module.maas:1023][INFO    ][14809] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1854.23430705s left)
2019-03-25 06:05:03,813 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325060503804372
2019-03-25 06:05:03,836 [salt.minion      :1432][INFO    ][15780] Starting a new job with PID 15780
2019-03-25 06:05:03,859 [salt.minion      :1711][INFO    ][15780] Returning information for job: 20190325060503804372
2019-03-25 06:05:34,027 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325060534019477
2019-03-25 06:05:34,042 [salt.minion      :1432][INFO    ][15813] Starting a new job with PID 15813
2019-03-25 06:05:34,066 [salt.minion      :1711][INFO    ][15813] Returning information for job: 20190325060534019477
2019-03-25 06:05:34,943 [salt.loaded.ext.module.maas:1023][INFO    ][14809] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1821.48138404s left)
2019-03-25 06:06:04,040 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325060604032523
2019-03-25 06:06:04,064 [salt.minion      :1432][INFO    ][15978] Starting a new job with PID 15978
2019-03-25 06:06:04,088 [salt.minion      :1711][INFO    ][15978] Returning information for job: 20190325060604032523
2019-03-25 06:06:07,826 [salt.loaded.ext.module.maas:1023][INFO    ][14809] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1788.59854293s left)
2019-03-25 06:06:34,256 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325060634243218
2019-03-25 06:06:34,281 [salt.minion      :1432][INFO    ][16007] Starting a new job with PID 16007
2019-03-25 06:06:34,303 [salt.minion      :1711][INFO    ][16007] Returning information for job: 20190325060634243218
2019-03-25 06:06:40,574 [salt.loaded.ext.module.maas:1023][INFO    ][14809] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1755.85127592s left)
2019-03-25 06:07:04,268 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325060704260717
2019-03-25 06:07:04,291 [salt.minion      :1432][INFO    ][16105] Starting a new job with PID 16105
2019-03-25 06:07:04,319 [salt.minion      :1711][INFO    ][16105] Returning information for job: 20190325060704260717
2019-03-25 06:07:13,354 [salt.loaded.ext.module.maas:1023][INFO    ][14809] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1723.07063603s left)
2019-03-25 06:07:34,284 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325060734277199
2019-03-25 06:07:34,297 [salt.minion      :1432][INFO    ][16136] Starting a new job with PID 16136
2019-03-25 06:07:34,321 [salt.minion      :1711][INFO    ][16136] Returning information for job: 20190325060734277199
2019-03-25 06:07:46,510 [salt.loaded.ext.module.maas:1023][INFO    ][14809] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1689.91492605s left)
2019-03-25 06:08:04,352 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325060804341960
2019-03-25 06:08:04,375 [salt.minion      :1432][INFO    ][16211] Starting a new job with PID 16211
2019-03-25 06:08:04,399 [salt.minion      :1711][INFO    ][16211] Returning information for job: 20190325060804341960
2019-03-25 06:08:19,205 [salt.loaded.ext.module.maas:1023][INFO    ][14809] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1657.21996713s left)
2019-03-25 06:08:34,516 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325060834501636
2019-03-25 06:08:34,542 [salt.minion      :1432][INFO    ][16240] Starting a new job with PID 16240
2019-03-25 06:08:34,565 [salt.minion      :1711][INFO    ][16240] Returning information for job: 20190325060834501636
2019-03-25 06:08:51,998 [salt.loaded.ext.module.maas:1023][INFO    ][14809] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1624.42680001s left)
2019-03-25 06:09:04,561 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325060904548228
2019-03-25 06:09:04,578 [salt.minion      :1432][INFO    ][16312] Starting a new job with PID 16312
2019-03-25 06:09:04,604 [salt.minion      :1711][INFO    ][16312] Returning information for job: 20190325060904548228
2019-03-25 06:09:24,826 [salt.loaded.ext.module.maas:1023][INFO    ][14809] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1591.59896207s left)
2019-03-25 06:09:34,726 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325060934716031
2019-03-25 06:09:34,747 [salt.minion      :1432][INFO    ][16349] Starting a new job with PID 16349
2019-03-25 06:09:34,771 [salt.minion      :1711][INFO    ][16349] Returning information for job: 20190325060934716031
2019-03-25 06:09:35,429 [salt.utils.schedule:1377][INFO    ][3032] Running scheduled job: __mine_interval
2019-03-25 06:09:57,878 [salt.loaded.ext.module.maas:1023][INFO    ][14809] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1558.54665995s left)
2019-03-25 06:10:04,823 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190325061004816351
2019-03-25 06:10:04,843 [salt.minion      :1432][INFO    ][16615] Starting a new job with PID 16615
2019-03-25 06:10:04,867 [salt.minion      :1711][INFO    ][16615] Returning information for job: 20190325061004816351
2019-03-25 06:10:30,708 [salt.state       :300 ][INFO    ][14809] {'ret': True}
2019-03-25 06:10:30,708 [salt.state       :1951][INFO    ][14809] Completed state [maas.wait_for_machine_status] at time 06:10:30.708532 duration_in_ms=724291.274
2019-03-25 06:10:30,714 [salt.minion      :1711][INFO    ][14809] Returning information for job: 20190325055816840734
2019-03-25 07:09:35,428 [salt.utils.schedule:1377][INFO    ][3032] Running scheduled job: __mine_interval
2019-03-25 08:09:35,428 [salt.utils.schedule:1377][INFO    ][3032] Running scheduled job: __mine_interval
2019-03-25 08:10:10,378 [salt.minion      :1308][INFO    ][3032] User sudo_ubuntu Executing command cp.push_dir with jid 20190325081010369509
2019-03-25 08:10:10,401 [salt.minion      :1432][INFO    ][28979] Starting a new job with PID 28979
