2019-06-19 20:11:47,772 [salt.utils.decorators:613 ][WARNING ][1924] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-19 20:11:48,741 [salt.utils.decorators:613 ][WARNING ][1924] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-19 20:11:51,667 [salt.loaded.int.states.file:2298][WARNING ][2387] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-06-19 20:12:14,225 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2908] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-06-19 20:12:14,262 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2908] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-06-19 20:12:14,302 [salt.utils.parsers:1051][WARNING ][378] Minion received a SIGTERM. Exiting.
2019-06-19 20:12:15,303 [salt.cli.daemons :293 ][INFO    ][3024] Setting up the Salt Minion "mas01.mcp-fdio-noha.local"
2019-06-19 20:12:15,428 [salt.cli.daemons :82  ][INFO    ][3024] Starting up the Salt Minion
2019-06-19 20:12:15,428 [salt.utils.event :1017][INFO    ][3024] Starting pull socket on /var/run/salt/minion/minion_event_38d774b16c_pull.ipc
2019-06-19 20:12:16,648 [salt.minion      :976 ][INFO    ][3024] Creating minion process manager
2019-06-19 20:12:18,536 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][3024] Executing command ['date', '+%z'] in directory '/root'
2019-06-19 20:12:18,561 [salt.utils.schedule:568 ][INFO    ][3024] Updating job settings for scheduled job: __mine_interval
2019-06-19 20:12:18,565 [salt.minion      :1108][INFO    ][3024] Added mine.update to scheduler
2019-06-19 20:12:18,573 [salt.minion      :1975][INFO    ][3024] Minion is starting as user 'root'
2019-06-19 20:12:18,586 [salt.minion      :2336][INFO    ][3024] Minion is ready to receive requests!
2019-06-19 20:12:23,012 [salt.state       :2022][WARNING ][2912] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-06-19 20:12:26,108 [salt.utils.decorators:613 ][WARNING ][2912] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-19 20:12:27,120 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619201227104728
2019-06-19 20:12:27,143 [salt.minion      :1432][INFO    ][3415] Starting a new job with PID 3415
2019-06-19 20:12:27,172 [salt.minion      :1711][INFO    ][3415] Returning information for job: 20190619201227104728
2019-06-19 20:12:57,239 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619201257219929
2019-06-19 20:12:57,269 [salt.minion      :1432][INFO    ][3608] Starting a new job with PID 3608
2019-06-19 20:12:57,294 [salt.minion      :1711][INFO    ][3608] Returning information for job: 20190619201257219929
2019-06-19 20:13:27,347 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619201327334139
2019-06-19 20:13:27,368 [salt.minion      :1432][INFO    ][3897] Starting a new job with PID 3897
2019-06-19 20:13:27,393 [salt.minion      :1711][INFO    ][3897] Returning information for job: 20190619201327334139
2019-06-19 20:13:33,003 [salt.utils.decorators:613 ][WARNING ][2912] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-19 20:13:57,440 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619201357430230
2019-06-19 20:13:57,467 [salt.minion      :1432][INFO    ][4177] Starting a new job with PID 4177
2019-06-19 20:13:57,490 [salt.minion      :1711][INFO    ][4177] Returning information for job: 20190619201357430230
2019-06-19 20:14:00,154 [salt.utils.decorators:613 ][WARNING ][2912] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-19 20:14:01,204 [salt.utils.decorators:613 ][WARNING ][2912] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-19 20:14:03,632 [salt.loaded.ext.module.maasng:1008][WARNING ][2912] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-06-19 20:14:03,632 [salt.loaded.ext.module.maasng:1011][WARNING ][2912] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-06-19 20:14:04,457 [salt.loaded.ext.module.maasng:1235][WARNING ][2912] Ignoring parameter vlan:0
2019-06-19 20:14:05,353 [salt.utils.decorators:613 ][WARNING ][2912] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-19 20:14:06,109 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command state.apply with jid 20190619201406099471
2019-06-19 20:14:06,127 [salt.minion      :1432][INFO    ][4525] Starting a new job with PID 4525
2019-06-19 20:14:12,152 [salt.state       :915 ][INFO    ][4525] Loading fresh modules for state activity
2019-06-19 20:14:12,231 [salt.fileclient  :1219][INFO    ][4525] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-06-19 20:14:12,282 [salt.state       :1780][INFO    ][4525] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:14:12.281937
2019-06-19 20:14:12,282 [salt.state       :1813][INFO    ][4525] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-19 20:14:12,284 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4525] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-19 20:14:14,053 [salt.state       :300 ][INFO    ][4525] {'pid': 4550, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-19 20:14:14,054 [salt.state       :1951][INFO    ][4525] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:14:14.054714 duration_in_ms=1772.775
2019-06-19 20:14:14,057 [salt.state       :1780][INFO    ][4525] Running state [maas.process_machines] at time 20:14:14.057708
2019-06-19 20:14:14,058 [salt.state       :1813][INFO    ][4525] Executing state module.run for [maas.process_machines]
2019-06-19 20:14:14,059 [salt.utils.decorators:613 ][WARNING ][4525] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-19 20:14:14,123 [salt.loaded.ext.module.maas:412 ][WARNING ][4525] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-19 20:14:14,123 [salt.loaded.ext.module.maas:92  ][INFO    ][4525] machine hostname=gtw01 power_type=ipmi mac_addresses=14:58:d0:54:6a:60 power_parameters_power_address=172.16.1.17 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-19 20:14:15,786 [salt.loaded.ext.module.maas:412 ][WARNING ][4525] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-19 20:14:15,786 [salt.loaded.ext.module.maas:92  ][INFO    ][4525] machine hostname=cmp002 power_type=ipmi mac_addresses=9c:b6:54:8a:10:18 power_parameters_power_address=172.16.1.20 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-19 20:14:17,279 [salt.loaded.ext.module.maas:412 ][WARNING ][4525] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-19 20:14:17,279 [salt.loaded.ext.module.maas:92  ][INFO    ][4525] machine hostname=cmp001 power_type=ipmi mac_addresses=9c:b6:54:8a:95:a0 power_parameters_power_address=172.16.1.19 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-19 20:14:18,672 [salt.loaded.ext.module.maas:412 ][WARNING ][4525] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-19 20:14:18,672 [salt.loaded.ext.module.maas:92  ][INFO    ][4525] machine hostname=ctl01 power_type=ipmi mac_addresses=14:58:d0:54:e7:88 power_parameters_power_address=172.16.1.16 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-19 20:14:20,108 [salt.state       :300 ][INFO    ][4525] {'ret': {'updated': [], 'errors': {}, 'success': ['gtw01', 'cmp002', 'cmp001', 'ctl01']}}
2019-06-19 20:14:20,108 [salt.state       :1951][INFO    ][4525] Completed state [maas.process_machines] at time 20:14:20.108430 duration_in_ms=6050.722
2019-06-19 20:14:20,113 [salt.minion      :1711][INFO    ][4525] Returning information for job: 20190619201406099471
2019-06-19 20:14:51,195 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command state.apply with jid 20190619201451181411
2019-06-19 20:14:51,217 [salt.minion      :1432][INFO    ][4828] Starting a new job with PID 4828
2019-06-19 20:14:57,175 [salt.state       :915 ][INFO    ][4828] Loading fresh modules for state activity
2019-06-19 20:14:57,235 [salt.fileclient  :1219][INFO    ][4828] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-06-19 20:14:57,283 [salt.state       :1780][INFO    ][4828] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:14:57.283245
2019-06-19 20:14:57,283 [salt.state       :1813][INFO    ][4828] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-19 20:14:57,285 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4828] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-19 20:14:59,008 [salt.state       :300 ][INFO    ][4828] {'pid': 4838, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-19 20:14:59,009 [salt.state       :1951][INFO    ][4828] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:14:59.009174 duration_in_ms=1725.929
2019-06-19 20:14:59,012 [salt.state       :1780][INFO    ][4828] Running state [maas.wait_for_machine_status] at time 20:14:59.012665
2019-06-19 20:14:59,013 [salt.state       :1813][INFO    ][4828] Executing state module.run for [maas.wait_for_machine_status]
2019-06-19 20:14:59,014 [salt.utils.decorators:613 ][WARNING ][4828] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-19 20:14:59,636 [salt.loaded.ext.module.maas:1023][INFO    ][4828] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1499.38915205s left)
2019-06-19 20:15:06,248 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619201506233505
2019-06-19 20:15:06,276 [salt.minion      :1432][INFO    ][4850] Starting a new job with PID 4850
2019-06-19 20:15:06,295 [salt.minion      :1711][INFO    ][4850] Returning information for job: 20190619201506233505
2019-06-19 20:15:30,344 [salt.loaded.ext.module.maas:1023][INFO    ][4828] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1468.68107796s left)
2019-06-19 20:15:36,332 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619201536318746
2019-06-19 20:15:36,352 [salt.minion      :1432][INFO    ][4899] Starting a new job with PID 4899
2019-06-19 20:15:36,372 [salt.minion      :1711][INFO    ][4899] Returning information for job: 20190619201536318746
2019-06-19 20:16:00,941 [salt.loaded.ext.module.maas:1023][INFO    ][4828] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1438.08438897s left)
2019-06-19 20:16:06,401 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619201606386226
2019-06-19 20:16:06,433 [salt.minion      :1432][INFO    ][4919] Starting a new job with PID 4919
2019-06-19 20:16:06,452 [salt.minion      :1711][INFO    ][4919] Returning information for job: 20190619201606386226
2019-06-19 20:16:31,577 [salt.loaded.ext.module.maas:1023][INFO    ][4828] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1407.44817805s left)
2019-06-19 20:16:36,488 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619201636472739
2019-06-19 20:16:36,516 [salt.minion      :1432][INFO    ][4972] Starting a new job with PID 4972
2019-06-19 20:16:36,537 [salt.minion      :1711][INFO    ][4972] Returning information for job: 20190619201636472739
2019-06-19 20:17:02,392 [salt.loaded.ext.module.maas:1023][INFO    ][4828] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1376.63343596s left)
2019-06-19 20:17:06,592 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619201706577212
2019-06-19 20:17:06,619 [salt.minion      :1432][INFO    ][5021] Starting a new job with PID 5021
2019-06-19 20:17:06,640 [salt.minion      :1711][INFO    ][5021] Returning information for job: 20190619201706577212
2019-06-19 20:17:33,260 [salt.loaded.ext.module.maas:1023][INFO    ][4828] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1345.76514196s left)
2019-06-19 20:17:36,697 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619201736683329
2019-06-19 20:17:36,720 [salt.minion      :1432][INFO    ][5186] Starting a new job with PID 5186
2019-06-19 20:17:36,739 [salt.minion      :1711][INFO    ][5186] Returning information for job: 20190619201736683329
2019-06-19 20:18:04,188 [salt.loaded.ext.module.maas:1023][INFO    ][4828] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1314.83730793s left)
2019-06-19 20:18:06,785 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619201806770843
2019-06-19 20:18:06,811 [salt.minion      :1432][INFO    ][5254] Starting a new job with PID 5254
2019-06-19 20:18:06,831 [salt.minion      :1711][INFO    ][5254] Returning information for job: 20190619201806770843
2019-06-19 20:18:35,167 [salt.loaded.ext.module.maas:1023][INFO    ][4828] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1283.85836005s left)
2019-06-19 20:18:36,924 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619201836910452
2019-06-19 20:18:36,945 [salt.minion      :1432][INFO    ][5579] Starting a new job with PID 5579
2019-06-19 20:18:36,967 [salt.minion      :1711][INFO    ][5579] Returning information for job: 20190619201836910452
2019-06-19 20:19:06,483 [salt.loaded.ext.module.maas:1023][INFO    ][4828] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1252.54195189s left)
2019-06-19 20:19:06,988 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619201906975594
2019-06-19 20:19:07,012 [salt.minion      :1432][INFO    ][5676] Starting a new job with PID 5676
2019-06-19 20:19:07,031 [salt.minion      :1711][INFO    ][5676] Returning information for job: 20190619201906975594
2019-06-19 20:19:37,124 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619201937113187
2019-06-19 20:19:37,148 [salt.minion      :1432][INFO    ][5974] Starting a new job with PID 5974
2019-06-19 20:19:37,175 [salt.minion      :1711][INFO    ][5974] Returning information for job: 20190619201937113187
2019-06-19 20:19:38,049 [salt.loaded.ext.module.maas:1023][INFO    ][4828] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1220.97625089s left)
2019-06-19 20:20:07,232 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619202007219845
2019-06-19 20:20:07,263 [salt.minion      :1432][INFO    ][6063] Starting a new job with PID 6063
2019-06-19 20:20:07,283 [salt.minion      :1711][INFO    ][6063] Returning information for job: 20190619202007219845
2019-06-19 20:20:09,646 [salt.loaded.ext.module.maas:1023][INFO    ][4828] Waiting status:Ready|Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:1500s (1189.37979102s left)
2019-06-19 20:20:37,363 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619202037346767
2019-06-19 20:20:37,396 [salt.minion      :1432][INFO    ][6180] Starting a new job with PID 6180
2019-06-19 20:20:37,420 [salt.minion      :1711][INFO    ][6180] Returning information for job: 20190619202037346767
2019-06-19 20:20:41,632 [salt.loaded.ext.module.maas:1023][INFO    ][4828] Waiting status:Ready|Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:1500s (1157.39303398s left)
2019-06-19 20:21:07,482 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619202107471150
2019-06-19 20:21:07,500 [salt.minion      :1432][INFO    ][6289] Starting a new job with PID 6289
2019-06-19 20:21:07,523 [salt.minion      :1711][INFO    ][6289] Returning information for job: 20190619202107471150
2019-06-19 20:21:13,925 [salt.state       :300 ][INFO    ][4828] {'ret': True}
2019-06-19 20:21:13,926 [salt.state       :1951][INFO    ][4828] Completed state [maas.wait_for_machine_status] at time 20:21:13.926289 duration_in_ms=374913.622
2019-06-19 20:21:13,931 [salt.minion      :1711][INFO    ][4828] Returning information for job: 20190619201451181411
2019-06-19 20:21:14,524 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command state.apply with jid 20190619202114511233
2019-06-19 20:21:14,553 [salt.minion      :1432][INFO    ][6322] Starting a new job with PID 6322
2019-06-19 20:21:20,667 [salt.state       :915 ][INFO    ][6322] Loading fresh modules for state activity
2019-06-19 20:21:20,729 [salt.fileclient  :1219][INFO    ][6322] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-06-19 20:21:20,826 [salt.state       :1780][INFO    ][6322] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:21:20.826477
2019-06-19 20:21:20,826 [salt.state       :1813][INFO    ][6322] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-19 20:21:20,828 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6322] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-19 20:21:22,563 [salt.state       :300 ][INFO    ][6322] {'pid': 6348, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-19 20:21:22,564 [salt.state       :1951][INFO    ][6322] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:21:22.564114 duration_in_ms=1737.636
2019-06-19 20:21:22,568 [salt.state       :1780][INFO    ][6322] Running state [maas_machines_storage_cmp002_lvm] at time 20:21:22.568434
2019-06-19 20:21:22,569 [salt.state       :1813][INFO    ][6322] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-06-19 20:21:23,656 [salt.loaded.ext.module.maasng:610 ][INFO    ][6322] exd4t6
2019-06-19 20:21:23,657 [salt.loaded.ext.module.maasng:626 ][INFO    ][6322] sda
2019-06-19 20:21:24,161 [salt.loaded.ext.module.maasng:361 ][INFO    ][6322] exd4t6
2019-06-19 20:21:24,252 [salt.loaded.ext.module.maasng:367 ][INFO    ][6322] [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'9d7a98e2-d7c7-4eb2-ab8c-fde36bcce82a', u'resource_uri': u'/MAAS/api/2.0/nodes/exd4t6/blockdevices/2/partition/1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'exd4t6', u'filesystem': {u'uuid': u'7bff3a80-d711-409a-bc9d-a5b9e9def8f2', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 1, u'device_id': 2}], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'exd4t6', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/exd4t6/blockdevices/2/', u'used_size': 800106479616, u'id': 2, u'size': 800109715456}, {u'model': None, u'block_size': 4096, u'uuid': u'a4771261-eca0-453d-994d-486201266a8b', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'exd4t6', u'partition_table_type': None, u'filesystem': {u'uuid': u'87afec2b-b712-49cd-9fce-568fa5426475', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/exd4t6/blockdevices/4/', u'used_size': 800097042432, u'id': 4, u'size': 800097042432}]
2019-06-19 20:21:24,253 [salt.loaded.ext.module.maasng:632 ][INFO    ][6322] vgroot
2019-06-19 20:21:24,253 [salt.loaded.ext.module.maasng:635 ][INFO    ][6322] lvroot
2019-06-19 20:21:24,254 [salt.loaded.ext.module.maasng:639 ][INFO    ][6322] 107374182400
2019-06-19 20:21:24,863 [salt.loaded.ext.module.maasng:645 ][INFO    ][6322] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'swap_size': None, u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'commissioning_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'status_action': u'', u'blockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'541d4178-e88f-4a59-8ceb-b15e90228494', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'exd4t6', u'device_id': 2, u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'800f4a14-a8de-4cd5-8034-95e3986b354f', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/exd4t6/blockdevices/2/partition/5'}], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'exd4t6', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'type': u'physical', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/exd4t6/blockdevices/2/'}, {u'size': 107374182400, u'model': None, u'available_size': 0, u'uuid': u'ef32e880-edfa-429c-a936-9aa18480f060', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'exd4t6', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'uuid': u'9bdebea2-24e8-4da8-9086-ea1daa008df6', u'fstype': u'ext4', u'mount_options': None}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 10, u'resource_uri': u'/MAAS/api/2.0/nodes/exd4t6/blockdevices/10/'}], u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'description': u''}, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'commissioning_status': 2, u'testing_status': 2, u'system_id': u'exd4t6', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'available_size': 0, u'uuid': u'ef32e880-edfa-429c-a936-9aa18480f060', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'exd4t6', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'uuid': u'9bdebea2-24e8-4da8-9086-ea1daa008df6', u'fstype': u'ext4', u'mount_options': None}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 10, u'resource_uri': u'/MAAS/api/2.0/nodes/exd4t6/blockdevices/10/'}], u'architecture': u'amd64/generic', u'status': 4, u'storage_test_status': 2, u'cpu_count': 40, u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'exd4t6', u'id': 5}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'boot_disk': {u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'541d4178-e88f-4a59-8ceb-b15e90228494', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'exd4t6', u'device_id': 2, u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'800f4a14-a8de-4cd5-8034-95e3986b354f', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/exd4t6/blockdevices/2/partition/5'}], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'exd4t6', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'type': u'physical', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/exd4t6/blockdevices/2/'}, u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'7br3n6', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 16}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'7br3n6', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'7br3n6', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'children': [], u'system_id': u'exd4t6', u'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/exd4t6/interfaces/5/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'children': [], u'system_id': u'exd4t6', u'mac_address': u'38:ea:a7:8f:07:50', u'params': u'', u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/exd4t6/interfaces/14/'}, {u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 17}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'children': [], u'system_id': u'exd4t6', u'mac_address': u'38:ea:a7:8f:12:48', u'params': u'', u'type': u'physical', u'id': 10, u'resource_uri': u'/MAAS/api/2.0/nodes/exd4t6/interfaces/10/'}, {u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 18}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'children': [], u'system_id': u'exd4t6', u'mac_address': u'38:ea:a7:8f:12:49', u'params': u'', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/exd4t6/interfaces/12/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'7br3n6', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'mode': u'link_up', u'id': 19}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'7br3n6', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'7br3n6', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40'}], u'children': [], u'system_id': u'exd4t6', u'mac_address': u'9c:b6:54:8a:10:1c', u'params': u'', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/exd4t6/interfaces/13/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'children': [], u'system_id': u'exd4t6', u'mac_address': u'38:ea:a7:8f:07:51', u'params': u'', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/exd4t6/interfaces/11/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'541d4178-e88f-4a59-8ceb-b15e90228494', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'exd4t6', u'device_id': 2, u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'800f4a14-a8de-4cd5-8034-95e3986b354f', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/exd4t6/blockdevices/2/partition/5'}], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'exd4t6', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'type': u'physical', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/exd4t6/blockdevices/2/'}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'min_hwe_kernel': u'hwe-16.04', u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'7br3n6', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 16}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'7br3n6', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'7br3n6', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'children': [], u'system_id': u'exd4t6', u'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/exd4t6/interfaces/5/'}, u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/exd4t6/', u'distro_series': u'', u'memory_test_status': -1}
2019-06-19 20:21:24,868 [salt.state       :300 ][INFO    ][6322] {'new': {'storage_layout': 'lvm'}}
2019-06-19 20:21:24,868 [salt.state       :1951][INFO    ][6322] Completed state [maas_machines_storage_cmp002_lvm] at time 20:21:24.868200 duration_in_ms=2299.766
2019-06-19 20:21:24,868 [salt.state       :1780][INFO    ][6322] Running state [maas_machines_storage_cmp001_lvm] at time 20:21:24.868556
2019-06-19 20:21:24,868 [salt.state       :1813][INFO    ][6322] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-06-19 20:21:25,823 [salt.loaded.ext.module.maasng:610 ][INFO    ][6322] qpb4tt
2019-06-19 20:21:25,823 [salt.loaded.ext.module.maasng:626 ][INFO    ][6322] sda
2019-06-19 20:21:26,362 [salt.loaded.ext.module.maasng:361 ][INFO    ][6322] qpb4tt
2019-06-19 20:21:26,513 [salt.loaded.ext.module.maasng:367 ][INFO    ][6322] [{u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'75cb8314-86e8-4dcc-9392-91741580adc4', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qpb4tt', u'device_id': 3, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'31a56585-549e-4d5a-aa4f-95bdb692b620', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/qpb4tt/blockdevices/3/partition/2', u'type': u'partition', u'id': 2, u'size': 800101236736}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'qpb4tt', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'size': 800109715456, u'used_size': 800106479616, u'id': 3, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/qpb4tt/blockdevices/3/'}, {u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'2edebf7a-0dbb-4a58-b994-97912d9d125d', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'qpb4tt', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'aa39dbe5-7919-4f55-afc0-4214bd06ab5e', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'size': 800097042432, u'used_size': 800097042432, u'id': 5, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/qpb4tt/blockdevices/5/'}]
2019-06-19 20:21:26,514 [salt.loaded.ext.module.maasng:632 ][INFO    ][6322] vgroot
2019-06-19 20:21:26,514 [salt.loaded.ext.module.maasng:635 ][INFO    ][6322] lvroot
2019-06-19 20:21:26,514 [salt.loaded.ext.module.maasng:639 ][INFO    ][6322] 107374182400
2019-06-19 20:21:27,120 [salt.loaded.ext.module.maasng:645 ][INFO    ][6322] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'swap_size': None, u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'commissioning_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'status_action': u'', u'blockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'70074ec1-4c2a-4034-841b-75fdf1f59cb3', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qpb4tt', u'device_id': 3, u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'6804ba9d-a319-4e27-97d8-2115555f584c', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/qpb4tt/blockdevices/3/partition/6'}], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'qpb4tt', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'type': u'physical', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/nodes/qpb4tt/blockdevices/3/'}, {u'size': 107374182400, u'model': None, u'available_size': 0, u'uuid': u'b12260ec-ff2d-4e56-ba79-3efec0edddaf', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'qpb4tt', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'uuid': u'd6e3edbe-e93c-4f35-bd0b-ece7cd9c8277', u'fstype': u'ext4', u'mount_options': None}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/qpb4tt/blockdevices/11/'}], u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'description': u''}, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'commissioning_status': 2, u'testing_status': 2, u'system_id': u'qpb4tt', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'available_size': 0, u'uuid': u'b12260ec-ff2d-4e56-ba79-3efec0edddaf', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'qpb4tt', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'uuid': u'd6e3edbe-e93c-4f35-bd0b-ece7cd9c8277', u'fstype': u'ext4', u'mount_options': None}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/qpb4tt/blockdevices/11/'}], u'architecture': u'amd64/generic', u'status': 4, u'storage_test_status': 2, u'cpu_count': 40, u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'qpb4tt', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 6, u'boot_disk': {u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'70074ec1-4c2a-4034-841b-75fdf1f59cb3', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qpb4tt', u'device_id': 3, u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'6804ba9d-a319-4e27-97d8-2115555f584c', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/qpb4tt/blockdevices/3/partition/6'}], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'qpb4tt', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'type': u'physical', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/nodes/qpb4tt/blockdevices/3/'}, u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'7br3n6', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 22}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'7br3n6', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'7br3n6', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'children': [], u'system_id': u'qpb4tt', u'mac_address': u'9c:b6:54:8a:95:a0', u'params': u'', u'type': u'physical', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/qpb4tt/interfaces/6/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'children': [], u'system_id': u'qpb4tt', u'mac_address': u'38:ea:a7:8f:1f:d5', u'params': u'', u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/qpb4tt/interfaces/16/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'children': [], u'system_id': u'qpb4tt', u'mac_address': u'38:ea:a7:8f:1f:d4', u'params': u'', u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/qpb4tt/interfaces/19/'}, {u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 23}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'children': [], u'system_id': u'qpb4tt', u'mac_address': u'38:ea:a7:8f:52:cc', u'params': u'', u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/qpb4tt/interfaces/15/'}, {u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 24}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'children': [], u'system_id': u'qpb4tt', u'mac_address': u'38:ea:a7:8f:52:cd', u'params': u'', u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/qpb4tt/interfaces/17/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'7br3n6', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'mode': u'link_up', u'id': 25}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'7br3n6', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'7br3n6', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.43'}], u'children': [], u'system_id': u'qpb4tt', u'mac_address': u'9c:b6:54:8a:95:a4', u'params': u'', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/qpb4tt/interfaces/18/'}], u'current_testing_result_id': 7, u'cpu_test_status': -1, u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'70074ec1-4c2a-4034-841b-75fdf1f59cb3', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qpb4tt', u'device_id': 3, u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'6804ba9d-a319-4e27-97d8-2115555f584c', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/qpb4tt/blockdevices/3/partition/6'}], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'qpb4tt', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'type': u'physical', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/nodes/qpb4tt/blockdevices/3/'}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'ip_addresses': [u'192.168.11.39', u'192.168.11.43'], u'min_hwe_kernel': u'hwe-16.04', u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'7br3n6', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 22}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'7br3n6', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'7br3n6', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'children': [], u'system_id': u'qpb4tt', u'mac_address': u'9c:b6:54:8a:95:a0', u'params': u'', u'type': u'physical', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/qpb4tt/interfaces/6/'}, u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/qpb4tt/', u'distro_series': u'', u'memory_test_status': -1}
2019-06-19 20:21:27,124 [salt.state       :300 ][INFO    ][6322] {'new': {'storage_layout': 'lvm'}}
2019-06-19 20:21:27,124 [salt.state       :1951][INFO    ][6322] Completed state [maas_machines_storage_cmp001_lvm] at time 20:21:27.124505 duration_in_ms=2255.946
2019-06-19 20:21:27,130 [salt.minion      :1711][INFO    ][6322] Returning information for job: 20190619202114511233
2019-06-19 20:21:27,695 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command state.apply with jid 20190619202127680009
2019-06-19 20:21:27,720 [salt.minion      :1432][INFO    ][6383] Starting a new job with PID 6383
2019-06-19 20:21:28,586 [salt.state       :915 ][INFO    ][6383] Loading fresh modules for state activity
2019-06-19 20:21:28,636 [salt.fileclient  :1219][INFO    ][6383] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-06-19 20:21:28,689 [salt.state       :1780][INFO    ][6383] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:21:28.689840
2019-06-19 20:21:28,690 [salt.state       :1813][INFO    ][6383] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-19 20:21:28,691 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6383] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-19 20:21:30,408 [salt.state       :300 ][INFO    ][6383] {'pid': 6390, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-19 20:21:30,409 [salt.state       :1951][INFO    ][6383] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:21:30.409422 duration_in_ms=1719.581
2019-06-19 20:21:30,413 [salt.state       :1780][INFO    ][6383] Running state [maas.deploy_machines] at time 20:21:30.413073
2019-06-19 20:21:30,413 [salt.state       :1813][INFO    ][6383] Executing state module.run for [maas.deploy_machines]
2019-06-19 20:21:30,415 [salt.utils.decorators:613 ][WARNING ][6383] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-19 20:21:30,911 [salt.loaded.ext.module.maas:684 ][INFO    ][6383] deploymachines hwe_kernel=hwe-16.04 system_id=gpfqmx distro_series=xenial
2019-06-19 20:21:33,488 [salt.loaded.ext.module.maas:684 ][INFO    ][6383] deploymachines hwe_kernel=hwe-16.04 system_id=exd4t6 distro_series=xenial
2019-06-19 20:21:35,959 [salt.loaded.ext.module.maas:684 ][INFO    ][6383] deploymachines hwe_kernel=hwe-16.04 system_id=qpb4tt distro_series=xenial
2019-06-19 20:21:38,423 [salt.loaded.ext.module.maas:684 ][INFO    ][6383] deploymachines hwe_kernel=hwe-16.04 system_id=gk6hnc distro_series=xenial
2019-06-19 20:21:40,918 [salt.state       :300 ][INFO    ][6383] {'ret': {'updated': [], 'errors': {}, 'success': ['gtw01', 'cmp002', 'cmp001', 'ctl01']}}
2019-06-19 20:21:40,919 [salt.state       :1951][INFO    ][6383] Completed state [maas.deploy_machines] at time 20:21:40.919239 duration_in_ms=10506.166
2019-06-19 20:21:40,921 [salt.minion      :1711][INFO    ][6383] Returning information for job: 20190619202127680009
2019-06-19 20:21:41,512 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command state.apply with jid 20190619202141497583
2019-06-19 20:21:41,533 [salt.minion      :1432][INFO    ][6603] Starting a new job with PID 6603
2019-06-19 20:21:47,650 [salt.state       :915 ][INFO    ][6603] Loading fresh modules for state activity
2019-06-19 20:21:47,709 [salt.fileclient  :1219][INFO    ][6603] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-06-19 20:21:47,757 [salt.state       :1780][INFO    ][6603] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:21:47.757875
2019-06-19 20:21:47,758 [salt.state       :1813][INFO    ][6603] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-19 20:21:47,759 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6603] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-19 20:21:49,519 [salt.state       :300 ][INFO    ][6603] {'pid': 6623, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-19 20:21:49,520 [salt.state       :1951][INFO    ][6603] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:21:49.520759 duration_in_ms=1762.883
2019-06-19 20:21:49,524 [salt.state       :1780][INFO    ][6603] Running state [maas.wait_for_machine_status] at time 20:21:49.524722
2019-06-19 20:21:49,525 [salt.state       :1813][INFO    ][6603] Executing state module.run for [maas.wait_for_machine_status]
2019-06-19 20:21:49,527 [salt.utils.decorators:613 ][WARNING ][6603] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-19 20:21:51,589 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2247.94686604s left)
2019-06-19 20:21:56,611 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619202156601012
2019-06-19 20:21:56,640 [salt.minion      :1432][INFO    ][6642] Starting a new job with PID 6642
2019-06-19 20:21:56,664 [salt.minion      :1711][INFO    ][6642] Returning information for job: 20190619202156601012
2019-06-19 20:22:23,505 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2216.03083801s left)
2019-06-19 20:22:26,683 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619202226667415
2019-06-19 20:22:26,710 [salt.minion      :1432][INFO    ][6710] Starting a new job with PID 6710
2019-06-19 20:22:26,732 [salt.minion      :1711][INFO    ][6710] Returning information for job: 20190619202226667415
2019-06-19 20:22:55,574 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2183.96194506s left)
2019-06-19 20:22:56,769 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619202256753614
2019-06-19 20:22:56,797 [salt.minion      :1432][INFO    ][6744] Starting a new job with PID 6744
2019-06-19 20:22:56,819 [salt.minion      :1711][INFO    ][6744] Returning information for job: 20190619202256753614
2019-06-19 20:23:26,851 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619202326836960
2019-06-19 20:23:26,874 [salt.minion      :1432][INFO    ][6795] Starting a new job with PID 6795
2019-06-19 20:23:26,896 [salt.minion      :1711][INFO    ][6795] Returning information for job: 20190619202326836960
2019-06-19 20:23:27,774 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2151.76212001s left)
2019-06-19 20:23:56,934 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619202356920973
2019-06-19 20:23:56,962 [salt.minion      :1432][INFO    ][6818] Starting a new job with PID 6818
2019-06-19 20:23:56,982 [salt.minion      :1711][INFO    ][6818] Returning information for job: 20190619202356920973
2019-06-19 20:23:59,820 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2119.71585321s left)
2019-06-19 20:24:26,994 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619202426979291
2019-06-19 20:24:27,021 [salt.minion      :1432][INFO    ][6889] Starting a new job with PID 6889
2019-06-19 20:24:27,045 [salt.minion      :1711][INFO    ][6889] Returning information for job: 20190619202426979291
2019-06-19 20:24:31,767 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2087.76884604s left)
2019-06-19 20:24:57,075 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619202457058857
2019-06-19 20:24:57,100 [salt.minion      :1432][INFO    ][6956] Starting a new job with PID 6956
2019-06-19 20:24:57,124 [salt.minion      :1711][INFO    ][6956] Returning information for job: 20190619202457058857
2019-06-19 20:25:03,757 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2055.77937007s left)
2019-06-19 20:25:27,187 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619202527175122
2019-06-19 20:25:27,213 [salt.minion      :1432][INFO    ][7174] Starting a new job with PID 7174
2019-06-19 20:25:27,240 [salt.minion      :1711][INFO    ][7174] Returning information for job: 20190619202527175122
2019-06-19 20:25:35,734 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2023.80212808s left)
2019-06-19 20:25:57,288 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619202557270722
2019-06-19 20:25:57,312 [salt.minion      :1432][INFO    ][7308] Starting a new job with PID 7308
2019-06-19 20:25:57,334 [salt.minion      :1711][INFO    ][7308] Returning information for job: 20190619202557270722
2019-06-19 20:26:07,843 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1991.69269705s left)
2019-06-19 20:26:27,379 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619202627361068
2019-06-19 20:26:27,396 [salt.minion      :1432][INFO    ][7497] Starting a new job with PID 7497
2019-06-19 20:26:27,419 [salt.minion      :1711][INFO    ][7497] Returning information for job: 20190619202627361068
2019-06-19 20:26:39,847 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1959.68893409s left)
2019-06-19 20:26:57,481 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619202657469662
2019-06-19 20:26:57,504 [salt.minion      :1432][INFO    ][7566] Starting a new job with PID 7566
2019-06-19 20:26:57,527 [salt.minion      :1711][INFO    ][7566] Returning information for job: 20190619202657469662
2019-06-19 20:27:12,027 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1927.50933814s left)
2019-06-19 20:27:27,612 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619202727600085
2019-06-19 20:27:27,644 [salt.minion      :1432][INFO    ][7756] Starting a new job with PID 7756
2019-06-19 20:27:27,670 [salt.minion      :1711][INFO    ][7756] Returning information for job: 20190619202727600085
2019-06-19 20:27:44,031 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1895.50520515s left)
2019-06-19 20:27:57,763 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619202757744792
2019-06-19 20:27:57,791 [salt.minion      :1432][INFO    ][7884] Starting a new job with PID 7884
2019-06-19 20:27:57,813 [salt.minion      :1711][INFO    ][7884] Returning information for job: 20190619202757744792
2019-06-19 20:28:16,112 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1863.42423511s left)
2019-06-19 20:28:27,924 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619202827910165
2019-06-19 20:28:27,956 [salt.minion      :1432][INFO    ][8096] Starting a new job with PID 8096
2019-06-19 20:28:27,982 [salt.minion      :1711][INFO    ][8096] Returning information for job: 20190619202827910165
2019-06-19 20:28:47,924 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1831.61223412s left)
2019-06-19 20:28:58,065 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619202858051973
2019-06-19 20:28:58,097 [salt.minion      :1432][INFO    ][8141] Starting a new job with PID 8141
2019-06-19 20:28:58,120 [salt.minion      :1711][INFO    ][8141] Returning information for job: 20190619202858051973
2019-06-19 20:29:19,928 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1799.60858107s left)
2019-06-19 20:29:28,230 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619202928212482
2019-06-19 20:29:28,255 [salt.minion      :1432][INFO    ][8423] Starting a new job with PID 8423
2019-06-19 20:29:28,280 [salt.minion      :1711][INFO    ][8423] Returning information for job: 20190619202928212482
2019-06-19 20:29:51,856 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1767.68014312s left)
2019-06-19 20:29:58,391 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619202958376373
2019-06-19 20:29:58,420 [salt.minion      :1432][INFO    ][8467] Starting a new job with PID 8467
2019-06-19 20:29:58,446 [salt.minion      :1711][INFO    ][8467] Returning information for job: 20190619202958376373
2019-06-19 20:30:23,921 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1735.61494708s left)
2019-06-19 20:30:28,567 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619203028554932
2019-06-19 20:30:28,594 [salt.minion      :1432][INFO    ][8627] Starting a new job with PID 8627
2019-06-19 20:30:28,628 [salt.minion      :1711][INFO    ][8627] Returning information for job: 20190619203028554932
2019-06-19 20:30:55,892 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1703.64427304s left)
2019-06-19 20:30:58,727 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619203058720140
2019-06-19 20:30:58,748 [salt.minion      :1432][INFO    ][8646] Starting a new job with PID 8646
2019-06-19 20:30:58,772 [salt.minion      :1711][INFO    ][8646] Returning information for job: 20190619203058720140
2019-06-19 20:31:28,332 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1671.20384622s left)
2019-06-19 20:31:28,900 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619203128890444
2019-06-19 20:31:28,928 [salt.minion      :1432][INFO    ][8778] Starting a new job with PID 8778
2019-06-19 20:31:28,952 [salt.minion      :1711][INFO    ][8778] Returning information for job: 20190619203128890444
2019-06-19 20:31:59,046 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619203159028593
2019-06-19 20:31:59,072 [salt.minion      :1432][INFO    ][8809] Starting a new job with PID 8809
2019-06-19 20:31:59,097 [salt.minion      :1711][INFO    ][8809] Returning information for job: 20190619203159028593
2019-06-19 20:32:00,474 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1639.06187201s left)
2019-06-19 20:32:29,250 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619203229235356
2019-06-19 20:32:29,279 [salt.minion      :1432][INFO    ][9138] Starting a new job with PID 9138
2019-06-19 20:32:29,302 [salt.minion      :1711][INFO    ][9138] Returning information for job: 20190619203229235356
2019-06-19 20:32:32,586 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:2250s (1606.94991016s left)
2019-06-19 20:32:59,311 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619203259295110
2019-06-19 20:32:59,340 [salt.minion      :1432][INFO    ][9181] Starting a new job with PID 9181
2019-06-19 20:32:59,368 [salt.minion      :1711][INFO    ][9181] Returning information for job: 20190619203259295110
2019-06-19 20:33:04,556 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:2250s (1574.9798851s left)
2019-06-19 20:33:29,503 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619203329484708
2019-06-19 20:33:29,531 [salt.minion      :1432][INFO    ][9293] Starting a new job with PID 9293
2019-06-19 20:33:29,552 [salt.minion      :1711][INFO    ][9293] Returning information for job: 20190619203329484708
2019-06-19 20:33:36,541 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:2250s (1542.99552798s left)
2019-06-19 20:33:59,705 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619203359689091
2019-06-19 20:33:59,728 [salt.minion      :1432][INFO    ][9312] Starting a new job with PID 9312
2019-06-19 20:33:59,758 [salt.minion      :1711][INFO    ][9312] Returning information for job: 20190619203359689091
2019-06-19 20:34:08,491 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:2250s (1511.04526615s left)
2019-06-19 20:34:29,916 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619203429904650
2019-06-19 20:34:29,944 [salt.minion      :1432][INFO    ][9395] Starting a new job with PID 9395
2019-06-19 20:34:29,967 [salt.minion      :1711][INFO    ][9395] Returning information for job: 20190619203429904650
2019-06-19 20:34:40,496 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1479.03988004s left)
2019-06-19 20:35:00,029 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619203500018245
2019-06-19 20:35:00,059 [salt.minion      :1432][INFO    ][9428] Starting a new job with PID 9428
2019-06-19 20:35:00,080 [salt.minion      :1711][INFO    ][9428] Returning information for job: 20190619203500018245
2019-06-19 20:35:12,496 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1447.03959513s left)
2019-06-19 20:35:30,060 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619203530045740
2019-06-19 20:35:30,085 [salt.minion      :1432][INFO    ][9541] Starting a new job with PID 9541
2019-06-19 20:35:30,108 [salt.minion      :1711][INFO    ][9541] Returning information for job: 20190619203530045740
2019-06-19 20:35:44,661 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1414.875422s left)
2019-06-19 20:36:00,094 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619203600080663
2019-06-19 20:36:00,121 [salt.minion      :1432][INFO    ][9559] Starting a new job with PID 9559
2019-06-19 20:36:00,140 [salt.minion      :1711][INFO    ][9559] Returning information for job: 20190619203600080663
2019-06-19 20:36:16,787 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1382.74935412s left)
2019-06-19 20:36:30,129 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619203630117461
2019-06-19 20:36:30,161 [salt.minion      :1432][INFO    ][9614] Starting a new job with PID 9614
2019-06-19 20:36:30,182 [salt.minion      :1711][INFO    ][9614] Returning information for job: 20190619203630117461
2019-06-19 20:36:48,803 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1350.73324013s left)
2019-06-19 20:37:00,173 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619203700159099
2019-06-19 20:37:00,202 [salt.minion      :1432][INFO    ][9642] Starting a new job with PID 9642
2019-06-19 20:37:00,221 [salt.minion      :1711][INFO    ][9642] Returning information for job: 20190619203700159099
2019-06-19 20:37:20,703 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1318.83357406s left)
2019-06-19 20:37:30,232 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619203730224169
2019-06-19 20:37:30,251 [salt.minion      :1432][INFO    ][9698] Starting a new job with PID 9698
2019-06-19 20:37:30,272 [salt.minion      :1711][INFO    ][9698] Returning information for job: 20190619203730224169
2019-06-19 20:37:52,800 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1286.73588514s left)
2019-06-19 20:38:00,291 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619203800275262
2019-06-19 20:38:00,318 [salt.minion      :1432][INFO    ][9718] Starting a new job with PID 9718
2019-06-19 20:38:00,340 [salt.minion      :1711][INFO    ][9718] Returning information for job: 20190619203800275262
2019-06-19 20:38:24,718 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1254.81826115s left)
2019-06-19 20:38:30,381 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619203830371116
2019-06-19 20:38:30,411 [salt.minion      :1432][INFO    ][9771] Starting a new job with PID 9771
2019-06-19 20:38:30,432 [salt.minion      :1711][INFO    ][9771] Returning information for job: 20190619203830371116
2019-06-19 20:38:56,644 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1222.89156914s left)
2019-06-19 20:39:00,480 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619203900464878
2019-06-19 20:39:00,511 [salt.minion      :1432][INFO    ][9796] Starting a new job with PID 9796
2019-06-19 20:39:00,537 [salt.minion      :1711][INFO    ][9796] Returning information for job: 20190619203900464878
2019-06-19 20:39:28,530 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1191.00588202s left)
2019-06-19 20:39:30,586 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619203930574590
2019-06-19 20:39:30,618 [salt.minion      :1432][INFO    ][9849] Starting a new job with PID 9849
2019-06-19 20:39:30,643 [salt.minion      :1711][INFO    ][9849] Returning information for job: 20190619203930574590
2019-06-19 20:40:00,388 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1159.14785218s left)
2019-06-19 20:40:00,727 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619204000713626
2019-06-19 20:40:00,755 [salt.minion      :1432][INFO    ][9868] Starting a new job with PID 9868
2019-06-19 20:40:00,775 [salt.minion      :1711][INFO    ][9868] Returning information for job: 20190619204000713626
2019-06-19 20:40:30,871 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619204030859002
2019-06-19 20:40:30,898 [salt.minion      :1432][INFO    ][9922] Starting a new job with PID 9922
2019-06-19 20:40:30,923 [salt.minion      :1711][INFO    ][9922] Returning information for job: 20190619204030859002
2019-06-19 20:40:32,381 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1127.15544915s left)
2019-06-19 20:41:01,009 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619204100999870
2019-06-19 20:41:01,037 [salt.minion      :1432][INFO    ][9942] Starting a new job with PID 9942
2019-06-19 20:41:01,061 [salt.minion      :1711][INFO    ][9942] Returning information for job: 20190619204100999870
2019-06-19 20:41:04,416 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1095.11975312s left)
2019-06-19 20:41:31,163 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619204131156357
2019-06-19 20:41:31,189 [salt.minion      :1432][INFO    ][9998] Starting a new job with PID 9998
2019-06-19 20:41:31,213 [salt.minion      :1711][INFO    ][9998] Returning information for job: 20190619204131156357
2019-06-19 20:41:36,389 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1063.14658022s left)
2019-06-19 20:42:01,329 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619204201321104
2019-06-19 20:42:01,355 [salt.minion      :1432][INFO    ][10018] Starting a new job with PID 10018
2019-06-19 20:42:01,379 [salt.minion      :1711][INFO    ][10018] Returning information for job: 20190619204201321104
2019-06-19 20:42:08,292 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1031.24424005s left)
2019-06-19 20:42:31,530 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619204231512787
2019-06-19 20:42:31,557 [salt.minion      :1432][INFO    ][10087] Starting a new job with PID 10087
2019-06-19 20:42:31,584 [salt.minion      :1711][INFO    ][10087] Returning information for job: 20190619204231512787
2019-06-19 20:42:40,223 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (999.313078165s left)
2019-06-19 20:43:01,746 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619204301731037
2019-06-19 20:43:01,772 [salt.minion      :1432][INFO    ][10116] Starting a new job with PID 10116
2019-06-19 20:43:01,795 [salt.minion      :1711][INFO    ][10116] Returning information for job: 20190619204301731037
2019-06-19 20:43:12,252 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (967.283850193s left)
2019-06-19 20:43:31,978 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619204331962242
2019-06-19 20:43:32,008 [salt.minion      :1432][INFO    ][10173] Starting a new job with PID 10173
2019-06-19 20:43:32,034 [salt.minion      :1711][INFO    ][10173] Returning information for job: 20190619204331962242
2019-06-19 20:43:44,224 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (935.311910152s left)
2019-06-19 20:44:02,024 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619204402008632
2019-06-19 20:44:02,047 [salt.minion      :1432][INFO    ][10196] Starting a new job with PID 10196
2019-06-19 20:44:02,072 [salt.minion      :1711][INFO    ][10196] Returning information for job: 20190619204402008632
2019-06-19 20:44:16,041 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (903.494952202s left)
2019-06-19 20:44:32,088 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619204432075342
2019-06-19 20:44:32,118 [salt.minion      :1432][INFO    ][10250] Starting a new job with PID 10250
2019-06-19 20:44:32,146 [salt.minion      :1711][INFO    ][10250] Returning information for job: 20190619204432075342
2019-06-19 20:44:48,127 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (871.40887022s left)
2019-06-19 20:45:02,172 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619204502157530
2019-06-19 20:45:02,200 [salt.minion      :1432][INFO    ][10270] Starting a new job with PID 10270
2019-06-19 20:45:02,225 [salt.minion      :1711][INFO    ][10270] Returning information for job: 20190619204502157530
2019-06-19 20:45:20,131 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (839.405224085s left)
2019-06-19 20:45:32,228 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619204532214949
2019-06-19 20:45:32,255 [salt.minion      :1432][INFO    ][10320] Starting a new job with PID 10320
2019-06-19 20:45:32,278 [salt.minion      :1711][INFO    ][10320] Returning information for job: 20190619204532214949
2019-06-19 20:45:51,980 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (807.555846214s left)
2019-06-19 20:46:02,324 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619204602309581
2019-06-19 20:46:02,353 [salt.minion      :1432][INFO    ][10341] Starting a new job with PID 10341
2019-06-19 20:46:02,376 [salt.minion      :1711][INFO    ][10341] Returning information for job: 20190619204602309581
2019-06-19 20:46:23,829 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (775.707086086s left)
2019-06-19 20:46:32,397 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619204632384802
2019-06-19 20:46:32,423 [salt.minion      :1432][INFO    ][10396] Starting a new job with PID 10396
2019-06-19 20:46:32,445 [salt.minion      :1711][INFO    ][10396] Returning information for job: 20190619204632384802
2019-06-19 20:46:55,721 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (743.814568996s left)
2019-06-19 20:47:02,526 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619204702517546
2019-06-19 20:47:02,551 [salt.minion      :1432][INFO    ][10416] Starting a new job with PID 10416
2019-06-19 20:47:02,574 [salt.minion      :1711][INFO    ][10416] Returning information for job: 20190619204702517546
2019-06-19 20:47:27,865 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (711.671195984s left)
2019-06-19 20:47:32,688 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619204732669532
2019-06-19 20:47:32,717 [salt.minion      :1432][INFO    ][10471] Starting a new job with PID 10471
2019-06-19 20:47:32,741 [salt.minion      :1711][INFO    ][10471] Returning information for job: 20190619204732669532
2019-06-19 20:47:59,728 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (679.807651043s left)
2019-06-19 20:48:02,879 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619204802862354
2019-06-19 20:48:02,908 [salt.minion      :1432][INFO    ][10495] Starting a new job with PID 10495
2019-06-19 20:48:02,934 [salt.minion      :1711][INFO    ][10495] Returning information for job: 20190619204802862354
2019-06-19 20:48:31,701 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (647.834865093s left)
2019-06-19 20:48:33,096 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619204833083423
2019-06-19 20:48:33,125 [salt.minion      :1432][INFO    ][10550] Starting a new job with PID 10550
2019-06-19 20:48:33,163 [salt.minion      :1711][INFO    ][10550] Returning information for job: 20190619204833083423
2019-06-19 20:49:03,319 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619204903308477
2019-06-19 20:49:03,342 [salt.minion      :1432][INFO    ][10570] Starting a new job with PID 10570
2019-06-19 20:49:03,366 [salt.minion      :1711][INFO    ][10570] Returning information for job: 20190619204903308477
2019-06-19 20:49:03,585 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (615.951371193s left)
2019-06-19 20:49:33,355 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619204933345476
2019-06-19 20:49:33,376 [salt.minion      :1432][INFO    ][10623] Starting a new job with PID 10623
2019-06-19 20:49:33,400 [salt.minion      :1711][INFO    ][10623] Returning information for job: 20190619204933345476
2019-06-19 20:49:35,718 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (583.817820072s left)
2019-06-19 20:50:03,400 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619205003387292
2019-06-19 20:50:03,427 [salt.minion      :1432][INFO    ][10645] Starting a new job with PID 10645
2019-06-19 20:50:03,450 [salt.minion      :1711][INFO    ][10645] Returning information for job: 20190619205003387292
2019-06-19 20:50:07,577 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (551.958920002s left)
2019-06-19 20:50:33,466 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619205033458319
2019-06-19 20:50:33,496 [salt.minion      :1432][INFO    ][10702] Starting a new job with PID 10702
2019-06-19 20:50:33,521 [salt.minion      :1711][INFO    ][10702] Returning information for job: 20190619205033458319
2019-06-19 20:50:39,353 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (520.182898998s left)
2019-06-19 20:51:03,533 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619205103515654
2019-06-19 20:51:03,561 [salt.minion      :1432][INFO    ][10724] Starting a new job with PID 10724
2019-06-19 20:51:03,584 [salt.minion      :1711][INFO    ][10724] Returning information for job: 20190619205103515654
2019-06-19 20:51:11,211 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (488.324837208s left)
2019-06-19 20:51:33,661 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619205133645553
2019-06-19 20:51:33,689 [salt.minion      :1432][INFO    ][10779] Starting a new job with PID 10779
2019-06-19 20:51:33,713 [salt.minion      :1711][INFO    ][10779] Returning information for job: 20190619205133645553
2019-06-19 20:51:43,042 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (456.494105101s left)
2019-06-19 20:52:03,751 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619205203741436
2019-06-19 20:52:03,780 [salt.minion      :1432][INFO    ][10799] Starting a new job with PID 10799
2019-06-19 20:52:03,802 [salt.minion      :1711][INFO    ][10799] Returning information for job: 20190619205203741436
2019-06-19 20:52:15,105 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (424.431021214s left)
2019-06-19 20:52:33,930 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619205233915215
2019-06-19 20:52:33,957 [salt.minion      :1432][INFO    ][11002] Starting a new job with PID 11002
2019-06-19 20:52:33,981 [salt.minion      :1711][INFO    ][11002] Returning information for job: 20190619205233915215
2019-06-19 20:52:47,127 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (392.409454107s left)
2019-06-19 20:53:04,084 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619205304068506
2019-06-19 20:53:04,112 [salt.minion      :1432][INFO    ][11027] Starting a new job with PID 11027
2019-06-19 20:53:04,136 [salt.minion      :1711][INFO    ][11027] Returning information for job: 20190619205304068506
2019-06-19 20:53:19,256 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (360.280078173s left)
2019-06-19 20:53:34,283 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619205334272516
2019-06-19 20:53:34,312 [salt.minion      :1432][INFO    ][11080] Starting a new job with PID 11080
2019-06-19 20:53:34,335 [salt.minion      :1711][INFO    ][11080] Returning information for job: 20190619205334272516
2019-06-19 20:53:51,234 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (328.301783085s left)
2019-06-19 20:54:04,471 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619205404455940
2019-06-19 20:54:04,501 [salt.minion      :1432][INFO    ][11104] Starting a new job with PID 11104
2019-06-19 20:54:04,529 [salt.minion      :1711][INFO    ][11104] Returning information for job: 20190619205404455940
2019-06-19 20:54:23,169 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (296.367539167s left)
2019-06-19 20:54:34,690 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619205434675714
2019-06-19 20:54:34,715 [salt.minion      :1432][INFO    ][11159] Starting a new job with PID 11159
2019-06-19 20:54:34,738 [salt.minion      :1711][INFO    ][11159] Returning information for job: 20190619205434675714
2019-06-19 20:54:55,066 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (264.470249176s left)
2019-06-19 20:55:04,928 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619205504911608
2019-06-19 20:55:04,954 [salt.minion      :1432][INFO    ][11179] Starting a new job with PID 11179
2019-06-19 20:55:04,976 [salt.minion      :1711][INFO    ][11179] Returning information for job: 20190619205504911608
2019-06-19 20:55:27,288 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (232.247658014s left)
2019-06-19 20:55:35,014 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619205534999979
2019-06-19 20:55:35,045 [salt.minion      :1432][INFO    ][11237] Starting a new job with PID 11237
2019-06-19 20:55:35,066 [salt.minion      :1711][INFO    ][11237] Returning information for job: 20190619205534999979
2019-06-19 20:55:59,167 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (200.368643999s left)
2019-06-19 20:56:05,105 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619205605091084
2019-06-19 20:56:05,136 [salt.minion      :1432][INFO    ][11256] Starting a new job with PID 11256
2019-06-19 20:56:05,159 [salt.minion      :1711][INFO    ][11256] Returning information for job: 20190619205605091084
2019-06-19 20:56:31,110 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (168.426802158s left)
2019-06-19 20:56:35,229 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619205635217374
2019-06-19 20:56:35,260 [salt.minion      :1432][INFO    ][11311] Starting a new job with PID 11311
2019-06-19 20:56:35,284 [salt.minion      :1711][INFO    ][11311] Returning information for job: 20190619205635217374
2019-06-19 20:57:03,019 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (136.51714015s left)
2019-06-19 20:57:05,311 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619205705299321
2019-06-19 20:57:05,337 [salt.minion      :1432][INFO    ][11330] Starting a new job with PID 11330
2019-06-19 20:57:05,365 [salt.minion      :1711][INFO    ][11330] Returning information for job: 20190619205705299321
2019-06-19 20:57:34,930 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (104.605920076s left)
2019-06-19 20:57:35,507 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619205735490610
2019-06-19 20:57:35,535 [salt.minion      :1432][INFO    ][11384] Starting a new job with PID 11384
2019-06-19 20:57:35,556 [salt.minion      :1711][INFO    ][11384] Returning information for job: 20190619205735490610
2019-06-19 20:58:05,683 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619205805672902
2019-06-19 20:58:05,706 [salt.minion      :1432][INFO    ][11405] Starting a new job with PID 11405
2019-06-19 20:58:05,729 [salt.minion      :1711][INFO    ][11405] Returning information for job: 20190619205805672902
2019-06-19 20:58:06,881 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (72.6548922062s left)
2019-06-19 20:58:35,880 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619205835867469
2019-06-19 20:58:35,909 [salt.minion      :1432][INFO    ][11460] Starting a new job with PID 11460
2019-06-19 20:58:35,932 [salt.minion      :1711][INFO    ][11460] Returning information for job: 20190619205835867469
2019-06-19 20:58:38,873 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (40.6635200977s left)
2019-06-19 20:59:06,069 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619205906053625
2019-06-19 20:59:06,099 [salt.minion      :1432][INFO    ][11480] Starting a new job with PID 11480
2019-06-19 20:59:06,123 [salt.minion      :1711][INFO    ][11480] Returning information for job: 20190619205906053625
2019-06-19 20:59:10,871 [salt.loaded.ext.module.maas:1023][INFO    ][6603] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (8.66492509842s left)
2019-06-19 20:59:36,216 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619205936203183
2019-06-19 20:59:36,245 [salt.minion      :1432][INFO    ][11534] Starting a new job with PID 11534
2019-06-19 20:59:36,268 [salt.minion      :1711][INFO    ][11534] Returning information for job: 20190619205936203183
2019-06-19 20:59:42,774 [salt.state       :302 ][ERROR   ][6603] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['gtw01']not in Deployed state
2019-06-19 20:59:42,774 [salt.state       :1951][INFO    ][6603] Completed state [maas.wait_for_machine_status] at time 20:59:42.774704 duration_in_ms=2273249.98
2019-06-19 20:59:42,780 [salt.minion      :1711][INFO    ][6603] Returning information for job: 20190619202141497583
2019-06-19 20:59:53,599 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command pillar.get with jid 20190619205953587633
2019-06-19 20:59:53,626 [salt.minion      :1432][INFO    ][11551] Starting a new job with PID 11551
2019-06-19 20:59:53,636 [salt.minion      :1711][INFO    ][11551] Returning information for job: 20190619205953587633
2019-06-19 20:59:54,213 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command service.status with jid 20190619205954196281
2019-06-19 20:59:54,243 [salt.minion      :1432][INFO    ][11556] Starting a new job with PID 11556
2019-06-19 20:59:54,784 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][11556] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-06-19 20:59:54,828 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][11556] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-06-19 20:59:54,850 [salt.minion      :1711][INFO    ][11556] Returning information for job: 20190619205954196281
2019-06-19 20:59:55,435 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command state.apply with jid 20190619205955422568
2019-06-19 20:59:55,461 [salt.minion      :1432][INFO    ][11569] Starting a new job with PID 11569
2019-06-19 21:00:01,387 [salt.state       :915 ][INFO    ][11569] Loading fresh modules for state activity
2019-06-19 21:00:01,962 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11569] Executing command 'salt-minion --version' in directory '/root'
2019-06-19 21:00:02,263 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11569] Executing command 'salt-minion --version' in directory '/root'
2019-06-19 21:00:03,212 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11569] Executing command 'salt-minion --version' in directory '/root'
2019-06-19 21:00:03,502 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11569] Executing command 'salt-minion --version' in directory '/root'
2019-06-19 21:00:05,268 [salt.state       :1780][INFO    ][11569] Running state [salt-minion] at time 21:00:05.268599
2019-06-19 21:00:05,269 [salt.state       :1813][INFO    ][11569] Executing state pkg.installed for [salt-minion]
2019-06-19 21:00:05,270 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11569] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-19 21:00:05,383 [salt.state       :300 ][INFO    ][11569] All specified packages are already installed
2019-06-19 21:00:05,384 [salt.state       :1951][INFO    ][11569] Completed state [salt-minion] at time 21:00:05.383937 duration_in_ms=115.339
2019-06-19 21:00:05,384 [salt.state       :1780][INFO    ][11569] Running state [salt_minion_dependency_packages] at time 21:00:05.384283
2019-06-19 21:00:05,384 [salt.state       :1813][INFO    ][11569] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-06-19 21:00:05,394 [salt.state       :300 ][INFO    ][11569] All specified packages are already installed
2019-06-19 21:00:05,395 [salt.state       :1951][INFO    ][11569] Completed state [salt_minion_dependency_packages] at time 21:00:05.395134 duration_in_ms=10.851
2019-06-19 21:00:05,398 [salt.state       :1780][INFO    ][11569] Running state [/etc/salt/minion.d/minion.conf] at time 21:00:05.398191
2019-06-19 21:00:05,398 [salt.state       :1813][INFO    ][11569] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-06-19 21:00:05,678 [salt.state       :300 ][INFO    ][11569] File /etc/salt/minion.d/minion.conf is in the correct state
2019-06-19 21:00:05,678 [salt.state       :1951][INFO    ][11569] Completed state [/etc/salt/minion.d/minion.conf] at time 21:00:05.678449 duration_in_ms=280.258
2019-06-19 21:00:05,681 [salt.state       :1780][INFO    ][11569] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 21:00:05.681485
2019-06-19 21:00:05,682 [salt.state       :1813][INFO    ][11569] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-06-19 21:00:05,697 [salt.state       :300 ][INFO    ][11569] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-06-19 21:00:05,698 [salt.state       :1951][INFO    ][11569] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 21:00:05.697544 duration_in_ms=16.057
2019-06-19 21:00:05,700 [salt.state       :1780][INFO    ][11569] Running state [salt-minion] at time 21:00:05.700271
2019-06-19 21:00:05,700 [salt.state       :1813][INFO    ][11569] Executing state service.running for [salt-minion]
2019-06-19 21:00:05,702 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11569] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-06-19 21:00:05,755 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11569] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-06-19 21:00:05,772 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11569] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-06-19 21:00:05,792 [salt.state       :300 ][INFO    ][11569] The service salt-minion is already running
2019-06-19 21:00:05,793 [salt.state       :1951][INFO    ][11569] Completed state [salt-minion] at time 21:00:05.793286 duration_in_ms=93.014
2019-06-19 21:00:05,796 [salt.state       :1780][INFO    ][11569] Running state [/etc/salt/grains.d] at time 21:00:05.796167
2019-06-19 21:00:05,796 [salt.state       :1813][INFO    ][11569] Executing state file.directory for [/etc/salt/grains.d]
2019-06-19 21:00:05,799 [salt.state       :300 ][INFO    ][11569] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-06-19 21:00:05,800 [salt.state       :1951][INFO    ][11569] Completed state [/etc/salt/grains.d] at time 21:00:05.800172 duration_in_ms=4.005
2019-06-19 21:00:05,801 [salt.state       :1780][INFO    ][11569] Running state [/etc/salt/grains] at time 21:00:05.801049
2019-06-19 21:00:05,801 [salt.state       :1813][INFO    ][11569] Executing state file.managed for [/etc/salt/grains]
2019-06-19 21:00:05,802 [salt.state       :300 ][INFO    ][11569] File /etc/salt/grains exists with proper permissions. No changes made.
2019-06-19 21:00:05,802 [salt.state       :1951][INFO    ][11569] Completed state [/etc/salt/grains] at time 21:00:05.802653 duration_in_ms=1.605
2019-06-19 21:00:05,803 [salt.state       :1780][INFO    ][11569] Running state [/etc/salt/grains.d/placeholder] at time 21:00:05.803271
2019-06-19 21:00:05,803 [salt.state       :1813][INFO    ][11569] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-06-19 21:00:05,804 [salt.state       :300 ][INFO    ][11569] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-06-19 21:00:05,804 [salt.state       :1951][INFO    ][11569] Completed state [/etc/salt/grains.d/placeholder] at time 21:00:05.804529 duration_in_ms=1.258
2019-06-19 21:00:05,805 [salt.state       :1780][INFO    ][11569] Running state [/etc/salt/grains.d/sphinx] at time 21:00:05.805145
2019-06-19 21:00:05,805 [salt.state       :1813][INFO    ][11569] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-06-19 21:00:05,806 [salt.state       :300 ][INFO    ][11569] File /etc/salt/grains.d/sphinx is in the correct state
2019-06-19 21:00:05,807 [salt.state       :1951][INFO    ][11569] Completed state [/etc/salt/grains.d/sphinx] at time 21:00:05.807188 duration_in_ms=2.043
2019-06-19 21:00:05,811 [salt.state       :1780][INFO    ][11569] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 21:00:05.811723
2019-06-19 21:00:05,812 [salt.state       :1813][INFO    ][11569] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-06-19 21:00:05,812 [salt.state       :300 ][INFO    ][11569] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-06-19 21:00:05,812 [salt.state       :1951][INFO    ][11569] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 21:00:05.812504 duration_in_ms=0.781
2019-06-19 21:00:05,813 [salt.state       :1780][INFO    ][11569] Running state [/etc/salt/grains.d/dns_records] at time 21:00:05.812970
2019-06-19 21:00:05,813 [salt.state       :1813][INFO    ][11569] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-06-19 21:00:05,814 [salt.state       :300 ][INFO    ][11569] File /etc/salt/grains.d/dns_records is in the correct state
2019-06-19 21:00:05,814 [salt.state       :1951][INFO    ][11569] Completed state [/etc/salt/grains.d/dns_records] at time 21:00:05.814627 duration_in_ms=1.657
2019-06-19 21:00:05,815 [salt.state       :1780][INFO    ][11569] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 21:00:05.815523
2019-06-19 21:00:05,815 [salt.state       :1813][INFO    ][11569] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-06-19 21:00:05,816 [salt.state       :300 ][INFO    ][11569] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-06-19 21:00:05,816 [salt.state       :1951][INFO    ][11569] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 21:00:05.816287 duration_in_ms=0.765
2019-06-19 21:00:05,816 [salt.state       :1780][INFO    ][11569] Running state [/etc/salt/grains.d/salt] at time 21:00:05.816749
2019-06-19 21:00:05,817 [salt.state       :1813][INFO    ][11569] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-06-19 21:00:05,818 [salt.state       :300 ][INFO    ][11569] File /etc/salt/grains.d/salt is in the correct state
2019-06-19 21:00:05,818 [salt.state       :1951][INFO    ][11569] Completed state [/etc/salt/grains.d/salt] at time 21:00:05.818415 duration_in_ms=1.666
2019-06-19 21:00:05,819 [salt.state       :1780][INFO    ][11569] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 21:00:05.819573
2019-06-19 21:00:05,819 [salt.state       :1813][INFO    ][11569] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-06-19 21:00:05,820 [salt.state       :300 ][INFO    ][11569] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-06-19 21:00:05,820 [salt.state       :1951][INFO    ][11569] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 21:00:05.820312 duration_in_ms=0.738
2019-06-19 21:00:05,823 [salt.state       :1780][INFO    ][11569] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 21:00:05.823346
2019-06-19 21:00:05,823 [salt.state       :1813][INFO    ][11569] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-06-19 21:00:05,823 [salt.state       :300 ][INFO    ][11569] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-06-19 21:00:05,824 [salt.state       :1951][INFO    ][11569] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 21:00:05.824130 duration_in_ms=0.784
2019-06-19 21:00:05,824 [salt.state       :1780][INFO    ][11569] Running state [mine.update] at time 21:00:05.824778
2019-06-19 21:00:05,825 [salt.state       :1813][INFO    ][11569] Executing state module.wait for [mine.update]
2019-06-19 21:00:05,825 [salt.state       :300 ][INFO    ][11569] No changes made for mine.update
2019-06-19 21:00:05,825 [salt.state       :1951][INFO    ][11569] Completed state [mine.update] at time 21:00:05.825481 duration_in_ms=0.702
2019-06-19 21:00:05,825 [salt.state       :1780][INFO    ][11569] Running state [ca-certificates] at time 21:00:05.825723
2019-06-19 21:00:05,825 [salt.state       :1813][INFO    ][11569] Executing state pkg.installed for [ca-certificates]
2019-06-19 21:00:05,835 [salt.state       :300 ][INFO    ][11569] All specified packages are already installed
2019-06-19 21:00:05,835 [salt.state       :1951][INFO    ][11569] Completed state [ca-certificates] at time 21:00:05.835739 duration_in_ms=10.016
2019-06-19 21:00:05,836 [salt.state       :1780][INFO    ][11569] Running state [update-ca-certificates] at time 21:00:05.836632
2019-06-19 21:00:05,836 [salt.state       :1813][INFO    ][11569] Executing state cmd.wait for [update-ca-certificates]
2019-06-19 21:00:05,837 [salt.state       :300 ][INFO    ][11569] No changes made for update-ca-certificates
2019-06-19 21:00:05,837 [salt.state       :1951][INFO    ][11569] Completed state [update-ca-certificates] at time 21:00:05.837371 duration_in_ms=0.739
2019-06-19 21:00:05,837 [salt.state       :1780][INFO    ][11569] Running state [iptables] at time 21:00:05.837613
2019-06-19 21:00:05,838 [salt.state       :1813][INFO    ][11569] Executing state pkg.installed for [iptables]
2019-06-19 21:00:05,847 [salt.state       :300 ][INFO    ][11569] All specified packages are already installed
2019-06-19 21:00:05,847 [salt.state       :1951][INFO    ][11569] Completed state [iptables] at time 21:00:05.847480 duration_in_ms=9.867
2019-06-19 21:00:05,847 [salt.state       :1780][INFO    ][11569] Running state [iptables-persistent] at time 21:00:05.847720
2019-06-19 21:00:05,847 [salt.state       :1813][INFO    ][11569] Executing state pkg.installed for [iptables-persistent]
2019-06-19 21:00:05,856 [salt.state       :300 ][INFO    ][11569] All specified packages are already installed
2019-06-19 21:00:05,856 [salt.state       :1951][INFO    ][11569] Completed state [iptables-persistent] at time 21:00:05.856519 duration_in_ms=8.798
2019-06-19 21:00:05,857 [salt.state       :1780][INFO    ][11569] Running state [iptables_modules_v4_load] at time 21:00:05.857475
2019-06-19 21:00:05,858 [salt.state       :1813][INFO    ][11569] Executing state kmod.present for [iptables_modules_v4_load]
2019-06-19 21:00:05,859 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11569] Executing command 'lsmod' in directory '/root'
2019-06-19 21:00:05,880 [salt.state       :300 ][INFO    ][11569] Kernel modules iptable_filter, ip_tables are already present
2019-06-19 21:00:05,880 [salt.state       :1951][INFO    ][11569] Completed state [iptables_modules_v4_load] at time 21:00:05.880877 duration_in_ms=23.401
2019-06-19 21:00:05,882 [salt.state       :1780][INFO    ][11569] Running state [/etc/iptables/rules.v4] at time 21:00:05.882801
2019-06-19 21:00:05,883 [salt.state       :1813][INFO    ][11569] Executing state file.managed for [/etc/iptables/rules.v4]
2019-06-19 21:00:05,994 [salt.state       :300 ][INFO    ][11569] File /etc/iptables/rules.v4 is in the correct state
2019-06-19 21:00:05,994 [salt.state       :1951][INFO    ][11569] Completed state [/etc/iptables/rules.v4] at time 21:00:05.994319 duration_in_ms=111.518
2019-06-19 21:00:05,995 [salt.state       :1780][INFO    ][11569] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 21:00:05.995413
2019-06-19 21:00:05,995 [salt.state       :1813][INFO    ][11569] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-06-19 21:00:05,996 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11569] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-06-19 21:00:06,015 [salt.state       :300 ][INFO    ][11569] onlyif execution failed
2019-06-19 21:00:06,016 [salt.state       :1951][INFO    ][11569] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 21:00:06.016323 duration_in_ms=20.909
2019-06-19 21:00:06,019 [salt.state       :1780][INFO    ][11569] Running state [netfilter-persistent] at time 21:00:06.019424
2019-06-19 21:00:06,020 [salt.state       :1813][INFO    ][11569] Executing state service.running for [netfilter-persistent]
2019-06-19 21:00:06,021 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11569] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-06-19 21:00:06,046 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11569] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-06-19 21:00:06,067 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11569] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-06-19 21:00:06,090 [salt.state       :300 ][INFO    ][11569] The service netfilter-persistent is already running
2019-06-19 21:00:06,091 [salt.state       :1951][INFO    ][11569] Completed state [netfilter-persistent] at time 21:00:06.091369 duration_in_ms=71.945
2019-06-19 21:00:06,093 [salt.state       :1780][INFO    ][11569] Running state [iptables_extra.remove_stale_tables] at time 21:00:06.092926
2019-06-19 21:00:06,093 [salt.state       :1813][INFO    ][11569] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-06-19 21:00:06,094 [salt.state       :300 ][INFO    ][11569] No changes made for iptables_extra.remove_stale_tables
2019-06-19 21:00:06,094 [salt.state       :1951][INFO    ][11569] Completed state [iptables_extra.remove_stale_tables] at time 21:00:06.094592 duration_in_ms=1.666
2019-06-19 21:00:06,095 [salt.state       :1780][INFO    ][11569] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 21:00:06.095042
2019-06-19 21:00:06,095 [salt.state       :1813][INFO    ][11569] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-06-19 21:00:06,096 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11569] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-06-19 21:00:06,116 [salt.state       :300 ][INFO    ][11569] onlyif execution failed
2019-06-19 21:00:06,117 [salt.state       :1951][INFO    ][11569] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 21:00:06.116993 duration_in_ms=21.952
2019-06-19 21:00:06,118 [salt.state       :1780][INFO    ][11569] Running state [/etc/iptables/rules.v6] at time 21:00:06.118602
2019-06-19 21:00:06,119 [salt.state       :1813][INFO    ][11569] Executing state file.absent for [/etc/iptables/rules.v6]
2019-06-19 21:00:06,119 [salt.state       :300 ][INFO    ][11569] File /etc/iptables/rules.v6 is not present
2019-06-19 21:00:06,120 [salt.state       :1951][INFO    ][11569] Completed state [/etc/iptables/rules.v6] at time 21:00:06.120167 duration_in_ms=1.565
2019-06-19 21:00:06,121 [salt.state       :1780][INFO    ][11569] Running state [iptables_extra.flush_all] at time 21:00:06.121330
2019-06-19 21:00:06,124 [salt.state       :1813][INFO    ][11569] Executing state module.wait for [iptables_extra.flush_all]
2019-06-19 21:00:06,124 [salt.state       :300 ][INFO    ][11569] No changes made for iptables_extra.flush_all
2019-06-19 21:00:06,124 [salt.state       :1951][INFO    ][11569] Completed state [iptables_extra.flush_all] at time 21:00:06.124674 duration_in_ms=3.345
2019-06-19 21:00:06,127 [salt.minion      :1711][INFO    ][11569] Returning information for job: 20190619205955422568
2019-06-19 21:00:06,693 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command state.apply with jid 20190619210006681184
2019-06-19 21:00:06,723 [salt.minion      :1432][INFO    ][11649] Starting a new job with PID 11649
2019-06-19 21:00:07,589 [salt.state       :915 ][INFO    ][11649] Loading fresh modules for state activity
2019-06-19 21:00:08,494 [salt.state       :1780][INFO    ][11649] Running state [maas-rack-controller] at time 21:00:08.494804
2019-06-19 21:00:08,495 [salt.state       :1813][INFO    ][11649] Executing state pkg.installed for [maas-rack-controller]
2019-06-19 21:00:08,496 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11649] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-19 21:00:08,610 [salt.state       :300 ][INFO    ][11649] All specified packages are already installed
2019-06-19 21:00:08,610 [salt.state       :1951][INFO    ][11649] Completed state [maas-rack-controller] at time 21:00:08.610890 duration_in_ms=116.087
2019-06-19 21:00:08,611 [salt.state       :1780][INFO    ][11649] Running state [ipmitool] at time 21:00:08.611257
2019-06-19 21:00:08,611 [salt.state       :1813][INFO    ][11649] Executing state pkg.installed for [ipmitool]
2019-06-19 21:00:08,621 [salt.state       :300 ][INFO    ][11649] All specified packages are already installed
2019-06-19 21:00:08,621 [salt.state       :1951][INFO    ][11649] Completed state [ipmitool] at time 21:00:08.621336 duration_in_ms=10.079
2019-06-19 21:00:08,625 [salt.state       :1780][INFO    ][11649] Running state [/etc/maas/rackd.conf] at time 21:00:08.625275
2019-06-19 21:00:08,625 [salt.state       :1813][INFO    ][11649] Executing state file.line for [/etc/maas/rackd.conf]
2019-06-19 21:00:08,626 [salt.state       :300 ][INFO    ][11649] No changes needed to be made
2019-06-19 21:00:08,626 [salt.state       :1951][INFO    ][11649] Completed state [/etc/maas/rackd.conf] at time 21:00:08.626731 duration_in_ms=1.456
2019-06-19 21:00:08,627 [salt.state       :1780][INFO    ][11649] Running state [/etc/maas/rackd.conf] at time 21:00:08.626971
2019-06-19 21:00:08,627 [salt.state       :1813][INFO    ][11649] Executing state file.managed for [/etc/maas/rackd.conf]
2019-06-19 21:00:08,627 [salt.loaded.int.states.file:2298][WARNING ][11649] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-06-19 21:00:08,628 [salt.state       :300 ][INFO    ][11649] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-06-19 21:00:08,628 [salt.state       :1951][INFO    ][11649] Completed state [/etc/maas/rackd.conf] at time 21:00:08.628467 duration_in_ms=1.496
2019-06-19 21:00:08,630 [salt.state       :1780][INFO    ][11649] Running state [maas-rackd] at time 21:00:08.630386
2019-06-19 21:00:08,630 [salt.state       :1813][INFO    ][11649] Executing state service.running for [maas-rackd]
2019-06-19 21:00:08,631 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11649] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-06-19 21:00:08,671 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11649] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-06-19 21:00:08,693 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11649] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-06-19 21:00:08,715 [salt.state       :300 ][INFO    ][11649] The service maas-rackd is already running
2019-06-19 21:00:08,716 [salt.state       :1951][INFO    ][11649] Completed state [maas-rackd] at time 21:00:08.716295 duration_in_ms=85.907
2019-06-19 21:00:08,719 [salt.minion      :1711][INFO    ][11649] Returning information for job: 20190619210006681184
2019-06-19 21:00:09,283 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command state.apply with jid 20190619210009267052
2019-06-19 21:00:09,311 [salt.minion      :1432][INFO    ][11672] Starting a new job with PID 11672
2019-06-19 21:00:10,222 [salt.state       :915 ][INFO    ][11672] Loading fresh modules for state activity
2019-06-19 21:00:11,269 [salt.state       :1780][INFO    ][11672] Running state [maas-region-controller] at time 21:00:11.269397
2019-06-19 21:00:11,271 [salt.state       :1813][INFO    ][11672] Executing state pkg.installed for [maas-region-controller]
2019-06-19 21:00:11,271 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11672] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-19 21:00:11,385 [salt.state       :300 ][INFO    ][11672] All specified packages are already installed
2019-06-19 21:00:11,386 [salt.state       :1951][INFO    ][11672] Completed state [maas-region-controller] at time 21:00:11.385677 duration_in_ms=116.281
2019-06-19 21:00:11,386 [salt.state       :1780][INFO    ][11672] Running state [python-oauth] at time 21:00:11.386342
2019-06-19 21:00:11,386 [salt.state       :1813][INFO    ][11672] Executing state pkg.installed for [python-oauth]
2019-06-19 21:00:11,395 [salt.state       :300 ][INFO    ][11672] All specified packages are already installed
2019-06-19 21:00:11,396 [salt.state       :1951][INFO    ][11672] Completed state [python-oauth] at time 21:00:11.396130 duration_in_ms=9.788
2019-06-19 21:00:11,399 [salt.state       :1780][INFO    ][11672] Running state [/etc/maas/regiond.conf] at time 21:00:11.399591
2019-06-19 21:00:11,399 [salt.state       :1813][INFO    ][11672] Executing state file.replace for [/etc/maas/regiond.conf]
2019-06-19 21:00:11,404 [salt.state       :300 ][INFO    ][11672] No changes needed to be made
2019-06-19 21:00:11,405 [salt.state       :1951][INFO    ][11672] Completed state [/etc/maas/regiond.conf] at time 21:00:11.405077 duration_in_ms=5.487
2019-06-19 21:00:11,405 [salt.state       :1780][INFO    ][11672] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 21:00:11.405553
2019-06-19 21:00:11,405 [salt.state       :1813][INFO    ][11672] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-06-19 21:00:11,467 [salt.state       :300 ][INFO    ][11672] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-06-19 21:00:11,468 [salt.state       :1951][INFO    ][11672] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 21:00:11.468025 duration_in_ms=62.472
2019-06-19 21:00:11,468 [salt.state       :1780][INFO    ][11672] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 21:00:11.468563
2019-06-19 21:00:11,468 [salt.state       :1813][INFO    ][11672] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-06-19 21:00:11,472 [salt.state       :300 ][INFO    ][11672] No changes needed to be made
2019-06-19 21:00:11,472 [salt.state       :1951][INFO    ][11672] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 21:00:11.472660 duration_in_ms=4.097
2019-06-19 21:00:11,473 [salt.state       :1780][INFO    ][11672] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 21:00:11.473139
2019-06-19 21:00:11,473 [salt.state       :1813][INFO    ][11672] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-06-19 21:00:11,477 [salt.state       :300 ][INFO    ][11672] No changes needed to be made
2019-06-19 21:00:11,477 [salt.state       :1951][INFO    ][11672] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 21:00:11.477583 duration_in_ms=4.443
2019-06-19 21:00:11,478 [salt.state       :1780][INFO    ][11672] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 21:00:11.478067
2019-06-19 21:00:11,478 [salt.state       :1813][INFO    ][11672] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-06-19 21:00:11,509 [salt.state       :300 ][INFO    ][11672] No changes needed to be made
2019-06-19 21:00:11,509 [salt.state       :1951][INFO    ][11672] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 21:00:11.509457 duration_in_ms=31.39
2019-06-19 21:00:11,511 [salt.state       :1780][INFO    ][11672] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 21:00:11.511575
2019-06-19 21:00:11,511 [salt.state       :1813][INFO    ][11672] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-06-19 21:00:11,527 [salt.state       :300 ][INFO    ][11672] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-06-19 21:00:11,528 [salt.state       :1951][INFO    ][11672] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 21:00:11.527938 duration_in_ms=16.362
2019-06-19 21:00:11,529 [salt.state       :1780][INFO    ][11672] Running state [a2enmod headers] at time 21:00:11.529517
2019-06-19 21:00:11,529 [salt.state       :1813][INFO    ][11672] Executing state cmd.run for [a2enmod headers]
2019-06-19 21:00:11,530 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11672] Executing command 'a2enmod headers' in directory '/root'
2019-06-19 21:00:11,596 [salt.state       :300 ][INFO    ][11672] {'pid': 11691, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-06-19 21:00:11,597 [salt.state       :1951][INFO    ][11672] Completed state [a2enmod headers] at time 21:00:11.597075 duration_in_ms=67.558
2019-06-19 21:00:11,598 [salt.state       :1780][INFO    ][11672] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 21:00:11.598073
2019-06-19 21:00:11,599 [salt.state       :1813][INFO    ][11672] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-06-19 21:00:11,620 [salt.state       :300 ][INFO    ][11672] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-06-19 21:00:11,621 [salt.state       :1951][INFO    ][11672] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 21:00:11.621106 duration_in_ms=23.032
2019-06-19 21:00:11,622 [salt.state       :1780][INFO    ][11672] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 21:00:11.622081
2019-06-19 21:00:11,622 [salt.state       :1813][INFO    ][11672] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-06-19 21:00:11,683 [salt.state       :300 ][INFO    ][11672] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-06-19 21:00:11,683 [salt.state       :1951][INFO    ][11672] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 21:00:11.683303 duration_in_ms=61.223
2019-06-19 21:00:11,683 [salt.state       :1780][INFO    ][11672] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 21:00:11.683812
2019-06-19 21:00:11,684 [salt.state       :1813][INFO    ][11672] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-06-19 21:00:11,754 [salt.state       :300 ][INFO    ][11672] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-06-19 21:00:11,754 [salt.state       :1951][INFO    ][11672] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 21:00:11.754309 duration_in_ms=70.497
2019-06-19 21:00:11,755 [salt.state       :1780][INFO    ][11672] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 21:00:11.755103
2019-06-19 21:00:11,755 [salt.state       :1813][INFO    ][11672] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-06-19 21:00:11,819 [salt.state       :300 ][INFO    ][11672] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-06-19 21:00:11,819 [salt.state       :1951][INFO    ][11672] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 21:00:11.819277 duration_in_ms=64.174
2019-06-19 21:00:11,819 [salt.state       :1780][INFO    ][11672] Running state [/root/.pgpass] at time 21:00:11.819555
2019-06-19 21:00:11,819 [salt.state       :1813][INFO    ][11672] Executing state file.managed for [/root/.pgpass]
2019-06-19 21:00:11,864 [salt.state       :300 ][INFO    ][11672] File /root/.pgpass is in the correct state
2019-06-19 21:00:11,865 [salt.state       :1951][INFO    ][11672] Completed state [/root/.pgpass] at time 21:00:11.865085 duration_in_ms=45.53
2019-06-19 21:00:11,870 [salt.state       :1780][INFO    ][11672] Running state [maas-region syncdb --noinput] at time 21:00:11.870849
2019-06-19 21:00:11,871 [salt.state       :1813][INFO    ][11672] Executing state cmd.run for [maas-region syncdb --noinput]
2019-06-19 21:00:11,871 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11672] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-06-19 21:00:14,480 [salt.state       :300 ][INFO    ][11672] {'pid': 11704, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: sessions, piston3, sites, contenttypes, auth, metadataserver, maasserver\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-06-19 21:00:14,481 [salt.state       :1951][INFO    ][11672] Completed state [maas-region syncdb --noinput] at time 21:00:14.481346 duration_in_ms=2610.495
2019-06-19 21:00:14,482 [salt.state       :2022][WARNING ][11672] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-06-19 21:00:14,485 [salt.state       :1780][INFO    ][11672] Running state [maas-regiond] at time 21:00:14.485636
2019-06-19 21:00:14,486 [salt.state       :1813][INFO    ][11672] Executing state service.running for [maas-regiond]
2019-06-19 21:00:14,487 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11672] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-06-19 21:00:14,537 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11672] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-06-19 21:00:14,559 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11672] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-06-19 21:00:14,580 [salt.state       :300 ][INFO    ][11672] The service maas-regiond is already running
2019-06-19 21:00:14,581 [salt.state       :1951][INFO    ][11672] Completed state [maas-regiond] at time 21:00:14.581358 duration_in_ms=95.723
2019-06-19 21:00:14,586 [salt.state       :1780][INFO    ][11672] Running state [bind9] at time 21:00:14.586430
2019-06-19 21:00:14,586 [salt.state       :1813][INFO    ][11672] Executing state service.running for [bind9]
2019-06-19 21:00:14,587 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11672] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-06-19 21:00:14,608 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11672] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-06-19 21:00:14,634 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11672] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-06-19 21:00:14,652 [salt.state       :300 ][INFO    ][11672] The service bind9 is already running
2019-06-19 21:00:14,653 [salt.state       :1951][INFO    ][11672] Completed state [bind9] at time 21:00:14.652921 duration_in_ms=66.491
2019-06-19 21:00:14,657 [salt.state       :1780][INFO    ][11672] Running state [apache2] at time 21:00:14.657564
2019-06-19 21:00:14,658 [salt.state       :1813][INFO    ][11672] Executing state service.running for [apache2]
2019-06-19 21:00:14,659 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11672] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-06-19 21:00:14,685 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11672] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-06-19 21:00:14,708 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11672] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-06-19 21:00:14,735 [salt.state       :300 ][INFO    ][11672] The service apache2 is already running
2019-06-19 21:00:14,736 [salt.state       :1951][INFO    ][11672] Completed state [apache2] at time 21:00:14.736175 duration_in_ms=78.611
2019-06-19 21:00:14,739 [salt.state       :1780][INFO    ][11672] Running state [maasng.wait_for_http_code] at time 21:00:14.739380
2019-06-19 21:00:14,739 [salt.state       :1813][INFO    ][11672] Executing state module.run for [maasng.wait_for_http_code]
2019-06-19 21:00:14,740 [salt.utils.decorators:613 ][WARNING ][11672] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-19 21:00:14,881 [salt.state       :300 ][INFO    ][11672] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-06-19 21:00:14,883 [salt.state       :1951][INFO    ][11672] Completed state [maasng.wait_for_http_code] at time 21:00:14.883300 duration_in_ms=143.92
2019-06-19 21:00:14,884 [salt.state       :1780][INFO    ][11672] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 21:00:14.884554
2019-06-19 21:00:14,885 [salt.state       :1813][INFO    ][11672] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-06-19 21:00:14,886 [salt.state       :300 ][INFO    ][11672] /var/lib/maas/.setup_admin exists
2019-06-19 21:00:14,886 [salt.state       :1951][INFO    ][11672] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 21:00:14.886347 duration_in_ms=1.793
2019-06-19 21:00:14,887 [salt.state       :1780][INFO    ][11672] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:00:14.887509
2019-06-19 21:00:14,888 [salt.state       :1813][INFO    ][11672] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-19 21:00:14,888 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11672] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-19 21:00:16,642 [salt.state       :300 ][INFO    ][11672] {'pid': 11738, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-19 21:00:16,643 [salt.state       :1951][INFO    ][11672] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:00:16.643787 duration_in_ms=1756.278
2019-06-19 21:00:16,650 [salt.state       :1780][INFO    ][11672] Running state [maas_region_boot_source_resources_mirror] at time 21:00:16.650689
2019-06-19 21:00:16,651 [salt.state       :1813][INFO    ][11672] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-06-19 21:00:16,739 [salt.state       :300 ][INFO    ][11672] {'changes': {}}
2019-06-19 21:00:16,740 [salt.state       :1951][INFO    ][11672] Completed state [maas_region_boot_source_resources_mirror] at time 21:00:16.740272 duration_in_ms=89.574
2019-06-19 21:00:16,741 [salt.state       :1780][INFO    ][11672] Running state [maasng.boot_resources_import] at time 21:00:16.741490
2019-06-19 21:00:16,743 [salt.state       :1813][INFO    ][11672] Executing state module.run for [maasng.boot_resources_import]
2019-06-19 21:00:16,743 [salt.utils.decorators:613 ][WARNING ][11672] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-19 21:00:16,854 [salt.loaded.ext.module.maasng:1600][INFO    ][11672] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-06-19 21:00:21,904 [salt.loaded.ext.module.maasng:1600][INFO    ][11672] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-06-19 21:00:24,393 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619210024381667
2019-06-19 21:00:24,420 [salt.minion      :1432][INFO    ][11788] Starting a new job with PID 11788
2019-06-19 21:00:24,447 [salt.minion      :1711][INFO    ][11788] Returning information for job: 20190619210024381667
2019-06-19 21:00:27,049 [salt.state       :300 ][INFO    ][11672] {'ret': True}
2019-06-19 21:00:27,049 [salt.state       :1951][INFO    ][11672] Completed state [maasng.boot_resources_import] at time 21:00:27.049374 duration_in_ms=10307.883
2019-06-19 21:00:27,052 [salt.state       :1780][INFO    ][11672] Running state [maas_region_boot_sources_selection_xenial] at time 21:00:27.052288
2019-06-19 21:00:27,052 [salt.state       :1813][INFO    ][11672] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-06-19 21:00:27,216 [salt.state       :300 ][INFO    ][11672] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-06-19 21:00:27,216 [salt.state       :1951][INFO    ][11672] Completed state [maas_region_boot_sources_selection_xenial] at time 21:00:27.216317 duration_in_ms=164.029
2019-06-19 21:00:27,217 [salt.state       :1780][INFO    ][11672] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 21:00:27.217460
2019-06-19 21:00:27,219 [salt.state       :1813][INFO    ][11672] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-06-19 21:00:27,220 [salt.utils.decorators:613 ][WARNING ][11672] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-19 21:00:27,220 [salt.loaded.ext.module.maasng:1771][INFO    ][11672] boot-sources sync initiated for ALL Rack's
2019-06-19 21:00:28,111 [salt.state       :300 ][INFO    ][11672] {'ret': True}
2019-06-19 21:00:28,112 [salt.state       :1951][INFO    ][11672] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 21:00:28.112170 duration_in_ms=894.709
2019-06-19 21:00:28,114 [salt.state       :1780][INFO    ][11672] Running state [maas.process_maas_config] at time 21:00:28.114621
2019-06-19 21:00:28,115 [salt.state       :1813][INFO    ][11672] Executing state module.run for [maas.process_maas_config]
2019-06-19 21:00:28,115 [salt.utils.decorators:613 ][WARNING ][11672] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-19 21:00:28,116 [salt.loaded.ext.module.maas:92  ][INFO    ][11672] maasconfig name=enable_http_proxy value=True
2019-06-19 21:00:28,163 [salt.loaded.ext.module.maas:92  ][INFO    ][11672] maasconfig name=upstream_dns value=8.8.8.8
2019-06-19 21:00:29,741 [salt.loaded.ext.module.maas:92  ][INFO    ][11672] maasconfig name=commissioning_distro_series value=xenial
2019-06-19 21:00:29,791 [salt.loaded.ext.module.maas:92  ][INFO    ][11672] maasconfig name=default_osystem value=ubuntu
2019-06-19 21:00:29,839 [salt.loaded.ext.module.maas:92  ][INFO    ][11672] maasconfig name=active_discovery_interval value=600
2019-06-19 21:00:29,878 [salt.loaded.ext.module.maas:92  ][INFO    ][11672] maasconfig name=dnssec_validation value=no
2019-06-19 21:00:29,940 [salt.loaded.ext.module.maas:92  ][INFO    ][11672] maasconfig name=maas_name value=mas01
2019-06-19 21:00:29,988 [salt.loaded.ext.module.maas:92  ][INFO    ][11672] maasconfig name=network_discovery value=enabled
2019-06-19 21:00:30,093 [salt.loaded.ext.module.maas:92  ][INFO    ][11672] maasconfig name=enable_third_party_drivers value=True
2019-06-19 21:00:30,162 [salt.loaded.ext.module.maas:92  ][INFO    ][11672] maasconfig name=default_storage_layout value=lvm
2019-06-19 21:00:30,203 [salt.loaded.ext.module.maas:92  ][INFO    ][11672] maasconfig name=ntp_external_only value=True
2019-06-19 21:00:30,244 [salt.loaded.ext.module.maas:92  ][INFO    ][11672] maasconfig name=disk_erase_with_secure_erase value=False
2019-06-19 21:00:30,295 [salt.loaded.ext.module.maas:92  ][INFO    ][11672] maasconfig name=default_distro_series value=xenial
2019-06-19 21:00:30,349 [salt.loaded.ext.module.maas:92  ][INFO    ][11672] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-06-19 21:00:30,476 [salt.state       :300 ][INFO    ][11672] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-06-19 21:00:30,476 [salt.state       :1951][INFO    ][11672] Completed state [maas.process_maas_config] at time 21:00:30.476651 duration_in_ms=2362.031
2019-06-19 21:00:30,477 [salt.state       :1780][INFO    ][11672] Running state [pxe_admin] at time 21:00:30.477242
2019-06-19 21:00:30,477 [salt.state       :1813][INFO    ][11672] Executing state maasng.fabric_present for [pxe_admin]
2019-06-19 21:00:30,539 [salt.loaded.ext.module.maasng:945 ][INFO    ][11672] [{u'name': u'fabric-0', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'name': u'fabric-1', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'name': u'pxe_admin', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'7br3n6', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-06-19 21:00:30,605 [salt.loaded.ext.module.maasng:1008][WARNING ][11672] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-06-19 21:00:30,605 [salt.loaded.ext.module.maasng:1011][WARNING ][11672] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-06-19 21:00:30,663 [salt.state       :300 ][INFO    ][11672] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-06-19 21:00:30,663 [salt.state       :1951][INFO    ][11672] Completed state [pxe_admin] at time 21:00:30.663202 duration_in_ms=185.959
2019-06-19 21:00:30,663 [salt.state       :1780][INFO    ][11672] Running state [vlan 0] at time 21:00:30.663478
2019-06-19 21:00:30,663 [salt.state       :1813][INFO    ][11672] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-06-19 21:00:30,720 [salt.loaded.ext.module.maasng:945 ][INFO    ][11672] [{u'name': u'fabric-0', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'name': u'fabric-1', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'name': u'pxe_admin', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'7br3n6', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-06-19 21:00:30,809 [salt.loaded.ext.module.maasng:945 ][INFO    ][11672] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'7br3n6', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-06-19 21:00:31,047 [salt.loaded.ext.module.maasng:945 ][INFO    ][11672] [{u'name': u'fabric-0', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'name': u'fabric-1', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'name': u'pxe_admin', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'7br3n6', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-06-19 21:00:31,126 [salt.state       :300 ][INFO    ][11672] {'new': 'Vlan untagged was updated'}
2019-06-19 21:00:31,126 [salt.state       :1951][INFO    ][11672] Completed state [vlan 0] at time 21:00:31.126354 duration_in_ms=462.876
2019-06-19 21:00:31,127 [salt.state       :1780][INFO    ][11672] Running state [192.168.11.0/24] at time 21:00:31.127415
2019-06-19 21:00:31,127 [salt.state       :1813][INFO    ][11672] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-06-19 21:00:31,279 [salt.loaded.ext.module.maasng:945 ][INFO    ][11672] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'7br3n6', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-06-19 21:00:31,279 [salt.loaded.ext.module.maasng:1235][WARNING ][11672] Ignoring parameter vlan:0
2019-06-19 21:00:31,350 [salt.state       :300 ][INFO    ][11672] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-06-19 21:00:31,350 [salt.state       :1951][INFO    ][11672] Completed state [192.168.11.0/24] at time 21:00:31.350500 duration_in_ms=223.085
2019-06-19 21:00:31,351 [salt.state       :1780][INFO    ][11672] Running state [maas_create_iprange_1] at time 21:00:31.351262
2019-06-19 21:00:31,351 [salt.state       :1813][INFO    ][11672] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-06-19 21:00:31,403 [salt.state       :300 ][INFO    ][11672] Iprange maas_create_iprange_1 already exist.
2019-06-19 21:00:31,403 [salt.state       :1951][INFO    ][11672] Completed state [maas_create_iprange_1] at time 21:00:31.403489 duration_in_ms=52.227
2019-06-19 21:00:31,403 [salt.state       :1780][INFO    ][11672] Running state [vlan 0] at time 21:00:31.403745
2019-06-19 21:00:31,404 [salt.state       :1813][INFO    ][11672] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-06-19 21:00:31,451 [salt.loaded.ext.module.maasng:945 ][INFO    ][11672] [{u'name': u'fabric-0', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'name': u'fabric-1', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'name': u'pxe_admin', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'7br3n6', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-06-19 21:00:31,538 [salt.loaded.ext.module.maasng:945 ][INFO    ][11672] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'7br3n6', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-06-19 21:00:31,748 [salt.loaded.ext.module.maasng:945 ][INFO    ][11672] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'7br3n6', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-06-19 21:00:31,818 [salt.state       :300 ][INFO    ][11672] {'new': 'Vlan untagged was updated'}
2019-06-19 21:00:31,818 [salt.state       :1951][INFO    ][11672] Completed state [vlan 0] at time 21:00:31.818203 duration_in_ms=414.458
2019-06-19 21:00:31,818 [salt.state       :1780][INFO    ][11672] Running state [opnfv] at time 21:00:31.818785
2019-06-19 21:00:31,819 [salt.state       :1813][INFO    ][11672] Executing state maasng.sshkey_present for [opnfv]
2019-06-19 21:00:31,867 [salt.loaded.ext.module.maasng:1903][INFO    ][11672] [{u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b', u'keysource': u''}]
2019-06-19 21:00:31,867 [salt.state       :300 ][INFO    ][11672] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-06-19 21:00:31,867 [salt.state       :1951][INFO    ][11672] Completed state [opnfv] at time 21:00:31.867836 duration_in_ms=49.051
2019-06-19 21:00:31,868 [salt.state       :1780][INFO    ][11672] Running state [maas.process_tags] at time 21:00:31.868344
2019-06-19 21:00:31,868 [salt.state       :1813][INFO    ][11672] Executing state module.run for [maas.process_tags]
2019-06-19 21:00:31,868 [salt.utils.decorators:613 ][WARNING ][11672] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-19 21:00:31,928 [salt.loaded.ext.module.maas:92  ][INFO    ][11672] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-06-19 21:00:31,998 [salt.state       :300 ][INFO    ][11672] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-06-19 21:00:31,998 [salt.state       :1951][INFO    ][11672] Completed state [maas.process_tags] at time 21:00:31.998851 duration_in_ms=130.505
2019-06-19 21:00:32,002 [salt.minion      :1711][INFO    ][11672] Returning information for job: 20190619210009267052
2019-06-19 21:00:32,612 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command state.apply with jid 20190619210032593056
2019-06-19 21:00:32,638 [salt.minion      :1432][INFO    ][12156] Starting a new job with PID 12156
2019-06-19 21:00:38,590 [salt.state       :915 ][INFO    ][12156] Loading fresh modules for state activity
2019-06-19 21:00:38,698 [salt.state       :1780][INFO    ][12156] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:00:38.698526
2019-06-19 21:00:38,698 [salt.state       :1813][INFO    ][12156] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-19 21:00:38,700 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12156] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-19 21:00:40,416 [salt.state       :300 ][INFO    ][12156] {'pid': 12179, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-19 21:00:40,418 [salt.state       :1951][INFO    ][12156] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:00:40.417610 duration_in_ms=1719.083
2019-06-19 21:00:40,421 [salt.state       :1780][INFO    ][12156] Running state [maas.process_machines] at time 21:00:40.421494
2019-06-19 21:00:40,422 [salt.state       :1813][INFO    ][12156] Executing state module.run for [maas.process_machines]
2019-06-19 21:00:40,424 [salt.utils.decorators:613 ][WARNING ][12156] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-19 21:00:40,957 [salt.loaded.ext.module.maas:412 ][WARNING ][12156] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-19 21:00:40,959 [salt.loaded.ext.module.maas:92  ][INFO    ][12156] machine hostname=gtw01 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=gpfqmx architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-19 21:00:42,169 [salt.loaded.ext.module.maas:412 ][WARNING ][12156] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-19 21:00:42,171 [salt.loaded.ext.module.maas:92  ][INFO    ][12156] 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=exd4t6 architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-19 21:00:43,431 [salt.loaded.ext.module.maas:412 ][WARNING ][12156] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-19 21:00:43,431 [salt.loaded.ext.module.maas:92  ][INFO    ][12156] 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=qpb4tt architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-19 21:00:44,684 [salt.loaded.ext.module.maas:412 ][WARNING ][12156] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-19 21:00:44,686 [salt.loaded.ext.module.maas:92  ][INFO    ][12156] machine hostname=ctl01 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=gk6hnc architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-19 21:00:45,888 [salt.state       :300 ][INFO    ][12156] {'ret': {'updated': ['gtw01', 'cmp002', 'cmp001', 'ctl01'], 'errors': {}, 'success': []}}
2019-06-19 21:00:45,889 [salt.state       :1951][INFO    ][12156] Completed state [maas.process_machines] at time 21:00:45.888981 duration_in_ms=5467.486
2019-06-19 21:00:45,894 [salt.minion      :1711][INFO    ][12156] Returning information for job: 20190619210032593056
2019-06-19 21:01:18,479 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command state.apply with jid 20190619210118466534
2019-06-19 21:01:18,509 [salt.minion      :1432][INFO    ][12394] Starting a new job with PID 12394
2019-06-19 21:01:24,412 [salt.state       :915 ][INFO    ][12394] Loading fresh modules for state activity
2019-06-19 21:01:24,514 [salt.state       :1780][INFO    ][12394] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:01:24.514308
2019-06-19 21:01:24,514 [salt.state       :1813][INFO    ][12394] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-19 21:01:24,516 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12394] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-19 21:01:26,254 [salt.state       :300 ][INFO    ][12394] {'pid': 12402, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-19 21:01:26,255 [salt.state       :1951][INFO    ][12394] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:01:26.255341 duration_in_ms=1741.032
2019-06-19 21:01:26,260 [salt.state       :1780][INFO    ][12394] Running state [maas.wait_for_machine_status] at time 21:01:26.260460
2019-06-19 21:01:26,260 [salt.state       :1813][INFO    ][12394] Executing state module.run for [maas.wait_for_machine_status]
2019-06-19 21:01:26,261 [salt.utils.decorators:613 ][WARNING ][12394] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-19 21:01:28,258 [salt.state       :300 ][INFO    ][12394] {'ret': True}
2019-06-19 21:01:28,259 [salt.state       :1951][INFO    ][12394] Completed state [maas.wait_for_machine_status] at time 21:01:28.259264 duration_in_ms=1998.802
2019-06-19 21:01:28,265 [salt.minion      :1711][INFO    ][12394] Returning information for job: 20190619210118466534
2019-06-19 21:01:28,835 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command state.apply with jid 20190619210128819486
2019-06-19 21:01:28,860 [salt.minion      :1432][INFO    ][12430] Starting a new job with PID 12430
2019-06-19 21:01:29,780 [salt.state       :915 ][INFO    ][12430] Loading fresh modules for state activity
2019-06-19 21:01:29,929 [salt.state       :1780][INFO    ][12430] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:01:29.929387
2019-06-19 21:01:29,930 [salt.state       :1813][INFO    ][12430] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-19 21:01:29,932 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12430] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-19 21:01:31,679 [salt.state       :300 ][INFO    ][12430] {'pid': 12437, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-19 21:01:31,680 [salt.state       :1951][INFO    ][12430] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:01:31.680379 duration_in_ms=1750.991
2019-06-19 21:01:31,683 [salt.state       :1780][INFO    ][12430] Running state [maas_machines_storage_cmp002_lvm] at time 21:01:31.683293
2019-06-19 21:01:31,683 [salt.state       :1813][INFO    ][12430] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-06-19 21:01:32,128 [salt.state       :300 ][INFO    ][12430] Machine cmp002 is not in Ready state.
2019-06-19 21:01:32,129 [salt.state       :1951][INFO    ][12430] Completed state [maas_machines_storage_cmp002_lvm] at time 21:01:32.129249 duration_in_ms=445.956
2019-06-19 21:01:32,129 [salt.state       :1780][INFO    ][12430] Running state [maas_machines_storage_cmp001_lvm] at time 21:01:32.129670
2019-06-19 21:01:32,129 [salt.state       :1813][INFO    ][12430] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-06-19 21:01:32,600 [salt.state       :300 ][INFO    ][12430] Machine cmp001 is not in Ready state.
2019-06-19 21:01:32,600 [salt.state       :1951][INFO    ][12430] Completed state [maas_machines_storage_cmp001_lvm] at time 21:01:32.600742 duration_in_ms=471.072
2019-06-19 21:01:32,604 [salt.minion      :1711][INFO    ][12430] Returning information for job: 20190619210128819486
2019-06-19 21:01:33,160 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command state.apply with jid 20190619210133149393
2019-06-19 21:01:33,180 [salt.minion      :1432][INFO    ][12447] Starting a new job with PID 12447
2019-06-19 21:01:34,056 [salt.state       :915 ][INFO    ][12447] Loading fresh modules for state activity
2019-06-19 21:01:34,159 [salt.state       :1780][INFO    ][12447] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:01:34.159574
2019-06-19 21:01:34,159 [salt.state       :1813][INFO    ][12447] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-19 21:01:34,161 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12447] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-19 21:01:35,868 [salt.state       :300 ][INFO    ][12447] {'pid': 12454, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-19 21:01:35,869 [salt.state       :1951][INFO    ][12447] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:01:35.869387 duration_in_ms=1709.813
2019-06-19 21:01:35,874 [salt.state       :1780][INFO    ][12447] Running state [maas.deploy_machines] at time 21:01:35.874106
2019-06-19 21:01:35,874 [salt.state       :1813][INFO    ][12447] Executing state module.run for [maas.deploy_machines]
2019-06-19 21:01:35,875 [salt.utils.decorators:613 ][WARNING ][12447] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-19 21:01:36,360 [salt.loaded.ext.module.maas:684 ][INFO    ][12447] deploymachines hwe_kernel=hwe-16.04 system_id=gpfqmx distro_series=xenial
2019-06-19 21:01:38,595 [salt.state       :300 ][INFO    ][12447] {'ret': {'updated': ['cmp002', 'cmp001', 'ctl01'], 'errors': {}, 'success': ['gtw01']}}
2019-06-19 21:01:38,596 [salt.state       :1951][INFO    ][12447] Completed state [maas.deploy_machines] at time 21:01:38.596333 duration_in_ms=2722.226
2019-06-19 21:01:38,599 [salt.minion      :1711][INFO    ][12447] Returning information for job: 20190619210133149393
2019-06-19 21:01:39,226 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command state.apply with jid 20190619210139162245
2019-06-19 21:01:39,248 [salt.minion      :1432][INFO    ][12516] Starting a new job with PID 12516
2019-06-19 21:01:40,104 [salt.state       :915 ][INFO    ][12516] Loading fresh modules for state activity
2019-06-19 21:01:40,212 [salt.state       :1780][INFO    ][12516] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:01:40.211957
2019-06-19 21:01:40,212 [salt.state       :1813][INFO    ][12516] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-19 21:01:40,214 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12516] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-19 21:01:41,979 [salt.state       :300 ][INFO    ][12516] {'pid': 12523, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-19 21:01:41,980 [salt.state       :1951][INFO    ][12516] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:01:41.980096 duration_in_ms=1768.138
2019-06-19 21:01:41,984 [salt.state       :1780][INFO    ][12516] Running state [maas.wait_for_machine_status] at time 21:01:41.984243
2019-06-19 21:01:41,984 [salt.state       :1813][INFO    ][12516] Executing state module.run for [maas.wait_for_machine_status]
2019-06-19 21:01:41,985 [salt.utils.decorators:613 ][WARNING ][12516] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-19 21:01:43,895 [salt.loaded.ext.module.maas:1023][INFO    ][12516] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2248.10561895s left)
2019-06-19 21:01:54,282 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619210154265091
2019-06-19 21:01:54,309 [salt.minion      :1432][INFO    ][12543] Starting a new job with PID 12543
2019-06-19 21:01:54,332 [salt.minion      :1711][INFO    ][12543] Returning information for job: 20190619210154265091
2019-06-19 21:02:15,827 [salt.loaded.ext.module.maas:1023][INFO    ][12516] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2216.17321014s left)
2019-06-19 21:02:24,367 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619210224349211
2019-06-19 21:02:24,395 [salt.minion      :1432][INFO    ][12598] Starting a new job with PID 12598
2019-06-19 21:02:24,420 [salt.minion      :1711][INFO    ][12598] Returning information for job: 20190619210224349211
2019-06-19 21:02:47,837 [salt.loaded.ext.module.maas:1023][INFO    ][12516] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2184.16361117s left)
2019-06-19 21:02:54,441 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619210254424323
2019-06-19 21:02:54,469 [salt.minion      :1432][INFO    ][12643] Starting a new job with PID 12643
2019-06-19 21:02:54,491 [salt.minion      :1711][INFO    ][12643] Returning information for job: 20190619210254424323
2019-06-19 21:03:19,872 [salt.loaded.ext.module.maas:1023][INFO    ][12516] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2152.12860894s left)
2019-06-19 21:03:24,525 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619210324509621
2019-06-19 21:03:24,555 [salt.minion      :1432][INFO    ][12686] Starting a new job with PID 12686
2019-06-19 21:03:24,576 [salt.minion      :1711][INFO    ][12686] Returning information for job: 20190619210324509621
2019-06-19 21:03:51,706 [salt.loaded.ext.module.maas:1023][INFO    ][12516] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2120.29385209s left)
2019-06-19 21:03:54,608 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619210354593973
2019-06-19 21:03:54,639 [salt.minion      :1432][INFO    ][12720] Starting a new job with PID 12720
2019-06-19 21:03:54,662 [salt.minion      :1711][INFO    ][12720] Returning information for job: 20190619210354593973
2019-06-19 21:04:23,705 [salt.loaded.ext.module.maas:1023][INFO    ][12516] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2088.29527307s left)
2019-06-19 21:04:24,699 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619210424687818
2019-06-19 21:04:24,719 [salt.minion      :1432][INFO    ][12765] Starting a new job with PID 12765
2019-06-19 21:04:24,744 [salt.minion      :1711][INFO    ][12765] Returning information for job: 20190619210424687818
2019-06-19 21:04:54,769 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619210454759579
2019-06-19 21:04:54,787 [salt.minion      :1432][INFO    ][12803] Starting a new job with PID 12803
2019-06-19 21:04:54,810 [salt.minion      :1711][INFO    ][12803] Returning information for job: 20190619210454759579
2019-06-19 21:04:55,620 [salt.loaded.ext.module.maas:1023][INFO    ][12516] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2056.38050699s left)
2019-06-19 21:05:24,848 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619210524835254
2019-06-19 21:05:24,879 [salt.minion      :1432][INFO    ][12842] Starting a new job with PID 12842
2019-06-19 21:05:24,903 [salt.minion      :1711][INFO    ][12842] Returning information for job: 20190619210524835254
2019-06-19 21:05:27,812 [salt.loaded.ext.module.maas:1023][INFO    ][12516] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2024.18775415s left)
2019-06-19 21:05:54,952 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619210554937215
2019-06-19 21:05:54,973 [salt.minion      :1432][INFO    ][12896] Starting a new job with PID 12896
2019-06-19 21:05:54,996 [salt.minion      :1711][INFO    ][12896] Returning information for job: 20190619210554937215
2019-06-19 21:05:59,572 [salt.loaded.ext.module.maas:1023][INFO    ][12516] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1992.42809105s left)
2019-06-19 21:06:25,060 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619210625044210
2019-06-19 21:06:25,091 [salt.minion      :1432][INFO    ][12964] Starting a new job with PID 12964
2019-06-19 21:06:25,114 [salt.minion      :1711][INFO    ][12964] Returning information for job: 20190619210625044210
2019-06-19 21:06:31,565 [salt.loaded.ext.module.maas:1023][INFO    ][12516] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1960.43554616s left)
2019-06-19 21:06:55,180 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619210655166654
2019-06-19 21:06:55,207 [salt.minion      :1432][INFO    ][13041] Starting a new job with PID 13041
2019-06-19 21:06:55,238 [salt.minion      :1711][INFO    ][13041] Returning information for job: 20190619210655166654
2019-06-19 21:07:03,419 [salt.loaded.ext.module.maas:1023][INFO    ][12516] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1928.58125305s left)
2019-06-19 21:07:25,308 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619210725290633
2019-06-19 21:07:25,335 [salt.minion      :1432][INFO    ][13123] Starting a new job with PID 13123
2019-06-19 21:07:25,361 [salt.minion      :1711][INFO    ][13123] Returning information for job: 20190619210725290633
2019-06-19 21:07:35,310 [salt.loaded.ext.module.maas:1023][INFO    ][12516] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1896.69018912s left)
2019-06-19 21:07:55,427 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619210755414549
2019-06-19 21:07:55,458 [salt.minion      :1432][INFO    ][13231] Starting a new job with PID 13231
2019-06-19 21:07:55,488 [salt.minion      :1711][INFO    ][13231] Returning information for job: 20190619210755414549
2019-06-19 21:08:07,316 [salt.loaded.ext.module.maas:1023][INFO    ][12516] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1864.68438816s left)
2019-06-19 21:08:25,552 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619210825537021
2019-06-19 21:08:25,581 [salt.minion      :1432][INFO    ][13280] Starting a new job with PID 13280
2019-06-19 21:08:25,611 [salt.minion      :1711][INFO    ][13280] Returning information for job: 20190619210825537021
2019-06-19 21:08:39,211 [salt.loaded.ext.module.maas:1023][INFO    ][12516] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1832.78900909s left)
2019-06-19 21:08:55,691 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619210855676007
2019-06-19 21:08:55,716 [salt.minion      :1432][INFO    ][13319] Starting a new job with PID 13319
2019-06-19 21:08:55,741 [salt.minion      :1711][INFO    ][13319] Returning information for job: 20190619210855676007
2019-06-19 21:09:11,272 [salt.loaded.ext.module.maas:1023][INFO    ][12516] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1800.72865009s left)
2019-06-19 21:09:25,831 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619210925815163
2019-06-19 21:09:25,859 [salt.minion      :1432][INFO    ][13384] Starting a new job with PID 13384
2019-06-19 21:09:25,891 [salt.minion      :1711][INFO    ][13384] Returning information for job: 20190619210925815163
2019-06-19 21:09:43,484 [salt.loaded.ext.module.maas:1023][INFO    ][12516] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1768.51610208s left)
2019-06-19 21:09:56,004 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619210955992196
2019-06-19 21:09:56,032 [salt.minion      :1432][INFO    ][13505] Starting a new job with PID 13505
2019-06-19 21:09:56,068 [salt.minion      :1711][INFO    ][13505] Returning information for job: 20190619210955992196
2019-06-19 21:10:15,741 [salt.loaded.ext.module.maas:1023][INFO    ][12516] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1736.25931501s left)
2019-06-19 21:10:26,184 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619211026170650
2019-06-19 21:10:26,220 [salt.minion      :1432][INFO    ][13576] Starting a new job with PID 13576
2019-06-19 21:10:26,253 [salt.minion      :1711][INFO    ][13576] Returning information for job: 20190619211026170650
2019-06-19 21:10:47,775 [salt.loaded.ext.module.maas:1023][INFO    ][12516] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1704.22563601s left)
2019-06-19 21:10:56,376 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619211056359594
2019-06-19 21:10:56,401 [salt.minion      :1432][INFO    ][13652] Starting a new job with PID 13652
2019-06-19 21:10:56,424 [salt.minion      :1711][INFO    ][13652] Returning information for job: 20190619211056359594
2019-06-19 21:11:19,752 [salt.loaded.ext.module.maas:1023][INFO    ][12516] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1672.24843907s left)
2019-06-19 21:11:26,551 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619211126543470
2019-06-19 21:11:26,572 [salt.minion      :1432][INFO    ][13704] Starting a new job with PID 13704
2019-06-19 21:11:26,597 [salt.minion      :1711][INFO    ][13704] Returning information for job: 20190619211126543470
2019-06-19 21:11:51,778 [salt.loaded.ext.module.maas:1023][INFO    ][12516] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1640.22200608s left)
2019-06-19 21:11:56,708 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619211156692352
2019-06-19 21:11:56,740 [salt.minion      :1432][INFO    ][13729] Starting a new job with PID 13729
2019-06-19 21:11:56,764 [salt.minion      :1711][INFO    ][13729] Returning information for job: 20190619211156692352
2019-06-19 21:12:19,588 [salt.utils.schedule:1377][INFO    ][3024] Running scheduled job: __mine_interval
2019-06-19 21:12:23,732 [salt.loaded.ext.module.maas:1023][INFO    ][12516] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1608.268255s left)
2019-06-19 21:12:26,892 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619211226880244
2019-06-19 21:12:26,921 [salt.minion      :1432][INFO    ][13800] Starting a new job with PID 13800
2019-06-19 21:12:26,951 [salt.minion      :1711][INFO    ][13800] Returning information for job: 20190619211226880244
2019-06-19 21:12:55,700 [salt.loaded.ext.module.maas:1023][INFO    ][12516] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1576.300524s left)
2019-06-19 21:12:57,063 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619211257047561
2019-06-19 21:12:57,088 [salt.minion      :1432][INFO    ][13832] Starting a new job with PID 13832
2019-06-19 21:12:57,113 [salt.minion      :1711][INFO    ][13832] Returning information for job: 20190619211257047561
2019-06-19 21:13:27,256 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619211327244727
2019-06-19 21:13:27,276 [salt.minion      :1432][INFO    ][14031] Starting a new job with PID 14031
2019-06-19 21:13:27,301 [salt.minion      :1711][INFO    ][14031] Returning information for job: 20190619211327244727
2019-06-19 21:13:27,841 [salt.loaded.ext.module.maas:1023][INFO    ][12516] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1544.159343s left)
2019-06-19 21:13:57,429 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619211357414961
2019-06-19 21:13:57,459 [salt.minion      :1432][INFO    ][14049] Starting a new job with PID 14049
2019-06-19 21:13:57,484 [salt.minion      :1711][INFO    ][14049] Returning information for job: 20190619211357414961
2019-06-19 21:13:59,970 [salt.loaded.ext.module.maas:1023][INFO    ][12516] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1512.03039718s left)
2019-06-19 21:14:27,620 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command saltutil.find_job with jid 20190619211427610149
2019-06-19 21:14:27,645 [salt.minion      :1432][INFO    ][14189] Starting a new job with PID 14189
2019-06-19 21:14:27,667 [salt.minion      :1711][INFO    ][14189] Returning information for job: 20190619211427610149
2019-06-19 21:14:31,937 [salt.state       :300 ][INFO    ][12516] {'ret': True}
2019-06-19 21:14:31,938 [salt.state       :1951][INFO    ][12516] Completed state [maas.wait_for_machine_status] at time 21:14:31.938139 duration_in_ms=769953.895
2019-06-19 21:14:31,941 [salt.minion      :1711][INFO    ][12516] Returning information for job: 20190619210139162245
2019-06-19 22:06:07,428 [salt.minion      :1308][INFO    ][3024] User sudo_ubuntu Executing command cp.push_dir with jid 20190619220607415753
2019-06-19 22:06:07,462 [salt.minion      :1432][INFO    ][17869] Starting a new job with PID 17869
