2019-04-28 02:03:57,174 [salt.utils.decorators:613 ][WARNING ][2022] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-28 02:03:57,851 [salt.utils.decorators:613 ][WARNING ][2022] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-28 02:04:00,701 [salt.loaded.int.states.file:2298][WARNING ][2554] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-04-28 02:04:22,999 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3123] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-04-28 02:04:23,023 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3123] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-04-28 02:04:23,065 [salt.utils.parsers:1051][WARNING ][378] Minion received a SIGTERM. Exiting.
2019-04-28 02:04:24,043 [salt.cli.daemons :293 ][INFO    ][3235] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-04-28 02:04:24,156 [salt.cli.daemons :82  ][INFO    ][3235] Starting up the Salt Minion
2019-04-28 02:04:24,157 [salt.utils.event :1017][INFO    ][3235] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-04-28 02:04:25,227 [salt.minion      :976 ][INFO    ][3235] Creating minion process manager
2019-04-28 02:04:27,101 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][3235] Executing command ['date', '+%z'] in directory '/root'
2019-04-28 02:04:27,120 [salt.utils.schedule:568 ][INFO    ][3235] Updating job settings for scheduled job: __mine_interval
2019-04-28 02:04:27,121 [salt.minion      :1108][INFO    ][3235] Added mine.update to scheduler
2019-04-28 02:04:27,128 [salt.minion      :1975][INFO    ][3235] Minion is starting as user 'root'
2019-04-28 02:04:27,139 [salt.minion      :2336][INFO    ][3235] Minion is ready to receive requests!
2019-04-28 02:04:31,619 [salt.state       :2022][WARNING ][3127] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-04-28 02:04:34,591 [salt.utils.decorators:613 ][WARNING ][3127] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-28 02:04:36,021 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428020436009112
2019-04-28 02:04:36,046 [salt.minion      :1432][INFO    ][3786] Starting a new job with PID 3786
2019-04-28 02:04:36,073 [salt.minion      :1711][INFO    ][3786] Returning information for job: 20190428020436009112
2019-04-28 02:04:46,211 [salt.utils.decorators:613 ][WARNING ][3127] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-28 02:05:06,058 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428020506042656
2019-04-28 02:05:06,083 [salt.minion      :1432][INFO    ][4242] Starting a new job with PID 4242
2019-04-28 02:05:06,103 [salt.minion      :1711][INFO    ][4242] Returning information for job: 20190428020506042656
2019-04-28 02:05:11,913 [salt.utils.decorators:613 ][WARNING ][3127] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-28 02:05:12,914 [salt.utils.decorators:613 ][WARNING ][3127] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-28 02:05:15,361 [salt.loaded.ext.module.maasng:1008][WARNING ][3127] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-04-28 02:05:15,362 [salt.loaded.ext.module.maasng:1011][WARNING ][3127] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-04-28 02:05:16,028 [salt.loaded.ext.module.maasng:1235][WARNING ][3127] Ignoring parameter vlan:0
2019-04-28 02:05:17,817 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command state.apply with jid 20190428020517804908
2019-04-28 02:05:17,830 [salt.minion      :1432][INFO    ][4602] Starting a new job with PID 4602
2019-04-28 02:05:23,920 [salt.state       :915 ][INFO    ][4602] Loading fresh modules for state activity
2019-04-28 02:05:23,982 [salt.fileclient  :1219][INFO    ][4602] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-04-28 02:05:24,022 [salt.state       :1780][INFO    ][4602] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:05:24.022223
2019-04-28 02:05:24,022 [salt.state       :1813][INFO    ][4602] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-28 02:05:24,024 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4602] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-28 02:05:25,709 [salt.state       :300 ][INFO    ][4602] {'pid': 4645, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-28 02:05:25,709 [salt.state       :1951][INFO    ][4602] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:05:25.709475 duration_in_ms=1687.252
2019-04-28 02:05:25,711 [salt.state       :1780][INFO    ][4602] Running state [maas.process_machines] at time 02:05:25.711501
2019-04-28 02:05:25,711 [salt.state       :1813][INFO    ][4602] Executing state module.run for [maas.process_machines]
2019-04-28 02:05:25,712 [salt.utils.decorators:613 ][WARNING ][4602] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-28 02:05:25,769 [salt.loaded.ext.module.maas:412 ][WARNING ][4602] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-28 02:05:25,769 [salt.loaded.ext.module.maas:92  ][INFO    ][4602] machine hostname=cmp002 power_type=ipmi mac_addresses=9c:b6:54:8a:10:18 power_parameters_power_address=172.16.1.20 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-28 02:05:27,141 [salt.loaded.ext.module.maas:412 ][WARNING ][4602] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-28 02:05:27,141 [salt.loaded.ext.module.maas:92  ][INFO    ][4602] machine hostname=cmp001 power_type=ipmi mac_addresses=9c:b6:54:8a:95:a0 power_parameters_power_address=172.16.1.19 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-28 02:05:28,454 [salt.loaded.ext.module.maas:412 ][WARNING ][4602] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-28 02:05:28,454 [salt.loaded.ext.module.maas:92  ][INFO    ][4602] machine hostname=kvm01 power_type=ipmi mac_addresses=14:58:d0:54:e7:88 power_parameters_power_address=172.16.1.16 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-28 02:05:29,615 [salt.loaded.ext.module.maas:412 ][WARNING ][4602] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-28 02:05:29,615 [salt.loaded.ext.module.maas:92  ][INFO    ][4602] machine hostname=kvm03 power_type=ipmi mac_addresses=14:58:d0:54:7a:28 power_parameters_power_address=172.16.1.18 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-28 02:05:30,755 [salt.loaded.ext.module.maas:412 ][WARNING ][4602] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-28 02:05:30,755 [salt.loaded.ext.module.maas:92  ][INFO    ][4602] machine hostname=kvm02 power_type=ipmi mac_addresses=14:58:d0:54:6a:60 power_parameters_power_address=172.16.1.17 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-28 02:05:31,814 [salt.state       :300 ][INFO    ][4602] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-04-28 02:05:31,818 [salt.state       :1951][INFO    ][4602] Completed state [maas.process_machines] at time 02:05:31.818815 duration_in_ms=6107.313
2019-04-28 02:05:31,821 [salt.minion      :1711][INFO    ][4602] Returning information for job: 20190428020517804908
2019-04-28 02:06:02,821 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command state.apply with jid 20190428020602810290
2019-04-28 02:06:02,832 [salt.minion      :1432][INFO    ][4991] Starting a new job with PID 4991
2019-04-28 02:06:08,711 [salt.state       :915 ][INFO    ][4991] Loading fresh modules for state activity
2019-04-28 02:06:08,771 [salt.fileclient  :1219][INFO    ][4991] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-04-28 02:06:08,819 [salt.state       :1780][INFO    ][4991] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:06:08.819627
2019-04-28 02:06:08,819 [salt.state       :1813][INFO    ][4991] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-28 02:06:08,821 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4991] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-28 02:06:10,519 [salt.state       :300 ][INFO    ][4991] {'pid': 5014, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-28 02:06:10,520 [salt.state       :1951][INFO    ][4991] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:06:10.520168 duration_in_ms=1700.539
2019-04-28 02:06:10,523 [salt.state       :1780][INFO    ][4991] Running state [maas.wait_for_machine_status] at time 02:06:10.523866
2019-04-28 02:06:10,524 [salt.state       :1813][INFO    ][4991] Executing state module.run for [maas.wait_for_machine_status]
2019-04-28 02:06:10,525 [salt.utils.decorators:613 ][WARNING ][4991] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-28 02:06:11,264 [salt.loaded.ext.module.maas:1023][INFO    ][4991] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.26936698s left)
2019-04-28 02:06:17,898 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428020617885906
2019-04-28 02:06:17,917 [salt.minion      :1432][INFO    ][5026] Starting a new job with PID 5026
2019-04-28 02:06:17,938 [salt.minion      :1711][INFO    ][5026] Returning information for job: 20190428020617885906
2019-04-28 02:06:42,320 [salt.loaded.ext.module.maas:1023][INFO    ][4991] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.21341395s left)
2019-04-28 02:06:47,953 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428020647939261
2019-04-28 02:06:47,972 [salt.minion      :1432][INFO    ][5133] Starting a new job with PID 5133
2019-04-28 02:06:47,999 [salt.minion      :1711][INFO    ][5133] Returning information for job: 20190428020647939261
2019-04-28 02:07:13,104 [salt.loaded.ext.module.maas:1023][INFO    ][4991] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.43000793s left)
2019-04-28 02:07:18,023 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428020718008796
2019-04-28 02:07:18,043 [salt.minion      :1432][INFO    ][5158] Starting a new job with PID 5158
2019-04-28 02:07:18,065 [salt.minion      :1711][INFO    ][5158] Returning information for job: 20190428020718008796
2019-04-28 02:07:43,749 [salt.loaded.ext.module.maas:1023][INFO    ][4991] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.78508091s left)
2019-04-28 02:07:48,069 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428020748054485
2019-04-28 02:07:48,089 [salt.minion      :1432][INFO    ][5234] Starting a new job with PID 5234
2019-04-28 02:07:48,109 [salt.minion      :1711][INFO    ][5234] Returning information for job: 20190428020748054485
2019-04-28 02:08:14,811 [salt.loaded.ext.module.maas:1023][INFO    ][4991] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.72248793s left)
2019-04-28 02:08:18,297 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428020818284699
2019-04-28 02:08:18,320 [salt.minion      :1432][INFO    ][5283] Starting a new job with PID 5283
2019-04-28 02:08:18,341 [salt.minion      :1711][INFO    ][5283] Returning information for job: 20190428020818284699
2019-04-28 02:08:45,852 [salt.loaded.ext.module.maas:1023][INFO    ][4991] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.68121195s left)
2019-04-28 02:08:48,372 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428020848362210
2019-04-28 02:08:48,384 [salt.minion      :1432][INFO    ][5490] Starting a new job with PID 5490
2019-04-28 02:08:48,406 [salt.minion      :1711][INFO    ][5490] Returning information for job: 20190428020848362210
2019-04-28 02:09:17,168 [salt.loaded.ext.module.maas:1023][INFO    ][4991] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1313.36548805s left)
2019-04-28 02:09:18,405 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428020918390557
2019-04-28 02:09:18,426 [salt.minion      :1432][INFO    ][5605] Starting a new job with PID 5605
2019-04-28 02:09:18,446 [salt.minion      :1711][INFO    ][5605] Returning information for job: 20190428020918390557
2019-04-28 02:09:48,360 [salt.loaded.ext.module.maas:1023][INFO    ][4991] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1282.17410207s left)
2019-04-28 02:09:48,501 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428020948477892
2019-04-28 02:09:48,529 [salt.minion      :1432][INFO    ][5905] Starting a new job with PID 5905
2019-04-28 02:09:48,564 [salt.minion      :1711][INFO    ][5905] Returning information for job: 20190428020948477892
2019-04-28 02:10:18,599 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428021018590644
2019-04-28 02:10:18,614 [salt.minion      :1432][INFO    ][6033] Starting a new job with PID 6033
2019-04-28 02:10:18,635 [salt.minion      :1711][INFO    ][6033] Returning information for job: 20190428021018590644
2019-04-28 02:10:20,037 [salt.loaded.ext.module.maas:1023][INFO    ][4991] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1250.49683189s left)
2019-04-28 02:10:48,688 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428021048672123
2019-04-28 02:10:48,707 [salt.minion      :1432][INFO    ][6338] Starting a new job with PID 6338
2019-04-28 02:10:48,731 [salt.minion      :1711][INFO    ][6338] Returning information for job: 20190428021048672123
2019-04-28 02:10:52,226 [salt.loaded.ext.module.maas:1023][INFO    ][4991] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1218.30811596s left)
2019-04-28 02:11:18,777 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428021118765673
2019-04-28 02:11:18,800 [salt.minion      :1432][INFO    ][6575] Starting a new job with PID 6575
2019-04-28 02:11:18,823 [salt.minion      :1711][INFO    ][6575] Returning information for job: 20190428021118765673
2019-04-28 02:11:24,234 [salt.loaded.ext.module.maas:1023][INFO    ][4991] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1186.2994411s left)
2019-04-28 02:11:48,828 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428021148818323
2019-04-28 02:11:48,846 [salt.minion      :1432][INFO    ][6760] Starting a new job with PID 6760
2019-04-28 02:11:48,866 [salt.minion      :1711][INFO    ][6760] Returning information for job: 20190428021148818323
2019-04-28 02:11:56,276 [salt.loaded.ext.module.maas:1023][INFO    ][4991] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1154.25766492s left)
2019-04-28 02:12:18,890 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428021218878815
2019-04-28 02:12:18,909 [salt.minion      :1432][INFO    ][6901] Starting a new job with PID 6901
2019-04-28 02:12:18,931 [salt.minion      :1711][INFO    ][6901] Returning information for job: 20190428021218878815
2019-04-28 02:12:29,123 [salt.state       :300 ][INFO    ][4991] {'ret': True}
2019-04-28 02:12:29,123 [salt.state       :1951][INFO    ][4991] Completed state [maas.wait_for_machine_status] at time 02:12:29.123645 duration_in_ms=378599.778
2019-04-28 02:12:29,129 [salt.minion      :1711][INFO    ][4991] Returning information for job: 20190428020602810290
2019-04-28 02:12:29,695 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command state.apply with jid 20190428021229682522
2019-04-28 02:12:29,712 [salt.minion      :1432][INFO    ][6936] Starting a new job with PID 6936
2019-04-28 02:12:35,750 [salt.state       :915 ][INFO    ][6936] Loading fresh modules for state activity
2019-04-28 02:12:35,808 [salt.fileclient  :1219][INFO    ][6936] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-04-28 02:12:35,911 [salt.state       :1780][INFO    ][6936] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:12:35.911429
2019-04-28 02:12:35,911 [salt.state       :1813][INFO    ][6936] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-28 02:12:35,913 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6936] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-28 02:12:37,620 [salt.state       :300 ][INFO    ][6936] {'pid': 7027, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-28 02:12:37,621 [salt.state       :1951][INFO    ][6936] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:12:37.621131 duration_in_ms=1709.7
2019-04-28 02:12:37,624 [salt.state       :1780][INFO    ][6936] Running state [maas_machines_storage_cmp002_lvm] at time 02:12:37.624917
2019-04-28 02:12:37,625 [salt.state       :1813][INFO    ][6936] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-04-28 02:12:38,743 [salt.loaded.ext.module.maasng:610 ][INFO    ][6936] 6yhhnx
2019-04-28 02:12:38,743 [salt.loaded.ext.module.maasng:626 ][INFO    ][6936] sda
2019-04-28 02:12:39,398 [salt.loaded.ext.module.maasng:361 ][INFO    ][6936] 6yhhnx
2019-04-28 02:12:39,491 [salt.loaded.ext.module.maasng:367 ][INFO    ][6936] [{u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/6yhhnx/blockdevices/1/', u'available_size': 0, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'uuid': u'5e9fdc52-fb0b-4c0d-9a45-a55439f41ad9', u'resource_uri': u'/MAAS/api/2.0/nodes/6yhhnx/blockdevices/1/partition/1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6yhhnx', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'66c3f2cc-fc7d-4a87-b72b-96faab39f0bc', 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'name': u'sda', u'system_id': u'6yhhnx', 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'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'size': 800109715456}, {u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/6yhhnx/blockdevices/4/', u'available_size': 0, u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'partitions': [], u'uuid': u'2a977542-710a-42a2-9204-a1254d9f7789', u'name': u'vgroot-lvroot', u'system_id': u'6yhhnx', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'53bac458-b95f-4c57-b978-ac1b5f7c3f31', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'used_size': 800097042432, u'id': 4, u'size': 800097042432}]
2019-04-28 02:12:39,492 [salt.loaded.ext.module.maasng:632 ][INFO    ][6936] vgroot
2019-04-28 02:12:39,492 [salt.loaded.ext.module.maasng:635 ][INFO    ][6936] lvroot
2019-04-28 02:12:39,492 [salt.loaded.ext.module.maasng:639 ][INFO    ][6936] 107374182400
2019-04-28 02:12:40,086 [salt.loaded.ext.module.maasng:645 ][INFO    ][6936] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'testing_status_name': u'Passed', u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'boot_interface': {u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qgxrnm', 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'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'qgxrnm', 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': 18}], u'tags': [u'sriov'], 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'qgxrnm', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'system_id': u'6yhhnx', u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/6yhhnx/interfaces/4/'}, u'min_hwe_kernel': u'ga-16.04', u'status_action': u'', u'tag_names': [], u'swap_size': None, 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'block_size': 4096, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'6yhhnx', u'resource_uri': u'/MAAS/api/2.0/nodes/6yhhnx/blockdevices/1/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456, u'type': u'physical', u'id': 1, u'partitions': [{u'uuid': u'2aa91d1c-99c3-48c1-a400-7f25c321d470', u'resource_uri': u'/MAAS/api/2.0/nodes/6yhhnx/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6yhhnx', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'5c5217f9-e476-4c3a-b4e9-ec8ba0483b74', 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'size': 800101236736}]}, u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/6yhhnx/', u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'node_type': 0, u'testing_status': 2, u'system_id': u'6yhhnx', 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'block_size': 4096, u'available_size': 0, u'uuid': u'e9d2eea5-dd85-4357-bf92-c4ba9b5ed8b5', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'6yhhnx', u'resource_uri': u'/MAAS/api/2.0/nodes/6yhhnx/blockdevices/12/', u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'65f34110-8990-469a-ab6c-ed06a1180003', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'size': 107374182400, u'type': u'virtual', u'id': 12, u'partitions': []}], u'blockdevice_set': [{u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/6yhhnx/blockdevices/1/', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'6yhhnx', u'block_size': 4096, u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456, u'type': u'physical', u'id': 1, u'partitions': [{u'uuid': u'2aa91d1c-99c3-48c1-a400-7f25c321d470', u'resource_uri': u'/MAAS/api/2.0/nodes/6yhhnx/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6yhhnx', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'5c5217f9-e476-4c3a-b4e9-ec8ba0483b74', 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'size': 800101236736}]}, {u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/6yhhnx/blockdevices/12/', u'uuid': u'e9d2eea5-dd85-4357-bf92-c4ba9b5ed8b5', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'6yhhnx', u'block_size': 4096, u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'65f34110-8990-469a-ab6c-ed06a1180003', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'size': 107374182400, u'type': u'virtual', u'id': 12, u'partitions': []}], u'status': 4, u'storage_test_status': 2, u'cpu_count': 40, u'raids': [], u'owner_data': {}, u'memory_test_status_name': u'Unknown', u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'6yhhnx', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'commissioning_status_name': u'Passed', u'interface_set': [{u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qgxrnm', 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'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'qgxrnm', 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': 18}], u'tags': [u'sriov'], 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'qgxrnm', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'system_id': u'6yhhnx', u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/6yhhnx/interfaces/4/'}, {u'discovered': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'system_id': u'6yhhnx', u'params': u'', u'mac_address': u'38:ea:a7:8f:07:51', u'parents': [], u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/6yhhnx/interfaces/14/'}, {u'discovered': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'system_id': u'6yhhnx', u'params': u'', u'mac_address': u'38:ea:a7:8f:07:50', u'parents': [], u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/6yhhnx/interfaces/15/'}, {u'discovered': None, u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 19}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'system_id': u'6yhhnx', u'params': u'', u'mac_address': u'38:ea:a7:8f:12:49', u'parents': [], u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/6yhhnx/interfaces/11/'}, {u'discovered': None, u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 20}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'system_id': u'6yhhnx', u'params': u'', u'mac_address': u'38:ea:a7:8f:12:48', u'parents': [], u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/6yhhnx/interfaces/12/'}, {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'qgxrnm', 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'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'qgxrnm', 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'mode': u'link_up', u'id': 21}], u'tags': [u'sriov'], 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'qgxrnm', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'system_id': u'6yhhnx', u'params': u'', u'mac_address': u'9c:b6:54:8a:10:1c', u'parents': [], u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/6yhhnx/interfaces/13/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'bcaches': [], u'status_name': u'Ready', u'physicalblockdevice_set': [{u'model': u'LOGICAL VOLUME', u'partition_table_type': u'MBR', u'block_size': 4096, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'6yhhnx', u'resource_uri': u'/MAAS/api/2.0/nodes/6yhhnx/blockdevices/1/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456, u'type': u'physical', u'id': 1, u'partitions': [{u'uuid': u'2aa91d1c-99c3-48c1-a400-7f25c321d470', u'resource_uri': u'/MAAS/api/2.0/nodes/6yhhnx/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6yhhnx', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'5c5217f9-e476-4c3a-b4e9-ec8ba0483b74', 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'size': 800101236736}]}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'disable_ipv4': False, u'commissioning_status': 2, u'architecture': u'amd64/generic', u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-04-28 02:12:40,090 [salt.state       :300 ][INFO    ][6936] {'new': {'storage_layout': 'lvm'}}
2019-04-28 02:12:40,090 [salt.state       :1951][INFO    ][6936] Completed state [maas_machines_storage_cmp002_lvm] at time 02:12:40.090449 duration_in_ms=2465.531
2019-04-28 02:12:40,091 [salt.state       :1780][INFO    ][6936] Running state [maas_machines_storage_cmp001_lvm] at time 02:12:40.091194
2019-04-28 02:12:40,091 [salt.state       :1813][INFO    ][6936] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-04-28 02:12:41,174 [salt.loaded.ext.module.maasng:610 ][INFO    ][6936] dpw3by
2019-04-28 02:12:41,174 [salt.loaded.ext.module.maasng:626 ][INFO    ][6936] sda
2019-04-28 02:12:41,686 [salt.loaded.ext.module.maasng:361 ][INFO    ][6936] dpw3by
2019-04-28 02:12:41,769 [salt.loaded.ext.module.maasng:367 ][INFO    ][6936] [{u'size': 800109715456, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'1813b227-e153-44cb-b300-5eccadec9566', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'dpw3by', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'a98c1d78-e4c3-4173-b87f-67c8ad7467e6', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/dpw3by/blockdevices/3/partition/2', u'type': u'partition', u'id': 2, u'device_id': 3}], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'dpw3by', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 3, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/dpw3by/blockdevices/3/'}, {u'size': 800097042432, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'c1c46649-3a10-45dd-a3ca-daaf42a9c0d7', u'fstype': u'ext4'}, u'uuid': u'134a30c4-f25d-48fb-9d57-165b42335924', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'dpw3by', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 800097042432, u'id': 5, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/dpw3by/blockdevices/5/'}]
2019-04-28 02:12:41,769 [salt.loaded.ext.module.maasng:632 ][INFO    ][6936] vgroot
2019-04-28 02:12:41,770 [salt.loaded.ext.module.maasng:635 ][INFO    ][6936] lvroot
2019-04-28 02:12:41,770 [salt.loaded.ext.module.maasng:639 ][INFO    ][6936] 107374182400
2019-04-28 02:12:42,358 [salt.loaded.ext.module.maasng:645 ][INFO    ][6936] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'testing_status_name': u'Passed', u'ip_addresses': [u'192.168.11.39', u'192.168.11.41'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'boot_interface': {u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qgxrnm', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'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'qgxrnm', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 24}], u'tags': [u'sriov'], 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'qgxrnm', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'system_id': u'dpw3by', u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/dpw3by/interfaces/5/'}, u'min_hwe_kernel': u'ga-16.04', u'status_action': u'', u'tag_names': [], u'swap_size': None, 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'block_size': 4096, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'dpw3by', u'resource_uri': u'/MAAS/api/2.0/nodes/dpw3by/blockdevices/3/', 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'size': 800109715456, u'type': u'physical', u'id': 3, u'partitions': [{u'uuid': u'39e78f10-8855-4da2-bf0a-b562123a4f26', u'resource_uri': u'/MAAS/api/2.0/nodes/dpw3by/blockdevices/3/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'dpw3by', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'1d189402-a46d-48ff-89d9-6f950c3da3cd', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 3, u'type': u'partition', u'id': 7, u'size': 800101236736}]}, u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/dpw3by/', u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'node_type': 0, u'testing_status': 2, u'system_id': u'dpw3by', 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'block_size': 4096, u'available_size': 0, u'uuid': u'd98355b5-7a66-4360-8352-9d43d9038dc2', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'dpw3by', u'resource_uri': u'/MAAS/api/2.0/nodes/dpw3by/blockdevices/13/', u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'a39b8716-9167-4ae6-8ebd-cb3acfca3910', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'size': 107374182400, u'type': u'virtual', u'id': 13, u'partitions': []}], u'blockdevice_set': [{u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/dpw3by/blockdevices/3/', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'dpw3by', u'block_size': 4096, u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456, u'type': u'physical', u'id': 3, u'partitions': [{u'uuid': u'39e78f10-8855-4da2-bf0a-b562123a4f26', u'resource_uri': u'/MAAS/api/2.0/nodes/dpw3by/blockdevices/3/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'dpw3by', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'1d189402-a46d-48ff-89d9-6f950c3da3cd', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 3, u'type': u'partition', u'id': 7, u'size': 800101236736}]}, {u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/dpw3by/blockdevices/13/', u'uuid': u'd98355b5-7a66-4360-8352-9d43d9038dc2', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'dpw3by', u'block_size': 4096, u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'a39b8716-9167-4ae6-8ebd-cb3acfca3910', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'size': 107374182400, u'type': u'virtual', u'id': 13, u'partitions': []}], u'status': 4, u'storage_test_status': 2, u'cpu_count': 40, u'raids': [], u'owner_data': {}, u'memory_test_status_name': u'Unknown', u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'dpw3by', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'commissioning_status_name': u'Passed', u'interface_set': [{u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qgxrnm', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'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'qgxrnm', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 24}], u'tags': [u'sriov'], 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'qgxrnm', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'system_id': u'dpw3by', u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/dpw3by/interfaces/5/'}, {u'discovered': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'system_id': u'dpw3by', u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d4', u'parents': [], u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/dpw3by/interfaces/16/'}, {u'discovered': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'system_id': u'dpw3by', u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d5', u'parents': [], u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/dpw3by/interfaces/19/'}, {u'discovered': None, u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 25}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'system_id': u'dpw3by', u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cd', u'parents': [], u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/dpw3by/interfaces/17/'}, {u'discovered': None, u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 26}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'system_id': u'dpw3by', u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cc', u'parents': [], u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/dpw3by/interfaces/18/'}, {u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qgxrnm', 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.41'}], 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'qgxrnm', 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'mode': u'link_up', u'id': 27}], u'tags': [u'sriov'], 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'qgxrnm', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'system_id': u'dpw3by', u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a4', u'parents': [], u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/dpw3by/interfaces/20/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'bcaches': [], u'status_name': u'Ready', u'physicalblockdevice_set': [{u'model': u'LOGICAL VOLUME', u'partition_table_type': u'MBR', u'block_size': 4096, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'dpw3by', u'resource_uri': u'/MAAS/api/2.0/nodes/dpw3by/blockdevices/3/', 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'size': 800109715456, u'type': u'physical', u'id': 3, u'partitions': [{u'uuid': u'39e78f10-8855-4da2-bf0a-b562123a4f26', u'resource_uri': u'/MAAS/api/2.0/nodes/dpw3by/blockdevices/3/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'dpw3by', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'1d189402-a46d-48ff-89d9-6f950c3da3cd', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 3, u'type': u'partition', u'id': 7, u'size': 800101236736}]}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'disable_ipv4': False, u'commissioning_status': 2, u'architecture': u'amd64/generic', u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-04-28 02:12:42,361 [salt.state       :300 ][INFO    ][6936] {'new': {'storage_layout': 'lvm'}}
2019-04-28 02:12:42,362 [salt.state       :1951][INFO    ][6936] Completed state [maas_machines_storage_cmp001_lvm] at time 02:12:42.362223 duration_in_ms=2271.027
2019-04-28 02:12:42,366 [salt.minion      :1711][INFO    ][6936] Returning information for job: 20190428021229682522
2019-04-28 02:12:42,897 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command state.apply with jid 20190428021242886161
2019-04-28 02:12:42,914 [salt.minion      :1432][INFO    ][7072] Starting a new job with PID 7072
2019-04-28 02:12:43,880 [salt.state       :915 ][INFO    ][7072] Loading fresh modules for state activity
2019-04-28 02:12:43,932 [salt.fileclient  :1219][INFO    ][7072] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-04-28 02:12:43,979 [salt.state       :1780][INFO    ][7072] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:12:43.979157
2019-04-28 02:12:43,979 [salt.state       :1813][INFO    ][7072] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-28 02:12:43,981 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7072] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-28 02:12:45,692 [salt.state       :300 ][INFO    ][7072] {'pid': 7079, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-28 02:12:45,692 [salt.state       :1951][INFO    ][7072] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:12:45.692743 duration_in_ms=1713.587
2019-04-28 02:12:45,694 [salt.state       :1780][INFO    ][7072] Running state [maas.deploy_machines] at time 02:12:45.694132
2019-04-28 02:12:45,694 [salt.state       :1813][INFO    ][7072] Executing state module.run for [maas.deploy_machines]
2019-04-28 02:12:45,695 [salt.utils.decorators:613 ][WARNING ][7072] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-28 02:12:46,262 [salt.loaded.ext.module.maas:684 ][INFO    ][7072] deploymachines hwe_kernel=ga-16.04 system_id=6yhhnx distro_series=xenial
2019-04-28 02:12:48,600 [salt.loaded.ext.module.maas:684 ][INFO    ][7072] deploymachines hwe_kernel=ga-16.04 system_id=dpw3by distro_series=xenial
2019-04-28 02:12:51,036 [salt.loaded.ext.module.maas:684 ][INFO    ][7072] deploymachines hwe_kernel=ga-16.04 system_id=cnresf distro_series=xenial
2019-04-28 02:12:53,436 [salt.loaded.ext.module.maas:684 ][INFO    ][7072] deploymachines hwe_kernel=ga-16.04 system_id=x7ekd6 distro_series=xenial
2019-04-28 02:12:55,794 [salt.loaded.ext.module.maas:684 ][INFO    ][7072] deploymachines hwe_kernel=ga-16.04 system_id=k3em77 distro_series=xenial
2019-04-28 02:12:57,929 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428021257918457
2019-04-28 02:12:57,946 [salt.minion      :1432][INFO    ][7343] Starting a new job with PID 7343
2019-04-28 02:12:57,970 [salt.minion      :1711][INFO    ][7343] Returning information for job: 20190428021257918457
2019-04-28 02:12:58,218 [salt.state       :300 ][INFO    ][7072] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-04-28 02:12:58,219 [salt.state       :1951][INFO    ][7072] Completed state [maas.deploy_machines] at time 02:12:58.219912 duration_in_ms=12525.779
2019-04-28 02:12:58,222 [salt.minion      :1711][INFO    ][7072] Returning information for job: 20190428021242886161
2019-04-28 02:12:58,773 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command state.apply with jid 20190428021258762296
2019-04-28 02:12:58,794 [salt.minion      :1432][INFO    ][7359] Starting a new job with PID 7359
2019-04-28 02:13:04,700 [salt.state       :915 ][INFO    ][7359] Loading fresh modules for state activity
2019-04-28 02:13:04,750 [salt.fileclient  :1219][INFO    ][7359] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-04-28 02:13:04,794 [salt.state       :1780][INFO    ][7359] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:13:04.794512
2019-04-28 02:13:04,794 [salt.state       :1813][INFO    ][7359] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-28 02:13:04,796 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7359] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-28 02:13:06,504 [salt.state       :300 ][INFO    ][7359] {'pid': 7377, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-28 02:13:06,505 [salt.state       :1951][INFO    ][7359] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:13:06.505268 duration_in_ms=1710.755
2019-04-28 02:13:06,509 [salt.state       :1780][INFO    ][7359] Running state [maas.wait_for_machine_status] at time 02:13:06.509447
2019-04-28 02:13:06,510 [salt.state       :1813][INFO    ][7359] Executing state module.run for [maas.wait_for_machine_status]
2019-04-28 02:13:06,510 [salt.utils.decorators:613 ][WARNING ][7359] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-28 02:13:09,126 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.39633894s left)
2019-04-28 02:13:13,862 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428021313849712
2019-04-28 02:13:13,880 [salt.minion      :1432][INFO    ][7393] Starting a new job with PID 7393
2019-04-28 02:13:13,903 [salt.minion      :1711][INFO    ][7393] Returning information for job: 20190428021313849712
2019-04-28 02:13:41,870 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.65209699s left)
2019-04-28 02:13:44,045 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428021344036565
2019-04-28 02:13:44,063 [salt.minion      :1432][INFO    ][7474] Starting a new job with PID 7474
2019-04-28 02:13:44,083 [salt.minion      :1711][INFO    ][7474] Returning information for job: 20190428021344036565
2019-04-28 02:14:14,096 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428021414088740
2019-04-28 02:14:14,111 [salt.minion      :1432][INFO    ][7499] Starting a new job with PID 7499
2019-04-28 02:14:14,132 [salt.minion      :1711][INFO    ][7499] Returning information for job: 20190428021414088740
2019-04-28 02:14:14,596 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.92632413s left)
2019-04-28 02:14:44,314 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428021444305324
2019-04-28 02:14:44,335 [salt.minion      :1432][INFO    ][7588] Starting a new job with PID 7588
2019-04-28 02:14:44,356 [salt.minion      :1711][INFO    ][7588] Returning information for job: 20190428021444305324
2019-04-28 02:14:47,489 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2149.03324795s left)
2019-04-28 02:15:14,493 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428021514486494
2019-04-28 02:15:14,507 [salt.minion      :1432][INFO    ][7616] Starting a new job with PID 7616
2019-04-28 02:15:14,528 [salt.minion      :1711][INFO    ][7616] Returning information for job: 20190428021514486494
2019-04-28 02:15:20,134 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2116.38835812s left)
2019-04-28 02:15:44,531 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428021544520258
2019-04-28 02:15:44,550 [salt.minion      :1432][INFO    ][7725] Starting a new job with PID 7725
2019-04-28 02:15:44,569 [salt.minion      :1711][INFO    ][7725] Returning information for job: 20190428021544520258
2019-04-28 02:15:52,865 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2083.65770292s left)
2019-04-28 02:16:14,600 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428021614583643
2019-04-28 02:16:14,620 [salt.minion      :1432][INFO    ][7832] Starting a new job with PID 7832
2019-04-28 02:16:14,641 [salt.minion      :1711][INFO    ][7832] Returning information for job: 20190428021614583643
2019-04-28 02:16:25,473 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2051.04940009s left)
2019-04-28 02:16:44,675 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428021644660019
2019-04-28 02:16:44,697 [salt.minion      :1432][INFO    ][8049] Starting a new job with PID 8049
2019-04-28 02:16:44,718 [salt.minion      :1711][INFO    ][8049] Returning information for job: 20190428021644660019
2019-04-28 02:16:58,405 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2018.11727095s left)
2019-04-28 02:17:14,724 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428021714717498
2019-04-28 02:17:14,737 [salt.minion      :1432][INFO    ][8184] Starting a new job with PID 8184
2019-04-28 02:17:14,758 [salt.minion      :1711][INFO    ][8184] Returning information for job: 20190428021714717498
2019-04-28 02:17:30,953 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1985.56900692s left)
2019-04-28 02:17:44,817 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428021744808936
2019-04-28 02:17:44,833 [salt.minion      :1432][INFO    ][8519] Starting a new job with PID 8519
2019-04-28 02:17:44,855 [salt.minion      :1711][INFO    ][8519] Returning information for job: 20190428021744808936
2019-04-28 02:18:03,757 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1952.76538706s left)
2019-04-28 02:18:14,897 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428021814887554
2019-04-28 02:18:14,916 [salt.minion      :1432][INFO    ][8627] Starting a new job with PID 8627
2019-04-28 02:18:14,942 [salt.minion      :1711][INFO    ][8627] Returning information for job: 20190428021814887554
2019-04-28 02:18:37,713 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1918.80907893s left)
2019-04-28 02:18:45,013 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428021845004837
2019-04-28 02:18:45,033 [salt.minion      :1432][INFO    ][8946] Starting a new job with PID 8946
2019-04-28 02:18:45,055 [salt.minion      :1711][INFO    ][8946] Returning information for job: 20190428021845004837
2019-04-28 02:19:10,622 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1885.90070105s left)
2019-04-28 02:19:15,137 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428021915116263
2019-04-28 02:19:15,156 [salt.minion      :1432][INFO    ][9053] Starting a new job with PID 9053
2019-04-28 02:19:15,175 [salt.minion      :1711][INFO    ][9053] Returning information for job: 20190428021915116263
2019-04-28 02:19:43,192 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1853.32992101s left)
2019-04-28 02:19:45,269 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428021945258582
2019-04-28 02:19:45,284 [salt.minion      :1432][INFO    ][9281] Starting a new job with PID 9281
2019-04-28 02:19:45,312 [salt.minion      :1711][INFO    ][9281] Returning information for job: 20190428021945258582
2019-04-28 02:20:15,330 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428022015319221
2019-04-28 02:20:15,346 [salt.minion      :1432][INFO    ][9354] Starting a new job with PID 9354
2019-04-28 02:20:15,377 [salt.minion      :1711][INFO    ][9354] Returning information for job: 20190428022015319221
2019-04-28 02:20:16,294 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1820.22821403s left)
2019-04-28 02:20:45,500 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428022045486782
2019-04-28 02:20:45,523 [salt.minion      :1432][INFO    ][9698] Starting a new job with PID 9698
2019-04-28 02:20:45,553 [salt.minion      :1711][INFO    ][9698] Returning information for job: 20190428022045486782
2019-04-28 02:20:48,935 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1787.5869801s left)
2019-04-28 02:21:15,637 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428022115626763
2019-04-28 02:21:15,654 [salt.minion      :1432][INFO    ][9802] Starting a new job with PID 9802
2019-04-28 02:21:15,683 [salt.minion      :1711][INFO    ][9802] Returning information for job: 20190428022115626763
2019-04-28 02:21:21,608 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1754.91409898s left)
2019-04-28 02:21:45,777 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428022145767504
2019-04-28 02:21:45,792 [salt.minion      :1432][INFO    ][10054] Starting a new job with PID 10054
2019-04-28 02:21:45,826 [salt.minion      :1711][INFO    ][10054] Returning information for job: 20190428022145767504
2019-04-28 02:21:54,349 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1722.17305303s left)
2019-04-28 02:22:15,921 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428022215907131
2019-04-28 02:22:15,942 [salt.minion      :1432][INFO    ][10127] Starting a new job with PID 10127
2019-04-28 02:22:15,969 [salt.minion      :1711][INFO    ][10127] Returning information for job: 20190428022215907131
2019-04-28 02:22:27,233 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1689.28958106s left)
2019-04-28 02:22:46,074 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428022246059894
2019-04-28 02:22:46,097 [salt.minion      :1432][INFO    ][10206] Starting a new job with PID 10206
2019-04-28 02:22:46,125 [salt.minion      :1711][INFO    ][10206] Returning information for job: 20190428022246059894
2019-04-28 02:22:59,872 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1656.65006399s left)
2019-04-28 02:23:16,216 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428022316199812
2019-04-28 02:23:16,241 [salt.minion      :1432][INFO    ][10252] Starting a new job with PID 10252
2019-04-28 02:23:16,271 [salt.minion      :1711][INFO    ][10252] Returning information for job: 20190428022316199812
2019-04-28 02:23:32,674 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1623.84846091s left)
2019-04-28 02:23:46,232 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428022346220991
2019-04-28 02:23:46,254 [salt.minion      :1432][INFO    ][10420] Starting a new job with PID 10420
2019-04-28 02:23:46,283 [salt.minion      :1711][INFO    ][10420] Returning information for job: 20190428022346220991
2019-04-28 02:24:05,567 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1590.95510793s left)
2019-04-28 02:24:16,402 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428022416391714
2019-04-28 02:24:16,424 [salt.minion      :1432][INFO    ][10521] Starting a new job with PID 10521
2019-04-28 02:24:16,450 [salt.minion      :1711][INFO    ][10521] Returning information for job: 20190428022416391714
2019-04-28 02:24:38,694 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1557.82817197s left)
2019-04-28 02:24:46,596 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428022446580963
2019-04-28 02:24:46,616 [salt.minion      :1432][INFO    ][10863] Starting a new job with PID 10863
2019-04-28 02:24:46,643 [salt.minion      :1711][INFO    ][10863] Returning information for job: 20190428022446580963
2019-04-28 02:25:11,435 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1525.08725095s left)
2019-04-28 02:25:16,757 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428022516747586
2019-04-28 02:25:16,775 [salt.minion      :1432][INFO    ][10890] Starting a new job with PID 10890
2019-04-28 02:25:16,804 [salt.minion      :1711][INFO    ][10890] Returning information for job: 20190428022516747586
2019-04-28 02:25:44,232 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1492.29000306s left)
2019-04-28 02:25:46,952 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428022546942435
2019-04-28 02:25:46,974 [salt.minion      :1432][INFO    ][10990] Starting a new job with PID 10990
2019-04-28 02:25:47,001 [salt.minion      :1711][INFO    ][10990] Returning information for job: 20190428022546942435
2019-04-28 02:26:16,856 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1459.66600704s left)
2019-04-28 02:26:17,041 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428022617033741
2019-04-28 02:26:17,058 [salt.minion      :1432][INFO    ][11071] Starting a new job with PID 11071
2019-04-28 02:26:17,084 [salt.minion      :1711][INFO    ][11071] Returning information for job: 20190428022617033741
2019-04-28 02:26:47,252 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428022647244474
2019-04-28 02:26:47,269 [salt.minion      :1432][INFO    ][11309] Starting a new job with PID 11309
2019-04-28 02:26:47,301 [salt.minion      :1711][INFO    ][11309] Returning information for job: 20190428022647244474
2019-04-28 02:26:49,625 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1426.89697409s left)
2019-04-28 02:27:17,277 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428022717262691
2019-04-28 02:27:17,298 [salt.minion      :1432][INFO    ][11399] Starting a new job with PID 11399
2019-04-28 02:27:17,327 [salt.minion      :1711][INFO    ][11399] Returning information for job: 20190428022717262691
2019-04-28 02:27:22,184 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1394.33804202s left)
2019-04-28 02:27:47,497 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428022747484241
2019-04-28 02:27:47,518 [salt.minion      :1432][INFO    ][11477] Starting a new job with PID 11477
2019-04-28 02:27:47,552 [salt.minion      :1711][INFO    ][11477] Returning information for job: 20190428022747484241
2019-04-28 02:27:54,868 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1361.65398312s left)
2019-04-28 02:28:17,544 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428022817536025
2019-04-28 02:28:17,562 [salt.minion      :1432][INFO    ][11508] Starting a new job with PID 11508
2019-04-28 02:28:17,590 [salt.minion      :1711][INFO    ][11508] Returning information for job: 20190428022817536025
2019-04-28 02:28:27,474 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1329.048244s left)
2019-04-28 02:28:47,573 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428022847561860
2019-04-28 02:28:47,589 [salt.minion      :1432][INFO    ][11586] Starting a new job with PID 11586
2019-04-28 02:28:47,618 [salt.minion      :1711][INFO    ][11586] Returning information for job: 20190428022847561860
2019-04-28 02:29:00,268 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1296.25419402s left)
2019-04-28 02:29:17,644 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428022917627959
2019-04-28 02:29:17,662 [salt.minion      :1432][INFO    ][11610] Starting a new job with PID 11610
2019-04-28 02:29:17,697 [salt.minion      :1711][INFO    ][11610] Returning information for job: 20190428022917627959
2019-04-28 02:29:32,891 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1263.63193512s left)
2019-04-28 02:29:47,710 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428022947696600
2019-04-28 02:29:47,730 [salt.minion      :1432][INFO    ][11687] Starting a new job with PID 11687
2019-04-28 02:29:47,759 [salt.minion      :1711][INFO    ][11687] Returning information for job: 20190428022947696600
2019-04-28 02:30:05,641 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1230.88107395s left)
2019-04-28 02:30:17,801 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428023017794026
2019-04-28 02:30:17,818 [salt.minion      :1432][INFO    ][11711] Starting a new job with PID 11711
2019-04-28 02:30:17,848 [salt.minion      :1711][INFO    ][11711] Returning information for job: 20190428023017794026
2019-04-28 02:30:38,373 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1198.14938593s left)
2019-04-28 02:30:47,890 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428023047876584
2019-04-28 02:30:47,911 [salt.minion      :1432][INFO    ][11786] Starting a new job with PID 11786
2019-04-28 02:30:47,938 [salt.minion      :1711][INFO    ][11786] Returning information for job: 20190428023047876584
2019-04-28 02:31:11,025 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1165.49709702s left)
2019-04-28 02:31:17,996 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428023117982819
2019-04-28 02:31:18,019 [salt.minion      :1432][INFO    ][11822] Starting a new job with PID 11822
2019-04-28 02:31:18,048 [salt.minion      :1711][INFO    ][11822] Returning information for job: 20190428023117982819
2019-04-28 02:31:43,577 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1132.94555211s left)
2019-04-28 02:31:48,072 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428023148065310
2019-04-28 02:31:48,085 [salt.minion      :1432][INFO    ][11901] Starting a new job with PID 11901
2019-04-28 02:31:48,118 [salt.minion      :1711][INFO    ][11901] Returning information for job: 20190428023148065310
2019-04-28 02:32:16,162 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1100.36033797s left)
2019-04-28 02:32:18,209 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428023218197285
2019-04-28 02:32:18,227 [salt.minion      :1432][INFO    ][11926] Starting a new job with PID 11926
2019-04-28 02:32:18,256 [salt.minion      :1711][INFO    ][11926] Returning information for job: 20190428023218197285
2019-04-28 02:32:48,337 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428023248330430
2019-04-28 02:32:48,353 [salt.minion      :1432][INFO    ][12003] Starting a new job with PID 12003
2019-04-28 02:32:48,381 [salt.minion      :1711][INFO    ][12003] Returning information for job: 20190428023248330430
2019-04-28 02:32:48,897 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1067.62530613s left)
2019-04-28 02:33:18,468 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428023318456818
2019-04-28 02:33:18,488 [salt.minion      :1432][INFO    ][12024] Starting a new job with PID 12024
2019-04-28 02:33:18,523 [salt.minion      :1711][INFO    ][12024] Returning information for job: 20190428023318456818
2019-04-28 02:33:21,668 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1034.85467792s left)
2019-04-28 02:33:48,655 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428023348641861
2019-04-28 02:33:48,673 [salt.minion      :1432][INFO    ][12101] Starting a new job with PID 12101
2019-04-28 02:33:48,706 [salt.minion      :1711][INFO    ][12101] Returning information for job: 20190428023348641861
2019-04-28 02:33:54,361 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1002.16102004s left)
2019-04-28 02:34:18,813 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428023418801755
2019-04-28 02:34:18,835 [salt.minion      :1432][INFO    ][12126] Starting a new job with PID 12126
2019-04-28 02:34:18,865 [salt.minion      :1711][INFO    ][12126] Returning information for job: 20190428023418801755
2019-04-28 02:34:27,048 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (969.474331141s left)
2019-04-28 02:34:49,032 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428023449015805
2019-04-28 02:34:49,052 [salt.minion      :1432][INFO    ][12223] Starting a new job with PID 12223
2019-04-28 02:34:49,084 [salt.minion      :1711][INFO    ][12223] Returning information for job: 20190428023449015805
2019-04-28 02:34:59,673 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (936.849148035s left)
2019-04-28 02:35:19,220 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428023519210296
2019-04-28 02:35:19,238 [salt.minion      :1432][INFO    ][12252] Starting a new job with PID 12252
2019-04-28 02:35:19,271 [salt.minion      :1711][INFO    ][12252] Returning information for job: 20190428023519210296
2019-04-28 02:35:32,332 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (904.191937923s left)
2019-04-28 02:35:49,444 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428023549428642
2019-04-28 02:35:49,461 [salt.minion      :1432][INFO    ][12330] Starting a new job with PID 12330
2019-04-28 02:35:49,492 [salt.minion      :1711][INFO    ][12330] Returning information for job: 20190428023549428642
2019-04-28 02:36:05,040 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (871.482480049s left)
2019-04-28 02:36:19,662 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428023619653880
2019-04-28 02:36:19,684 [salt.minion      :1432][INFO    ][12357] Starting a new job with PID 12357
2019-04-28 02:36:19,713 [salt.minion      :1711][INFO    ][12357] Returning information for job: 20190428023619653880
2019-04-28 02:36:37,697 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (838.824928045s left)
2019-04-28 02:36:49,728 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428023649712629
2019-04-28 02:36:49,750 [salt.minion      :1432][INFO    ][12433] Starting a new job with PID 12433
2019-04-28 02:36:49,778 [salt.minion      :1711][INFO    ][12433] Returning information for job: 20190428023649712629
2019-04-28 02:37:10,311 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (806.211251974s left)
2019-04-28 02:37:19,780 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428023719766303
2019-04-28 02:37:19,799 [salt.minion      :1432][INFO    ][12460] Starting a new job with PID 12460
2019-04-28 02:37:19,831 [salt.minion      :1711][INFO    ][12460] Returning information for job: 20190428023719766303
2019-04-28 02:37:43,184 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (773.338515997s left)
2019-04-28 02:37:49,883 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428023749868074
2019-04-28 02:37:49,904 [salt.minion      :1432][INFO    ][12538] Starting a new job with PID 12538
2019-04-28 02:37:49,939 [salt.minion      :1711][INFO    ][12538] Returning information for job: 20190428023749868074
2019-04-28 02:38:15,713 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (740.809114933s left)
2019-04-28 02:38:19,941 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428023819934063
2019-04-28 02:38:19,955 [salt.minion      :1432][INFO    ][12563] Starting a new job with PID 12563
2019-04-28 02:38:19,985 [salt.minion      :1711][INFO    ][12563] Returning information for job: 20190428023819934063
2019-04-28 02:38:48,465 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (708.057094097s left)
2019-04-28 02:38:50,056 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428023850042543
2019-04-28 02:38:50,076 [salt.minion      :1432][INFO    ][12640] Starting a new job with PID 12640
2019-04-28 02:38:50,106 [salt.minion      :1711][INFO    ][12640] Returning information for job: 20190428023850042543
2019-04-28 02:39:20,222 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428023920213289
2019-04-28 02:39:20,240 [salt.minion      :1432][INFO    ][12666] Starting a new job with PID 12666
2019-04-28 02:39:20,272 [salt.minion      :1711][INFO    ][12666] Returning information for job: 20190428023920213289
2019-04-28 02:39:21,144 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (675.378210068s left)
2019-04-28 02:39:50,337 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428023950329172
2019-04-28 02:39:50,359 [salt.minion      :1432][INFO    ][12739] Starting a new job with PID 12739
2019-04-28 02:39:50,392 [salt.minion      :1711][INFO    ][12739] Returning information for job: 20190428023950329172
2019-04-28 02:39:53,849 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (642.673181057s left)
2019-04-28 02:40:20,534 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428024020521041
2019-04-28 02:40:20,554 [salt.minion      :1432][INFO    ][12768] Starting a new job with PID 12768
2019-04-28 02:40:20,585 [salt.minion      :1711][INFO    ][12768] Returning information for job: 20190428024020521041
2019-04-28 02:40:26,453 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (610.069250107s left)
2019-04-28 02:40:50,681 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428024050665698
2019-04-28 02:40:50,699 [salt.minion      :1432][INFO    ][12846] Starting a new job with PID 12846
2019-04-28 02:40:50,731 [salt.minion      :1711][INFO    ][12846] Returning information for job: 20190428024050665698
2019-04-28 02:40:59,063 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (577.45994997s left)
2019-04-28 02:41:20,706 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428024120692699
2019-04-28 02:41:20,726 [salt.minion      :1432][INFO    ][12871] Starting a new job with PID 12871
2019-04-28 02:41:20,756 [salt.minion      :1711][INFO    ][12871] Returning information for job: 20190428024120692699
2019-04-28 02:41:31,752 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (544.770553112s left)
2019-04-28 02:41:50,901 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428024150888081
2019-04-28 02:41:50,923 [salt.minion      :1432][INFO    ][12949] Starting a new job with PID 12949
2019-04-28 02:41:50,956 [salt.minion      :1711][INFO    ][12949] Returning information for job: 20190428024150888081
2019-04-28 02:42:04,763 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (511.758933067s left)
2019-04-28 02:42:20,985 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428024220970482
2019-04-28 02:42:21,006 [salt.minion      :1432][INFO    ][12974] Starting a new job with PID 12974
2019-04-28 02:42:21,036 [salt.minion      :1711][INFO    ][12974] Returning information for job: 20190428024220970482
2019-04-28 02:42:37,345 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (479.177442074s left)
2019-04-28 02:42:51,036 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428024251028454
2019-04-28 02:42:51,057 [salt.minion      :1432][INFO    ][13050] Starting a new job with PID 13050
2019-04-28 02:42:51,086 [salt.minion      :1711][INFO    ][13050] Returning information for job: 20190428024251028454
2019-04-28 02:43:09,925 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (446.597291946s left)
2019-04-28 02:43:21,138 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428024321123966
2019-04-28 02:43:21,158 [salt.minion      :1432][INFO    ][13077] Starting a new job with PID 13077
2019-04-28 02:43:21,189 [salt.minion      :1711][INFO    ][13077] Returning information for job: 20190428024321123966
2019-04-28 02:43:42,560 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (413.962450027s left)
2019-04-28 02:43:51,181 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428024351170080
2019-04-28 02:43:51,204 [salt.minion      :1432][INFO    ][13155] Starting a new job with PID 13155
2019-04-28 02:43:51,234 [salt.minion      :1711][INFO    ][13155] Returning information for job: 20190428024351170080
2019-04-28 02:44:15,223 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (381.299163103s left)
2019-04-28 02:44:21,329 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428024421320216
2019-04-28 02:44:21,348 [salt.minion      :1432][INFO    ][13182] Starting a new job with PID 13182
2019-04-28 02:44:21,380 [salt.minion      :1711][INFO    ][13182] Returning information for job: 20190428024421320216
2019-04-28 02:44:47,900 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (348.622272015s left)
2019-04-28 02:44:51,429 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428024451422502
2019-04-28 02:44:51,444 [salt.minion      :1432][INFO    ][13408] Starting a new job with PID 13408
2019-04-28 02:44:51,473 [salt.minion      :1711][INFO    ][13408] Returning information for job: 20190428024451422502
2019-04-28 02:45:20,543 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (315.978857994s left)
2019-04-28 02:45:21,590 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428024521576003
2019-04-28 02:45:21,609 [salt.minion      :1432][INFO    ][13434] Starting a new job with PID 13434
2019-04-28 02:45:21,638 [salt.minion      :1711][INFO    ][13434] Returning information for job: 20190428024521576003
2019-04-28 02:45:51,800 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428024551789166
2019-04-28 02:45:51,817 [salt.minion      :1432][INFO    ][13512] Starting a new job with PID 13512
2019-04-28 02:45:51,850 [salt.minion      :1711][INFO    ][13512] Returning information for job: 20190428024551789166
2019-04-28 02:45:53,302 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (283.220834017s left)
2019-04-28 02:46:21,973 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428024621961997
2019-04-28 02:46:21,993 [salt.minion      :1432][INFO    ][13543] Starting a new job with PID 13543
2019-04-28 02:46:22,024 [salt.minion      :1711][INFO    ][13543] Returning information for job: 20190428024621961997
2019-04-28 02:46:26,034 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (250.488862038s left)
2019-04-28 02:46:52,005 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428024651996543
2019-04-28 02:46:52,025 [salt.minion      :1432][INFO    ][13615] Starting a new job with PID 13615
2019-04-28 02:46:52,056 [salt.minion      :1711][INFO    ][13615] Returning information for job: 20190428024651996543
2019-04-28 02:46:58,598 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (217.924561024s left)
2019-04-28 02:47:22,220 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428024722210419
2019-04-28 02:47:22,241 [salt.minion      :1432][INFO    ][13648] Starting a new job with PID 13648
2019-04-28 02:47:22,269 [salt.minion      :1711][INFO    ][13648] Returning information for job: 20190428024722210419
2019-04-28 02:47:31,242 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (185.280324936s left)
2019-04-28 02:47:52,279 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428024752268006
2019-04-28 02:47:52,301 [salt.minion      :1432][INFO    ][13716] Starting a new job with PID 13716
2019-04-28 02:47:52,330 [salt.minion      :1711][INFO    ][13716] Returning information for job: 20190428024752268006
2019-04-28 02:48:03,850 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (152.672444105s left)
2019-04-28 02:48:22,499 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428024822486097
2019-04-28 02:48:22,519 [salt.minion      :1432][INFO    ][13751] Starting a new job with PID 13751
2019-04-28 02:48:22,546 [salt.minion      :1711][INFO    ][13751] Returning information for job: 20190428024822486097
2019-04-28 02:48:36,909 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (119.613028049s left)
2019-04-28 02:48:52,573 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428024852563419
2019-04-28 02:48:52,594 [salt.minion      :1432][INFO    ][13823] Starting a new job with PID 13823
2019-04-28 02:48:52,626 [salt.minion      :1711][INFO    ][13823] Returning information for job: 20190428024852563419
2019-04-28 02:49:09,420 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (87.10247612s left)
2019-04-28 02:49:22,600 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428024922592451
2019-04-28 02:49:22,618 [salt.minion      :1432][INFO    ][13856] Starting a new job with PID 13856
2019-04-28 02:49:22,648 [salt.minion      :1711][INFO    ][13856] Returning information for job: 20190428024922592451
2019-04-28 02:49:42,157 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (54.3653540611s left)
2019-04-28 02:49:52,721 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428024952706802
2019-04-28 02:49:52,741 [salt.minion      :1432][INFO    ][13928] Starting a new job with PID 13928
2019-04-28 02:49:52,772 [salt.minion      :1711][INFO    ][13928] Returning information for job: 20190428024952706802
2019-04-28 02:50:14,802 [salt.loaded.ext.module.maas:1023][INFO    ][7359] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (21.7203800678s left)
2019-04-28 02:50:22,764 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428025022753942
2019-04-28 02:50:22,778 [salt.minion      :1432][INFO    ][13961] Starting a new job with PID 13961
2019-04-28 02:50:22,810 [salt.minion      :1711][INFO    ][13961] Returning information for job: 20190428025022753942
2019-04-28 02:50:47,436 [salt.state       :302 ][ERROR   ][7359] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-04-28 02:50:47,437 [salt.state       :1951][INFO    ][7359] Completed state [maas.wait_for_machine_status] at time 02:50:47.437087 duration_in_ms=2260927.638
2019-04-28 02:50:47,443 [salt.minion      :1711][INFO    ][7359] Returning information for job: 20190428021258762296
2019-04-28 02:50:58,339 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command pillar.get with jid 20190428025058328165
2019-04-28 02:50:58,354 [salt.minion      :1432][INFO    ][14034] Starting a new job with PID 14034
2019-04-28 02:50:58,364 [salt.minion      :1711][INFO    ][14034] Returning information for job: 20190428025058328165
2019-04-28 02:50:59,036 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command service.status with jid 20190428025059025501
2019-04-28 02:50:59,058 [salt.minion      :1432][INFO    ][14039] Starting a new job with PID 14039
2019-04-28 02:50:59,795 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][14039] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-04-28 02:50:59,828 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][14039] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-04-28 02:50:59,845 [salt.minion      :1711][INFO    ][14039] Returning information for job: 20190428025059025501
2019-04-28 02:51:00,521 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command state.apply with jid 20190428025100510564
2019-04-28 02:51:00,539 [salt.minion      :1432][INFO    ][14050] Starting a new job with PID 14050
2019-04-28 02:51:06,475 [salt.state       :915 ][INFO    ][14050] Loading fresh modules for state activity
2019-04-28 02:51:07,054 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14050] Executing command 'salt-minion --version' in directory '/root'
2019-04-28 02:51:07,412 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14050] Executing command 'salt-minion --version' in directory '/root'
2019-04-28 02:51:08,456 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14050] Executing command 'salt-minion --version' in directory '/root'
2019-04-28 02:51:08,809 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14050] Executing command 'salt-minion --version' in directory '/root'
2019-04-28 02:51:10,947 [salt.state       :1780][INFO    ][14050] Running state [salt-minion] at time 02:51:10.947828
2019-04-28 02:51:10,948 [salt.state       :1813][INFO    ][14050] Executing state pkg.installed for [salt-minion]
2019-04-28 02:51:10,949 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14050] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-28 02:51:11,061 [salt.state       :300 ][INFO    ][14050] All specified packages are already installed
2019-04-28 02:51:11,061 [salt.state       :1951][INFO    ][14050] Completed state [salt-minion] at time 02:51:11.061702 duration_in_ms=113.874
2019-04-28 02:51:11,062 [salt.state       :1780][INFO    ][14050] Running state [salt_minion_dependency_packages] at time 02:51:11.062099
2019-04-28 02:51:11,062 [salt.state       :1813][INFO    ][14050] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-04-28 02:51:11,071 [salt.state       :300 ][INFO    ][14050] All specified packages are already installed
2019-04-28 02:51:11,072 [salt.state       :1951][INFO    ][14050] Completed state [salt_minion_dependency_packages] at time 02:51:11.072205 duration_in_ms=10.105
2019-04-28 02:51:11,075 [salt.state       :1780][INFO    ][14050] Running state [/etc/salt/minion.d/minion.conf] at time 02:51:11.075352
2019-04-28 02:51:11,075 [salt.state       :1813][INFO    ][14050] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-04-28 02:51:11,348 [salt.state       :300 ][INFO    ][14050] File /etc/salt/minion.d/minion.conf is in the correct state
2019-04-28 02:51:11,348 [salt.state       :1951][INFO    ][14050] Completed state [/etc/salt/minion.d/minion.conf] at time 02:51:11.348743 duration_in_ms=273.392
2019-04-28 02:51:11,349 [salt.state       :1780][INFO    ][14050] Running state [python-netaddr] at time 02:51:11.349042
2019-04-28 02:51:11,349 [salt.state       :1813][INFO    ][14050] Executing state pkg.installed for [python-netaddr]
2019-04-28 02:51:11,358 [salt.state       :300 ][INFO    ][14050] All specified packages are already installed
2019-04-28 02:51:11,359 [salt.state       :1951][INFO    ][14050] Completed state [python-netaddr] at time 02:51:11.358799 duration_in_ms=9.756
2019-04-28 02:51:11,362 [salt.state       :1780][INFO    ][14050] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:51:11.362508
2019-04-28 02:51:11,362 [salt.state       :1813][INFO    ][14050] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-04-28 02:51:11,378 [salt.state       :300 ][INFO    ][14050] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-04-28 02:51:11,381 [salt.state       :1951][INFO    ][14050] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:51:11.380985 duration_in_ms=18.476
2019-04-28 02:51:11,383 [salt.state       :1780][INFO    ][14050] Running state [salt-minion] at time 02:51:11.383289
2019-04-28 02:51:11,383 [salt.state       :1813][INFO    ][14050] Executing state service.running for [salt-minion]
2019-04-28 02:51:11,385 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14050] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-04-28 02:51:11,424 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14050] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-04-28 02:51:11,445 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14050] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-04-28 02:51:11,465 [salt.state       :300 ][INFO    ][14050] The service salt-minion is already running
2019-04-28 02:51:11,465 [salt.state       :1951][INFO    ][14050] Completed state [salt-minion] at time 02:51:11.465801 duration_in_ms=82.512
2019-04-28 02:51:11,468 [salt.state       :1780][INFO    ][14050] Running state [/etc/salt/grains.d] at time 02:51:11.468647
2019-04-28 02:51:11,469 [salt.state       :1813][INFO    ][14050] Executing state file.directory for [/etc/salt/grains.d]
2019-04-28 02:51:11,471 [salt.state       :300 ][INFO    ][14050] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-04-28 02:51:11,471 [salt.state       :1951][INFO    ][14050] Completed state [/etc/salt/grains.d] at time 02:51:11.471628 duration_in_ms=2.98
2019-04-28 02:51:11,473 [salt.state       :1780][INFO    ][14050] Running state [/etc/salt/grains] at time 02:51:11.472920
2019-04-28 02:51:11,473 [salt.state       :1813][INFO    ][14050] Executing state file.managed for [/etc/salt/grains]
2019-04-28 02:51:11,474 [salt.state       :300 ][INFO    ][14050] File /etc/salt/grains exists with proper permissions. No changes made.
2019-04-28 02:51:11,476 [salt.state       :1951][INFO    ][14050] Completed state [/etc/salt/grains] at time 02:51:11.474815 duration_in_ms=1.895
2019-04-28 02:51:11,477 [salt.state       :1780][INFO    ][14050] Running state [/etc/salt/grains.d/placeholder] at time 02:51:11.477019
2019-04-28 02:51:11,477 [salt.state       :1813][INFO    ][14050] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-04-28 02:51:11,477 [salt.state       :300 ][INFO    ][14050] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-04-28 02:51:11,478 [salt.state       :1951][INFO    ][14050] Completed state [/etc/salt/grains.d/placeholder] at time 02:51:11.478114 duration_in_ms=1.094
2019-04-28 02:51:11,478 [salt.state       :1780][INFO    ][14050] Running state [/etc/salt/grains.d/sphinx] at time 02:51:11.478631
2019-04-28 02:51:11,479 [salt.state       :1813][INFO    ][14050] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-04-28 02:51:11,480 [salt.state       :300 ][INFO    ][14050] File /etc/salt/grains.d/sphinx is in the correct state
2019-04-28 02:51:11,481 [salt.state       :1951][INFO    ][14050] Completed state [/etc/salt/grains.d/sphinx] at time 02:51:11.480980 duration_in_ms=2.348
2019-04-28 02:51:11,483 [salt.state       :1780][INFO    ][14050] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:51:11.483085
2019-04-28 02:51:11,483 [salt.state       :1813][INFO    ][14050] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-04-28 02:51:11,483 [salt.state       :300 ][INFO    ][14050] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-04-28 02:51:11,484 [salt.state       :1951][INFO    ][14050] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:51:11.483939 duration_in_ms=0.854
2019-04-28 02:51:11,484 [salt.state       :1780][INFO    ][14050] Running state [/etc/salt/grains.d/dns_records] at time 02:51:11.484460
2019-04-28 02:51:11,484 [salt.state       :1813][INFO    ][14050] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-04-28 02:51:11,485 [salt.state       :300 ][INFO    ][14050] File /etc/salt/grains.d/dns_records is in the correct state
2019-04-28 02:51:11,486 [salt.state       :1951][INFO    ][14050] Completed state [/etc/salt/grains.d/dns_records] at time 02:51:11.486018 duration_in_ms=1.558
2019-04-28 02:51:11,489 [salt.state       :1780][INFO    ][14050] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:51:11.488655
2019-04-28 02:51:11,489 [salt.state       :1813][INFO    ][14050] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-04-28 02:51:11,489 [salt.state       :300 ][INFO    ][14050] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-04-28 02:51:11,489 [salt.state       :1951][INFO    ][14050] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:51:11.489783 duration_in_ms=1.129
2019-04-28 02:51:11,490 [salt.state       :1780][INFO    ][14050] Running state [/etc/salt/grains.d/salt] at time 02:51:11.490303
2019-04-28 02:51:11,490 [salt.state       :1813][INFO    ][14050] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-04-28 02:51:11,491 [salt.state       :300 ][INFO    ][14050] File /etc/salt/grains.d/salt is in the correct state
2019-04-28 02:51:11,491 [salt.state       :1951][INFO    ][14050] Completed state [/etc/salt/grains.d/salt] at time 02:51:11.491899 duration_in_ms=1.596
2019-04-28 02:51:11,492 [salt.state       :1780][INFO    ][14050] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:51:11.492828
2019-04-28 02:51:11,493 [salt.state       :1813][INFO    ][14050] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-04-28 02:51:11,493 [salt.state       :300 ][INFO    ][14050] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-04-28 02:51:11,493 [salt.state       :1951][INFO    ][14050] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:51:11.493668 duration_in_ms=0.841
2019-04-28 02:51:11,496 [salt.state       :1780][INFO    ][14050] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:51:11.496048
2019-04-28 02:51:11,496 [salt.state       :1813][INFO    ][14050] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-04-28 02:51:11,496 [salt.state       :300 ][INFO    ][14050] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-04-28 02:51:11,496 [salt.state       :1951][INFO    ][14050] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:51:11.496907 duration_in_ms=0.859
2019-04-28 02:51:11,497 [salt.state       :1780][INFO    ][14050] Running state [mine.update] at time 02:51:11.497609
2019-04-28 02:51:11,497 [salt.state       :1813][INFO    ][14050] Executing state module.wait for [mine.update]
2019-04-28 02:51:11,498 [salt.state       :300 ][INFO    ][14050] No changes made for mine.update
2019-04-28 02:51:11,498 [salt.state       :1951][INFO    ][14050] Completed state [mine.update] at time 02:51:11.498398 duration_in_ms=0.789
2019-04-28 02:51:11,498 [salt.state       :1780][INFO    ][14050] Running state [ca-certificates] at time 02:51:11.498677
2019-04-28 02:51:11,499 [salt.state       :1813][INFO    ][14050] Executing state pkg.installed for [ca-certificates]
2019-04-28 02:51:11,509 [salt.state       :300 ][INFO    ][14050] All specified packages are already installed
2019-04-28 02:51:11,509 [salt.state       :1951][INFO    ][14050] Completed state [ca-certificates] at time 02:51:11.509788 duration_in_ms=11.111
2019-04-28 02:51:11,510 [salt.state       :1780][INFO    ][14050] Running state [update-ca-certificates] at time 02:51:11.510525
2019-04-28 02:51:11,512 [salt.state       :1813][INFO    ][14050] Executing state cmd.wait for [update-ca-certificates]
2019-04-28 02:51:11,512 [salt.state       :300 ][INFO    ][14050] No changes made for update-ca-certificates
2019-04-28 02:51:11,512 [salt.state       :1951][INFO    ][14050] Completed state [update-ca-certificates] at time 02:51:11.512735 duration_in_ms=2.21
2019-04-28 02:51:11,513 [salt.state       :1780][INFO    ][14050] Running state [iptables] at time 02:51:11.513011
2019-04-28 02:51:11,513 [salt.state       :1813][INFO    ][14050] Executing state pkg.installed for [iptables]
2019-04-28 02:51:11,522 [salt.state       :300 ][INFO    ][14050] All specified packages are already installed
2019-04-28 02:51:11,522 [salt.state       :1951][INFO    ][14050] Completed state [iptables] at time 02:51:11.522544 duration_in_ms=9.532
2019-04-28 02:51:11,523 [salt.state       :1780][INFO    ][14050] Running state [iptables-persistent] at time 02:51:11.522825
2019-04-28 02:51:11,523 [salt.state       :1813][INFO    ][14050] Executing state pkg.installed for [iptables-persistent]
2019-04-28 02:51:11,532 [salt.state       :300 ][INFO    ][14050] All specified packages are already installed
2019-04-28 02:51:11,532 [salt.state       :1951][INFO    ][14050] Completed state [iptables-persistent] at time 02:51:11.532790 duration_in_ms=9.964
2019-04-28 02:51:11,533 [salt.state       :1780][INFO    ][14050] Running state [iptables_modules_v4_load] at time 02:51:11.533828
2019-04-28 02:51:11,534 [salt.state       :1813][INFO    ][14050] Executing state kmod.present for [iptables_modules_v4_load]
2019-04-28 02:51:11,534 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14050] Executing command 'lsmod' in directory '/root'
2019-04-28 02:51:11,560 [salt.state       :300 ][INFO    ][14050] Kernel modules iptable_filter, ip_tables are already present
2019-04-28 02:51:11,560 [salt.state       :1951][INFO    ][14050] Completed state [iptables_modules_v4_load] at time 02:51:11.560614 duration_in_ms=26.786
2019-04-28 02:51:11,561 [salt.state       :1780][INFO    ][14050] Running state [/etc/iptables/rules.v4] at time 02:51:11.561614
2019-04-28 02:51:11,562 [salt.state       :1813][INFO    ][14050] Executing state file.managed for [/etc/iptables/rules.v4]
2019-04-28 02:51:11,661 [salt.state       :300 ][INFO    ][14050] File /etc/iptables/rules.v4 is in the correct state
2019-04-28 02:51:11,661 [salt.state       :1951][INFO    ][14050] Completed state [/etc/iptables/rules.v4] at time 02:51:11.661563 duration_in_ms=99.949
2019-04-28 02:51:11,662 [salt.state       :1780][INFO    ][14050] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:51:11.662703
2019-04-28 02:51:11,663 [salt.state       :1813][INFO    ][14050] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-04-28 02:51:11,664 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14050] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-04-28 02:51:11,683 [salt.state       :300 ][INFO    ][14050] onlyif execution failed
2019-04-28 02:51:11,684 [salt.state       :1951][INFO    ][14050] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:51:11.684166 duration_in_ms=21.462
2019-04-28 02:51:11,686 [salt.state       :1780][INFO    ][14050] Running state [netfilter-persistent] at time 02:51:11.686507
2019-04-28 02:51:11,687 [salt.state       :1813][INFO    ][14050] Executing state service.running for [netfilter-persistent]
2019-04-28 02:51:11,688 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14050] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-04-28 02:51:11,710 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14050] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-04-28 02:51:11,733 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14050] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-04-28 02:51:11,750 [salt.state       :300 ][INFO    ][14050] The service netfilter-persistent is already running
2019-04-28 02:51:11,753 [salt.state       :1951][INFO    ][14050] Completed state [netfilter-persistent] at time 02:51:11.753495 duration_in_ms=66.988
2019-04-28 02:51:11,754 [salt.state       :1780][INFO    ][14050] Running state [iptables_extra.remove_stale_tables] at time 02:51:11.754830
2019-04-28 02:51:11,755 [salt.state       :1813][INFO    ][14050] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-04-28 02:51:11,755 [salt.state       :300 ][INFO    ][14050] No changes made for iptables_extra.remove_stale_tables
2019-04-28 02:51:11,756 [salt.state       :1951][INFO    ][14050] Completed state [iptables_extra.remove_stale_tables] at time 02:51:11.756074 duration_in_ms=1.244
2019-04-28 02:51:11,756 [salt.state       :1780][INFO    ][14050] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:51:11.756561
2019-04-28 02:51:11,757 [salt.state       :1813][INFO    ][14050] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-04-28 02:51:11,758 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14050] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-04-28 02:51:11,774 [salt.state       :300 ][INFO    ][14050] onlyif execution failed
2019-04-28 02:51:11,774 [salt.state       :1951][INFO    ][14050] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:51:11.774537 duration_in_ms=17.977
2019-04-28 02:51:11,777 [salt.state       :1780][INFO    ][14050] Running state [/etc/iptables/rules.v6] at time 02:51:11.777556
2019-04-28 02:51:11,778 [salt.state       :1813][INFO    ][14050] Executing state file.absent for [/etc/iptables/rules.v6]
2019-04-28 02:51:11,778 [salt.state       :300 ][INFO    ][14050] File /etc/iptables/rules.v6 is not present
2019-04-28 02:51:11,779 [salt.state       :1951][INFO    ][14050] Completed state [/etc/iptables/rules.v6] at time 02:51:11.779355 duration_in_ms=1.798
2019-04-28 02:51:11,780 [salt.state       :1780][INFO    ][14050] Running state [iptables_extra.flush_all] at time 02:51:11.780566
2019-04-28 02:51:11,781 [salt.state       :1813][INFO    ][14050] Executing state module.wait for [iptables_extra.flush_all]
2019-04-28 02:51:11,781 [salt.state       :300 ][INFO    ][14050] No changes made for iptables_extra.flush_all
2019-04-28 02:51:11,782 [salt.state       :1951][INFO    ][14050] Completed state [iptables_extra.flush_all] at time 02:51:11.782067 duration_in_ms=1.499
2019-04-28 02:51:11,786 [salt.minion      :1711][INFO    ][14050] Returning information for job: 20190428025100510564
2019-04-28 02:51:12,467 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command state.apply with jid 20190428025112458255
2019-04-28 02:51:12,487 [salt.minion      :1432][INFO    ][14135] Starting a new job with PID 14135
2019-04-28 02:51:13,422 [salt.state       :915 ][INFO    ][14135] Loading fresh modules for state activity
2019-04-28 02:51:14,629 [salt.state       :1780][INFO    ][14135] Running state [maas-rack-controller] at time 02:51:14.629655
2019-04-28 02:51:14,630 [salt.state       :1813][INFO    ][14135] Executing state pkg.installed for [maas-rack-controller]
2019-04-28 02:51:14,631 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14135] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-28 02:51:14,745 [salt.state       :300 ][INFO    ][14135] All specified packages are already installed
2019-04-28 02:51:14,745 [salt.state       :1951][INFO    ][14135] Completed state [maas-rack-controller] at time 02:51:14.745568 duration_in_ms=115.913
2019-04-28 02:51:14,745 [salt.state       :1780][INFO    ][14135] Running state [ipmitool] at time 02:51:14.745931
2019-04-28 02:51:14,746 [salt.state       :1813][INFO    ][14135] Executing state pkg.installed for [ipmitool]
2019-04-28 02:51:14,755 [salt.state       :300 ][INFO    ][14135] All specified packages are already installed
2019-04-28 02:51:14,756 [salt.state       :1951][INFO    ][14135] Completed state [ipmitool] at time 02:51:14.756084 duration_in_ms=10.153
2019-04-28 02:51:14,759 [salt.state       :1780][INFO    ][14135] Running state [/etc/maas/rackd.conf] at time 02:51:14.759140
2019-04-28 02:51:14,759 [salt.state       :1813][INFO    ][14135] Executing state file.line for [/etc/maas/rackd.conf]
2019-04-28 02:51:14,760 [salt.state       :300 ][INFO    ][14135] No changes needed to be made
2019-04-28 02:51:14,760 [salt.state       :1951][INFO    ][14135] Completed state [/etc/maas/rackd.conf] at time 02:51:14.760670 duration_in_ms=1.53
2019-04-28 02:51:14,761 [salt.state       :1780][INFO    ][14135] Running state [/etc/maas/rackd.conf] at time 02:51:14.760965
2019-04-28 02:51:14,761 [salt.state       :1813][INFO    ][14135] Executing state file.managed for [/etc/maas/rackd.conf]
2019-04-28 02:51:14,761 [salt.loaded.int.states.file:2298][WARNING ][14135] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-04-28 02:51:14,762 [salt.state       :300 ][INFO    ][14135] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-04-28 02:51:14,762 [salt.state       :1951][INFO    ][14135] Completed state [/etc/maas/rackd.conf] at time 02:51:14.762610 duration_in_ms=1.645
2019-04-28 02:51:14,764 [salt.state       :1780][INFO    ][14135] Running state [maas-rackd] at time 02:51:14.764235
2019-04-28 02:51:14,764 [salt.state       :1813][INFO    ][14135] Executing state service.running for [maas-rackd]
2019-04-28 02:51:14,765 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14135] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-04-28 02:51:14,798 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14135] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-04-28 02:51:14,821 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14135] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-04-28 02:51:14,842 [salt.state       :300 ][INFO    ][14135] The service maas-rackd is already running
2019-04-28 02:51:14,843 [salt.state       :1951][INFO    ][14135] Completed state [maas-rackd] at time 02:51:14.842954 duration_in_ms=78.719
2019-04-28 02:51:14,844 [salt.minion      :1711][INFO    ][14135] Returning information for job: 20190428025112458255
2019-04-28 02:51:15,514 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command state.apply with jid 20190428025115504592
2019-04-28 02:51:15,533 [salt.minion      :1432][INFO    ][14158] Starting a new job with PID 14158
2019-04-28 02:51:16,516 [salt.state       :915 ][INFO    ][14158] Loading fresh modules for state activity
2019-04-28 02:51:17,854 [salt.state       :1780][INFO    ][14158] Running state [maas-region-controller] at time 02:51:17.854188
2019-04-28 02:51:17,854 [salt.state       :1813][INFO    ][14158] Executing state pkg.installed for [maas-region-controller]
2019-04-28 02:51:17,855 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14158] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-28 02:51:17,961 [salt.state       :300 ][INFO    ][14158] All specified packages are already installed
2019-04-28 02:51:17,962 [salt.state       :1951][INFO    ][14158] Completed state [maas-region-controller] at time 02:51:17.961959 duration_in_ms=107.771
2019-04-28 02:51:17,962 [salt.state       :1780][INFO    ][14158] Running state [python-oauth] at time 02:51:17.962316
2019-04-28 02:51:17,962 [salt.state       :1813][INFO    ][14158] Executing state pkg.installed for [python-oauth]
2019-04-28 02:51:17,972 [salt.state       :300 ][INFO    ][14158] All specified packages are already installed
2019-04-28 02:51:17,973 [salt.state       :1951][INFO    ][14158] Completed state [python-oauth] at time 02:51:17.973161 duration_in_ms=10.845
2019-04-28 02:51:17,976 [salt.state       :1780][INFO    ][14158] Running state [/etc/maas/regiond.conf] at time 02:51:17.976264
2019-04-28 02:51:17,976 [salt.state       :1813][INFO    ][14158] Executing state file.replace for [/etc/maas/regiond.conf]
2019-04-28 02:51:17,982 [salt.state       :300 ][INFO    ][14158] No changes needed to be made
2019-04-28 02:51:17,982 [salt.state       :1951][INFO    ][14158] Completed state [/etc/maas/regiond.conf] at time 02:51:17.982519 duration_in_ms=6.254
2019-04-28 02:51:17,983 [salt.state       :1780][INFO    ][14158] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:51:17.983042
2019-04-28 02:51:17,983 [salt.state       :1813][INFO    ][14158] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-04-28 02:51:18,043 [salt.state       :300 ][INFO    ][14158] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-04-28 02:51:18,043 [salt.state       :1951][INFO    ][14158] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:51:18.043389 duration_in_ms=60.347
2019-04-28 02:51:18,043 [salt.state       :1780][INFO    ][14158] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:51:18.043925
2019-04-28 02:51:18,044 [salt.state       :1813][INFO    ][14158] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-04-28 02:51:18,048 [salt.state       :300 ][INFO    ][14158] No changes needed to be made
2019-04-28 02:51:18,049 [salt.state       :1951][INFO    ][14158] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:51:18.049008 duration_in_ms=5.082
2019-04-28 02:51:18,049 [salt.state       :1780][INFO    ][14158] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:51:18.049530
2019-04-28 02:51:18,049 [salt.state       :1813][INFO    ][14158] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-04-28 02:51:18,054 [salt.state       :300 ][INFO    ][14158] No changes needed to be made
2019-04-28 02:51:18,055 [salt.state       :1951][INFO    ][14158] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:51:18.054829 duration_in_ms=5.299
2019-04-28 02:51:18,055 [salt.state       :1780][INFO    ][14158] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:51:18.055642
2019-04-28 02:51:18,055 [salt.state       :1813][INFO    ][14158] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-04-28 02:51:18,080 [salt.state       :300 ][INFO    ][14158] No changes needed to be made
2019-04-28 02:51:18,081 [salt.state       :1951][INFO    ][14158] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:51:18.080942 duration_in_ms=25.3
2019-04-28 02:51:18,081 [salt.state       :1780][INFO    ][14158] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:51:18.081463
2019-04-28 02:51:18,081 [salt.state       :1813][INFO    ][14158] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-04-28 02:51:18,095 [salt.state       :300 ][INFO    ][14158] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-04-28 02:51:18,096 [salt.state       :1951][INFO    ][14158] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:51:18.096137 duration_in_ms=14.674
2019-04-28 02:51:18,097 [salt.state       :1780][INFO    ][14158] Running state [a2enmod headers] at time 02:51:18.097490
2019-04-28 02:51:18,097 [salt.state       :1813][INFO    ][14158] Executing state cmd.run for [a2enmod headers]
2019-04-28 02:51:18,098 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14158] Executing command 'a2enmod headers' in directory '/root'
2019-04-28 02:51:18,168 [salt.state       :300 ][INFO    ][14158] {'pid': 14177, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-04-28 02:51:18,169 [salt.state       :1951][INFO    ][14158] Completed state [a2enmod headers] at time 02:51:18.169492 duration_in_ms=72.0
2019-04-28 02:51:18,170 [salt.state       :1780][INFO    ][14158] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:51:18.170243
2019-04-28 02:51:18,173 [salt.state       :1813][INFO    ][14158] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-04-28 02:51:18,194 [salt.state       :300 ][INFO    ][14158] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-04-28 02:51:18,194 [salt.state       :1951][INFO    ][14158] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:51:18.194639 duration_in_ms=24.397
2019-04-28 02:51:18,198 [salt.state       :1780][INFO    ][14158] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:51:18.198138
2019-04-28 02:51:18,198 [salt.state       :1813][INFO    ][14158] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-04-28 02:51:18,250 [salt.state       :300 ][INFO    ][14158] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-04-28 02:51:18,250 [salt.state       :1951][INFO    ][14158] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:51:18.250347 duration_in_ms=52.209
2019-04-28 02:51:18,250 [salt.state       :1780][INFO    ][14158] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:51:18.250904
2019-04-28 02:51:18,251 [salt.state       :1813][INFO    ][14158] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-04-28 02:51:18,309 [salt.state       :300 ][INFO    ][14158] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-04-28 02:51:18,309 [salt.state       :1951][INFO    ][14158] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:51:18.309913 duration_in_ms=59.009
2019-04-28 02:51:18,310 [salt.state       :1780][INFO    ][14158] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:51:18.310453
2019-04-28 02:51:18,310 [salt.state       :1813][INFO    ][14158] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-04-28 02:51:18,378 [salt.state       :300 ][INFO    ][14158] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-04-28 02:51:18,379 [salt.state       :1951][INFO    ][14158] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:51:18.379323 duration_in_ms=68.87
2019-04-28 02:51:18,379 [salt.state       :1780][INFO    ][14158] Running state [/root/.pgpass] at time 02:51:18.379849
2019-04-28 02:51:18,380 [salt.state       :1813][INFO    ][14158] Executing state file.managed for [/root/.pgpass]
2019-04-28 02:51:18,430 [salt.state       :300 ][INFO    ][14158] File /root/.pgpass is in the correct state
2019-04-28 02:51:18,430 [salt.state       :1951][INFO    ][14158] Completed state [/root/.pgpass] at time 02:51:18.430379 duration_in_ms=50.53
2019-04-28 02:51:18,437 [salt.state       :1780][INFO    ][14158] Running state [maas-region syncdb --noinput] at time 02:51:18.437036
2019-04-28 02:51:18,437 [salt.state       :1813][INFO    ][14158] Executing state cmd.run for [maas-region syncdb --noinput]
2019-04-28 02:51:18,437 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14158] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-04-28 02:51:21,152 [salt.state       :300 ][INFO    ][14158] {'pid': 14190, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: sessions, sites, maasserver, auth, contenttypes, piston3, metadataserver\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-04-28 02:51:21,153 [salt.state       :1951][INFO    ][14158] Completed state [maas-region syncdb --noinput] at time 02:51:21.153144 duration_in_ms=2716.107
2019-04-28 02:51:21,153 [salt.state       :2022][WARNING ][14158] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-04-28 02:51:21,155 [salt.state       :1780][INFO    ][14158] Running state [maas-regiond] at time 02:51:21.155564
2019-04-28 02:51:21,155 [salt.state       :1813][INFO    ][14158] Executing state service.running for [maas-regiond]
2019-04-28 02:51:21,156 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14158] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-04-28 02:51:21,181 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14158] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-04-28 02:51:21,193 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14158] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-04-28 02:51:21,204 [salt.state       :300 ][INFO    ][14158] The service maas-regiond is already running
2019-04-28 02:51:21,205 [salt.state       :1951][INFO    ][14158] Completed state [maas-regiond] at time 02:51:21.205120 duration_in_ms=49.556
2019-04-28 02:51:21,208 [salt.state       :1780][INFO    ][14158] Running state [bind9] at time 02:51:21.208522
2019-04-28 02:51:21,209 [salt.state       :1813][INFO    ][14158] Executing state service.running for [bind9]
2019-04-28 02:51:21,209 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14158] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-04-28 02:51:21,222 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14158] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-04-28 02:51:21,233 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14158] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-04-28 02:51:21,245 [salt.state       :300 ][INFO    ][14158] The service bind9 is already running
2019-04-28 02:51:21,245 [salt.state       :1951][INFO    ][14158] Completed state [bind9] at time 02:51:21.245916 duration_in_ms=37.394
2019-04-28 02:51:21,247 [salt.state       :1780][INFO    ][14158] Running state [apache2] at time 02:51:21.247636
2019-04-28 02:51:21,248 [salt.state       :1813][INFO    ][14158] Executing state service.running for [apache2]
2019-04-28 02:51:21,248 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14158] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-04-28 02:51:21,262 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14158] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-04-28 02:51:21,273 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14158] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-04-28 02:51:21,289 [salt.state       :300 ][INFO    ][14158] The service apache2 is already running
2019-04-28 02:51:21,289 [salt.state       :1951][INFO    ][14158] Completed state [apache2] at time 02:51:21.289329 duration_in_ms=41.693
2019-04-28 02:51:21,290 [salt.state       :1780][INFO    ][14158] Running state [maasng.wait_for_http_code] at time 02:51:21.290449
2019-04-28 02:51:21,290 [salt.state       :1813][INFO    ][14158] Executing state module.run for [maasng.wait_for_http_code]
2019-04-28 02:51:21,293 [salt.utils.decorators:613 ][WARNING ][14158] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-28 02:51:21,299 [salt.state       :300 ][INFO    ][14158] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-04-28 02:51:21,299 [salt.state       :1951][INFO    ][14158] Completed state [maasng.wait_for_http_code] at time 02:51:21.299441 duration_in_ms=8.992
2019-04-28 02:51:21,300 [salt.state       :1780][INFO    ][14158] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:51:21.300212
2019-04-28 02:51:21,300 [salt.state       :1813][INFO    ][14158] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-04-28 02:51:21,300 [salt.state       :300 ][INFO    ][14158] /var/lib/maas/.setup_admin exists
2019-04-28 02:51:21,301 [salt.state       :1951][INFO    ][14158] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:51:21.301142 duration_in_ms=0.93
2019-04-28 02:51:21,301 [salt.state       :1780][INFO    ][14158] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:51:21.301844
2019-04-28 02:51:21,302 [salt.state       :1813][INFO    ][14158] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-28 02:51:21,302 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14158] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-28 02:51:23,268 [salt.state       :300 ][INFO    ][14158] {'pid': 14209, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-28 02:51:23,268 [salt.state       :1951][INFO    ][14158] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:51:23.268664 duration_in_ms=1966.819
2019-04-28 02:51:23,274 [salt.state       :1780][INFO    ][14158] Running state [maas_region_boot_source_resources_mirror] at time 02:51:23.274323
2019-04-28 02:51:23,274 [salt.state       :1813][INFO    ][14158] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-04-28 02:51:23,336 [salt.state       :300 ][INFO    ][14158] {'changes': {}}
2019-04-28 02:51:23,336 [salt.state       :1951][INFO    ][14158] Completed state [maas_region_boot_source_resources_mirror] at time 02:51:23.336487 duration_in_ms=62.164
2019-04-28 02:51:23,337 [salt.state       :1780][INFO    ][14158] Running state [maasng.boot_resources_import] at time 02:51:23.337177
2019-04-28 02:51:23,337 [salt.state       :1813][INFO    ][14158] Executing state module.run for [maasng.boot_resources_import]
2019-04-28 02:51:23,337 [salt.utils.decorators:613 ][WARNING ][14158] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-28 02:51:23,402 [salt.loaded.ext.module.maasng:1600][INFO    ][14158] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-04-28 02:51:28,452 [salt.loaded.ext.module.maasng:1600][INFO    ][14158] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-04-28 02:51:30,554 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428025130541298
2019-04-28 02:51:30,577 [salt.minion      :1432][INFO    ][14329] Starting a new job with PID 14329
2019-04-28 02:51:30,637 [salt.minion      :1711][INFO    ][14329] Returning information for job: 20190428025130541298
2019-04-28 02:51:33,529 [salt.state       :300 ][INFO    ][14158] {'ret': True}
2019-04-28 02:51:33,530 [salt.state       :1951][INFO    ][14158] Completed state [maasng.boot_resources_import] at time 02:51:33.530156 duration_in_ms=10192.978
2019-04-28 02:51:33,532 [salt.state       :1780][INFO    ][14158] Running state [maas_region_boot_sources_selection_xenial] at time 02:51:33.532495
2019-04-28 02:51:33,533 [salt.state       :1813][INFO    ][14158] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-04-28 02:51:33,693 [salt.state       :300 ][INFO    ][14158] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-04-28 02:51:33,693 [salt.state       :1951][INFO    ][14158] Completed state [maas_region_boot_sources_selection_xenial] at time 02:51:33.693439 duration_in_ms=160.945
2019-04-28 02:51:33,695 [salt.state       :1780][INFO    ][14158] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 02:51:33.695821
2019-04-28 02:51:33,696 [salt.state       :1813][INFO    ][14158] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-04-28 02:51:33,696 [salt.utils.decorators:613 ][WARNING ][14158] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-28 02:51:33,697 [salt.loaded.ext.module.maasng:1771][INFO    ][14158] boot-sources sync initiated for ALL Rack's
2019-04-28 02:51:34,762 [salt.state       :300 ][INFO    ][14158] {'ret': True}
2019-04-28 02:51:34,763 [salt.state       :1951][INFO    ][14158] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 02:51:34.763603 duration_in_ms=1067.781
2019-04-28 02:51:34,765 [salt.state       :1780][INFO    ][14158] Running state [maas.process_maas_config] at time 02:51:34.765030
2019-04-28 02:51:34,765 [salt.state       :1813][INFO    ][14158] Executing state module.run for [maas.process_maas_config]
2019-04-28 02:51:34,765 [salt.utils.decorators:613 ][WARNING ][14158] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-28 02:51:34,766 [salt.loaded.ext.module.maas:92  ][INFO    ][14158] maasconfig name=enable_http_proxy value=True
2019-04-28 02:51:34,806 [salt.loaded.ext.module.maas:92  ][INFO    ][14158] maasconfig name=upstream_dns value=8.8.8.8
2019-04-28 02:51:34,847 [salt.loaded.ext.module.maas:92  ][INFO    ][14158] maasconfig name=commissioning_distro_series value=xenial
2019-04-28 02:51:34,907 [salt.loaded.ext.module.maas:92  ][INFO    ][14158] maasconfig name=default_osystem value=ubuntu
2019-04-28 02:51:34,981 [salt.loaded.ext.module.maas:92  ][INFO    ][14158] maasconfig name=active_discovery_interval value=600
2019-04-28 02:51:36,276 [salt.loaded.ext.module.maas:92  ][INFO    ][14158] maasconfig name=dnssec_validation value=no
2019-04-28 02:51:36,323 [salt.loaded.ext.module.maas:92  ][INFO    ][14158] maasconfig name=maas_name value=mas01
2019-04-28 02:51:36,398 [salt.loaded.ext.module.maas:92  ][INFO    ][14158] maasconfig name=network_discovery value=enabled
2019-04-28 02:51:36,491 [salt.loaded.ext.module.maas:92  ][INFO    ][14158] maasconfig name=enable_third_party_drivers value=True
2019-04-28 02:51:36,532 [salt.loaded.ext.module.maas:92  ][INFO    ][14158] maasconfig name=default_storage_layout value=lvm
2019-04-28 02:51:36,571 [salt.loaded.ext.module.maas:92  ][INFO    ][14158] maasconfig name=ntp_external_only value=True
2019-04-28 02:51:36,617 [salt.loaded.ext.module.maas:92  ][INFO    ][14158] maasconfig name=disk_erase_with_secure_erase value=False
2019-04-28 02:51:36,656 [salt.loaded.ext.module.maas:92  ][INFO    ][14158] maasconfig name=default_distro_series value=xenial
2019-04-28 02:51:36,717 [salt.loaded.ext.module.maas:92  ][INFO    ][14158] maasconfig name=default_min_hwe_kernel value=ga-16.04
2019-04-28 02:51:36,837 [salt.state       :300 ][INFO    ][14158] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-04-28 02:51:36,837 [salt.state       :1951][INFO    ][14158] Completed state [maas.process_maas_config] at time 02:51:36.837585 duration_in_ms=2072.555
2019-04-28 02:51:36,838 [salt.state       :1780][INFO    ][14158] Running state [pxe_admin] at time 02:51:36.838211
2019-04-28 02:51:36,838 [salt.state       :1813][INFO    ][14158] Executing state maasng.fabric_present for [pxe_admin]
2019-04-28 02:51:36,888 [salt.loaded.ext.module.maasng:945 ][INFO    ][14158] [{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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/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': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qgxrnm', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-28 02:51:36,942 [salt.loaded.ext.module.maasng:1008][WARNING ][14158] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-04-28 02:51:36,942 [salt.loaded.ext.module.maasng:1011][WARNING ][14158] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-04-28 02:51:36,996 [salt.state       :300 ][INFO    ][14158] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-04-28 02:51:36,997 [salt.state       :1951][INFO    ][14158] Completed state [pxe_admin] at time 02:51:36.997006 duration_in_ms=158.795
2019-04-28 02:51:36,997 [salt.state       :1780][INFO    ][14158] Running state [vlan 0] at time 02:51:36.997374
2019-04-28 02:51:36,997 [salt.state       :1813][INFO    ][14158] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-04-28 02:51:37,045 [salt.loaded.ext.module.maasng:945 ][INFO    ][14158] [{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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/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': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qgxrnm', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-28 02:51:37,134 [salt.loaded.ext.module.maasng:945 ][INFO    ][14158] [{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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/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': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qgxrnm', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-28 02:51:37,353 [salt.loaded.ext.module.maasng:945 ][INFO    ][14158] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'qgxrnm', 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/'}]
2019-04-28 02:51:37,423 [salt.state       :300 ][INFO    ][14158] {'new': 'Vlan untagged was updated'}
2019-04-28 02:51:37,424 [salt.state       :1951][INFO    ][14158] Completed state [vlan 0] at time 02:51:37.424001 duration_in_ms=426.625
2019-04-28 02:51:37,425 [salt.state       :1780][INFO    ][14158] Running state [192.168.11.0/24] at time 02:51:37.425663
2019-04-28 02:51:37,426 [salt.state       :1813][INFO    ][14158] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-04-28 02:51:37,589 [salt.loaded.ext.module.maasng:945 ][INFO    ][14158] [{u'id': 0, 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qgxrnm', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-28 02:51:37,590 [salt.loaded.ext.module.maasng:1235][WARNING ][14158] Ignoring parameter vlan:0
2019-04-28 02:51:37,651 [salt.state       :300 ][INFO    ][14158] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-04-28 02:51:37,652 [salt.state       :1951][INFO    ][14158] Completed state [192.168.11.0/24] at time 02:51:37.652247 duration_in_ms=226.584
2019-04-28 02:51:37,653 [salt.state       :1780][INFO    ][14158] Running state [maas_create_iprange_1] at time 02:51:37.653103
2019-04-28 02:51:37,653 [salt.state       :1813][INFO    ][14158] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-04-28 02:51:37,704 [salt.state       :300 ][INFO    ][14158] Iprange maas_create_iprange_1 already exist.
2019-04-28 02:51:37,704 [salt.state       :1951][INFO    ][14158] Completed state [maas_create_iprange_1] at time 02:51:37.704280 duration_in_ms=51.176
2019-04-28 02:51:37,704 [salt.state       :1780][INFO    ][14158] Running state [vlan 0] at time 02:51:37.704610
2019-04-28 02:51:37,704 [salt.state       :1813][INFO    ][14158] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-04-28 02:51:37,764 [salt.loaded.ext.module.maasng:945 ][INFO    ][14158] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'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'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'qgxrnm', 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'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-28 02:51:37,840 [salt.loaded.ext.module.maasng:945 ][INFO    ][14158] [{u'id': 0, 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qgxrnm', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-28 02:51:38,046 [salt.loaded.ext.module.maasng:945 ][INFO    ][14158] [{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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qgxrnm', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-28 02:51:38,118 [salt.state       :300 ][INFO    ][14158] {'new': 'Vlan untagged was updated'}
2019-04-28 02:51:38,119 [salt.state       :1951][INFO    ][14158] Completed state [vlan 0] at time 02:51:38.119769 duration_in_ms=415.155
2019-04-28 02:51:38,121 [salt.state       :1780][INFO    ][14158] Running state [opnfv] at time 02:51:38.121289
2019-04-28 02:51:38,121 [salt.state       :1813][INFO    ][14158] Executing state maasng.sshkey_present for [opnfv]
2019-04-28 02:51:38,167 [salt.loaded.ext.module.maasng:1903][INFO    ][14158] [{u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b', u'keysource': u''}]
2019-04-28 02:51:38,167 [salt.state       :300 ][INFO    ][14158] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-04-28 02:51:38,167 [salt.state       :1951][INFO    ][14158] Completed state [opnfv] at time 02:51:38.167803 duration_in_ms=46.514
2019-04-28 02:51:38,171 [salt.minion      :1711][INFO    ][14158] Returning information for job: 20190428025115504592
2019-04-28 02:51:38,926 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command state.apply with jid 20190428025138913629
2019-04-28 02:51:38,947 [salt.minion      :1432][INFO    ][14643] Starting a new job with PID 14643
2019-04-28 02:51:45,065 [salt.state       :915 ][INFO    ][14643] Loading fresh modules for state activity
2019-04-28 02:51:45,165 [salt.state       :1780][INFO    ][14643] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:51:45.165014
2019-04-28 02:51:45,165 [salt.state       :1813][INFO    ][14643] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-28 02:51:45,168 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14643] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-28 02:51:47,082 [salt.state       :300 ][INFO    ][14643] {'pid': 14678, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-28 02:51:47,083 [salt.state       :1951][INFO    ][14643] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:51:47.083749 duration_in_ms=1918.735
2019-04-28 02:51:47,086 [salt.state       :1780][INFO    ][14643] Running state [maas.process_machines] at time 02:51:47.086462
2019-04-28 02:51:47,087 [salt.state       :1813][INFO    ][14643] Executing state module.run for [maas.process_machines]
2019-04-28 02:51:47,088 [salt.utils.decorators:613 ][WARNING ][14643] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-28 02:51:47,663 [salt.loaded.ext.module.maas:412 ][WARNING ][14643] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-28 02:51:47,664 [salt.loaded.ext.module.maas:92  ][INFO    ][14643] 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=6yhhnx architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-28 02:51:48,760 [salt.loaded.ext.module.maas:412 ][WARNING ][14643] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-28 02:51:48,761 [salt.loaded.ext.module.maas:92  ][INFO    ][14643] 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=dpw3by architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-28 02:51:49,884 [salt.loaded.ext.module.maas:412 ][WARNING ][14643] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-28 02:51:49,885 [salt.loaded.ext.module.maas:92  ][INFO    ][14643] 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=cnresf architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-28 02:51:50,993 [salt.loaded.ext.module.maas:412 ][WARNING ][14643] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-28 02:51:50,993 [salt.loaded.ext.module.maas:92  ][INFO    ][14643] 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=x7ekd6 architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-28 02:51:52,246 [salt.loaded.ext.module.maas:412 ][WARNING ][14643] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-28 02:51:52,247 [salt.loaded.ext.module.maas:92  ][INFO    ][14643] 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=k3em77 architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-28 02:51:53,349 [salt.state       :300 ][INFO    ][14643] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-04-28 02:51:53,350 [salt.state       :1951][INFO    ][14643] Completed state [maas.process_machines] at time 02:51:53.350145 duration_in_ms=6263.683
2019-04-28 02:51:53,354 [salt.minion      :1711][INFO    ][14643] Returning information for job: 20190428025138913629
2019-04-28 02:52:27,116 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command state.apply with jid 20190428025227107475
2019-04-28 02:52:27,130 [salt.minion      :1432][INFO    ][14922] Starting a new job with PID 14922
2019-04-28 02:52:32,957 [salt.state       :915 ][INFO    ][14922] Loading fresh modules for state activity
2019-04-28 02:52:33,046 [salt.state       :1780][INFO    ][14922] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:52:33.046604
2019-04-28 02:52:33,047 [salt.state       :1813][INFO    ][14922] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-28 02:52:33,049 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14922] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-28 02:52:35,167 [salt.state       :300 ][INFO    ][14922] {'pid': 14930, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-28 02:52:35,168 [salt.state       :1951][INFO    ][14922] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:52:35.168234 duration_in_ms=2121.629
2019-04-28 02:52:35,169 [salt.state       :1780][INFO    ][14922] Running state [maas.wait_for_machine_status] at time 02:52:35.169856
2019-04-28 02:52:35,170 [salt.state       :1813][INFO    ][14922] Executing state module.run for [maas.wait_for_machine_status]
2019-04-28 02:52:35,170 [salt.utils.decorators:613 ][WARNING ][14922] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-28 02:52:37,989 [salt.state       :300 ][INFO    ][14922] {'ret': True}
2019-04-28 02:52:37,989 [salt.state       :1951][INFO    ][14922] Completed state [maas.wait_for_machine_status] at time 02:52:37.989782 duration_in_ms=2819.925
2019-04-28 02:52:37,993 [salt.minion      :1711][INFO    ][14922] Returning information for job: 20190428025227107475
2019-04-28 02:52:38,690 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command state.apply with jid 20190428025238650613
2019-04-28 02:52:38,707 [salt.minion      :1432][INFO    ][14987] Starting a new job with PID 14987
2019-04-28 02:52:39,617 [salt.state       :915 ][INFO    ][14987] Loading fresh modules for state activity
2019-04-28 02:52:39,760 [salt.state       :1780][INFO    ][14987] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:52:39.760669
2019-04-28 02:52:39,761 [salt.state       :1813][INFO    ][14987] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-28 02:52:39,762 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14987] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-28 02:52:41,905 [salt.state       :300 ][INFO    ][14987] {'pid': 14994, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-28 02:52:41,906 [salt.state       :1951][INFO    ][14987] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:52:41.905922 duration_in_ms=2145.253
2019-04-28 02:52:41,909 [salt.state       :1780][INFO    ][14987] Running state [maas_machines_storage_cmp002_lvm] at time 02:52:41.909026
2019-04-28 02:52:41,909 [salt.state       :1813][INFO    ][14987] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-04-28 02:52:42,568 [salt.state       :300 ][INFO    ][14987] Machine cmp002 is not in Ready state.
2019-04-28 02:52:42,569 [salt.state       :1951][INFO    ][14987] Completed state [maas_machines_storage_cmp002_lvm] at time 02:52:42.569040 duration_in_ms=660.014
2019-04-28 02:52:42,569 [salt.state       :1780][INFO    ][14987] Running state [maas_machines_storage_cmp001_lvm] at time 02:52:42.569449
2019-04-28 02:52:42,569 [salt.state       :1813][INFO    ][14987] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-04-28 02:52:43,084 [salt.state       :300 ][INFO    ][14987] Machine cmp001 is not in Ready state.
2019-04-28 02:52:43,084 [salt.state       :1951][INFO    ][14987] Completed state [maas_machines_storage_cmp001_lvm] at time 02:52:43.084651 duration_in_ms=515.202
2019-04-28 02:52:43,087 [salt.minion      :1711][INFO    ][14987] Returning information for job: 20190428025238650613
2019-04-28 02:52:43,744 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command state.apply with jid 20190428025243729680
2019-04-28 02:52:43,760 [salt.minion      :1432][INFO    ][15018] Starting a new job with PID 15018
2019-04-28 02:52:44,755 [salt.state       :915 ][INFO    ][15018] Loading fresh modules for state activity
2019-04-28 02:52:44,848 [salt.state       :1780][INFO    ][15018] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:52:44.848169
2019-04-28 02:52:44,848 [salt.state       :1813][INFO    ][15018] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-28 02:52:44,850 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15018] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-28 02:52:46,730 [salt.state       :300 ][INFO    ][15018] {'pid': 15025, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-28 02:52:46,732 [salt.state       :1951][INFO    ][15018] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:52:46.731885 duration_in_ms=1883.716
2019-04-28 02:52:46,734 [salt.state       :1780][INFO    ][15018] Running state [maas.deploy_machines] at time 02:52:46.734717
2019-04-28 02:52:46,735 [salt.state       :1813][INFO    ][15018] Executing state module.run for [maas.deploy_machines]
2019-04-28 02:52:46,735 [salt.utils.decorators:613 ][WARNING ][15018] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-28 02:52:47,281 [salt.loaded.ext.module.maas:684 ][INFO    ][15018] deploymachines hwe_kernel=ga-16.04 system_id=k3em77 distro_series=xenial
2019-04-28 02:52:49,533 [salt.state       :300 ][INFO    ][15018] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-04-28 02:52:49,533 [salt.state       :1951][INFO    ][15018] Completed state [maas.deploy_machines] at time 02:52:49.533432 duration_in_ms=2798.716
2019-04-28 02:52:49,536 [salt.minion      :1711][INFO    ][15018] Returning information for job: 20190428025243729680
2019-04-28 02:52:50,210 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command state.apply with jid 20190428025250200890
2019-04-28 02:52:50,229 [salt.minion      :1432][INFO    ][15085] Starting a new job with PID 15085
2019-04-28 02:52:56,128 [salt.state       :915 ][INFO    ][15085] Loading fresh modules for state activity
2019-04-28 02:52:56,221 [salt.state       :1780][INFO    ][15085] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:52:56.221788
2019-04-28 02:52:56,222 [salt.state       :1813][INFO    ][15085] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-28 02:52:56,224 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15085] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-28 02:52:58,118 [salt.state       :300 ][INFO    ][15085] {'pid': 15100, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-28 02:52:58,120 [salt.state       :1951][INFO    ][15085] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:52:58.119844 duration_in_ms=1898.055
2019-04-28 02:52:58,123 [salt.state       :1780][INFO    ][15085] Running state [maas.wait_for_machine_status] at time 02:52:58.123245
2019-04-28 02:52:58,123 [salt.state       :1813][INFO    ][15085] Executing state module.run for [maas.wait_for_machine_status]
2019-04-28 02:52:58,124 [salt.utils.decorators:613 ][WARNING ][15085] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-28 02:53:00,757 [salt.loaded.ext.module.maas:1023][INFO    ][15085] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.38380194s left)
2019-04-28 02:53:05,309 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428025305296784
2019-04-28 02:53:05,334 [salt.minion      :1432][INFO    ][15121] Starting a new job with PID 15121
2019-04-28 02:53:05,363 [salt.minion      :1711][INFO    ][15121] Returning information for job: 20190428025305296784
2019-04-28 02:53:33,055 [salt.loaded.ext.module.maas:1023][INFO    ][15085] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2215.08568096s left)
2019-04-28 02:53:35,377 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428025335366734
2019-04-28 02:53:35,398 [salt.minion      :1432][INFO    ][15173] Starting a new job with PID 15173
2019-04-28 02:53:35,431 [salt.minion      :1711][INFO    ][15173] Returning information for job: 20190428025335366734
2019-04-28 02:54:05,426 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428025405418542
2019-04-28 02:54:05,445 [salt.minion      :1432][INFO    ][15223] Starting a new job with PID 15223
2019-04-28 02:54:05,474 [salt.minion      :1711][INFO    ][15223] Returning information for job: 20190428025405418542
2019-04-28 02:54:05,904 [salt.loaded.ext.module.maas:1023][INFO    ][15085] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2182.23742008s left)
2019-04-28 02:54:35,618 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428025435611627
2019-04-28 02:54:35,639 [salt.minion      :1432][INFO    ][15287] Starting a new job with PID 15287
2019-04-28 02:54:35,672 [salt.minion      :1711][INFO    ][15287] Returning information for job: 20190428025435611627
2019-04-28 02:54:38,342 [salt.loaded.ext.module.maas:1023][INFO    ][15085] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2149.79943204s left)
2019-04-28 02:55:05,697 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428025505688528
2019-04-28 02:55:05,719 [salt.minion      :1432][INFO    ][15342] Starting a new job with PID 15342
2019-04-28 02:55:05,753 [salt.minion      :1711][INFO    ][15342] Returning information for job: 20190428025505688528
2019-04-28 02:55:11,017 [salt.loaded.ext.module.maas:1023][INFO    ][15085] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2117.12396288s left)
2019-04-28 02:55:35,769 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428025535757606
2019-04-28 02:55:35,792 [salt.minion      :1432][INFO    ][15405] Starting a new job with PID 15405
2019-04-28 02:55:35,824 [salt.minion      :1711][INFO    ][15405] Returning information for job: 20190428025535757606
2019-04-28 02:55:43,538 [salt.loaded.ext.module.maas:1023][INFO    ][15085] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2084.60315895s left)
2019-04-28 02:56:05,844 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428025605827363
2019-04-28 02:56:05,865 [salt.minion      :1432][INFO    ][15445] Starting a new job with PID 15445
2019-04-28 02:56:05,898 [salt.minion      :1711][INFO    ][15445] Returning information for job: 20190428025605827363
2019-04-28 02:56:16,205 [salt.loaded.ext.module.maas:1023][INFO    ][15085] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2051.93631411s left)
2019-04-28 02:56:35,925 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428025635915709
2019-04-28 02:56:35,946 [salt.minion      :1432][INFO    ][15508] Starting a new job with PID 15508
2019-04-28 02:56:35,972 [salt.minion      :1711][INFO    ][15508] Returning information for job: 20190428025635915709
2019-04-28 02:56:48,645 [salt.loaded.ext.module.maas:1023][INFO    ][15085] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2019.49568009s left)
2019-04-28 02:57:05,994 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428025705985599
2019-04-28 02:57:06,010 [salt.minion      :1432][INFO    ][15573] Starting a new job with PID 15573
2019-04-28 02:57:06,036 [salt.minion      :1711][INFO    ][15573] Returning information for job: 20190428025705985599
2019-04-28 02:57:21,586 [salt.loaded.ext.module.maas:1023][INFO    ][15085] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1986.55479002s left)
2019-04-28 02:57:36,070 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428025736058361
2019-04-28 02:57:36,086 [salt.minion      :1432][INFO    ][15705] Starting a new job with PID 15705
2019-04-28 02:57:36,117 [salt.minion      :1711][INFO    ][15705] Returning information for job: 20190428025736058361
2019-04-28 02:57:54,216 [salt.loaded.ext.module.maas:1023][INFO    ][15085] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1953.92501998s left)
2019-04-28 02:58:06,158 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428025806144437
2019-04-28 02:58:06,178 [salt.minion      :1432][INFO    ][15782] Starting a new job with PID 15782
2019-04-28 02:58:06,212 [salt.minion      :1711][INFO    ][15782] Returning information for job: 20190428025806144437
2019-04-28 02:58:26,988 [salt.loaded.ext.module.maas:1023][INFO    ][15085] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1921.15321589s left)
2019-04-28 02:58:36,264 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428025836256685
2019-04-28 02:58:36,283 [salt.minion      :1432][INFO    ][15927] Starting a new job with PID 15927
2019-04-28 02:58:36,310 [salt.minion      :1711][INFO    ][15927] Returning information for job: 20190428025836256685
2019-04-28 02:58:59,596 [salt.loaded.ext.module.maas:1023][INFO    ][15085] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1888.54487991s left)
2019-04-28 02:59:06,466 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428025906455608
2019-04-28 02:59:06,489 [salt.minion      :1432][INFO    ][15977] Starting a new job with PID 15977
2019-04-28 02:59:06,525 [salt.minion      :1711][INFO    ][15977] Returning information for job: 20190428025906455608
2019-04-28 02:59:32,375 [salt.loaded.ext.module.maas:1023][INFO    ][15085] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1855.76652598s left)
2019-04-28 02:59:36,488 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428025936474246
2019-04-28 02:59:36,511 [salt.minion      :1432][INFO    ][16063] Starting a new job with PID 16063
2019-04-28 02:59:36,543 [salt.minion      :1711][INFO    ][16063] Returning information for job: 20190428025936474246
2019-04-28 03:00:05,172 [salt.loaded.ext.module.maas:1023][INFO    ][15085] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1822.96891689s left)
2019-04-28 03:00:06,612 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428030006605529
2019-04-28 03:00:06,625 [salt.minion      :1432][INFO    ][16116] Starting a new job with PID 16116
2019-04-28 03:00:06,656 [salt.minion      :1711][INFO    ][16116] Returning information for job: 20190428030006605529
2019-04-28 03:00:36,738 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428030036729147
2019-04-28 03:00:36,754 [salt.minion      :1432][INFO    ][16236] Starting a new job with PID 16236
2019-04-28 03:00:36,786 [salt.minion      :1711][INFO    ][16236] Returning information for job: 20190428030036729147
2019-04-28 03:00:38,002 [salt.loaded.ext.module.maas:1023][INFO    ][15085] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1790.1389451s left)
2019-04-28 03:01:06,873 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428030106864522
2019-04-28 03:01:06,893 [salt.minion      :1432][INFO    ][16294] Starting a new job with PID 16294
2019-04-28 03:01:06,922 [salt.minion      :1711][INFO    ][16294] Returning information for job: 20190428030106864522
2019-04-28 03:01:10,638 [salt.loaded.ext.module.maas:1023][INFO    ][15085] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1757.50302696s left)
2019-04-28 03:01:37,013 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428030137000265
2019-04-28 03:01:37,030 [salt.minion      :1432][INFO    ][16443] Starting a new job with PID 16443
2019-04-28 03:01:37,061 [salt.minion      :1711][INFO    ][16443] Returning information for job: 20190428030137000265
2019-04-28 03:01:43,401 [salt.loaded.ext.module.maas:1023][INFO    ][15085] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1724.73986292s left)
2019-04-28 03:02:07,154 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428030207139362
2019-04-28 03:02:07,173 [salt.minion      :1432][INFO    ][16487] Starting a new job with PID 16487
2019-04-28 03:02:07,202 [salt.minion      :1711][INFO    ][16487] Returning information for job: 20190428030207139362
2019-04-28 03:02:16,100 [salt.loaded.ext.module.maas:1023][INFO    ][15085] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1692.04129195s left)
2019-04-28 03:02:37,296 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428030237287790
2019-04-28 03:02:37,315 [salt.minion      :1432][INFO    ][16552] Starting a new job with PID 16552
2019-04-28 03:02:37,351 [salt.minion      :1711][INFO    ][16552] Returning information for job: 20190428030237287790
2019-04-28 03:02:48,878 [salt.loaded.ext.module.maas:1023][INFO    ][15085] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1659.26347494s left)
2019-04-28 03:03:07,450 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428030307442955
2019-04-28 03:03:07,470 [salt.minion      :1432][INFO    ][16588] Starting a new job with PID 16588
2019-04-28 03:03:07,500 [salt.minion      :1711][INFO    ][16588] Returning information for job: 20190428030307442955
2019-04-28 03:03:21,473 [salt.loaded.ext.module.maas:1023][INFO    ][15085] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1626.66796088s left)
2019-04-28 03:03:37,485 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428030337471840
2019-04-28 03:03:37,509 [salt.minion      :1432][INFO    ][16655] Starting a new job with PID 16655
2019-04-28 03:03:37,543 [salt.minion      :1711][INFO    ][16655] Returning information for job: 20190428030337471840
2019-04-28 03:03:54,491 [salt.loaded.ext.module.maas:1023][INFO    ][15085] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1593.65039897s left)
2019-04-28 03:04:07,673 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428030407659853
2019-04-28 03:04:07,690 [salt.minion      :1432][INFO    ][16693] Starting a new job with PID 16693
2019-04-28 03:04:07,721 [salt.minion      :1711][INFO    ][16693] Returning information for job: 20190428030407659853
2019-04-28 03:04:27,045 [salt.loaded.ext.module.maas:1023][INFO    ][15085] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1561.09587407s left)
2019-04-28 03:04:28,142 [salt.utils.schedule:1377][INFO    ][3235] Running scheduled job: __mine_interval
2019-04-28 03:04:37,821 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428030437812203
2019-04-28 03:04:37,838 [salt.minion      :1432][INFO    ][16794] Starting a new job with PID 16794
2019-04-28 03:04:37,869 [salt.minion      :1711][INFO    ][16794] Returning information for job: 20190428030437812203
2019-04-28 03:04:59,807 [salt.loaded.ext.module.maas:1023][INFO    ][15085] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1528.33382702s left)
2019-04-28 03:05:08,001 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428030507991679
2019-04-28 03:05:08,017 [salt.minion      :1432][INFO    ][16974] Starting a new job with PID 16974
2019-04-28 03:05:08,048 [salt.minion      :1711][INFO    ][16974] Returning information for job: 20190428030507991679
2019-04-28 03:05:32,533 [salt.loaded.ext.module.maas:1023][INFO    ][15085] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1495.60805106s left)
2019-04-28 03:05:38,179 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428030538168655
2019-04-28 03:05:38,195 [salt.minion      :1432][INFO    ][17065] Starting a new job with PID 17065
2019-04-28 03:05:38,226 [salt.minion      :1711][INFO    ][17065] Returning information for job: 20190428030538168655
2019-04-28 03:06:05,495 [salt.state       :300 ][INFO    ][15085] {'ret': True}
2019-04-28 03:06:05,496 [salt.state       :1951][INFO    ][15085] Completed state [maas.wait_for_machine_status] at time 03:06:05.496116 duration_in_ms=787372.865
2019-04-28 03:06:05,502 [salt.minion      :1711][INFO    ][15085] Returning information for job: 20190428025250200890
2019-04-28 04:04:28,142 [salt.utils.schedule:1377][INFO    ][3235] Running scheduled job: __mine_interval
2019-04-28 04:43:40,783 [salt.minion      :1308][INFO    ][3235] User sudo_ubuntu Executing command cp.push_dir with jid 20190428044340772172
2019-04-28 04:43:40,801 [salt.minion      :1432][INFO    ][27184] Starting a new job with PID 27184
