2019-02-27 08:13:05,802 [salt.utils.decorators:613 ][WARNING ][2050] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-27 08:13:06,822 [salt.utils.decorators:613 ][WARNING ][2050] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-27 08:13:10,136 [salt.loaded.int.states.file:2298][WARNING ][2568] 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-02-27 08:13:29,204 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3027] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-02-27 08:13:29,227 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3027] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-02-27 08:13:29,284 [salt.utils.parsers:1051][WARNING ][380] Minion received a SIGTERM. Exiting.
2019-02-27 08:13:30,417 [salt.cli.daemons :293 ][INFO    ][3148] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-02-27 08:13:30,585 [salt.cli.daemons :82  ][INFO    ][3148] Starting up the Salt Minion
2019-02-27 08:13:30,585 [salt.utils.event :1017][INFO    ][3148] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-02-27 08:13:31,877 [salt.minion      :976 ][INFO    ][3148] Creating minion process manager
2019-02-27 08:13:33,738 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][3148] Executing command ['date', '+%z'] in directory '/root'
2019-02-27 08:13:33,762 [salt.utils.schedule:568 ][INFO    ][3148] Updating job settings for scheduled job: __mine_interval
2019-02-27 08:13:33,764 [salt.minion      :1108][INFO    ][3148] Added mine.update to scheduler
2019-02-27 08:13:33,769 [salt.minion      :1975][INFO    ][3148] Minion is starting as user 'root'
2019-02-27 08:13:33,787 [salt.minion      :2336][INFO    ][3148] Minion is ready to receive requests!
2019-02-27 08:13:40,412 [salt.state       :2022][WARNING ][3032] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-02-27 08:13:41,652 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227081341642751
2019-02-27 08:13:41,665 [salt.minion      :1432][INFO    ][3574] Starting a new job with PID 3574
2019-02-27 08:13:41,700 [salt.minion      :1711][INFO    ][3574] Returning information for job: 20190227081341642751
2019-02-27 08:13:42,547 [salt.utils.decorators:613 ][WARNING ][3032] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-27 08:13:55,970 [salt.utils.decorators:613 ][WARNING ][3032] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-27 08:14:11,766 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227081411756081
2019-02-27 08:14:11,791 [salt.minion      :1432][INFO    ][4369] Starting a new job with PID 4369
2019-02-27 08:14:11,819 [salt.minion      :1711][INFO    ][4369] Returning information for job: 20190227081411756081
2019-02-27 08:14:17,750 [salt.utils.decorators:613 ][WARNING ][3032] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-27 08:14:18,660 [salt.utils.decorators:613 ][WARNING ][3032] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-27 08:14:21,144 [salt.loaded.ext.module.maasng:1008][WARNING ][3032] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-02-27 08:14:21,144 [salt.loaded.ext.module.maasng:1011][WARNING ][3032] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-02-27 08:14:21,949 [salt.loaded.ext.module.maasng:1235][WARNING ][3032] Ignoring parameter vlan:0
2019-02-27 08:14:23,623 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command state.apply with jid 20190227081423613432
2019-02-27 08:14:23,636 [salt.minion      :1432][INFO    ][4735] Starting a new job with PID 4735
2019-02-27 08:14:31,604 [salt.state       :915 ][INFO    ][4735] Loading fresh modules for state activity
2019-02-27 08:14:31,676 [salt.fileclient  :1219][INFO    ][4735] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-02-27 08:14:31,726 [salt.state       :1780][INFO    ][4735] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:14:31.726638
2019-02-27 08:14:31,727 [salt.state       :1813][INFO    ][4735] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-27 08:14:31,728 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4735] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-27 08:14:33,585 [salt.state       :300 ][INFO    ][4735] {'pid': 4773, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-27 08:14:33,586 [salt.state       :1951][INFO    ][4735] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:14:33.586607 duration_in_ms=1859.968
2019-02-27 08:14:33,589 [salt.state       :1780][INFO    ][4735] Running state [maas.process_machines] at time 08:14:33.589810
2019-02-27 08:14:33,590 [salt.state       :1813][INFO    ][4735] Executing state module.run for [maas.process_machines]
2019-02-27 08:14:33,592 [salt.utils.decorators:613 ][WARNING ][4735] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-27 08:14:33,648 [salt.loaded.ext.module.maas:412 ][WARNING ][4735] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-27 08:14:33,649 [salt.loaded.ext.module.maas:92  ][INFO    ][4735] 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-02-27 08:14:35,085 [salt.loaded.ext.module.maas:412 ][WARNING ][4735] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-27 08:14:35,085 [salt.loaded.ext.module.maas:92  ][INFO    ][4735] 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-02-27 08:14:36,381 [salt.loaded.ext.module.maas:412 ][WARNING ][4735] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-27 08:14:36,381 [salt.loaded.ext.module.maas:92  ][INFO    ][4735] 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-02-27 08:14:37,560 [salt.loaded.ext.module.maas:412 ][WARNING ][4735] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-27 08:14:37,561 [salt.loaded.ext.module.maas:92  ][INFO    ][4735] 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-02-27 08:14:38,713 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227081438702046
2019-02-27 08:14:38,734 [salt.minion      :1432][INFO    ][4987] Starting a new job with PID 4987
2019-02-27 08:14:38,746 [salt.loaded.ext.module.maas:412 ][WARNING ][4735] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-27 08:14:38,747 [salt.loaded.ext.module.maas:92  ][INFO    ][4735] 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-02-27 08:14:38,771 [salt.minion      :1711][INFO    ][4987] Returning information for job: 20190227081438702046
2019-02-27 08:14:39,826 [salt.state       :300 ][INFO    ][4735] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-02-27 08:14:39,826 [salt.state       :1951][INFO    ][4735] Completed state [maas.process_machines] at time 08:14:39.826693 duration_in_ms=6236.883
2019-02-27 08:14:39,829 [salt.minion      :1711][INFO    ][4735] Returning information for job: 20190227081423613432
2019-02-27 08:15:11,164 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command state.apply with jid 20190227081511153042
2019-02-27 08:15:11,182 [salt.minion      :1432][INFO    ][5122] Starting a new job with PID 5122
2019-02-27 08:15:18,977 [salt.state       :915 ][INFO    ][5122] Loading fresh modules for state activity
2019-02-27 08:15:19,034 [salt.fileclient  :1219][INFO    ][5122] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-02-27 08:15:19,081 [salt.state       :1780][INFO    ][5122] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:15:19.081878
2019-02-27 08:15:19,082 [salt.state       :1813][INFO    ][5122] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-27 08:15:19,084 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5122] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-27 08:15:20,905 [salt.state       :300 ][INFO    ][5122] {'pid': 5133, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-27 08:15:20,908 [salt.state       :1951][INFO    ][5122] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:15:20.906763 duration_in_ms=1824.884
2019-02-27 08:15:20,911 [salt.state       :1780][INFO    ][5122] Running state [maas.wait_for_machine_status] at time 08:15:20.911216
2019-02-27 08:15:20,911 [salt.state       :1813][INFO    ][5122] Executing state module.run for [maas.wait_for_machine_status]
2019-02-27 08:15:20,912 [salt.utils.decorators:613 ][WARNING ][5122] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-27 08:15:21,712 [salt.loaded.ext.module.maas:1023][INFO    ][5122] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.21486688s left)
2019-02-27 08:15:26,285 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227081526269817
2019-02-27 08:15:26,304 [salt.minion      :1432][INFO    ][5144] Starting a new job with PID 5144
2019-02-27 08:15:26,332 [salt.minion      :1711][INFO    ][5144] Returning information for job: 20190227081526269817
2019-02-27 08:15:52,526 [salt.loaded.ext.module.maas:1023][INFO    ][5122] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.40107799s left)
2019-02-27 08:15:56,345 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227081556336870
2019-02-27 08:15:56,361 [salt.minion      :1432][INFO    ][5222] Starting a new job with PID 5222
2019-02-27 08:15:56,389 [salt.minion      :1711][INFO    ][5222] Returning information for job: 20190227081556336870
2019-02-27 08:16:23,339 [salt.loaded.ext.module.maas:1023][INFO    ][5122] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.587749s left)
2019-02-27 08:16:26,425 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227081626410945
2019-02-27 08:16:26,445 [salt.minion      :1432][INFO    ][5242] Starting a new job with PID 5242
2019-02-27 08:16:26,472 [salt.minion      :1711][INFO    ][5242] Returning information for job: 20190227081626410945
2019-02-27 08:16:54,011 [salt.loaded.ext.module.maas:1023][INFO    ][5122] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.91563988s left)
2019-02-27 08:16:56,490 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227081656480082
2019-02-27 08:16:56,513 [salt.minion      :1432][INFO    ][5325] Starting a new job with PID 5325
2019-02-27 08:16:56,540 [salt.minion      :1711][INFO    ][5325] Returning information for job: 20190227081656480082
2019-02-27 08:17:25,065 [salt.loaded.ext.module.maas:1023][INFO    ][5122] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.86209989s left)
2019-02-27 08:17:26,558 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227081726540297
2019-02-27 08:17:26,578 [salt.minion      :1432][INFO    ][5374] Starting a new job with PID 5374
2019-02-27 08:17:26,608 [salt.minion      :1711][INFO    ][5374] Returning information for job: 20190227081726540297
2019-02-27 08:17:55,955 [salt.loaded.ext.module.maas:1023][INFO    ][5122] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.97178793s left)
2019-02-27 08:17:56,641 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227081756630234
2019-02-27 08:17:56,652 [salt.minion      :1432][INFO    ][5586] Starting a new job with PID 5586
2019-02-27 08:17:56,678 [salt.minion      :1711][INFO    ][5586] Returning information for job: 20190227081756630234
2019-02-27 08:18:26,840 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227081826828769
2019-02-27 08:18:26,862 [salt.minion      :1432][INFO    ][5658] Starting a new job with PID 5658
2019-02-27 08:18:26,890 [salt.minion      :1711][INFO    ][5658] Returning information for job: 20190227081826828769
2019-02-27 08:18:27,118 [salt.loaded.ext.module.maas:1023][INFO    ][5122] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1313.8089149s left)
2019-02-27 08:18:56,964 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227081856953313
2019-02-27 08:18:56,976 [salt.minion      :1432][INFO    ][5983] Starting a new job with PID 5983
2019-02-27 08:18:57,002 [salt.minion      :1711][INFO    ][5983] Returning information for job: 20190227081856953313
2019-02-27 08:18:58,317 [salt.loaded.ext.module.maas:1023][INFO    ][5122] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1282.60922289s left)
2019-02-27 08:19:27,030 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227081927017597
2019-02-27 08:19:27,048 [salt.minion      :1432][INFO    ][6104] Starting a new job with PID 6104
2019-02-27 08:19:27,077 [salt.minion      :1711][INFO    ][6104] Returning information for job: 20190227081927017597
2019-02-27 08:19:29,997 [salt.loaded.ext.module.maas:1023][INFO    ][5122] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1250.92948103s left)
2019-02-27 08:19:57,185 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227081957172940
2019-02-27 08:19:57,202 [salt.minion      :1432][INFO    ][6497] Starting a new job with PID 6497
2019-02-27 08:19:57,230 [salt.minion      :1711][INFO    ][6497] Returning information for job: 20190227081957172940
2019-02-27 08:20:02,199 [salt.loaded.ext.module.maas:1023][INFO    ][5122] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1218.72732186s left)
2019-02-27 08:20:27,262 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227082027252111
2019-02-27 08:20:27,282 [salt.minion      :1432][INFO    ][6571] Starting a new job with PID 6571
2019-02-27 08:20:27,309 [salt.minion      :1711][INFO    ][6571] Returning information for job: 20190227082027252111
2019-02-27 08:20:34,391 [salt.loaded.ext.module.maas:1023][INFO    ][5122] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1186.5357759s left)
2019-02-27 08:20:57,410 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227082057401718
2019-02-27 08:20:57,432 [salt.minion      :1432][INFO    ][6856] Starting a new job with PID 6856
2019-02-27 08:20:57,460 [salt.minion      :1711][INFO    ][6856] Returning information for job: 20190227082057401718
2019-02-27 08:21:07,075 [salt.loaded.ext.module.maas:1023][INFO    ][5122] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1153.85170698s left)
2019-02-27 08:21:27,521 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227082127509212
2019-02-27 08:21:27,537 [salt.minion      :1432][INFO    ][6988] Starting a new job with PID 6988
2019-02-27 08:21:27,565 [salt.minion      :1711][INFO    ][6988] Returning information for job: 20190227082127509212
2019-02-27 08:21:40,115 [salt.state       :300 ][INFO    ][5122] {'ret': True}
2019-02-27 08:21:40,116 [salt.state       :1951][INFO    ][5122] Completed state [maas.wait_for_machine_status] at time 08:21:40.116178 duration_in_ms=379204.961
2019-02-27 08:21:40,121 [salt.minion      :1711][INFO    ][5122] Returning information for job: 20190227081511153042
2019-02-27 08:21:40,837 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command state.apply with jid 20190227082140829881
2019-02-27 08:21:40,857 [salt.minion      :1432][INFO    ][7042] Starting a new job with PID 7042
2019-02-27 08:21:48,928 [salt.state       :915 ][INFO    ][7042] Loading fresh modules for state activity
2019-02-27 08:21:48,991 [salt.fileclient  :1219][INFO    ][7042] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-02-27 08:21:49,102 [salt.state       :1780][INFO    ][7042] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:21:49.102824
2019-02-27 08:21:49,103 [salt.state       :1813][INFO    ][7042] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-27 08:21:49,104 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7042] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-27 08:21:51,081 [salt.state       :300 ][INFO    ][7042] {'pid': 7144, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-27 08:21:51,082 [salt.state       :1951][INFO    ][7042] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:21:51.082716 duration_in_ms=1979.891
2019-02-27 08:21:51,086 [salt.state       :1780][INFO    ][7042] Running state [maas_machines_storage_cmp002_lvm] at time 08:21:51.086081
2019-02-27 08:21:51,086 [salt.state       :1813][INFO    ][7042] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-02-27 08:21:52,218 [salt.loaded.ext.module.maasng:610 ][INFO    ][7042] cmdkre
2019-02-27 08:21:52,218 [salt.loaded.ext.module.maasng:626 ][INFO    ][7042] sda
2019-02-27 08:21:52,777 [salt.loaded.ext.module.maasng:361 ][INFO    ][7042] cmdkre
2019-02-27 08:21:52,867 [salt.loaded.ext.module.maasng:367 ][INFO    ][7042] [{u'size': 800109715456, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'07ea8d17-4e81-4250-8c80-463799d6a9b2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'cmdkre', u'filesystem': {u'uuid': u'ae33714c-538a-4030-8b0b-a0666386f615', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/cmdkre/blockdevices/1/partition/1', u'type': u'partition', u'id': 1, u'device_id': 1}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'cmdkre', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/cmdkre/blockdevices/1/'}, {u'size': 800097042432, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'partitions': [], u'uuid': u'753ce074-62ed-4a1e-82bc-eb9a7dda1b00', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'cmdkre', u'partition_table_type': None, u'filesystem': {u'uuid': u'0e4b1abd-f1fc-48c6-bb9e-d30e375633a5', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 3, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/cmdkre/blockdevices/3/'}]
2019-02-27 08:21:52,868 [salt.loaded.ext.module.maasng:632 ][INFO    ][7042] vgroot
2019-02-27 08:21:52,868 [salt.loaded.ext.module.maasng:635 ][INFO    ][7042] lvroot
2019-02-27 08:21:52,868 [salt.loaded.ext.module.maasng:639 ][INFO    ][7042] 107374182400
2019-02-27 08:21:53,457 [salt.loaded.ext.module.maasng:645 ][INFO    ][7042] {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'disable_ipv4': False, 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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qmkgyp', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qmkgyp', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 18}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qmkgyp', 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'enabled': True, u'children': [], u'effective_mtu': 1500, u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'system_id': u'cmdkre', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/cmdkre/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'fqdn': u'cmp002.maas', u'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'id': 1}, u'resource_uri': u'/MAAS/api/2.0/machines/cmdkre/', u'current_commissioning_result_id': 2, u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'cmdkre', 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'block_size': 4096, u'uuid': u'f425073f-8db3-46c4-864c-01a62e79d36d', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'partitions': [], u'id': 11, u'name': u'vgroot-lvroot', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'cmdkre', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'a2f7022d-b82e-420f-a330-404ab2ce440b', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/cmdkre/blockdevices/11/', u'used_size': 107374182400, u'serial': None, u'size': 107374182400}], u'blockdevice_set': [{u'block_size': 4096, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'uuid': u'79b0029c-19e5-45f4-a806-fb09fed2514a', u'resource_uri': u'/MAAS/api/2.0/nodes/cmdkre/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'cmdkre', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'33cd3d32-e322-4c66-9d21-faf27efc8edb', 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'id': 1, u'name': u'sda', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'cmdkre', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/cmdkre/blockdevices/1/', u'used_size': 800106479616, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}, {u'block_size': 4096, u'uuid': u'f425073f-8db3-46c4-864c-01a62e79d36d', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'partitions': [], u'id': 11, u'name': u'vgroot-lvroot', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'cmdkre', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'a2f7022d-b82e-420f-a330-404ab2ce440b', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/cmdkre/blockdevices/11/', u'used_size': 107374182400, u'serial': None, u'size': 107374182400}], u'status': 4, u'bcaches': [], u'cpu_count': 40, u'raids': [], u'owner_data': {}, u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'cmdkre', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'node_type_name': u'Machine', u'current_testing_result_id': 3, u'cpu_test_status': -1, u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'block_size': 4096, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'uuid': u'79b0029c-19e5-45f4-a806-fb09fed2514a', u'resource_uri': u'/MAAS/api/2.0/nodes/cmdkre/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'cmdkre', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'33cd3d32-e322-4c66-9d21-faf27efc8edb', 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'id': 1, u'name': u'sda', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'cmdkre', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/cmdkre/blockdevices/1/', u'used_size': 800106479616, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}], u'netboot': True, u'osystem': u'', u'node_type': 0, u'memory_test_status_name': u'Unknown', u'commissioning_status': 2, u'architecture': u'amd64/generic', 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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qmkgyp', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qmkgyp', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 18}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qmkgyp', 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'enabled': True, u'children': [], u'effective_mtu': 1500, u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'system_id': u'cmdkre', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/cmdkre/interfaces/4/'}, {u'discovered': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'params': u'', u'mac_address': u'38:ea:a7:8f:07:50', u'parents': [], u'system_id': u'cmdkre', u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/cmdkre/interfaces/14/'}, {u'discovered': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'params': u'', u'mac_address': u'38:ea:a7:8f:07:51', u'parents': [], u'system_id': u'cmdkre', u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/cmdkre/interfaces/15/'}, {u'discovered': None, u'name': u'ens2f0', u'links': [{u'id': 19, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'params': u'', u'mac_address': u'38:ea:a7:8f:12:48', u'parents': [], u'system_id': u'cmdkre', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/cmdkre/interfaces/13/'}, {u'discovered': None, u'name': u'ens2f1', u'links': [{u'id': 20, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'params': u'', u'mac_address': u'38:ea:a7:8f:12:49', u'parents': [], u'system_id': u'cmdkre', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/cmdkre/interfaces/11/'}, {u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qmkgyp', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.40'}], u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qmkgyp', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'mode': u'link_up', u'id': 21}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qmkgyp', 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'enabled': True, u'children': [], u'effective_mtu': 1500, u'params': u'', u'mac_address': u'9c:b6:54:8a:10:1c', u'parents': [], u'system_id': u'cmdkre', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/cmdkre/interfaces/12/'}], u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'boot_disk': {u'block_size': 4096, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'uuid': u'79b0029c-19e5-45f4-a806-fb09fed2514a', u'resource_uri': u'/MAAS/api/2.0/nodes/cmdkre/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'cmdkre', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'33cd3d32-e322-4c66-9d21-faf27efc8edb', 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'id': 1, u'name': u'sda', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'cmdkre', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/cmdkre/blockdevices/1/', u'used_size': 800106479616, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}}
2019-02-27 08:21:53,462 [salt.state       :300 ][INFO    ][7042] {'new': {'storage_layout': 'lvm'}}
2019-02-27 08:21:53,465 [salt.state       :1951][INFO    ][7042] Completed state [maas_machines_storage_cmp002_lvm] at time 08:21:53.464284 duration_in_ms=2377.932
2019-02-27 08:21:53,466 [salt.state       :1780][INFO    ][7042] Running state [maas_machines_storage_cmp001_lvm] at time 08:21:53.465975
2019-02-27 08:21:53,466 [salt.state       :1813][INFO    ][7042] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-02-27 08:21:54,593 [salt.loaded.ext.module.maasng:610 ][INFO    ][7042] 7spane
2019-02-27 08:21:54,593 [salt.loaded.ext.module.maasng:626 ][INFO    ][7042] sda
2019-02-27 08:21:55,134 [salt.loaded.ext.module.maasng:361 ][INFO    ][7042] 7spane
2019-02-27 08:21:55,225 [salt.loaded.ext.module.maasng:367 ][INFO    ][7042] [{u'block_size': 4096, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'uuid': u'31a8b57f-195a-4d6f-bfb9-5df9ae4fa926', u'resource_uri': u'/MAAS/api/2.0/nodes/7spane/blockdevices/2/partition/2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'7spane', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'bd12f84f-770e-4b43-9d92-ffa7f2dff96e', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 2, u'size': 800101236736}], u'id': 2, u'name': u'sda', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'7spane', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/7spane/blockdevices/2/', u'used_size': 800106479616, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, {u'block_size': 4096, u'uuid': u'88fa7822-f490-4036-acbc-56859cf585ac', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'partitions': [], u'id': 4, u'name': u'vgroot-lvroot', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'7spane', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'22a1688b-ef46-4bd7-814a-023eb1f61955', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/7spane/blockdevices/4/', u'used_size': 800097042432, u'serial': None, u'size': 800097042432}]
2019-02-27 08:21:55,226 [salt.loaded.ext.module.maasng:632 ][INFO    ][7042] vgroot
2019-02-27 08:21:55,226 [salt.loaded.ext.module.maasng:635 ][INFO    ][7042] lvroot
2019-02-27 08:21:55,226 [salt.loaded.ext.module.maasng:639 ][INFO    ][7042] 107374182400
2019-02-27 08:21:55,822 [salt.loaded.ext.module.maasng:645 ][INFO    ][7042] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'swap_size': None, u'boot_interface': {u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qmkgyp', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qmkgyp', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'id': 24, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qmkgyp', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', 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'system_id': u'7spane', 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/7spane/interfaces/5/'}, 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'memory_test_status_name': u'Unknown', u'fqdn': u'cmp001.maas', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 800109715456, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/7spane/blockdevices/2/', u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'95e44d45-df04-439e-a7ca-527aaca64681', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'7spane', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'31471c54-12ad-4d1b-aa1b-1ecfc1b3c9de', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/7spane/blockdevices/2/partition/7'}], u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'7spane', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'uuid': None}, u'zone': {u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default', u'description': u''}, u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'7spane', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'owner_data': {}, u'blockdevice_set': [{u'uuid': None, u'resource_uri': u'/MAAS/api/2.0/nodes/7spane/blockdevices/2/', u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'95e44d45-df04-439e-a7ca-527aaca64681', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'7spane', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'31471c54-12ad-4d1b-aa1b-1ecfc1b3c9de', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/7spane/blockdevices/2/partition/7'}], u'tags': [u'ssd'], u'name': u'sda', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'7spane', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, {u'uuid': u'f5fd23f8-463d-4232-b0c5-8713557bc59b', u'resource_uri': u'/MAAS/api/2.0/nodes/7spane/blockdevices/12/', u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'partitions': [], u'tags': [], u'name': u'vgroot-lvroot', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'7spane', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'5930b594-6d8b-4362-a29c-8262986eba90', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 12, u'serial': None, u'size': 107374182400}], u'status': 4, u'storage_test_status': 2, u'cpu_count': 40, u'power_state': u'off', u'physicalblockdevice_set': [{u'size': 800109715456, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/7spane/blockdevices/2/', u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'95e44d45-df04-439e-a7ca-527aaca64681', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'7spane', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'31471c54-12ad-4d1b-aa1b-1ecfc1b3c9de', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/7spane/blockdevices/2/partition/7'}], u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'7spane', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'uuid': None}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'7spane', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'node_type_name': u'Machine', u'current_testing_result_id': 5, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'node_type': 0, u'disable_ipv4': False, u'virtualblockdevice_set': [{u'size': 107374182400, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/7spane/blockdevices/12/', u'used_size': 107374182400, u'partitions': [], u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'7spane', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'5930b594-6d8b-4362-a29c-8262986eba90', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 12, u'serial': None, u'uuid': u'f5fd23f8-463d-4232-b0c5-8713557bc59b'}], u'commissioning_status': 2, u'min_hwe_kernel': u'ga-16.04', u'commissioning_status_name': u'Passed', u'interface_set': [{u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qmkgyp', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qmkgyp', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'id': 24, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qmkgyp', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', 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'system_id': u'7spane', 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/7spane/interfaces/5/'}, {u'vlan': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'system_id': u'7spane', 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/7spane/interfaces/16/'}, {u'vlan': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'system_id': u'7spane', 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/7spane/interfaces/19/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f1', u'links': [{u'id': 25, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'system_id': u'7spane', 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/7spane/interfaces/17/'}, {u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qmkgyp', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qmkgyp', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 26, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qmkgyp', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', 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'system_id': u'7spane', u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a4', u'parents': [], u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/7spane/interfaces/18/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f0', u'links': [{u'id': 27, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'system_id': u'7spane', u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cc', u'parents': [], u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/7spane/interfaces/20/'}], u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/7spane/', u'distro_series': u'', u'memory_test_status': -1}
2019-02-27 08:21:55,825 [salt.state       :300 ][INFO    ][7042] {'new': {'storage_layout': 'lvm'}}
2019-02-27 08:21:55,825 [salt.state       :1951][INFO    ][7042] Completed state [maas_machines_storage_cmp001_lvm] at time 08:21:55.825402 duration_in_ms=2359.425
2019-02-27 08:21:55,830 [salt.minion      :1711][INFO    ][7042] Returning information for job: 20190227082140829881
2019-02-27 08:21:56,518 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command state.apply with jid 20190227082156504239
2019-02-27 08:21:56,540 [salt.minion      :1432][INFO    ][7175] Starting a new job with PID 7175
2019-02-27 08:21:57,418 [salt.state       :915 ][INFO    ][7175] Loading fresh modules for state activity
2019-02-27 08:21:57,477 [salt.fileclient  :1219][INFO    ][7175] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-02-27 08:21:57,524 [salt.state       :1780][INFO    ][7175] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:21:57.524537
2019-02-27 08:21:57,525 [salt.state       :1813][INFO    ][7175] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-27 08:21:57,528 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7175] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-27 08:21:59,385 [salt.state       :300 ][INFO    ][7175] {'pid': 7186, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-27 08:21:59,386 [salt.state       :1951][INFO    ][7175] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:21:59.386243 duration_in_ms=1861.708
2019-02-27 08:21:59,389 [salt.state       :1780][INFO    ][7175] Running state [maas.deploy_machines] at time 08:21:59.389536
2019-02-27 08:21:59,390 [salt.state       :1813][INFO    ][7175] Executing state module.run for [maas.deploy_machines]
2019-02-27 08:21:59,392 [salt.utils.decorators:613 ][WARNING ][7175] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-27 08:21:59,962 [salt.loaded.ext.module.maas:684 ][INFO    ][7175] deploymachines hwe_kernel=ga-16.04 system_id=cmdkre distro_series=xenial
2019-02-27 08:22:02,266 [salt.loaded.ext.module.maas:684 ][INFO    ][7175] deploymachines hwe_kernel=ga-16.04 system_id=7spane distro_series=xenial
2019-02-27 08:22:04,568 [salt.loaded.ext.module.maas:684 ][INFO    ][7175] deploymachines hwe_kernel=ga-16.04 system_id=4ms4tr distro_series=xenial
2019-02-27 08:22:06,859 [salt.loaded.ext.module.maas:684 ][INFO    ][7175] deploymachines hwe_kernel=ga-16.04 system_id=en4nmm distro_series=xenial
2019-02-27 08:22:09,330 [salt.loaded.ext.module.maas:684 ][INFO    ][7175] deploymachines hwe_kernel=ga-16.04 system_id=hd8grr distro_series=xenial
2019-02-27 08:22:11,588 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227082211574695
2019-02-27 08:22:11,606 [salt.minion      :1432][INFO    ][7454] Starting a new job with PID 7454
2019-02-27 08:22:11,640 [salt.minion      :1711][INFO    ][7454] Returning information for job: 20190227082211574695
2019-02-27 08:22:11,651 [salt.state       :300 ][INFO    ][7175] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-02-27 08:22:11,652 [salt.state       :1951][INFO    ][7175] Completed state [maas.deploy_machines] at time 08:22:11.652250 duration_in_ms=12262.714
2019-02-27 08:22:11,654 [salt.minion      :1711][INFO    ][7175] Returning information for job: 20190227082156504239
2019-02-27 08:22:12,356 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command state.apply with jid 20190227082212344720
2019-02-27 08:22:12,374 [salt.minion      :1432][INFO    ][7466] Starting a new job with PID 7466
2019-02-27 08:22:20,215 [salt.state       :915 ][INFO    ][7466] Loading fresh modules for state activity
2019-02-27 08:22:20,268 [salt.fileclient  :1219][INFO    ][7466] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-02-27 08:22:20,312 [salt.state       :1780][INFO    ][7466] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:22:20.312825
2019-02-27 08:22:20,313 [salt.state       :1813][INFO    ][7466] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-27 08:22:20,315 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7466] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-27 08:22:22,162 [salt.state       :300 ][INFO    ][7466] {'pid': 7482, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-27 08:22:22,163 [salt.state       :1951][INFO    ][7466] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:22:22.163500 duration_in_ms=1850.676
2019-02-27 08:22:22,165 [salt.state       :1780][INFO    ][7466] Running state [maas.wait_for_machine_status] at time 08:22:22.165486
2019-02-27 08:22:22,165 [salt.state       :1813][INFO    ][7466] Executing state module.run for [maas.wait_for_machine_status]
2019-02-27 08:22:22,166 [salt.utils.decorators:613 ][WARNING ][7466] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-27 08:22:24,857 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.32714891s left)
2019-02-27 08:22:27,409 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227082227395602
2019-02-27 08:22:27,432 [salt.minion      :1432][INFO    ][7506] Starting a new job with PID 7506
2019-02-27 08:22:27,467 [salt.minion      :1711][INFO    ][7506] Returning information for job: 20190227082227395602
2019-02-27 08:22:57,494 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227082257485313
2019-02-27 08:22:57,510 [salt.minion      :1432][INFO    ][7588] Starting a new job with PID 7588
2019-02-27 08:22:57,538 [salt.minion      :1711][INFO    ][7588] Returning information for job: 20190227082257485313
2019-02-27 08:22:57,695 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.48636603s left)
2019-02-27 08:23:27,563 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227082327557055
2019-02-27 08:23:27,576 [salt.minion      :1432][INFO    ][7616] Starting a new job with PID 7616
2019-02-27 08:23:27,602 [salt.minion      :1711][INFO    ][7616] Returning information for job: 20190227082327557055
2019-02-27 08:23:30,312 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.86983395s left)
2019-02-27 08:23:57,620 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227082357611756
2019-02-27 08:23:57,642 [salt.minion      :1432][INFO    ][7706] Starting a new job with PID 7706
2019-02-27 08:23:57,667 [salt.minion      :1711][INFO    ][7706] Returning information for job: 20190227082357611756
2019-02-27 08:24:03,210 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2148.97203398s left)
2019-02-27 08:24:27,680 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227082427666662
2019-02-27 08:24:27,701 [salt.minion      :1432][INFO    ][7735] Starting a new job with PID 7735
2019-02-27 08:24:27,726 [salt.minion      :1711][INFO    ][7735] Returning information for job: 20190227082427666662
2019-02-27 08:24:36,069 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2116.11273098s left)
2019-02-27 08:24:57,881 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227082457871568
2019-02-27 08:24:57,898 [salt.minion      :1432][INFO    ][7839] Starting a new job with PID 7839
2019-02-27 08:24:57,929 [salt.minion      :1711][INFO    ][7839] Returning information for job: 20190227082457871568
2019-02-27 08:25:08,863 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2083.31849003s left)
2019-02-27 08:25:28,090 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227082528078431
2019-02-27 08:25:28,112 [salt.minion      :1432][INFO    ][7933] Starting a new job with PID 7933
2019-02-27 08:25:28,140 [salt.minion      :1711][INFO    ][7933] Returning information for job: 20190227082528078431
2019-02-27 08:25:41,533 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2050.64827704s left)
2019-02-27 08:25:58,184 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227082558174969
2019-02-27 08:25:58,206 [salt.minion      :1432][INFO    ][8241] Starting a new job with PID 8241
2019-02-27 08:25:58,233 [salt.minion      :1711][INFO    ][8241] Returning information for job: 20190227082558174969
2019-02-27 08:26:14,386 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2017.79532695s left)
2019-02-27 08:26:28,244 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227082628231884
2019-02-27 08:26:28,257 [salt.minion      :1432][INFO    ][8309] Starting a new job with PID 8309
2019-02-27 08:26:28,286 [salt.minion      :1711][INFO    ][8309] Returning information for job: 20190227082628231884
2019-02-27 08:26:47,459 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1984.7224679s left)
2019-02-27 08:26:58,327 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227082658314343
2019-02-27 08:26:58,351 [salt.minion      :1432][INFO    ][8549] Starting a new job with PID 8549
2019-02-27 08:26:58,379 [salt.minion      :1711][INFO    ][8549] Returning information for job: 20190227082658314343
2019-02-27 08:27:20,648 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1951.53336s left)
2019-02-27 08:27:28,446 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227082728433061
2019-02-27 08:27:28,471 [salt.minion      :1432][INFO    ][8606] Starting a new job with PID 8606
2019-02-27 08:27:28,501 [salt.minion      :1711][INFO    ][8606] Returning information for job: 20190227082728433061
2019-02-27 08:27:53,362 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1918.81939006s left)
2019-02-27 08:27:58,634 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227082758624697
2019-02-27 08:27:58,656 [salt.minion      :1432][INFO    ][9148] Starting a new job with PID 9148
2019-02-27 08:27:58,685 [salt.minion      :1711][INFO    ][9148] Returning information for job: 20190227082758624697
2019-02-27 08:28:26,291 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1885.890558s left)
2019-02-27 08:28:28,760 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227082828745431
2019-02-27 08:28:28,783 [salt.minion      :1432][INFO    ][9191] Starting a new job with PID 9191
2019-02-27 08:28:28,809 [salt.minion      :1711][INFO    ][9191] Returning information for job: 20190227082828745431
2019-02-27 08:28:58,807 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1853.37450385s left)
2019-02-27 08:28:58,889 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227082858877555
2019-02-27 08:28:58,903 [salt.minion      :1432][INFO    ][9462] Starting a new job with PID 9462
2019-02-27 08:28:58,930 [salt.minion      :1711][INFO    ][9462] Returning information for job: 20190227082858877555
2019-02-27 08:29:29,012 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227082929001087
2019-02-27 08:29:29,026 [salt.minion      :1432][INFO    ][9492] Starting a new job with PID 9492
2019-02-27 08:29:29,053 [salt.minion      :1711][INFO    ][9492] Returning information for job: 20190227082929001087
2019-02-27 08:29:31,795 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1820.38699293s left)
2019-02-27 08:29:59,150 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227082959142247
2019-02-27 08:29:59,167 [salt.minion      :1432][INFO    ][9714] Starting a new job with PID 9714
2019-02-27 08:29:59,193 [salt.minion      :1711][INFO    ][9714] Returning information for job: 20190227082959142247
2019-02-27 08:30:04,223 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1787.95851684s left)
2019-02-27 08:30:29,273 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227083029256127
2019-02-27 08:30:29,293 [salt.minion      :1432][INFO    ][9757] Starting a new job with PID 9757
2019-02-27 08:30:29,325 [salt.minion      :1711][INFO    ][9757] Returning information for job: 20190227083029256127
2019-02-27 08:30:37,005 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1755.17683005s left)
2019-02-27 08:30:59,437 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227083059423914
2019-02-27 08:30:59,457 [salt.minion      :1432][INFO    ][9915] Starting a new job with PID 9915
2019-02-27 08:30:59,491 [salt.minion      :1711][INFO    ][9915] Returning information for job: 20190227083059423914
2019-02-27 08:31:09,490 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1722.691293s left)
2019-02-27 08:31:29,574 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227083129560239
2019-02-27 08:31:29,597 [salt.minion      :1432][INFO    ][9965] Starting a new job with PID 9965
2019-02-27 08:31:29,626 [salt.minion      :1711][INFO    ][9965] Returning information for job: 20190227083129560239
2019-02-27 08:31:42,317 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1689.86456299s left)
2019-02-27 08:31:59,598 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227083159587747
2019-02-27 08:31:59,617 [salt.minion      :1432][INFO    ][10165] Starting a new job with PID 10165
2019-02-27 08:31:59,649 [salt.minion      :1711][INFO    ][10165] Returning information for job: 20190227083159587747
2019-02-27 08:32:14,735 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1657.44614387s left)
2019-02-27 08:32:29,753 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227083229741905
2019-02-27 08:32:29,774 [salt.minion      :1432][INFO    ][10200] Starting a new job with PID 10200
2019-02-27 08:32:29,802 [salt.minion      :1711][INFO    ][10200] Returning information for job: 20190227083229741905
2019-02-27 08:32:47,746 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1624.43529701s left)
2019-02-27 08:32:59,950 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227083259935854
2019-02-27 08:32:59,971 [salt.minion      :1432][INFO    ][10442] Starting a new job with PID 10442
2019-02-27 08:33:00,002 [salt.minion      :1711][INFO    ][10442] Returning information for job: 20190227083259935854
2019-02-27 08:33:20,130 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1592.05178499s left)
2019-02-27 08:33:30,121 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227083330107490
2019-02-27 08:33:30,142 [salt.minion      :1432][INFO    ][10602] Starting a new job with PID 10602
2019-02-27 08:33:30,172 [salt.minion      :1711][INFO    ][10602] Returning information for job: 20190227083330107490
2019-02-27 08:33:53,072 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1559.10952091s left)
2019-02-27 08:34:00,311 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227083400297459
2019-02-27 08:34:00,333 [salt.minion      :1432][INFO    ][10711] Starting a new job with PID 10711
2019-02-27 08:34:00,366 [salt.minion      :1711][INFO    ][10711] Returning information for job: 20190227083400297459
2019-02-27 08:34:25,700 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1526.48108888s left)
2019-02-27 08:34:30,434 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227083430421619
2019-02-27 08:34:30,455 [salt.minion      :1432][INFO    ][10807] Starting a new job with PID 10807
2019-02-27 08:34:30,486 [salt.minion      :1711][INFO    ][10807] Returning information for job: 20190227083430421619
2019-02-27 08:34:58,644 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1493.53724098s left)
2019-02-27 08:35:00,457 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227083500443755
2019-02-27 08:35:00,478 [salt.minion      :1432][INFO    ][11130] Starting a new job with PID 11130
2019-02-27 08:35:00,509 [salt.minion      :1711][INFO    ][11130] Returning information for job: 20190227083500443755
2019-02-27 08:35:30,680 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227083530672509
2019-02-27 08:35:30,695 [salt.minion      :1432][INFO    ][11158] Starting a new job with PID 11158
2019-02-27 08:35:30,727 [salt.minion      :1711][INFO    ][11158] Returning information for job: 20190227083530672509
2019-02-27 08:35:31,042 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1461.13923287s left)
2019-02-27 08:36:00,895 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227083600886659
2019-02-27 08:36:00,912 [salt.minion      :1432][INFO    ][11226] Starting a new job with PID 11226
2019-02-27 08:36:00,946 [salt.minion      :1711][INFO    ][11226] Returning information for job: 20190227083600886659
2019-02-27 08:36:04,046 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1428.13605499s left)
2019-02-27 08:36:31,039 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227083631025424
2019-02-27 08:36:31,062 [salt.minion      :1432][INFO    ][11257] Starting a new job with PID 11257
2019-02-27 08:36:31,092 [salt.minion      :1711][INFO    ][11257] Returning information for job: 20190227083631025424
2019-02-27 08:36:36,468 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1395.713907s left)
2019-02-27 08:37:01,068 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227083701061607
2019-02-27 08:37:01,080 [salt.minion      :1432][INFO    ][11329] Starting a new job with PID 11329
2019-02-27 08:37:01,110 [salt.minion      :1711][INFO    ][11329] Returning information for job: 20190227083701061607
2019-02-27 08:37:09,482 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1362.69974589s left)
2019-02-27 08:37:31,098 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227083731087097
2019-02-27 08:37:31,113 [salt.minion      :1432][INFO    ][11365] Starting a new job with PID 11365
2019-02-27 08:37:31,142 [salt.minion      :1711][INFO    ][11365] Returning information for job: 20190227083731087097
2019-02-27 08:37:41,819 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1330.36229396s left)
2019-02-27 08:38:01,140 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227083801126929
2019-02-27 08:38:01,161 [salt.minion      :1432][INFO    ][11440] Starting a new job with PID 11440
2019-02-27 08:38:01,193 [salt.minion      :1711][INFO    ][11440] Returning information for job: 20190227083801126929
2019-02-27 08:38:14,514 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1297.66767597s left)
2019-02-27 08:38:31,218 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227083831206554
2019-02-27 08:38:31,241 [salt.minion      :1432][INFO    ][11471] Starting a new job with PID 11471
2019-02-27 08:38:31,272 [salt.minion      :1711][INFO    ][11471] Returning information for job: 20190227083831206554
2019-02-27 08:38:46,952 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1265.22921085s left)
2019-02-27 08:39:01,277 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227083901265816
2019-02-27 08:39:01,295 [salt.minion      :1432][INFO    ][11547] Starting a new job with PID 11547
2019-02-27 08:39:01,325 [salt.minion      :1711][INFO    ][11547] Returning information for job: 20190227083901265816
2019-02-27 08:39:19,760 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1232.42185903s left)
2019-02-27 08:39:31,384 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227083931371284
2019-02-27 08:39:31,402 [salt.minion      :1432][INFO    ][11580] Starting a new job with PID 11580
2019-02-27 08:39:31,434 [salt.minion      :1711][INFO    ][11580] Returning information for job: 20190227083931371284
2019-02-27 08:39:52,201 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1199.98021293s left)
2019-02-27 08:40:01,497 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227084001480071
2019-02-27 08:40:01,519 [salt.minion      :1432][INFO    ][11654] Starting a new job with PID 11654
2019-02-27 08:40:01,548 [salt.minion      :1711][INFO    ][11654] Returning information for job: 20190227084001480071
2019-02-27 08:40:25,046 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1167.13549805s left)
2019-02-27 08:40:31,620 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227084031604140
2019-02-27 08:40:31,641 [salt.minion      :1432][INFO    ][11683] Starting a new job with PID 11683
2019-02-27 08:40:31,670 [salt.minion      :1711][INFO    ][11683] Returning information for job: 20190227084031604140
2019-02-27 08:40:57,487 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1134.69416189s left)
2019-02-27 08:41:01,736 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227084101723055
2019-02-27 08:41:01,753 [salt.minion      :1432][INFO    ][11759] Starting a new job with PID 11759
2019-02-27 08:41:01,784 [salt.minion      :1711][INFO    ][11759] Returning information for job: 20190227084101723055
2019-02-27 08:41:30,253 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1101.92818093s left)
2019-02-27 08:41:31,856 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227084131846306
2019-02-27 08:41:31,877 [salt.minion      :1432][INFO    ][11788] Starting a new job with PID 11788
2019-02-27 08:41:31,906 [salt.minion      :1711][INFO    ][11788] Returning information for job: 20190227084131846306
2019-02-27 08:42:02,011 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227084202002701
2019-02-27 08:42:02,025 [salt.minion      :1432][INFO    ][11861] Starting a new job with PID 11861
2019-02-27 08:42:02,051 [salt.minion      :1711][INFO    ][11861] Returning information for job: 20190227084202002701
2019-02-27 08:42:02,638 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1069.543854s left)
2019-02-27 08:42:32,177 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227084232160672
2019-02-27 08:42:32,195 [salt.minion      :1432][INFO    ][11886] Starting a new job with PID 11886
2019-02-27 08:42:32,227 [salt.minion      :1711][INFO    ][11886] Returning information for job: 20190227084232160672
2019-02-27 08:42:35,323 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1036.85894704s left)
2019-02-27 08:43:02,364 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227084302356295
2019-02-27 08:43:02,382 [salt.minion      :1432][INFO    ][11961] Starting a new job with PID 11961
2019-02-27 08:43:02,411 [salt.minion      :1711][INFO    ][11961] Returning information for job: 20190227084302356295
2019-02-27 08:43:07,761 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1004.421031s left)
2019-02-27 08:43:32,538 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227084332522634
2019-02-27 08:43:32,557 [salt.minion      :1432][INFO    ][11996] Starting a new job with PID 11996
2019-02-27 08:43:32,586 [salt.minion      :1711][INFO    ][11996] Returning information for job: 20190227084332522634
2019-02-27 08:43:40,522 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (971.659673929s left)
2019-02-27 08:44:02,749 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227084402733483
2019-02-27 08:44:02,772 [salt.minion      :1432][INFO    ][12087] Starting a new job with PID 12087
2019-02-27 08:44:02,800 [salt.minion      :1711][INFO    ][12087] Returning information for job: 20190227084402733483
2019-02-27 08:44:13,246 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (938.935849905s left)
2019-02-27 08:44:32,963 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227084432948519
2019-02-27 08:44:32,985 [salt.minion      :1432][INFO    ][12118] Starting a new job with PID 12118
2019-02-27 08:44:33,014 [salt.minion      :1711][INFO    ][12118] Returning information for job: 20190227084432948519
2019-02-27 08:44:46,110 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (906.072088003s left)
2019-02-27 08:45:03,021 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227084503006515
2019-02-27 08:45:03,043 [salt.minion      :1432][INFO    ][12190] Starting a new job with PID 12190
2019-02-27 08:45:03,070 [salt.minion      :1711][INFO    ][12190] Returning information for job: 20190227084503006515
2019-02-27 08:45:18,668 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (873.513300896s left)
2019-02-27 08:45:33,054 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227084533040496
2019-02-27 08:45:33,074 [salt.minion      :1432][INFO    ][12219] Starting a new job with PID 12219
2019-02-27 08:45:33,105 [salt.minion      :1711][INFO    ][12219] Returning information for job: 20190227084533040496
2019-02-27 08:45:51,285 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (840.896694899s left)
2019-02-27 08:46:03,153 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227084603144724
2019-02-27 08:46:03,176 [salt.minion      :1432][INFO    ][12293] Starting a new job with PID 12293
2019-02-27 08:46:03,205 [salt.minion      :1711][INFO    ][12293] Returning information for job: 20190227084603144724
2019-02-27 08:46:23,845 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (808.336575985s left)
2019-02-27 08:46:33,224 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227084633208280
2019-02-27 08:46:33,246 [salt.minion      :1432][INFO    ][12322] Starting a new job with PID 12322
2019-02-27 08:46:33,275 [salt.minion      :1711][INFO    ][12322] Returning information for job: 20190227084633208280
2019-02-27 08:46:56,741 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (775.441047907s left)
2019-02-27 08:47:03,335 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227084703323658
2019-02-27 08:47:03,358 [salt.minion      :1432][INFO    ][12399] Starting a new job with PID 12399
2019-02-27 08:47:03,388 [salt.minion      :1711][INFO    ][12399] Returning information for job: 20190227084703323658
2019-02-27 08:47:29,357 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (742.824887037s left)
2019-02-27 08:47:33,444 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227084733436413
2019-02-27 08:47:33,458 [salt.minion      :1432][INFO    ][12428] Starting a new job with PID 12428
2019-02-27 08:47:33,488 [salt.minion      :1711][INFO    ][12428] Returning information for job: 20190227084733436413
2019-02-27 08:48:02,002 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (710.179587841s left)
2019-02-27 08:48:03,593 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227084803578518
2019-02-27 08:48:03,609 [salt.minion      :1432][INFO    ][12500] Starting a new job with PID 12500
2019-02-27 08:48:03,643 [salt.minion      :1711][INFO    ][12500] Returning information for job: 20190227084803578518
2019-02-27 08:48:33,782 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227084833775018
2019-02-27 08:48:33,798 [salt.minion      :1432][INFO    ][12531] Starting a new job with PID 12531
2019-02-27 08:48:33,826 [salt.minion      :1711][INFO    ][12531] Returning information for job: 20190227084833775018
2019-02-27 08:48:34,912 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (677.269599915s left)
2019-02-27 08:49:03,893 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227084903881109
2019-02-27 08:49:03,910 [salt.minion      :1432][INFO    ][12601] Starting a new job with PID 12601
2019-02-27 08:49:03,942 [salt.minion      :1711][INFO    ][12601] Returning information for job: 20190227084903881109
2019-02-27 08:49:07,595 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (644.58634901s left)
2019-02-27 08:49:33,914 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227084933903983
2019-02-27 08:49:33,933 [salt.minion      :1432][INFO    ][12630] Starting a new job with PID 12630
2019-02-27 08:49:33,967 [salt.minion      :1711][INFO    ][12630] Returning information for job: 20190227084933903983
2019-02-27 08:49:40,206 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (611.975570917s left)
2019-02-27 08:50:04,104 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227085004091217
2019-02-27 08:50:04,124 [salt.minion      :1432][INFO    ][12704] Starting a new job with PID 12704
2019-02-27 08:50:04,154 [salt.minion      :1711][INFO    ][12704] Returning information for job: 20190227085004091217
2019-02-27 08:50:12,857 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (579.324105024s left)
2019-02-27 08:50:34,154 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227085034140439
2019-02-27 08:50:34,173 [salt.minion      :1432][INFO    ][12735] Starting a new job with PID 12735
2019-02-27 08:50:34,204 [salt.minion      :1711][INFO    ][12735] Returning information for job: 20190227085034140439
2019-02-27 08:50:45,676 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (546.505422831s left)
2019-02-27 08:51:04,373 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227085104362019
2019-02-27 08:51:04,391 [salt.minion      :1432][INFO    ][12808] Starting a new job with PID 12808
2019-02-27 08:51:04,423 [salt.minion      :1711][INFO    ][12808] Returning information for job: 20190227085104362019
2019-02-27 08:51:18,554 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (513.627298832s left)
2019-02-27 08:51:34,445 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227085134431685
2019-02-27 08:51:34,465 [salt.minion      :1432][INFO    ][12837] Starting a new job with PID 12837
2019-02-27 08:51:34,498 [salt.minion      :1711][INFO    ][12837] Returning information for job: 20190227085134431685
2019-02-27 08:51:51,454 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (480.727547884s left)
2019-02-27 08:52:04,506 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227085204492652
2019-02-27 08:52:04,526 [salt.minion      :1432][INFO    ][12911] Starting a new job with PID 12911
2019-02-27 08:52:04,557 [salt.minion      :1711][INFO    ][12911] Returning information for job: 20190227085204492652
2019-02-27 08:52:24,082 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (448.099767923s left)
2019-02-27 08:52:34,637 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227085234627879
2019-02-27 08:52:34,652 [salt.minion      :1432][INFO    ][12940] Starting a new job with PID 12940
2019-02-27 08:52:34,689 [salt.minion      :1711][INFO    ][12940] Returning information for job: 20190227085234627879
2019-02-27 08:52:56,778 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (415.403411865s left)
2019-02-27 08:53:04,712 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227085304701817
2019-02-27 08:53:04,730 [salt.minion      :1432][INFO    ][13017] Starting a new job with PID 13017
2019-02-27 08:53:04,761 [salt.minion      :1711][INFO    ][13017] Returning information for job: 20190227085304701817
2019-02-27 08:53:29,543 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (382.638386965s left)
2019-02-27 08:53:34,908 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227085334892806
2019-02-27 08:53:34,931 [salt.minion      :1432][INFO    ][13182] Starting a new job with PID 13182
2019-02-27 08:53:34,967 [salt.minion      :1711][INFO    ][13182] Returning information for job: 20190227085334892806
2019-02-27 08:54:02,189 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (349.992995977s left)
2019-02-27 08:54:05,057 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227085405049293
2019-02-27 08:54:05,071 [salt.minion      :1432][INFO    ][13270] Starting a new job with PID 13270
2019-02-27 08:54:05,099 [salt.minion      :1711][INFO    ][13270] Returning information for job: 20190227085405049293
2019-02-27 08:54:34,816 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (317.365072012s left)
2019-02-27 08:54:35,236 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227085435224220
2019-02-27 08:54:35,254 [salt.minion      :1432][INFO    ][13300] Starting a new job with PID 13300
2019-02-27 08:54:35,284 [salt.minion      :1711][INFO    ][13300] Returning information for job: 20190227085435224220
2019-02-27 08:55:05,258 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227085505250410
2019-02-27 08:55:05,275 [salt.minion      :1432][INFO    ][13367] Starting a new job with PID 13367
2019-02-27 08:55:05,309 [salt.minion      :1711][INFO    ][13367] Returning information for job: 20190227085505250410
2019-02-27 08:55:07,708 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (284.473387957s left)
2019-02-27 08:55:35,438 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227085535424109
2019-02-27 08:55:35,457 [salt.minion      :1432][INFO    ][13398] Starting a new job with PID 13398
2019-02-27 08:55:35,489 [salt.minion      :1711][INFO    ][13398] Returning information for job: 20190227085535424109
2019-02-27 08:55:40,445 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (251.736837864s left)
2019-02-27 08:56:05,500 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227085605487974
2019-02-27 08:56:05,518 [salt.minion      :1432][INFO    ][13474] Starting a new job with PID 13474
2019-02-27 08:56:05,548 [salt.minion      :1711][INFO    ][13474] Returning information for job: 20190227085605487974
2019-02-27 08:56:13,162 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (219.020050049s left)
2019-02-27 08:56:35,690 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227085635675400
2019-02-27 08:56:35,710 [salt.minion      :1432][INFO    ][13504] Starting a new job with PID 13504
2019-02-27 08:56:35,740 [salt.minion      :1711][INFO    ][13504] Returning information for job: 20190227085635675400
2019-02-27 08:56:45,994 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (186.188056946s left)
2019-02-27 08:57:05,797 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227085705782422
2019-02-27 08:57:05,818 [salt.minion      :1432][INFO    ][13577] Starting a new job with PID 13577
2019-02-27 08:57:05,848 [salt.minion      :1711][INFO    ][13577] Returning information for job: 20190227085705782422
2019-02-27 08:57:18,632 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (153.549295902s left)
2019-02-27 08:57:35,862 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227085735847306
2019-02-27 08:57:35,881 [salt.minion      :1432][INFO    ][13611] Starting a new job with PID 13611
2019-02-27 08:57:35,913 [salt.minion      :1711][INFO    ][13611] Returning information for job: 20190227085735847306
2019-02-27 08:57:51,354 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (120.827568054s left)
2019-02-27 08:58:06,000 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227085805986644
2019-02-27 08:58:06,020 [salt.minion      :1432][INFO    ][13682] Starting a new job with PID 13682
2019-02-27 08:58:06,053 [salt.minion      :1711][INFO    ][13682] Returning information for job: 20190227085805986644
2019-02-27 08:58:24,103 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (88.0787169933s left)
2019-02-27 08:58:36,070 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227085836058951
2019-02-27 08:58:36,091 [salt.minion      :1432][INFO    ][13712] Starting a new job with PID 13712
2019-02-27 08:58:36,120 [salt.minion      :1711][INFO    ][13712] Returning information for job: 20190227085836058951
2019-02-27 08:58:56,865 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (55.3165519238s left)
2019-02-27 08:59:06,247 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227085906235839
2019-02-27 08:59:06,266 [salt.minion      :1432][INFO    ][13787] Starting a new job with PID 13787
2019-02-27 08:59:06,302 [salt.minion      :1711][INFO    ][13787] Returning information for job: 20190227085906235839
2019-02-27 08:59:29,610 [salt.loaded.ext.module.maas:1023][INFO    ][7466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (22.5718920231s left)
2019-02-27 08:59:36,420 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227085936405795
2019-02-27 08:59:36,441 [salt.minion      :1432][INFO    ][13819] Starting a new job with PID 13819
2019-02-27 08:59:36,470 [salt.minion      :1711][INFO    ][13819] Returning information for job: 20190227085936405795
2019-02-27 09:00:02,387 [salt.state       :302 ][ERROR   ][7466] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-02-27 09:00:02,387 [salt.state       :1951][INFO    ][7466] Completed state [maas.wait_for_machine_status] at time 09:00:02.387842 duration_in_ms=2260222.35
2019-02-27 09:00:02,394 [salt.minion      :1711][INFO    ][7466] Returning information for job: 20190227082212344720
2019-02-27 09:00:13,280 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command pillar.get with jid 20190227090013271375
2019-02-27 09:00:13,302 [salt.minion      :1432][INFO    ][13895] Starting a new job with PID 13895
2019-02-27 09:00:13,308 [salt.minion      :1711][INFO    ][13895] Returning information for job: 20190227090013271375
2019-02-27 09:00:14,005 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command service.status with jid 20190227090013996550
2019-02-27 09:00:14,026 [salt.minion      :1432][INFO    ][13903] Starting a new job with PID 13903
2019-02-27 09:00:14,758 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13903] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-02-27 09:00:14,794 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13903] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-02-27 09:00:14,808 [salt.minion      :1711][INFO    ][13903] Returning information for job: 20190227090013996550
2019-02-27 09:00:15,500 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command state.apply with jid 20190227090015491989
2019-02-27 09:00:15,523 [salt.minion      :1432][INFO    ][13916] Starting a new job with PID 13916
2019-02-27 09:00:23,412 [salt.state       :915 ][INFO    ][13916] Loading fresh modules for state activity
2019-02-27 09:00:24,005 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13916] Executing command 'salt-minion --version' in directory '/root'
2019-02-27 09:00:24,333 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13916] Executing command 'salt-minion --version' in directory '/root'
2019-02-27 09:00:25,337 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13916] Executing command 'salt-minion --version' in directory '/root'
2019-02-27 09:00:25,664 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13916] Executing command 'salt-minion --version' in directory '/root'
2019-02-27 09:00:27,818 [salt.state       :1780][INFO    ][13916] Running state [salt-minion] at time 09:00:27.817971
2019-02-27 09:00:27,818 [salt.state       :1813][INFO    ][13916] Executing state pkg.installed for [salt-minion]
2019-02-27 09:00:27,819 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13916] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-27 09:00:27,936 [salt.state       :300 ][INFO    ][13916] All specified packages are already installed
2019-02-27 09:00:27,936 [salt.state       :1951][INFO    ][13916] Completed state [salt-minion] at time 09:00:27.936317 duration_in_ms=118.346
2019-02-27 09:00:27,936 [salt.state       :1780][INFO    ][13916] Running state [salt_minion_dependency_packages] at time 09:00:27.936700
2019-02-27 09:00:27,937 [salt.state       :1813][INFO    ][13916] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-02-27 09:00:27,946 [salt.state       :300 ][INFO    ][13916] All specified packages are already installed
2019-02-27 09:00:27,947 [salt.state       :1951][INFO    ][13916] Completed state [salt_minion_dependency_packages] at time 09:00:27.946954 duration_in_ms=10.254
2019-02-27 09:00:27,950 [salt.state       :1780][INFO    ][13916] Running state [/etc/salt/minion.d/minion.conf] at time 09:00:27.950051
2019-02-27 09:00:27,950 [salt.state       :1813][INFO    ][13916] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-02-27 09:00:28,222 [salt.state       :300 ][INFO    ][13916] File /etc/salt/minion.d/minion.conf is in the correct state
2019-02-27 09:00:28,222 [salt.state       :1951][INFO    ][13916] Completed state [/etc/salt/minion.d/minion.conf] at time 09:00:28.222463 duration_in_ms=272.412
2019-02-27 09:00:28,222 [salt.state       :1780][INFO    ][13916] Running state [python-netaddr] at time 09:00:28.222763
2019-02-27 09:00:28,223 [salt.state       :1813][INFO    ][13916] Executing state pkg.installed for [python-netaddr]
2019-02-27 09:00:28,232 [salt.state       :300 ][INFO    ][13916] All specified packages are already installed
2019-02-27 09:00:28,233 [salt.state       :1951][INFO    ][13916] Completed state [python-netaddr] at time 09:00:28.233094 duration_in_ms=10.331
2019-02-27 09:00:28,236 [salt.state       :1780][INFO    ][13916] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 09:00:28.236563
2019-02-27 09:00:28,236 [salt.state       :1813][INFO    ][13916] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-02-27 09:00:28,249 [salt.state       :300 ][INFO    ][13916] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-02-27 09:00:28,249 [salt.state       :1951][INFO    ][13916] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 09:00:28.249660 duration_in_ms=13.097
2019-02-27 09:00:28,253 [salt.state       :1780][INFO    ][13916] Running state [salt-minion] at time 09:00:28.253713
2019-02-27 09:00:28,254 [salt.state       :1813][INFO    ][13916] Executing state service.running for [salt-minion]
2019-02-27 09:00:28,255 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13916] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-02-27 09:00:28,294 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13916] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-02-27 09:00:28,312 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13916] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-02-27 09:00:28,332 [salt.state       :300 ][INFO    ][13916] The service salt-minion is already running
2019-02-27 09:00:28,333 [salt.state       :1951][INFO    ][13916] Completed state [salt-minion] at time 09:00:28.333158 duration_in_ms=79.445
2019-02-27 09:00:28,337 [salt.state       :1780][INFO    ][13916] Running state [/etc/salt/grains.d] at time 09:00:28.337259
2019-02-27 09:00:28,337 [salt.state       :1813][INFO    ][13916] Executing state file.directory for [/etc/salt/grains.d]
2019-02-27 09:00:28,339 [salt.state       :300 ][INFO    ][13916] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-02-27 09:00:28,339 [salt.state       :1951][INFO    ][13916] Completed state [/etc/salt/grains.d] at time 09:00:28.339762 duration_in_ms=2.503
2019-02-27 09:00:28,340 [salt.state       :1780][INFO    ][13916] Running state [/etc/salt/grains] at time 09:00:28.340818
2019-02-27 09:00:28,341 [salt.state       :1813][INFO    ][13916] Executing state file.managed for [/etc/salt/grains]
2019-02-27 09:00:28,342 [salt.state       :300 ][INFO    ][13916] File /etc/salt/grains exists with proper permissions. No changes made.
2019-02-27 09:00:28,342 [salt.state       :1951][INFO    ][13916] Completed state [/etc/salt/grains] at time 09:00:28.342464 duration_in_ms=1.646
2019-02-27 09:00:28,343 [salt.state       :1780][INFO    ][13916] Running state [/etc/salt/grains.d/placeholder] at time 09:00:28.343247
2019-02-27 09:00:28,343 [salt.state       :1813][INFO    ][13916] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-02-27 09:00:28,344 [salt.state       :300 ][INFO    ][13916] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-02-27 09:00:28,345 [salt.state       :1951][INFO    ][13916] Completed state [/etc/salt/grains.d/placeholder] at time 09:00:28.344914 duration_in_ms=1.667
2019-02-27 09:00:28,345 [salt.state       :1780][INFO    ][13916] Running state [/etc/salt/grains.d/sphinx] at time 09:00:28.345675
2019-02-27 09:00:28,346 [salt.state       :1813][INFO    ][13916] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-02-27 09:00:28,349 [salt.state       :300 ][INFO    ][13916] File /etc/salt/grains.d/sphinx is in the correct state
2019-02-27 09:00:28,349 [salt.state       :1951][INFO    ][13916] Completed state [/etc/salt/grains.d/sphinx] at time 09:00:28.349796 duration_in_ms=4.121
2019-02-27 09:00:28,352 [salt.state       :1780][INFO    ][13916] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 09:00:28.352006
2019-02-27 09:00:28,352 [salt.state       :1813][INFO    ][13916] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-02-27 09:00:28,352 [salt.state       :300 ][INFO    ][13916] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-02-27 09:00:28,352 [salt.state       :1951][INFO    ][13916] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 09:00:28.352876 duration_in_ms=0.87
2019-02-27 09:00:28,353 [salt.state       :1780][INFO    ][13916] Running state [/etc/salt/grains.d/dns_records] at time 09:00:28.353402
2019-02-27 09:00:28,353 [salt.state       :1813][INFO    ][13916] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-02-27 09:00:28,354 [salt.state       :300 ][INFO    ][13916] File /etc/salt/grains.d/dns_records is in the correct state
2019-02-27 09:00:28,355 [salt.state       :1951][INFO    ][13916] Completed state [/etc/salt/grains.d/dns_records] at time 09:00:28.355275 duration_in_ms=1.873
2019-02-27 09:00:28,356 [salt.state       :1780][INFO    ][13916] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 09:00:28.356254
2019-02-27 09:00:28,356 [salt.state       :1813][INFO    ][13916] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-02-27 09:00:28,356 [salt.state       :300 ][INFO    ][13916] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-02-27 09:00:28,357 [salt.state       :1951][INFO    ][13916] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 09:00:28.357105 duration_in_ms=0.851
2019-02-27 09:00:28,357 [salt.state       :1780][INFO    ][13916] Running state [/etc/salt/grains.d/salt] at time 09:00:28.357641
2019-02-27 09:00:28,357 [salt.state       :1813][INFO    ][13916] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-02-27 09:00:28,360 [salt.state       :300 ][INFO    ][13916] File /etc/salt/grains.d/salt is in the correct state
2019-02-27 09:00:28,360 [salt.state       :1951][INFO    ][13916] Completed state [/etc/salt/grains.d/salt] at time 09:00:28.360366 duration_in_ms=2.725
2019-02-27 09:00:28,361 [salt.state       :1780][INFO    ][13916] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 09:00:28.361303
2019-02-27 09:00:28,361 [salt.state       :1813][INFO    ][13916] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-02-27 09:00:28,361 [salt.state       :300 ][INFO    ][13916] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-02-27 09:00:28,362 [salt.state       :1951][INFO    ][13916] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 09:00:28.362157 duration_in_ms=0.854
2019-02-27 09:00:28,364 [salt.state       :1780][INFO    ][13916] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 09:00:28.364890
2019-02-27 09:00:28,365 [salt.state       :1813][INFO    ][13916] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-02-27 09:00:28,365 [salt.state       :300 ][INFO    ][13916] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-02-27 09:00:28,365 [salt.state       :1951][INFO    ][13916] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 09:00:28.365788 duration_in_ms=0.898
2019-02-27 09:00:28,366 [salt.state       :1780][INFO    ][13916] Running state [mine.update] at time 09:00:28.366497
2019-02-27 09:00:28,366 [salt.state       :1813][INFO    ][13916] Executing state module.wait for [mine.update]
2019-02-27 09:00:28,367 [salt.state       :300 ][INFO    ][13916] No changes made for mine.update
2019-02-27 09:00:28,367 [salt.state       :1951][INFO    ][13916] Completed state [mine.update] at time 09:00:28.367315 duration_in_ms=0.818
2019-02-27 09:00:28,367 [salt.state       :1780][INFO    ][13916] Running state [ca-certificates] at time 09:00:28.367604
2019-02-27 09:00:28,367 [salt.state       :1813][INFO    ][13916] Executing state pkg.installed for [ca-certificates]
2019-02-27 09:00:28,378 [salt.state       :300 ][INFO    ][13916] All specified packages are already installed
2019-02-27 09:00:28,378 [salt.state       :1951][INFO    ][13916] Completed state [ca-certificates] at time 09:00:28.378556 duration_in_ms=10.952
2019-02-27 09:00:28,379 [salt.state       :1780][INFO    ][13916] Running state [update-ca-certificates] at time 09:00:28.379294
2019-02-27 09:00:28,379 [salt.state       :1813][INFO    ][13916] Executing state cmd.wait for [update-ca-certificates]
2019-02-27 09:00:28,379 [salt.state       :300 ][INFO    ][13916] No changes made for update-ca-certificates
2019-02-27 09:00:28,380 [salt.state       :1951][INFO    ][13916] Completed state [update-ca-certificates] at time 09:00:28.380108 duration_in_ms=0.815
2019-02-27 09:00:28,380 [salt.state       :1780][INFO    ][13916] Running state [iptables] at time 09:00:28.380390
2019-02-27 09:00:28,380 [salt.state       :1813][INFO    ][13916] Executing state pkg.installed for [iptables]
2019-02-27 09:00:28,390 [salt.state       :300 ][INFO    ][13916] All specified packages are already installed
2019-02-27 09:00:28,390 [salt.state       :1951][INFO    ][13916] Completed state [iptables] at time 09:00:28.390830 duration_in_ms=10.44
2019-02-27 09:00:28,391 [salt.state       :1780][INFO    ][13916] Running state [iptables-persistent] at time 09:00:28.391134
2019-02-27 09:00:28,391 [salt.state       :1813][INFO    ][13916] Executing state pkg.installed for [iptables-persistent]
2019-02-27 09:00:28,401 [salt.state       :300 ][INFO    ][13916] All specified packages are already installed
2019-02-27 09:00:28,401 [salt.state       :1951][INFO    ][13916] Completed state [iptables-persistent] at time 09:00:28.401897 duration_in_ms=10.763
2019-02-27 09:00:28,403 [salt.state       :1780][INFO    ][13916] Running state [iptables_modules_v4_load] at time 09:00:28.403321
2019-02-27 09:00:28,403 [salt.state       :1813][INFO    ][13916] Executing state kmod.present for [iptables_modules_v4_load]
2019-02-27 09:00:28,404 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13916] Executing command 'lsmod' in directory '/root'
2019-02-27 09:00:28,425 [salt.state       :300 ][INFO    ][13916] Kernel modules iptable_filter, ip_tables are already present
2019-02-27 09:00:28,426 [salt.state       :1951][INFO    ][13916] Completed state [iptables_modules_v4_load] at time 09:00:28.426258 duration_in_ms=22.937
2019-02-27 09:00:28,427 [salt.state       :1780][INFO    ][13916] Running state [/etc/iptables/rules.v4] at time 09:00:28.427402
2019-02-27 09:00:28,428 [salt.state       :1813][INFO    ][13916] Executing state file.managed for [/etc/iptables/rules.v4]
2019-02-27 09:00:28,530 [salt.state       :300 ][INFO    ][13916] File /etc/iptables/rules.v4 is in the correct state
2019-02-27 09:00:28,531 [salt.state       :1951][INFO    ][13916] Completed state [/etc/iptables/rules.v4] at time 09:00:28.531184 duration_in_ms=103.782
2019-02-27 09:00:28,532 [salt.state       :1780][INFO    ][13916] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 09:00:28.532340
2019-02-27 09:00:28,532 [salt.state       :1813][INFO    ][13916] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-02-27 09:00:28,533 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13916] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-02-27 09:00:28,554 [salt.state       :300 ][INFO    ][13916] onlyif execution failed
2019-02-27 09:00:28,554 [salt.state       :1951][INFO    ][13916] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 09:00:28.554528 duration_in_ms=22.188
2019-02-27 09:00:28,556 [salt.state       :1780][INFO    ][13916] Running state [netfilter-persistent] at time 09:00:28.556400
2019-02-27 09:00:28,557 [salt.state       :1813][INFO    ][13916] Executing state service.running for [netfilter-persistent]
2019-02-27 09:00:28,558 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13916] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-02-27 09:00:28,581 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13916] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-02-27 09:00:28,601 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13916] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-02-27 09:00:28,618 [salt.state       :300 ][INFO    ][13916] The service netfilter-persistent is already running
2019-02-27 09:00:28,618 [salt.state       :1951][INFO    ][13916] Completed state [netfilter-persistent] at time 09:00:28.618470 duration_in_ms=62.071
2019-02-27 09:00:28,620 [salt.state       :1780][INFO    ][13916] Running state [iptables_extra.remove_stale_tables] at time 09:00:28.620073
2019-02-27 09:00:28,620 [salt.state       :1813][INFO    ][13916] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-02-27 09:00:28,621 [salt.state       :300 ][INFO    ][13916] No changes made for iptables_extra.remove_stale_tables
2019-02-27 09:00:28,621 [salt.state       :1951][INFO    ][13916] Completed state [iptables_extra.remove_stale_tables] at time 09:00:28.621449 duration_in_ms=1.375
2019-02-27 09:00:28,621 [salt.state       :1780][INFO    ][13916] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 09:00:28.621894
2019-02-27 09:00:28,622 [salt.state       :1813][INFO    ][13916] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-02-27 09:00:28,625 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13916] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-02-27 09:00:28,640 [salt.state       :300 ][INFO    ][13916] onlyif execution failed
2019-02-27 09:00:28,640 [salt.state       :1951][INFO    ][13916] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 09:00:28.640515 duration_in_ms=18.62
2019-02-27 09:00:28,642 [salt.state       :1780][INFO    ][13916] Running state [/etc/iptables/rules.v6] at time 09:00:28.642017
2019-02-27 09:00:28,642 [salt.state       :1813][INFO    ][13916] Executing state file.absent for [/etc/iptables/rules.v6]
2019-02-27 09:00:28,643 [salt.state       :300 ][INFO    ][13916] File /etc/iptables/rules.v6 is not present
2019-02-27 09:00:28,643 [salt.state       :1951][INFO    ][13916] Completed state [/etc/iptables/rules.v6] at time 09:00:28.643607 duration_in_ms=1.591
2019-02-27 09:00:28,644 [salt.state       :1780][INFO    ][13916] Running state [iptables_extra.flush_all] at time 09:00:28.644740
2019-02-27 09:00:28,645 [salt.state       :1813][INFO    ][13916] Executing state module.wait for [iptables_extra.flush_all]
2019-02-27 09:00:28,645 [salt.state       :300 ][INFO    ][13916] No changes made for iptables_extra.flush_all
2019-02-27 09:00:28,646 [salt.state       :1951][INFO    ][13916] Completed state [iptables_extra.flush_all] at time 09:00:28.645975 duration_in_ms=1.235
2019-02-27 09:00:28,652 [salt.minion      :1711][INFO    ][13916] Returning information for job: 20190227090015491989
2019-02-27 09:00:29,334 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command state.apply with jid 20190227090029320193
2019-02-27 09:00:29,358 [salt.minion      :1432][INFO    ][14004] Starting a new job with PID 14004
2019-02-27 09:00:30,271 [salt.state       :915 ][INFO    ][14004] Loading fresh modules for state activity
2019-02-27 09:00:31,439 [salt.state       :1780][INFO    ][14004] Running state [maas-rack-controller] at time 09:00:31.438901
2019-02-27 09:00:31,439 [salt.state       :1813][INFO    ][14004] Executing state pkg.installed for [maas-rack-controller]
2019-02-27 09:00:31,440 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14004] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-27 09:00:31,556 [salt.state       :300 ][INFO    ][14004] All specified packages are already installed
2019-02-27 09:00:31,557 [salt.state       :1951][INFO    ][14004] Completed state [maas-rack-controller] at time 09:00:31.557037 duration_in_ms=118.137
2019-02-27 09:00:31,557 [salt.state       :1780][INFO    ][14004] Running state [ipmitool] at time 09:00:31.557437
2019-02-27 09:00:31,557 [salt.state       :1813][INFO    ][14004] Executing state pkg.installed for [ipmitool]
2019-02-27 09:00:31,610 [salt.state       :300 ][INFO    ][14004] All specified packages are already installed
2019-02-27 09:00:31,611 [salt.state       :1951][INFO    ][14004] Completed state [ipmitool] at time 09:00:31.610822 duration_in_ms=53.385
2019-02-27 09:00:31,613 [salt.state       :1780][INFO    ][14004] Running state [/etc/maas/rackd.conf] at time 09:00:31.613654
2019-02-27 09:00:31,614 [salt.state       :1813][INFO    ][14004] Executing state file.line for [/etc/maas/rackd.conf]
2019-02-27 09:00:31,615 [salt.state       :300 ][INFO    ][14004] No changes needed to be made
2019-02-27 09:00:31,615 [salt.state       :1951][INFO    ][14004] Completed state [/etc/maas/rackd.conf] at time 09:00:31.615458 duration_in_ms=1.804
2019-02-27 09:00:31,615 [salt.state       :1780][INFO    ][14004] Running state [/etc/maas/rackd.conf] at time 09:00:31.615751
2019-02-27 09:00:31,616 [salt.state       :1813][INFO    ][14004] Executing state file.managed for [/etc/maas/rackd.conf]
2019-02-27 09:00:31,616 [salt.loaded.int.states.file:2298][WARNING ][14004] 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-02-27 09:00:31,617 [salt.state       :300 ][INFO    ][14004] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-02-27 09:00:31,617 [salt.state       :1951][INFO    ][14004] Completed state [/etc/maas/rackd.conf] at time 09:00:31.617273 duration_in_ms=1.523
2019-02-27 09:00:31,618 [salt.state       :1780][INFO    ][14004] Running state [maas-rackd] at time 09:00:31.618313
2019-02-27 09:00:31,618 [salt.state       :1813][INFO    ][14004] Executing state service.running for [maas-rackd]
2019-02-27 09:00:31,619 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14004] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-02-27 09:00:31,654 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14004] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-02-27 09:00:31,673 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14004] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-02-27 09:00:31,691 [salt.state       :300 ][INFO    ][14004] The service maas-rackd is already running
2019-02-27 09:00:31,692 [salt.state       :1951][INFO    ][14004] Completed state [maas-rackd] at time 09:00:31.692002 duration_in_ms=73.688
2019-02-27 09:00:31,693 [salt.minion      :1711][INFO    ][14004] Returning information for job: 20190227090029320193
2019-02-27 09:00:32,378 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command state.apply with jid 20190227090032370691
2019-02-27 09:00:32,399 [salt.minion      :1432][INFO    ][14027] Starting a new job with PID 14027
2019-02-27 09:00:33,318 [salt.state       :915 ][INFO    ][14027] Loading fresh modules for state activity
2019-02-27 09:00:34,658 [salt.state       :1780][INFO    ][14027] Running state [maas-region-controller] at time 09:00:34.658036
2019-02-27 09:00:34,658 [salt.state       :1813][INFO    ][14027] Executing state pkg.installed for [maas-region-controller]
2019-02-27 09:00:34,659 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14027] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-27 09:00:34,773 [salt.state       :300 ][INFO    ][14027] All specified packages are already installed
2019-02-27 09:00:34,774 [salt.state       :1951][INFO    ][14027] Completed state [maas-region-controller] at time 09:00:34.774149 duration_in_ms=116.113
2019-02-27 09:00:34,774 [salt.state       :1780][INFO    ][14027] Running state [python-oauth] at time 09:00:34.774518
2019-02-27 09:00:34,775 [salt.state       :1813][INFO    ][14027] Executing state pkg.installed for [python-oauth]
2019-02-27 09:00:34,785 [salt.state       :300 ][INFO    ][14027] All specified packages are already installed
2019-02-27 09:00:34,785 [salt.state       :1951][INFO    ][14027] Completed state [python-oauth] at time 09:00:34.785767 duration_in_ms=11.248
2019-02-27 09:00:34,788 [salt.state       :1780][INFO    ][14027] Running state [/etc/maas/regiond.conf] at time 09:00:34.788817
2019-02-27 09:00:34,789 [salt.state       :1813][INFO    ][14027] Executing state file.replace for [/etc/maas/regiond.conf]
2019-02-27 09:00:34,795 [salt.state       :300 ][INFO    ][14027] No changes needed to be made
2019-02-27 09:00:34,795 [salt.state       :1951][INFO    ][14027] Completed state [/etc/maas/regiond.conf] at time 09:00:34.795794 duration_in_ms=6.977
2019-02-27 09:00:34,796 [salt.state       :1780][INFO    ][14027] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 09:00:34.796325
2019-02-27 09:00:34,796 [salt.state       :1813][INFO    ][14027] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-02-27 09:00:34,854 [salt.state       :300 ][INFO    ][14027] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-02-27 09:00:34,854 [salt.state       :1951][INFO    ][14027] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 09:00:34.854323 duration_in_ms=57.997
2019-02-27 09:00:34,855 [salt.state       :1780][INFO    ][14027] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 09:00:34.855262
2019-02-27 09:00:34,855 [salt.state       :1813][INFO    ][14027] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-02-27 09:00:34,862 [salt.state       :300 ][INFO    ][14027] No changes needed to be made
2019-02-27 09:00:34,864 [salt.state       :1951][INFO    ][14027] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 09:00:34.864727 duration_in_ms=9.465
2019-02-27 09:00:34,865 [salt.state       :1780][INFO    ][14027] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 09:00:34.865284
2019-02-27 09:00:34,865 [salt.state       :1813][INFO    ][14027] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-02-27 09:00:34,869 [salt.state       :300 ][INFO    ][14027] No changes needed to be made
2019-02-27 09:00:34,870 [salt.state       :1951][INFO    ][14027] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 09:00:34.870125 duration_in_ms=4.84
2019-02-27 09:00:34,870 [salt.state       :1780][INFO    ][14027] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 09:00:34.870666
2019-02-27 09:00:34,870 [salt.state       :1813][INFO    ][14027] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-02-27 09:00:34,896 [salt.state       :300 ][INFO    ][14027] No changes needed to be made
2019-02-27 09:00:34,896 [salt.state       :1951][INFO    ][14027] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 09:00:34.896411 duration_in_ms=25.745
2019-02-27 09:00:34,897 [salt.state       :1780][INFO    ][14027] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 09:00:34.896953
2019-02-27 09:00:34,897 [salt.state       :1813][INFO    ][14027] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-02-27 09:00:34,908 [salt.state       :300 ][INFO    ][14027] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-02-27 09:00:34,908 [salt.state       :1951][INFO    ][14027] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 09:00:34.908482 duration_in_ms=11.529
2019-02-27 09:00:34,909 [salt.state       :1780][INFO    ][14027] Running state [a2enmod headers] at time 09:00:34.909835
2019-02-27 09:00:34,910 [salt.state       :1813][INFO    ][14027] Executing state cmd.run for [a2enmod headers]
2019-02-27 09:00:34,910 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14027] Executing command 'a2enmod headers' in directory '/root'
2019-02-27 09:00:34,978 [salt.state       :300 ][INFO    ][14027] {'pid': 14046, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-02-27 09:00:34,979 [salt.state       :1951][INFO    ][14027] Completed state [a2enmod headers] at time 09:00:34.979188 duration_in_ms=69.352
2019-02-27 09:00:34,979 [salt.state       :1780][INFO    ][14027] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 09:00:34.979879
2019-02-27 09:00:34,980 [salt.state       :1813][INFO    ][14027] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-02-27 09:00:35,000 [salt.state       :300 ][INFO    ][14027] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-02-27 09:00:35,001 [salt.state       :1951][INFO    ][14027] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 09:00:35.000981 duration_in_ms=21.102
2019-02-27 09:00:35,001 [salt.state       :1780][INFO    ][14027] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 09:00:35.001607
2019-02-27 09:00:35,001 [salt.state       :1813][INFO    ][14027] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-02-27 09:00:35,061 [salt.state       :300 ][INFO    ][14027] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-02-27 09:00:35,061 [salt.state       :1951][INFO    ][14027] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 09:00:35.061283 duration_in_ms=59.676
2019-02-27 09:00:35,061 [salt.state       :1780][INFO    ][14027] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 09:00:35.061837
2019-02-27 09:00:35,062 [salt.state       :1813][INFO    ][14027] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-02-27 09:00:35,117 [salt.state       :300 ][INFO    ][14027] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-02-27 09:00:35,117 [salt.state       :1951][INFO    ][14027] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 09:00:35.117761 duration_in_ms=55.924
2019-02-27 09:00:35,118 [salt.state       :1780][INFO    ][14027] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 09:00:35.118325
2019-02-27 09:00:35,118 [salt.state       :1813][INFO    ][14027] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-02-27 09:00:35,192 [salt.state       :300 ][INFO    ][14027] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-02-27 09:00:35,192 [salt.state       :1951][INFO    ][14027] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 09:00:35.192919 duration_in_ms=74.594
2019-02-27 09:00:35,193 [salt.state       :1780][INFO    ][14027] Running state [/root/.pgpass] at time 09:00:35.193221
2019-02-27 09:00:35,193 [salt.state       :1813][INFO    ][14027] Executing state file.managed for [/root/.pgpass]
2019-02-27 09:00:35,238 [salt.state       :300 ][INFO    ][14027] File /root/.pgpass is in the correct state
2019-02-27 09:00:35,238 [salt.state       :1951][INFO    ][14027] Completed state [/root/.pgpass] at time 09:00:35.238702 duration_in_ms=45.48
2019-02-27 09:00:35,244 [salt.state       :1780][INFO    ][14027] Running state [maas-region syncdb --noinput] at time 09:00:35.244669
2019-02-27 09:00:35,245 [salt.state       :1813][INFO    ][14027] Executing state cmd.run for [maas-region syncdb --noinput]
2019-02-27 09:00:35,245 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14027] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-02-27 09:00:37,876 [salt.state       :300 ][INFO    ][14027] {'pid': 14060, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: maasserver, contenttypes, piston3, sites, auth, metadataserver, sessions\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-02-27 09:00:37,877 [salt.state       :1951][INFO    ][14027] Completed state [maas-region syncdb --noinput] at time 09:00:37.877070 duration_in_ms=2632.4
2019-02-27 09:00:37,877 [salt.state       :2022][WARNING ][14027] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-02-27 09:00:37,880 [salt.state       :1780][INFO    ][14027] Running state [maas-regiond] at time 09:00:37.880003
2019-02-27 09:00:37,880 [salt.state       :1813][INFO    ][14027] Executing state service.running for [maas-regiond]
2019-02-27 09:00:37,881 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14027] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-02-27 09:00:37,905 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14027] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-02-27 09:00:37,917 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14027] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-02-27 09:00:37,928 [salt.state       :300 ][INFO    ][14027] The service maas-regiond is already running
2019-02-27 09:00:37,929 [salt.state       :1951][INFO    ][14027] Completed state [maas-regiond] at time 09:00:37.929209 duration_in_ms=49.205
2019-02-27 09:00:37,931 [salt.state       :1780][INFO    ][14027] Running state [bind9] at time 09:00:37.931173
2019-02-27 09:00:37,931 [salt.state       :1813][INFO    ][14027] Executing state service.running for [bind9]
2019-02-27 09:00:37,932 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14027] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-02-27 09:00:37,944 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14027] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-02-27 09:00:37,956 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14027] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-02-27 09:00:37,967 [salt.state       :300 ][INFO    ][14027] The service bind9 is already running
2019-02-27 09:00:37,967 [salt.state       :1951][INFO    ][14027] Completed state [bind9] at time 09:00:37.967911 duration_in_ms=36.738
2019-02-27 09:00:37,969 [salt.state       :1780][INFO    ][14027] Running state [apache2] at time 09:00:37.969665
2019-02-27 09:00:37,970 [salt.state       :1813][INFO    ][14027] Executing state service.running for [apache2]
2019-02-27 09:00:37,970 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14027] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-02-27 09:00:37,985 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14027] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-02-27 09:00:37,997 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14027] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-02-27 09:00:38,012 [salt.state       :300 ][INFO    ][14027] The service apache2 is already running
2019-02-27 09:00:38,012 [salt.state       :1951][INFO    ][14027] Completed state [apache2] at time 09:00:38.012836 duration_in_ms=43.17
2019-02-27 09:00:38,014 [salt.state       :1780][INFO    ][14027] Running state [maasng.wait_for_http_code] at time 09:00:38.013976
2019-02-27 09:00:38,014 [salt.state       :1813][INFO    ][14027] Executing state module.run for [maasng.wait_for_http_code]
2019-02-27 09:00:38,014 [salt.utils.decorators:613 ][WARNING ][14027] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-27 09:00:38,134 [salt.state       :300 ][INFO    ][14027] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-02-27 09:00:38,135 [salt.state       :1951][INFO    ][14027] Completed state [maasng.wait_for_http_code] at time 09:00:38.135350 duration_in_ms=121.373
2019-02-27 09:00:38,136 [salt.state       :1780][INFO    ][14027] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 09:00:38.136475
2019-02-27 09:00:38,136 [salt.state       :1813][INFO    ][14027] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-02-27 09:00:38,137 [salt.state       :300 ][INFO    ][14027] /var/lib/maas/.setup_admin exists
2019-02-27 09:00:38,138 [salt.state       :1951][INFO    ][14027] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 09:00:38.138007 duration_in_ms=1.531
2019-02-27 09:00:38,141 [salt.state       :1780][INFO    ][14027] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:00:38.141317
2019-02-27 09:00:38,141 [salt.state       :1813][INFO    ][14027] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-27 09:00:38,142 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14027] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-27 09:00:39,962 [salt.state       :300 ][INFO    ][14027] {'pid': 14081, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-27 09:00:39,963 [salt.state       :1951][INFO    ][14027] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:00:39.963784 duration_in_ms=1822.467
2019-02-27 09:00:39,968 [salt.state       :1780][INFO    ][14027] Running state [maas_region_boot_source_resources_mirror] at time 09:00:39.968496
2019-02-27 09:00:39,968 [salt.state       :1813][INFO    ][14027] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-02-27 09:00:40,039 [salt.state       :300 ][INFO    ][14027] {'changes': {}}
2019-02-27 09:00:40,039 [salt.state       :1951][INFO    ][14027] Completed state [maas_region_boot_source_resources_mirror] at time 09:00:40.039814 duration_in_ms=71.318
2019-02-27 09:00:40,040 [salt.state       :1780][INFO    ][14027] Running state [maasng.boot_resources_import] at time 09:00:40.040534
2019-02-27 09:00:40,040 [salt.state       :1813][INFO    ][14027] Executing state module.run for [maasng.boot_resources_import]
2019-02-27 09:00:40,041 [salt.utils.decorators:613 ][WARNING ][14027] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-27 09:00:40,100 [salt.loaded.ext.module.maasng:1600][INFO    ][14027] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-02-27 09:00:45,152 [salt.loaded.ext.module.maasng:1600][INFO    ][14027] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-02-27 09:00:47,484 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227090047470394
2019-02-27 09:00:47,505 [salt.minion      :1432][INFO    ][14278] Starting a new job with PID 14278
2019-02-27 09:00:47,540 [salt.minion      :1711][INFO    ][14278] Returning information for job: 20190227090047470394
2019-02-27 09:00:50,225 [salt.state       :300 ][INFO    ][14027] {'ret': True}
2019-02-27 09:00:50,225 [salt.state       :1951][INFO    ][14027] Completed state [maasng.boot_resources_import] at time 09:00:50.225808 duration_in_ms=10185.273
2019-02-27 09:00:50,226 [salt.state       :1780][INFO    ][14027] Running state [maas_region_boot_sources_selection_xenial] at time 09:00:50.226573
2019-02-27 09:00:50,227 [salt.state       :1813][INFO    ][14027] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-02-27 09:00:50,353 [salt.state       :300 ][INFO    ][14027] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-02-27 09:00:50,353 [salt.state       :1951][INFO    ][14027] Completed state [maas_region_boot_sources_selection_xenial] at time 09:00:50.353778 duration_in_ms=127.204
2019-02-27 09:00:50,354 [salt.state       :1780][INFO    ][14027] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 09:00:50.354694
2019-02-27 09:00:50,357 [salt.state       :1813][INFO    ][14027] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-02-27 09:00:50,357 [salt.utils.decorators:613 ][WARNING ][14027] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-27 09:00:50,358 [salt.loaded.ext.module.maasng:1771][INFO    ][14027] boot-sources sync initiated for ALL Rack's
2019-02-27 09:00:51,272 [salt.state       :300 ][INFO    ][14027] {'ret': True}
2019-02-27 09:00:51,272 [salt.state       :1951][INFO    ][14027] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 09:00:51.272690 duration_in_ms=917.995
2019-02-27 09:00:51,274 [salt.state       :1780][INFO    ][14027] Running state [maas.process_maas_config] at time 09:00:51.274644
2019-02-27 09:00:51,275 [salt.state       :1813][INFO    ][14027] Executing state module.run for [maas.process_maas_config]
2019-02-27 09:00:51,275 [salt.utils.decorators:613 ][WARNING ][14027] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-27 09:00:51,276 [salt.loaded.ext.module.maas:92  ][INFO    ][14027] maasconfig name=enable_http_proxy value=True
2019-02-27 09:00:51,333 [salt.loaded.ext.module.maas:92  ][INFO    ][14027] maasconfig name=upstream_dns value=8.8.8.8
2019-02-27 09:00:51,394 [salt.loaded.ext.module.maas:92  ][INFO    ][14027] maasconfig name=commissioning_distro_series value=xenial
2019-02-27 09:00:51,452 [salt.loaded.ext.module.maas:92  ][INFO    ][14027] maasconfig name=default_osystem value=ubuntu
2019-02-27 09:00:51,509 [salt.loaded.ext.module.maas:92  ][INFO    ][14027] maasconfig name=active_discovery_interval value=600
2019-02-27 09:00:51,560 [salt.loaded.ext.module.maas:92  ][INFO    ][14027] maasconfig name=dnssec_validation value=no
2019-02-27 09:00:51,608 [salt.loaded.ext.module.maas:92  ][INFO    ][14027] maasconfig name=maas_name value=mas01
2019-02-27 09:00:51,662 [salt.loaded.ext.module.maas:92  ][INFO    ][14027] maasconfig name=network_discovery value=enabled
2019-02-27 09:00:51,764 [salt.loaded.ext.module.maas:92  ][INFO    ][14027] maasconfig name=enable_third_party_drivers value=True
2019-02-27 09:00:52,545 [salt.loaded.ext.module.maas:92  ][INFO    ][14027] maasconfig name=default_storage_layout value=lvm
2019-02-27 09:00:52,585 [salt.loaded.ext.module.maas:92  ][INFO    ][14027] maasconfig name=ntp_external_only value=True
2019-02-27 09:00:52,626 [salt.loaded.ext.module.maas:92  ][INFO    ][14027] maasconfig name=disk_erase_with_secure_erase value=False
2019-02-27 09:00:52,686 [salt.loaded.ext.module.maas:92  ][INFO    ][14027] maasconfig name=default_distro_series value=xenial
2019-02-27 09:00:52,736 [salt.loaded.ext.module.maas:92  ][INFO    ][14027] maasconfig name=default_min_hwe_kernel value=ga-16.04
2019-02-27 09:00:52,873 [salt.state       :300 ][INFO    ][14027] {'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-02-27 09:00:52,873 [salt.state       :1951][INFO    ][14027] Completed state [maas.process_maas_config] at time 09:00:52.873524 duration_in_ms=1598.88
2019-02-27 09:00:52,874 [salt.state       :1780][INFO    ][14027] Running state [pxe_admin] at time 09:00:52.874437
2019-02-27 09:00:52,876 [salt.state       :1813][INFO    ][14027] Executing state maasng.fabric_present for [pxe_admin]
2019-02-27 09:00:52,945 [salt.loaded.ext.module.maasng:945 ][INFO    ][14027] [{u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'id': 0}, {u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, 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'name': u'fabric-2', u'id': 2}, {u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'qmkgyp', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'id': 1}]
2019-02-27 09:00:53,011 [salt.loaded.ext.module.maasng:1008][WARNING ][14027] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-02-27 09:00:53,011 [salt.loaded.ext.module.maasng:1011][WARNING ][14027] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-02-27 09:00:53,071 [salt.state       :300 ][INFO    ][14027] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-02-27 09:00:53,072 [salt.state       :1951][INFO    ][14027] Completed state [pxe_admin] at time 09:00:53.071948 duration_in_ms=197.51
2019-02-27 09:00:53,072 [salt.state       :1780][INFO    ][14027] Running state [vlan 0] at time 09:00:53.072286
2019-02-27 09:00:53,072 [salt.state       :1813][INFO    ][14027] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-02-27 09:00:53,121 [salt.loaded.ext.module.maasng:945 ][INFO    ][14027] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', 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'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', 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'id': 2}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', 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'primary_rack': u'qmkgyp', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', 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'id': 1}]
2019-02-27 09:00:53,234 [salt.loaded.ext.module.maasng:945 ][INFO    ][14027] [{u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'id': 0}, {u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, 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'name': u'fabric-2', u'id': 2}, {u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'qmkgyp', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'id': 1}]
2019-02-27 09:00:53,456 [salt.loaded.ext.module.maasng:945 ][INFO    ][14027] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', 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'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', 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'id': 2}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', 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'primary_rack': u'qmkgyp', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', 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'id': 1}]
2019-02-27 09:00:53,554 [salt.state       :300 ][INFO    ][14027] {'new': 'Vlan untagged was updated'}
2019-02-27 09:00:53,554 [salt.state       :1951][INFO    ][14027] Completed state [vlan 0] at time 09:00:53.554428 duration_in_ms=482.141
2019-02-27 09:00:53,556 [salt.state       :1780][INFO    ][14027] Running state [192.168.11.0/24] at time 09:00:53.556259
2019-02-27 09:00:53,556 [salt.state       :1813][INFO    ][14027] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-02-27 09:00:53,738 [salt.loaded.ext.module.maasng:945 ][INFO    ][14027] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', 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'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', 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'id': 2}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'qmkgyp', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', 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'id': 1}]
2019-02-27 09:00:53,738 [salt.loaded.ext.module.maasng:1235][WARNING ][14027] Ignoring parameter vlan:0
2019-02-27 09:00:53,806 [salt.state       :300 ][INFO    ][14027] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-02-27 09:00:53,806 [salt.state       :1951][INFO    ][14027] Completed state [192.168.11.0/24] at time 09:00:53.806803 duration_in_ms=250.544
2019-02-27 09:00:53,807 [salt.state       :1780][INFO    ][14027] Running state [maas_create_iprange_1] at time 09:00:53.807588
2019-02-27 09:00:53,807 [salt.state       :1813][INFO    ][14027] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-02-27 09:00:53,854 [salt.state       :300 ][INFO    ][14027] Iprange maas_create_iprange_1 already exist.
2019-02-27 09:00:53,855 [salt.state       :1951][INFO    ][14027] Completed state [maas_create_iprange_1] at time 09:00:53.855627 duration_in_ms=48.038
2019-02-27 09:00:53,856 [salt.state       :1780][INFO    ][14027] Running state [vlan 0] at time 09:00:53.856134
2019-02-27 09:00:53,856 [salt.state       :1813][INFO    ][14027] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-02-27 09:00:53,905 [salt.loaded.ext.module.maasng:945 ][INFO    ][14027] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'qmkgyp', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-02-27 09:00:53,992 [salt.loaded.ext.module.maasng:945 ][INFO    ][14027] [{u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'id': 0}, {u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, 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'name': u'fabric-2', u'id': 2}, {u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'qmkgyp', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'id': 1}]
2019-02-27 09:00:54,209 [salt.loaded.ext.module.maasng:945 ][INFO    ][14027] [{u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'id': 0}, {u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, 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'name': u'fabric-2', u'id': 2}, {u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'qmkgyp', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'id': 1}]
2019-02-27 09:00:54,287 [salt.state       :300 ][INFO    ][14027] {'new': 'Vlan untagged was updated'}
2019-02-27 09:00:54,287 [salt.state       :1951][INFO    ][14027] Completed state [vlan 0] at time 09:00:54.287764 duration_in_ms=431.629
2019-02-27 09:00:54,288 [salt.state       :1780][INFO    ][14027] Running state [opnfv] at time 09:00:54.288598
2019-02-27 09:00:54,288 [salt.state       :1813][INFO    ][14027] Executing state maasng.sshkey_present for [opnfv]
2019-02-27 09:00:54,336 [salt.loaded.ext.module.maasng:1903][INFO    ][14027] [{u'keysource': u'', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCwbsLV1UY13y2+2b7H9h6zOFMrcnumZvKOz6oqAXgttZjoKci01cqGRU8OMjob/9xeAOP2Cmu7HOBpRxda00X57yYx4UidSgEtlbOi7LLj2DuvJScix+rgPAd19aukdCiTuMmyLZQPMzVrdBxqzupJ5zqS3djSCllnQ2myEwswcSBn0Dpusgt3bnwdsCgI6B6ZgL9aR9DLnyMPMVroI6ZZH7aP7syIy1sPEsiJd3smWEULgeRElsuK+4I3YKG1Z1J2n80lmtR2gVD5tuKLFRRxnbjAqzyCEXQBTUVRc13SAFrH0b5bzFaqfxY1wdQPzP+dBSA+t25KcjnpTAAtL86H', u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/'}]
2019-02-27 09:00:54,337 [salt.state       :300 ][INFO    ][14027] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCwbsLV1UY13y2+2b7H9h6zOFMrcnumZvKOz6oqAXgttZjoKci01cqGRU8OMjob/9xeAOP2Cmu7HOBpRxda00X57yYx4UidSgEtlbOi7LLj2DuvJScix+rgPAd19aukdCiTuMmyLZQPMzVrdBxqzupJ5zqS3djSCllnQ2myEwswcSBn0Dpusgt3bnwdsCgI6B6ZgL9aR9DLnyMPMVroI6ZZH7aP7syIy1sPEsiJd3smWEULgeRElsuK+4I3YKG1Z1J2n80lmtR2gVD5tuKLFRRxnbjAqzyCEXQBTUVRc13SAFrH0b5bzFaqfxY1wdQPzP+dBSA+t25KcjnpTAAtL86H already exist for user opnfv.
2019-02-27 09:00:54,337 [salt.state       :1951][INFO    ][14027] Completed state [opnfv] at time 09:00:54.337515 duration_in_ms=48.917
2019-02-27 09:00:54,344 [salt.minion      :1711][INFO    ][14027] Returning information for job: 20190227090032370691
2019-02-27 09:00:55,126 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command state.apply with jid 20190227090055117425
2019-02-27 09:00:55,150 [salt.minion      :1432][INFO    ][14516] Starting a new job with PID 14516
2019-02-27 09:01:02,989 [salt.state       :915 ][INFO    ][14516] Loading fresh modules for state activity
2019-02-27 09:01:03,088 [salt.state       :1780][INFO    ][14516] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:01:03.088268
2019-02-27 09:01:03,088 [salt.state       :1813][INFO    ][14516] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-27 09:01:03,090 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14516] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-27 09:01:04,902 [salt.state       :300 ][INFO    ][14516] {'pid': 14545, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-27 09:01:04,903 [salt.state       :1951][INFO    ][14516] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:01:04.903563 duration_in_ms=1815.294
2019-02-27 09:01:04,905 [salt.state       :1780][INFO    ][14516] Running state [maas.process_machines] at time 09:01:04.905193
2019-02-27 09:01:04,905 [salt.state       :1813][INFO    ][14516] Executing state module.run for [maas.process_machines]
2019-02-27 09:01:04,906 [salt.utils.decorators:613 ][WARNING ][14516] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-27 09:01:05,378 [salt.loaded.ext.module.maas:412 ][WARNING ][14516] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-27 09:01:05,382 [salt.loaded.ext.module.maas:92  ][INFO    ][14516] 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=cmdkre architecture=amd64/generic power_parameters_power_user=opnfv
2019-02-27 09:01:06,393 [salt.loaded.ext.module.maas:412 ][WARNING ][14516] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-27 09:01:06,394 [salt.loaded.ext.module.maas:92  ][INFO    ][14516] 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=7spane architecture=amd64/generic power_parameters_power_user=opnfv
2019-02-27 09:01:07,381 [salt.loaded.ext.module.maas:412 ][WARNING ][14516] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-27 09:01:07,382 [salt.loaded.ext.module.maas:92  ][INFO    ][14516] 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=4ms4tr architecture=amd64/generic power_parameters_power_user=opnfv
2019-02-27 09:01:08,366 [salt.loaded.ext.module.maas:412 ][WARNING ][14516] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-27 09:01:08,369 [salt.loaded.ext.module.maas:92  ][INFO    ][14516] 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=en4nmm architecture=amd64/generic power_parameters_power_user=opnfv
2019-02-27 09:01:09,353 [salt.loaded.ext.module.maas:412 ][WARNING ][14516] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-27 09:01:09,354 [salt.loaded.ext.module.maas:92  ][INFO    ][14516] 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=hd8grr architecture=amd64/generic power_parameters_power_user=opnfv
2019-02-27 09:01:10,201 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227090110191738
2019-02-27 09:01:10,225 [salt.minion      :1432][INFO    ][14757] Starting a new job with PID 14757
2019-02-27 09:01:10,257 [salt.minion      :1711][INFO    ][14757] Returning information for job: 20190227090110191738
2019-02-27 09:01:10,348 [salt.state       :300 ][INFO    ][14516] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-02-27 09:01:10,348 [salt.state       :1951][INFO    ][14516] Completed state [maas.process_machines] at time 09:01:10.348476 duration_in_ms=5443.282
2019-02-27 09:01:10,350 [salt.minion      :1711][INFO    ][14516] Returning information for job: 20190227090055117425
2019-02-27 09:01:44,420 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command state.apply with jid 20190227090144410671
2019-02-27 09:01:44,435 [salt.minion      :1432][INFO    ][14808] Starting a new job with PID 14808
2019-02-27 09:01:52,389 [salt.state       :915 ][INFO    ][14808] Loading fresh modules for state activity
2019-02-27 09:01:52,488 [salt.state       :1780][INFO    ][14808] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:01:52.488461
2019-02-27 09:01:52,488 [salt.state       :1813][INFO    ][14808] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-27 09:01:52,490 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14808] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-27 09:01:54,329 [salt.state       :300 ][INFO    ][14808] {'pid': 14851, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-27 09:01:54,330 [salt.state       :1951][INFO    ][14808] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:01:54.330218 duration_in_ms=1841.756
2019-02-27 09:01:54,334 [salt.state       :1780][INFO    ][14808] Running state [maas.wait_for_machine_status] at time 09:01:54.334208
2019-02-27 09:01:54,334 [salt.state       :1813][INFO    ][14808] Executing state module.run for [maas.wait_for_machine_status]
2019-02-27 09:01:54,335 [salt.utils.decorators:613 ][WARNING ][14808] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-27 09:01:57,096 [salt.state       :300 ][INFO    ][14808] {'ret': True}
2019-02-27 09:01:57,096 [salt.state       :1951][INFO    ][14808] Completed state [maas.wait_for_machine_status] at time 09:01:57.096780 duration_in_ms=2762.571
2019-02-27 09:01:57,102 [salt.minion      :1711][INFO    ][14808] Returning information for job: 20190227090144410671
2019-02-27 09:01:57,848 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command state.apply with jid 20190227090157791798
2019-02-27 09:01:57,864 [salt.minion      :1432][INFO    ][14871] Starting a new job with PID 14871
2019-02-27 09:01:58,785 [salt.state       :915 ][INFO    ][14871] Loading fresh modules for state activity
2019-02-27 09:01:58,931 [salt.state       :1780][INFO    ][14871] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:01:58.931852
2019-02-27 09:01:58,932 [salt.state       :1813][INFO    ][14871] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-27 09:01:58,934 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14871] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-27 09:02:00,777 [salt.state       :300 ][INFO    ][14871] {'pid': 14878, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-27 09:02:00,779 [salt.state       :1951][INFO    ][14871] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:02:00.779237 duration_in_ms=1847.386
2019-02-27 09:02:00,784 [salt.state       :1780][INFO    ][14871] Running state [maas_machines_storage_cmp002_lvm] at time 09:02:00.784496
2019-02-27 09:02:00,785 [salt.state       :1813][INFO    ][14871] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-02-27 09:02:01,349 [salt.state       :300 ][INFO    ][14871] Machine cmp002 is not in Ready state.
2019-02-27 09:02:01,349 [salt.state       :1951][INFO    ][14871] Completed state [maas_machines_storage_cmp002_lvm] at time 09:02:01.349600 duration_in_ms=565.103
2019-02-27 09:02:01,350 [salt.state       :1780][INFO    ][14871] Running state [maas_machines_storage_cmp001_lvm] at time 09:02:01.349996
2019-02-27 09:02:01,350 [salt.state       :1813][INFO    ][14871] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-02-27 09:02:01,861 [salt.state       :300 ][INFO    ][14871] Machine cmp001 is not in Ready state.
2019-02-27 09:02:01,861 [salt.state       :1951][INFO    ][14871] Completed state [maas_machines_storage_cmp001_lvm] at time 09:02:01.861863 duration_in_ms=511.867
2019-02-27 09:02:01,866 [salt.minion      :1711][INFO    ][14871] Returning information for job: 20190227090157791798
2019-02-27 09:02:02,551 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command state.apply with jid 20190227090202541064
2019-02-27 09:02:02,574 [salt.minion      :1432][INFO    ][14888] Starting a new job with PID 14888
2019-02-27 09:02:03,453 [salt.state       :915 ][INFO    ][14888] Loading fresh modules for state activity
2019-02-27 09:02:03,547 [salt.state       :1780][INFO    ][14888] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:02:03.547013
2019-02-27 09:02:03,547 [salt.state       :1813][INFO    ][14888] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-27 09:02:03,549 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14888] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-27 09:02:05,350 [salt.state       :300 ][INFO    ][14888] {'pid': 14897, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-27 09:02:05,351 [salt.state       :1951][INFO    ][14888] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:02:05.351512 duration_in_ms=1804.498
2019-02-27 09:02:05,354 [salt.state       :1780][INFO    ][14888] Running state [maas.deploy_machines] at time 09:02:05.354622
2019-02-27 09:02:05,356 [salt.state       :1813][INFO    ][14888] Executing state module.run for [maas.deploy_machines]
2019-02-27 09:02:05,357 [salt.utils.decorators:613 ][WARNING ][14888] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-27 09:02:06,029 [salt.loaded.ext.module.maas:684 ][INFO    ][14888] deploymachines hwe_kernel=ga-16.04 system_id=hd8grr distro_series=xenial
2019-02-27 09:02:08,281 [salt.state       :300 ][INFO    ][14888] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-02-27 09:02:08,282 [salt.state       :1951][INFO    ][14888] Completed state [maas.deploy_machines] at time 09:02:08.282004 duration_in_ms=2927.382
2019-02-27 09:02:08,286 [salt.minion      :1711][INFO    ][14888] Returning information for job: 20190227090202541064
2019-02-27 09:02:08,976 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command state.apply with jid 20190227090208968496
2019-02-27 09:02:08,997 [salt.minion      :1432][INFO    ][14957] Starting a new job with PID 14957
2019-02-27 09:02:16,777 [salt.state       :915 ][INFO    ][14957] Loading fresh modules for state activity
2019-02-27 09:02:16,877 [salt.state       :1780][INFO    ][14957] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:02:16.877783
2019-02-27 09:02:16,878 [salt.state       :1813][INFO    ][14957] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-27 09:02:16,880 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14957] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-27 09:02:18,747 [salt.state       :300 ][INFO    ][14957] {'pid': 14974, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-27 09:02:18,748 [salt.state       :1951][INFO    ][14957] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:02:18.748117 duration_in_ms=1870.334
2019-02-27 09:02:18,749 [salt.state       :1780][INFO    ][14957] Running state [maas.wait_for_machine_status] at time 09:02:18.749682
2019-02-27 09:02:18,750 [salt.state       :1813][INFO    ][14957] Executing state module.run for [maas.wait_for_machine_status]
2019-02-27 09:02:18,750 [salt.utils.decorators:613 ][WARNING ][14957] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-27 09:02:21,391 [salt.loaded.ext.module.maas:1023][INFO    ][14957] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.37053609s left)
2019-02-27 09:02:24,086 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227090224069808
2019-02-27 09:02:24,108 [salt.minion      :1432][INFO    ][14985] Starting a new job with PID 14985
2019-02-27 09:02:24,139 [salt.minion      :1711][INFO    ][14985] Returning information for job: 20190227090224069808
2019-02-27 09:02:54,025 [salt.loaded.ext.module.maas:1023][INFO    ][14957] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.73662114s left)
2019-02-27 09:02:54,156 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227090254145258
2019-02-27 09:02:54,175 [salt.minion      :1432][INFO    ][15065] Starting a new job with PID 15065
2019-02-27 09:02:54,205 [salt.minion      :1711][INFO    ][15065] Returning information for job: 20190227090254145258
2019-02-27 09:03:24,205 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227090324197335
2019-02-27 09:03:24,224 [salt.minion      :1432][INFO    ][15082] Starting a new job with PID 15082
2019-02-27 09:03:24,252 [salt.minion      :1711][INFO    ][15082] Returning information for job: 20190227090324197335
2019-02-27 09:03:26,817 [salt.loaded.ext.module.maas:1023][INFO    ][14957] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2181.94433093s left)
2019-02-27 09:03:54,265 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227090354258235
2019-02-27 09:03:54,277 [salt.minion      :1432][INFO    ][15181] Starting a new job with PID 15181
2019-02-27 09:03:54,306 [salt.minion      :1711][INFO    ][15181] Returning information for job: 20190227090354258235
2019-02-27 09:03:59,598 [salt.loaded.ext.module.maas:1023][INFO    ][14957] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2149.16355991s left)
2019-02-27 09:04:24,316 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227090424301926
2019-02-27 09:04:24,338 [salt.minion      :1432][INFO    ][15206] Starting a new job with PID 15206
2019-02-27 09:04:24,368 [salt.minion      :1711][INFO    ][15206] Returning information for job: 20190227090424301926
2019-02-27 09:04:32,256 [salt.loaded.ext.module.maas:1023][INFO    ][14957] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2116.50523114s left)
2019-02-27 09:04:54,405 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227090454390257
2019-02-27 09:04:54,427 [salt.minion      :1432][INFO    ][15290] Starting a new job with PID 15290
2019-02-27 09:04:54,457 [salt.minion      :1711][INFO    ][15290] Returning information for job: 20190227090454390257
2019-02-27 09:05:04,869 [salt.loaded.ext.module.maas:1023][INFO    ][14957] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2083.89221191s left)
2019-02-27 09:05:24,472 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227090524452862
2019-02-27 09:05:24,491 [salt.minion      :1432][INFO    ][15311] Starting a new job with PID 15311
2019-02-27 09:05:24,521 [salt.minion      :1711][INFO    ][15311] Returning information for job: 20190227090524452862
2019-02-27 09:05:37,483 [salt.loaded.ext.module.maas:1023][INFO    ][14957] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2051.27895904s left)
2019-02-27 09:05:54,549 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227090554536878
2019-02-27 09:05:54,566 [salt.minion      :1432][INFO    ][15391] Starting a new job with PID 15391
2019-02-27 09:05:54,597 [salt.minion      :1711][INFO    ][15391] Returning information for job: 20190227090554536878
2019-02-27 09:06:10,033 [salt.loaded.ext.module.maas:1023][INFO    ][14957] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2018.7285111s left)
2019-02-27 09:06:24,614 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227090624606762
2019-02-27 09:06:24,632 [salt.minion      :1432][INFO    ][15437] Starting a new job with PID 15437
2019-02-27 09:06:24,659 [salt.minion      :1711][INFO    ][15437] Returning information for job: 20190227090624606762
2019-02-27 09:06:42,912 [salt.loaded.ext.module.maas:1023][INFO    ][14957] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1985.84973598s left)
2019-02-27 09:06:54,692 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227090654675732
2019-02-27 09:06:54,716 [salt.minion      :1432][INFO    ][15563] Starting a new job with PID 15563
2019-02-27 09:06:54,748 [salt.minion      :1711][INFO    ][15563] Returning information for job: 20190227090654675732
2019-02-27 09:07:15,582 [salt.loaded.ext.module.maas:1023][INFO    ][14957] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1953.17929602s left)
2019-02-27 09:07:24,789 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227090724775290
2019-02-27 09:07:24,810 [salt.minion      :1432][INFO    ][15589] Starting a new job with PID 15589
2019-02-27 09:07:24,846 [salt.minion      :1711][INFO    ][15589] Returning information for job: 20190227090724775290
2019-02-27 09:07:48,386 [salt.loaded.ext.module.maas:1023][INFO    ][14957] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1920.37597609s left)
2019-02-27 09:07:54,917 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227090754903734
2019-02-27 09:07:54,944 [salt.minion      :1432][INFO    ][15806] Starting a new job with PID 15806
2019-02-27 09:07:54,973 [salt.minion      :1711][INFO    ][15806] Returning information for job: 20190227090754903734
2019-02-27 09:08:21,042 [salt.loaded.ext.module.maas:1023][INFO    ][14957] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1887.71950102s left)
2019-02-27 09:08:24,944 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227090824931354
2019-02-27 09:08:24,968 [salt.minion      :1432][INFO    ][15829] Starting a new job with PID 15829
2019-02-27 09:08:24,997 [salt.minion      :1711][INFO    ][15829] Returning information for job: 20190227090824931354
2019-02-27 09:08:53,829 [salt.loaded.ext.module.maas:1023][INFO    ][14957] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1854.93284392s left)
2019-02-27 09:08:55,068 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227090855054734
2019-02-27 09:08:55,087 [salt.minion      :1432][INFO    ][15930] Starting a new job with PID 15930
2019-02-27 09:08:55,116 [salt.minion      :1711][INFO    ][15930] Returning information for job: 20190227090855054734
2019-02-27 09:09:25,158 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227090925149050
2019-02-27 09:09:25,174 [salt.minion      :1432][INFO    ][15953] Starting a new job with PID 15953
2019-02-27 09:09:25,208 [salt.minion      :1711][INFO    ][15953] Returning information for job: 20190227090925149050
2019-02-27 09:09:26,502 [salt.loaded.ext.module.maas:1023][INFO    ][14957] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1822.25981903s left)
2019-02-27 09:09:55,321 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227090955308678
2019-02-27 09:09:55,339 [salt.minion      :1432][INFO    ][16085] Starting a new job with PID 16085
2019-02-27 09:09:55,369 [salt.minion      :1711][INFO    ][16085] Returning information for job: 20190227090955308678
2019-02-27 09:09:59,441 [salt.loaded.ext.module.maas:1023][INFO    ][14957] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1789.32076502s left)
2019-02-27 09:10:25,446 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227091025433540
2019-02-27 09:10:25,464 [salt.minion      :1432][INFO    ][16106] Starting a new job with PID 16106
2019-02-27 09:10:25,493 [salt.minion      :1711][INFO    ][16106] Returning information for job: 20190227091025433540
2019-02-27 09:10:31,921 [salt.loaded.ext.module.maas:1023][INFO    ][14957] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1756.84040403s left)
2019-02-27 09:10:55,594 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227091055579448
2019-02-27 09:10:55,616 [salt.minion      :1432][INFO    ][16257] Starting a new job with PID 16257
2019-02-27 09:10:55,650 [salt.minion      :1711][INFO    ][16257] Returning information for job: 20190227091055579448
2019-02-27 09:11:04,697 [salt.loaded.ext.module.maas:1023][INFO    ][14957] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1724.06470203s left)
2019-02-27 09:11:25,734 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227091125725814
2019-02-27 09:11:25,759 [salt.minion      :1432][INFO    ][16277] Starting a new job with PID 16277
2019-02-27 09:11:25,788 [salt.minion      :1711][INFO    ][16277] Returning information for job: 20190227091125725814
2019-02-27 09:11:37,293 [salt.loaded.ext.module.maas:1023][INFO    ][14957] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1691.46891809s left)
2019-02-27 09:11:55,884 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227091155870214
2019-02-27 09:11:55,905 [salt.minion      :1432][INFO    ][16360] Starting a new job with PID 16360
2019-02-27 09:11:55,940 [salt.minion      :1711][INFO    ][16360] Returning information for job: 20190227091155870214
2019-02-27 09:12:10,175 [salt.loaded.ext.module.maas:1023][INFO    ][14957] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1658.58670592s left)
2019-02-27 09:12:26,033 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227091226024421
2019-02-27 09:12:26,051 [salt.minion      :1432][INFO    ][16382] Starting a new job with PID 16382
2019-02-27 09:12:26,083 [salt.minion      :1711][INFO    ][16382] Returning information for job: 20190227091226024421
2019-02-27 09:12:42,755 [salt.loaded.ext.module.maas:1023][INFO    ][14957] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1626.00665998s left)
2019-02-27 09:12:56,166 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227091256157872
2019-02-27 09:12:56,182 [salt.minion      :1432][INFO    ][16463] Starting a new job with PID 16463
2019-02-27 09:12:56,213 [salt.minion      :1711][INFO    ][16463] Returning information for job: 20190227091256157872
2019-02-27 09:13:15,636 [salt.loaded.ext.module.maas:1023][INFO    ][14957] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1593.12606406s left)
2019-02-27 09:13:26,335 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227091326324211
2019-02-27 09:13:26,357 [salt.minion      :1432][INFO    ][16483] Starting a new job with PID 16483
2019-02-27 09:13:26,388 [salt.minion      :1711][INFO    ][16483] Returning information for job: 20190227091326324211
2019-02-27 09:13:34,790 [salt.utils.schedule:1377][INFO    ][3148] Running scheduled job: __mine_interval
2019-02-27 09:13:48,206 [salt.loaded.ext.module.maas:1023][INFO    ][14957] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1560.55533099s left)
2019-02-27 09:13:56,526 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190227091356515968
2019-02-27 09:13:56,546 [salt.minion      :1432][INFO    ][16743] Starting a new job with PID 16743
2019-02-27 09:13:56,576 [salt.minion      :1711][INFO    ][16743] Returning information for job: 20190227091356515968
2019-02-27 09:14:20,973 [salt.state       :300 ][INFO    ][14957] {'ret': True}
2019-02-27 09:14:20,973 [salt.state       :1951][INFO    ][14957] Completed state [maas.wait_for_machine_status] at time 09:14:20.973516 duration_in_ms=722223.828
2019-02-27 09:14:20,977 [salt.minion      :1711][INFO    ][14957] Returning information for job: 20190227090208968496
2019-02-27 10:13:34,790 [salt.utils.schedule:1377][INFO    ][3148] Running scheduled job: __mine_interval
2019-02-27 10:24:12,609 [salt.minion      :1308][INFO    ][3148] User sudo_ubuntu Executing command cp.push_dir with jid 20190227102412600677
2019-02-27 10:24:12,626 [salt.minion      :1432][INFO    ][24034] Starting a new job with PID 24034
