2019-05-08 20:06:47,338 [salt.utils.decorators:613 ][WARNING ][2219] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-08 20:06:48,345 [salt.utils.decorators:613 ][WARNING ][2219] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-08 20:06:51,096 [salt.loaded.int.states.file:2298][WARNING ][2436] 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-05-08 20:07:06,207 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2810] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-08 20:07:06,239 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2810] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-05-08 20:07:06,282 [salt.utils.parsers:1051][WARNING ][378] Minion received a SIGTERM. Exiting.
2019-05-08 20:07:07,267 [salt.cli.daemons :293 ][INFO    ][2934] Setting up the Salt Minion "mas01.mcp-fdio-noha.local"
2019-05-08 20:07:07,390 [salt.cli.daemons :82  ][INFO    ][2934] Starting up the Salt Minion
2019-05-08 20:07:07,390 [salt.utils.event :1017][INFO    ][2934] Starting pull socket on /var/run/salt/minion/minion_event_38d774b16c_pull.ipc
2019-05-08 20:07:08,584 [salt.minion      :976 ][INFO    ][2934] Creating minion process manager
2019-05-08 20:07:10,436 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][2934] Executing command ['date', '+%z'] in directory '/root'
2019-05-08 20:07:10,455 [salt.utils.schedule:568 ][INFO    ][2934] Updating job settings for scheduled job: __mine_interval
2019-05-08 20:07:10,462 [salt.minion      :1108][INFO    ][2934] Added mine.update to scheduler
2019-05-08 20:07:10,471 [salt.minion      :1975][INFO    ][2934] Minion is starting as user 'root'
2019-05-08 20:07:10,489 [salt.minion      :2336][INFO    ][2934] Minion is ready to receive requests!
2019-05-08 20:07:14,773 [salt.state       :2022][WARNING ][2813] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-08 20:07:17,785 [salt.utils.decorators:613 ][WARNING ][2813] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-08 20:07:18,941 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508200718931414
2019-05-08 20:07:18,955 [salt.minion      :1432][INFO    ][3348] Starting a new job with PID 3348
2019-05-08 20:07:18,978 [salt.minion      :1711][INFO    ][3348] Returning information for job: 20190508200718931414
2019-05-08 20:07:49,030 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508200749018982
2019-05-08 20:07:49,049 [salt.minion      :1432][INFO    ][3663] Starting a new job with PID 3663
2019-05-08 20:07:49,068 [salt.minion      :1711][INFO    ][3663] Returning information for job: 20190508200749018982
2019-05-08 20:07:49,780 [salt.utils.decorators:613 ][WARNING ][2813] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-08 20:08:15,682 [salt.utils.decorators:613 ][WARNING ][2813] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-08 20:08:16,595 [salt.utils.decorators:613 ][WARNING ][2813] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-08 20:08:18,743 [salt.loaded.ext.module.maasng:1008][WARNING ][2813] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-05-08 20:08:18,744 [salt.loaded.ext.module.maasng:1011][WARNING ][2813] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-05-08 20:08:19,115 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508200819104312
2019-05-08 20:08:19,136 [salt.minion      :1432][INFO    ][4422] Starting a new job with PID 4422
2019-05-08 20:08:19,157 [salt.minion      :1711][INFO    ][4422] Returning information for job: 20190508200819104312
2019-05-08 20:08:19,452 [salt.loaded.ext.module.maasng:1235][WARNING ][2813] Ignoring parameter vlan:0
2019-05-08 20:08:20,337 [salt.utils.decorators:613 ][WARNING ][2813] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-08 20:08:21,085 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command state.apply with jid 20190508200821077210
2019-05-08 20:08:21,105 [salt.minion      :1432][INFO    ][4470] Starting a new job with PID 4470
2019-05-08 20:08:27,073 [salt.state       :915 ][INFO    ][4470] Loading fresh modules for state activity
2019-05-08 20:08:27,139 [salt.fileclient  :1219][INFO    ][4470] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-05-08 20:08:27,189 [salt.state       :1780][INFO    ][4470] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:08:27.189600
2019-05-08 20:08:27,189 [salt.state       :1813][INFO    ][4470] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-08 20:08:27,191 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4470] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-08 20:08:28,927 [salt.state       :300 ][INFO    ][4470] {'pid': 4507, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-08 20:08:28,928 [salt.state       :1951][INFO    ][4470] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:08:28.928394 duration_in_ms=1738.794
2019-05-08 20:08:28,931 [salt.state       :1780][INFO    ][4470] Running state [maas.process_machines] at time 20:08:28.931315
2019-05-08 20:08:28,932 [salt.state       :1813][INFO    ][4470] Executing state module.run for [maas.process_machines]
2019-05-08 20:08:28,932 [salt.utils.decorators:613 ][WARNING ][4470] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-08 20:08:28,996 [salt.loaded.ext.module.maas:412 ][WARNING ][4470] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-08 20:08:28,996 [salt.loaded.ext.module.maas:92  ][INFO    ][4470] 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-05-08 20:08:30,633 [salt.loaded.ext.module.maas:412 ][WARNING ][4470] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-08 20:08:30,634 [salt.loaded.ext.module.maas:92  ][INFO    ][4470] 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-05-08 20:08:32,157 [salt.loaded.ext.module.maas:412 ][WARNING ][4470] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-08 20:08:32,158 [salt.loaded.ext.module.maas:92  ][INFO    ][4470] 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-05-08 20:08:33,809 [salt.loaded.ext.module.maas:412 ][WARNING ][4470] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-08 20:08:33,809 [salt.loaded.ext.module.maas:92  ][INFO    ][4470] 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-05-08 20:08:35,322 [salt.state       :300 ][INFO    ][4470] {'ret': {'updated': [], 'errors': {}, 'success': ['gtw01', 'cmp002', 'cmp001', 'ctl01']}}
2019-05-08 20:08:35,323 [salt.state       :1951][INFO    ][4470] Completed state [maas.process_machines] at time 20:08:35.323038 duration_in_ms=6391.722
2019-05-08 20:08:35,326 [salt.minion      :1711][INFO    ][4470] Returning information for job: 20190508200821077210
2019-05-08 20:09:06,389 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command state.apply with jid 20190508200906375182
2019-05-08 20:09:06,417 [salt.minion      :1432][INFO    ][4778] Starting a new job with PID 4778
2019-05-08 20:09:12,258 [salt.state       :915 ][INFO    ][4778] Loading fresh modules for state activity
2019-05-08 20:09:12,322 [salt.fileclient  :1219][INFO    ][4778] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-05-08 20:09:12,374 [salt.state       :1780][INFO    ][4778] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:09:12.374700
2019-05-08 20:09:12,375 [salt.state       :1813][INFO    ][4778] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-08 20:09:12,376 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4778] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-08 20:09:14,056 [salt.state       :300 ][INFO    ][4778] {'pid': 4785, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-08 20:09:14,057 [salt.state       :1951][INFO    ][4778] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:09:14.057525 duration_in_ms=1682.825
2019-05-08 20:09:14,061 [salt.state       :1780][INFO    ][4778] Running state [maas.wait_for_machine_status] at time 20:09:14.060931
2019-05-08 20:09:14,061 [salt.state       :1813][INFO    ][4778] Executing state module.run for [maas.wait_for_machine_status]
2019-05-08 20:09:14,062 [salt.utils.decorators:613 ][WARNING ][4778] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-08 20:09:14,681 [salt.loaded.ext.module.maas:1023][INFO    ][4778] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1499.39289093s left)
2019-05-08 20:09:21,452 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508200921438431
2019-05-08 20:09:21,473 [salt.minion      :1432][INFO    ][4822] Starting a new job with PID 4822
2019-05-08 20:09:21,492 [salt.minion      :1711][INFO    ][4822] Returning information for job: 20190508200921438431
2019-05-08 20:09:45,327 [salt.loaded.ext.module.maas:1023][INFO    ][4778] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1468.74688482s left)
2019-05-08 20:09:51,525 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508200951510079
2019-05-08 20:09:51,550 [salt.minion      :1432][INFO    ][4865] Starting a new job with PID 4865
2019-05-08 20:09:51,572 [salt.minion      :1711][INFO    ][4865] Returning information for job: 20190508200951510079
2019-05-08 20:10:15,962 [salt.loaded.ext.module.maas:1023][INFO    ][4778] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1438.11216497s left)
2019-05-08 20:10:21,594 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508201021580748
2019-05-08 20:10:21,622 [salt.minion      :1432][INFO    ][4916] Starting a new job with PID 4916
2019-05-08 20:10:21,648 [salt.minion      :1711][INFO    ][4916] Returning information for job: 20190508201021580748
2019-05-08 20:10:46,588 [salt.loaded.ext.module.maas:1023][INFO    ][4778] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1407.48596692s left)
2019-05-08 20:10:51,667 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508201051656128
2019-05-08 20:10:51,694 [salt.minion      :1432][INFO    ][4961] Starting a new job with PID 4961
2019-05-08 20:10:51,715 [salt.minion      :1711][INFO    ][4961] Returning information for job: 20190508201051656128
2019-05-08 20:11:17,411 [salt.loaded.ext.module.maas:1023][INFO    ][4778] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1376.66324401s left)
2019-05-08 20:11:21,761 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508201121748095
2019-05-08 20:11:21,789 [salt.minion      :1432][INFO    ][5038] Starting a new job with PID 5038
2019-05-08 20:11:21,809 [salt.minion      :1711][INFO    ][5038] Returning information for job: 20190508201121748095
2019-05-08 20:11:48,299 [salt.loaded.ext.module.maas:1023][INFO    ][4778] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1345.77451491s left)
2019-05-08 20:11:51,839 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508201151822731
2019-05-08 20:11:51,868 [salt.minion      :1432][INFO    ][5137] Starting a new job with PID 5137
2019-05-08 20:11:51,891 [salt.minion      :1711][INFO    ][5137] Returning information for job: 20190508201151822731
2019-05-08 20:12:19,512 [salt.loaded.ext.module.maas:1023][INFO    ][4778] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1314.56209397s left)
2019-05-08 20:12:21,982 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508201221966748
2019-05-08 20:12:22,012 [salt.minion      :1432][INFO    ][5360] Starting a new job with PID 5360
2019-05-08 20:12:22,033 [salt.minion      :1711][INFO    ][5360] Returning information for job: 20190508201221966748
2019-05-08 20:12:50,498 [salt.loaded.ext.module.maas:1023][INFO    ][4778] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1283.57575893s left)
2019-05-08 20:12:52,074 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508201252065047
2019-05-08 20:12:52,090 [salt.minion      :1432][INFO    ][5550] Starting a new job with PID 5550
2019-05-08 20:12:52,110 [salt.minion      :1711][INFO    ][5550] Returning information for job: 20190508201252065047
2019-05-08 20:13:21,754 [salt.loaded.ext.module.maas:1023][INFO    ][4778] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1252.31961584s left)
2019-05-08 20:13:22,180 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508201322168087
2019-05-08 20:13:22,196 [salt.minion      :1432][INFO    ][5690] Starting a new job with PID 5690
2019-05-08 20:13:22,220 [salt.minion      :1711][INFO    ][5690] Returning information for job: 20190508201322168087
2019-05-08 20:13:52,276 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508201352265660
2019-05-08 20:13:52,296 [salt.minion      :1432][INFO    ][5877] Starting a new job with PID 5877
2019-05-08 20:13:52,320 [salt.minion      :1711][INFO    ][5877] Returning information for job: 20190508201352265660
2019-05-08 20:13:53,363 [salt.loaded.ext.module.maas:1023][INFO    ][4778] Waiting status:Ready|Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:1500s (1220.71067786s left)
2019-05-08 20:14:22,423 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508201422406983
2019-05-08 20:14:22,448 [salt.minion      :1432][INFO    ][6172] Starting a new job with PID 6172
2019-05-08 20:14:22,472 [salt.minion      :1711][INFO    ][6172] Returning information for job: 20190508201422406983
2019-05-08 20:14:24,886 [salt.loaded.ext.module.maas:1023][INFO    ][4778] Waiting status:Ready|Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:1500s (1189.18787599s left)
2019-05-08 20:14:52,553 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508201452539955
2019-05-08 20:14:52,572 [salt.minion      :1432][INFO    ][6242] Starting a new job with PID 6242
2019-05-08 20:14:52,594 [salt.minion      :1711][INFO    ][6242] Returning information for job: 20190508201452539955
2019-05-08 20:14:56,564 [salt.loaded.ext.module.maas:1023][INFO    ][4778] Waiting status:Ready|Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:1500s (1157.51042604s left)
2019-05-08 20:15:22,664 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508201522651059
2019-05-08 20:15:22,692 [salt.minion      :1432][INFO    ][6380] Starting a new job with PID 6380
2019-05-08 20:15:22,712 [salt.minion      :1711][INFO    ][6380] Returning information for job: 20190508201522651059
2019-05-08 20:15:28,666 [salt.state       :300 ][INFO    ][4778] {'ret': True}
2019-05-08 20:15:28,667 [salt.state       :1951][INFO    ][4778] Completed state [maas.wait_for_machine_status] at time 20:15:28.666926 duration_in_ms=374605.995
2019-05-08 20:15:28,672 [salt.minion      :1711][INFO    ][4778] Returning information for job: 20190508200906375182
2019-05-08 20:15:29,261 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command state.apply with jid 20190508201529239486
2019-05-08 20:15:29,291 [salt.minion      :1432][INFO    ][6403] Starting a new job with PID 6403
2019-05-08 20:15:35,355 [salt.state       :915 ][INFO    ][6403] Loading fresh modules for state activity
2019-05-08 20:15:35,416 [salt.fileclient  :1219][INFO    ][6403] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-05-08 20:15:35,514 [salt.state       :1780][INFO    ][6403] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:15:35.514232
2019-05-08 20:15:35,514 [salt.state       :1813][INFO    ][6403] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-08 20:15:35,516 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6403] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-08 20:15:37,232 [salt.state       :300 ][INFO    ][6403] {'pid': 6446, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-08 20:15:37,233 [salt.state       :1951][INFO    ][6403] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:15:37.233279 duration_in_ms=1719.045
2019-05-08 20:15:37,237 [salt.state       :1780][INFO    ][6403] Running state [maas_machines_storage_cmp002_lvm] at time 20:15:37.237406
2019-05-08 20:15:37,239 [salt.state       :1813][INFO    ][6403] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-08 20:15:38,367 [salt.loaded.ext.module.maasng:610 ][INFO    ][6403] c6tft3
2019-05-08 20:15:38,367 [salt.loaded.ext.module.maasng:626 ][INFO    ][6403] sda
2019-05-08 20:15:38,847 [salt.loaded.ext.module.maasng:361 ][INFO    ][6403] c6tft3
2019-05-08 20:15:38,941 [salt.loaded.ext.module.maasng:367 ][INFO    ][6403] [{u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'df06d8b2-abf6-41eb-81a1-e266619992d5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'c6tft3', u'filesystem': {u'mount_options': None, u'uuid': u'8d987947-e115-4ce1-9ffb-899f9a6ce893', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/c6tft3/blockdevices/1/partition/1'}], u'filesystem': None, u'uuid': None, u'id': 1, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'c6tft3', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456, u'type': u'physical', u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/c6tft3/blockdevices/1/'}, {u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'partitions': [], u'filesystem': {u'mount_options': None, u'uuid': u'd3bc12cf-6a5a-4ebf-84b9-ddfcb3abaed0', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, u'uuid': u'abb48157-18bd-4efe-a846-ee634c9be6fe', u'id': 3, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'c6tft3', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'size': 800097042432, u'type': u'virtual', u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/c6tft3/blockdevices/3/'}]
2019-05-08 20:15:38,942 [salt.loaded.ext.module.maasng:632 ][INFO    ][6403] vgroot
2019-05-08 20:15:38,942 [salt.loaded.ext.module.maasng:635 ][INFO    ][6403] lvroot
2019-05-08 20:15:38,942 [salt.loaded.ext.module.maasng:639 ][INFO    ][6403] 107374182400
2019-05-08 20:15:39,549 [salt.loaded.ext.module.maasng:645 ][INFO    ][6403] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'memory_test_status': -1, u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'owner_data': {}, u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'boot_interface': {u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'exsxx8', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'exsxx8', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 18}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, 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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'exsxx8', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'system_id': u'c6tft3', u'mac_address': u'9c:b6:54:8a:10:18', u'id': 5, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/c6tft3/interfaces/5/'}, u'min_hwe_kernel': u'hwe-16.04', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 800109715456, u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 1, u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'c6tft3', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'partitions': [{u'size': 800101236736, u'uuid': u'66048486-774a-4750-a7d6-999df1feee6f', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'c6tft3', u'filesystem': {u'mount_options': None, u'uuid': u'5624a1b3-df36-4182-bf6d-ad0280d7b6eb', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/c6tft3/blockdevices/1/partition/5'}], u'type': u'physical', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/c6tft3/blockdevices/1/'}, u'fqdn': u'cmp002.maas', u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'description': u''}, u'resource_uri': u'/MAAS/api/2.0/machines/c6tft3/', u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'c6tft3', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'id': 10, u'filesystem': {u'mount_options': None, u'uuid': u'81867dbe-c8a9-47f7-a367-fcd165416b9f', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, u'uuid': u'09d3cc6a-9d98-42f4-bd17-ad216537b66b', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'c6tft3', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'partitions': [], u'type': u'virtual', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/c6tft3/blockdevices/10/'}], u'blockdevice_set': [{u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'66048486-774a-4750-a7d6-999df1feee6f', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'c6tft3', u'filesystem': {u'mount_options': None, u'uuid': u'5624a1b3-df36-4182-bf6d-ad0280d7b6eb', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/c6tft3/blockdevices/1/partition/5'}], u'filesystem': None, u'uuid': None, u'id': 1, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'c6tft3', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456, u'type': u'physical', u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/c6tft3/blockdevices/1/'}, {u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'mount_options': None, u'uuid': u'81867dbe-c8a9-47f7-a367-fcd165416b9f', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, u'uuid': u'09d3cc6a-9d98-42f4-bd17-ad216537b66b', u'id': 10, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'c6tft3', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'size': 107374182400, u'type': u'virtual', u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/c6tft3/blockdevices/10/'}], u'status': 4, u'storage_test_status': 2, u'cpu_count': 40, u'power_state': u'off', u'power_type': u'ipmi', u'physicalblockdevice_set': [{u'size': 800109715456, u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 1, u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'c6tft3', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'partitions': [{u'size': 800101236736, u'uuid': u'66048486-774a-4750-a7d6-999df1feee6f', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'c6tft3', u'filesystem': {u'mount_options': None, u'uuid': u'5624a1b3-df36-4182-bf6d-ad0280d7b6eb', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/c6tft3/blockdevices/1/partition/5'}], u'type': u'physical', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/c6tft3/blockdevices/1/'}], u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'c6tft3', u'id': 5}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'node_type_name': u'Machine', u'current_testing_result_id': 5, u'cpu_test_status': -1, u'bcaches': [], u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'status_action': u'', u'memory_test_status_name': u'Unknown', u'commissioning_status': 2, u'architecture': u'amd64/generic', u'interface_set': [{u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'exsxx8', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'exsxx8', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 18}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, 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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'exsxx8', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'system_id': u'c6tft3', u'mac_address': u'9c:b6:54:8a:10:18', u'id': 5, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/c6tft3/interfaces/5/'}, {u'vlan': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'parents': [], u'discovered': None, u'system_id': u'c6tft3', u'mac_address': u'38:ea:a7:8f:07:50', u'id': 14, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/c6tft3/interfaces/14/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 19}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'parents': [], u'discovered': None, u'system_id': u'c6tft3', u'mac_address': u'38:ea:a7:8f:12:49', u'id': 11, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/c6tft3/interfaces/11/'}, {u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'exsxx8', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'exsxx8', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'mode': u'link_up', u'id': 20}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, 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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'exsxx8', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.40'}], u'system_id': u'c6tft3', u'mac_address': u'9c:b6:54:8a:10:1c', u'id': 12, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/c6tft3/interfaces/12/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 21}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'parents': [], u'discovered': None, u'system_id': u'c6tft3', u'mac_address': u'38:ea:a7:8f:12:48', u'id': 13, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/c6tft3/interfaces/13/'}, {u'vlan': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'parents': [], u'discovered': None, u'system_id': u'c6tft3', u'mac_address': u'38:ea:a7:8f:07:51', u'id': 10, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/c6tft3/interfaces/10/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2019-05-08 20:15:39,552 [salt.state       :300 ][INFO    ][6403] {'new': {'storage_layout': 'lvm'}}
2019-05-08 20:15:39,553 [salt.state       :1951][INFO    ][6403] Completed state [maas_machines_storage_cmp002_lvm] at time 20:15:39.553184 duration_in_ms=2315.776
2019-05-08 20:15:39,555 [salt.state       :1780][INFO    ][6403] Running state [maas_machines_storage_cmp001_lvm] at time 20:15:39.555245
2019-05-08 20:15:39,555 [salt.state       :1813][INFO    ][6403] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-08 20:15:40,520 [salt.loaded.ext.module.maasng:610 ][INFO    ][6403] senk7w
2019-05-08 20:15:40,521 [salt.loaded.ext.module.maasng:626 ][INFO    ][6403] sda
2019-05-08 20:15:41,023 [salt.loaded.ext.module.maasng:361 ][INFO    ][6403] senk7w
2019-05-08 20:15:41,115 [salt.loaded.ext.module.maasng:367 ][INFO    ][6403] [{u'size': 800109715456, u'uuid': None, u'resource_uri': u'/MAAS/api/2.0/nodes/senk7w/blockdevices/2/', u'used_size': 800106479616, u'id': 2, u'tags': [u'ssd'], u'filesystem': None, u'partitions': [{u'size': 800101236736, u'uuid': u'98a81e0a-55f4-460b-b48d-a9d325566f38', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'senk7w', u'filesystem': {u'uuid': u'58ed68f9-756f-451e-9c2c-1e76d1663794', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/senk7w/blockdevices/2/partition/2', u'type': u'partition', u'id': 2, u'device_id': 2}], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'senk7w', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'name': u'sda'}, {u'size': 800097042432, u'uuid': u'742cdf74-3c2b-425e-91ae-82133604d3cf', u'resource_uri': u'/MAAS/api/2.0/nodes/senk7w/blockdevices/4/', u'used_size': 800097042432, u'id': 4, u'tags': [], u'filesystem': {u'uuid': u'1cc3d1e2-d464-4d15-9c98-01f6bfc5f756', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'senk7w', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'type': u'virtual', u'serial': None, u'name': u'vgroot-lvroot'}]
2019-05-08 20:15:41,115 [salt.loaded.ext.module.maasng:632 ][INFO    ][6403] vgroot
2019-05-08 20:15:41,116 [salt.loaded.ext.module.maasng:635 ][INFO    ][6403] lvroot
2019-05-08 20:15:41,116 [salt.loaded.ext.module.maasng:639 ][INFO    ][6403] 107374182400
2019-05-08 20:15:41,737 [salt.loaded.ext.module.maasng:645 ][INFO    ][6403] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'memory_test_status': -1, u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'owner_data': {}, u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'boot_interface': {u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'exsxx8', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'exsxx8', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 24}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, 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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'exsxx8', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'system_id': u'senk7w', u'mac_address': u'9c:b6:54:8a:95:a0', u'id': 6, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/senk7w/interfaces/6/'}, u'min_hwe_kernel': u'hwe-16.04', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 800109715456, u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 2, u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'senk7w', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'partitions': [{u'size': 800101236736, u'uuid': u'8d20407a-daa0-4738-93ca-b15692f0f4a7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'senk7w', u'filesystem': {u'mount_options': None, u'uuid': u'e6aeb367-6283-47ee-bf9d-06c3ce6f45c2', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/senk7w/blockdevices/2/partition/6'}], u'type': u'physical', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/senk7w/blockdevices/2/'}, u'fqdn': u'cmp001.maas', u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'description': u''}, u'resource_uri': u'/MAAS/api/2.0/machines/senk7w/', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'senk7w', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'id': 11, u'filesystem': {u'mount_options': None, u'uuid': u'd5f09a4a-87ab-4981-ad33-d2ba36f3a1b7', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, u'uuid': u'031b6860-be5b-4ce9-928d-3fab63accd78', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'senk7w', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'partitions': [], u'type': u'virtual', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/senk7w/blockdevices/11/'}], u'blockdevice_set': [{u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'8d20407a-daa0-4738-93ca-b15692f0f4a7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'senk7w', u'filesystem': {u'mount_options': None, u'uuid': u'e6aeb367-6283-47ee-bf9d-06c3ce6f45c2', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/senk7w/blockdevices/2/partition/6'}], u'filesystem': None, u'uuid': None, u'id': 2, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'senk7w', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456, u'type': u'physical', u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/senk7w/blockdevices/2/'}, {u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'mount_options': None, u'uuid': u'd5f09a4a-87ab-4981-ad33-d2ba36f3a1b7', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, u'uuid': u'031b6860-be5b-4ce9-928d-3fab63accd78', u'id': 11, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'senk7w', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'size': 107374182400, u'type': u'virtual', u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/senk7w/blockdevices/11/'}], u'status': 4, u'storage_test_status': 2, u'cpu_count': 40, u'power_state': u'off', u'power_type': u'ipmi', u'physicalblockdevice_set': [{u'size': 800109715456, u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 2, u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'senk7w', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'partitions': [{u'size': 800101236736, u'uuid': u'8d20407a-daa0-4738-93ca-b15692f0f4a7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'senk7w', u'filesystem': {u'mount_options': None, u'uuid': u'e6aeb367-6283-47ee-bf9d-06c3ce6f45c2', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/senk7w/blockdevices/2/partition/6'}], u'type': u'physical', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/senk7w/blockdevices/2/'}], u'ip_addresses': [u'192.168.11.39', u'192.168.11.43'], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'senk7w', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 6, u'node_type_name': u'Machine', u'current_testing_result_id': 7, u'cpu_test_status': -1, u'bcaches': [], u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'status_action': u'', u'memory_test_status_name': u'Unknown', u'commissioning_status': 2, u'architecture': u'amd64/generic', u'interface_set': [{u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'exsxx8', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'exsxx8', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 24}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, 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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'exsxx8', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'system_id': u'senk7w', u'mac_address': u'9c:b6:54:8a:95:a0', u'id': 6, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/senk7w/interfaces/6/'}, {u'vlan': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'parents': [], u'discovered': None, u'system_id': u'senk7w', u'mac_address': u'38:ea:a7:8f:1f:d5', u'id': 15, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/senk7w/interfaces/15/'}, {u'vlan': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'parents': [], u'discovered': None, u'system_id': u'senk7w', u'mac_address': u'38:ea:a7:8f:1f:d4', u'id': 16, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/senk7w/interfaces/16/'}, {u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'exsxx8', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'exsxx8', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'mode': u'link_up', u'id': 25}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, 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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'exsxx8', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.43'}], u'system_id': u'senk7w', u'mac_address': u'9c:b6:54:8a:95:a4', u'id': 17, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/senk7w/interfaces/17/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 26}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'parents': [], u'discovered': None, u'system_id': u'senk7w', u'mac_address': u'38:ea:a7:8f:52:cc', u'id': 18, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/senk7w/interfaces/18/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 27}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'parents': [], u'discovered': None, u'system_id': u'senk7w', u'mac_address': u'38:ea:a7:8f:52:cd', u'id': 19, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/senk7w/interfaces/19/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2019-05-08 20:15:41,740 [salt.state       :300 ][INFO    ][6403] {'new': {'storage_layout': 'lvm'}}
2019-05-08 20:15:41,741 [salt.state       :1951][INFO    ][6403] Completed state [maas_machines_storage_cmp001_lvm] at time 20:15:41.741179 duration_in_ms=2185.934
2019-05-08 20:15:41,745 [salt.minion      :1711][INFO    ][6403] Returning information for job: 20190508201529239486
2019-05-08 20:15:42,325 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command state.apply with jid 20190508201542315686
2019-05-08 20:15:42,354 [salt.minion      :1432][INFO    ][6464] Starting a new job with PID 6464
2019-05-08 20:15:43,231 [salt.state       :915 ][INFO    ][6464] Loading fresh modules for state activity
2019-05-08 20:15:43,294 [salt.fileclient  :1219][INFO    ][6464] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-05-08 20:15:43,340 [salt.state       :1780][INFO    ][6464] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:15:43.340567
2019-05-08 20:15:43,340 [salt.state       :1813][INFO    ][6464] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-08 20:15:43,342 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6464] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-08 20:15:45,047 [salt.state       :300 ][INFO    ][6464] {'pid': 6471, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-08 20:15:45,049 [salt.state       :1951][INFO    ][6464] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:15:45.048845 duration_in_ms=1708.277
2019-05-08 20:15:45,052 [salt.state       :1780][INFO    ][6464] Running state [maas.deploy_machines] at time 20:15:45.052650
2019-05-08 20:15:45,053 [salt.state       :1813][INFO    ][6464] Executing state module.run for [maas.deploy_machines]
2019-05-08 20:15:45,055 [salt.utils.decorators:613 ][WARNING ][6464] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-08 20:15:45,580 [salt.loaded.ext.module.maas:684 ][INFO    ][6464] deploymachines hwe_kernel=hwe-16.04 system_id=rfrs4k distro_series=xenial
2019-05-08 20:15:48,132 [salt.loaded.ext.module.maas:684 ][INFO    ][6464] deploymachines hwe_kernel=hwe-16.04 system_id=c6tft3 distro_series=xenial
2019-05-08 20:15:50,638 [salt.loaded.ext.module.maas:684 ][INFO    ][6464] deploymachines hwe_kernel=hwe-16.04 system_id=senk7w distro_series=xenial
2019-05-08 20:15:53,121 [salt.loaded.ext.module.maas:684 ][INFO    ][6464] deploymachines hwe_kernel=hwe-16.04 system_id=xph4ha distro_series=xenial
2019-05-08 20:15:55,607 [salt.state       :300 ][INFO    ][6464] {'ret': {'updated': [], 'errors': {}, 'success': ['gtw01', 'cmp002', 'cmp001', 'ctl01']}}
2019-05-08 20:15:55,607 [salt.state       :1951][INFO    ][6464] Completed state [maas.deploy_machines] at time 20:15:55.607546 duration_in_ms=10554.896
2019-05-08 20:15:55,614 [salt.minion      :1711][INFO    ][6464] Returning information for job: 20190508201542315686
2019-05-08 20:15:56,179 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command state.apply with jid 20190508201556166360
2019-05-08 20:15:56,203 [salt.minion      :1432][INFO    ][6687] Starting a new job with PID 6687
2019-05-08 20:16:02,123 [salt.state       :915 ][INFO    ][6687] Loading fresh modules for state activity
2019-05-08 20:16:02,182 [salt.fileclient  :1219][INFO    ][6687] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-05-08 20:16:02,234 [salt.state       :1780][INFO    ][6687] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:16:02.234723
2019-05-08 20:16:02,235 [salt.state       :1813][INFO    ][6687] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-08 20:16:02,236 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6687] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-08 20:16:03,934 [salt.state       :300 ][INFO    ][6687] {'pid': 6700, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-08 20:16:03,935 [salt.state       :1951][INFO    ][6687] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:16:03.935444 duration_in_ms=1700.72
2019-05-08 20:16:03,940 [salt.state       :1780][INFO    ][6687] Running state [maas.wait_for_machine_status] at time 20:16:03.940536
2019-05-08 20:16:03,941 [salt.state       :1813][INFO    ][6687] Executing state module.run for [maas.wait_for_machine_status]
2019-05-08 20:16:03,942 [salt.utils.decorators:613 ][WARNING ][6687] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-08 20:16:06,107 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2247.84966707s left)
2019-05-08 20:16:11,194 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508201611186300
2019-05-08 20:16:11,216 [salt.minion      :1432][INFO    ][6723] Starting a new job with PID 6723
2019-05-08 20:16:11,241 [salt.minion      :1711][INFO    ][6723] Returning information for job: 20190508201611186300
2019-05-08 20:16:38,068 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2215.88907194s left)
2019-05-08 20:16:41,283 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508201641264779
2019-05-08 20:16:41,309 [salt.minion      :1432][INFO    ][6801] Starting a new job with PID 6801
2019-05-08 20:16:41,331 [salt.minion      :1711][INFO    ][6801] Returning information for job: 20190508201641264779
2019-05-08 20:17:10,073 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2183.88341212s left)
2019-05-08 20:17:11,350 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508201711339782
2019-05-08 20:17:11,380 [salt.minion      :1432][INFO    ][6837] Starting a new job with PID 6837
2019-05-08 20:17:11,401 [salt.minion      :1711][INFO    ][6837] Returning information for job: 20190508201711339782
2019-05-08 20:17:41,431 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508201741418444
2019-05-08 20:17:41,456 [salt.minion      :1432][INFO    ][6921] Starting a new job with PID 6921
2019-05-08 20:17:41,477 [salt.minion      :1711][INFO    ][6921] Returning information for job: 20190508201741418444
2019-05-08 20:17:42,085 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2151.87209916s left)
2019-05-08 20:18:11,495 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508201811487689
2019-05-08 20:18:11,517 [salt.minion      :1432][INFO    ][6944] Starting a new job with PID 6944
2019-05-08 20:18:11,539 [salt.minion      :1711][INFO    ][6944] Returning information for job: 20190508201811487689
2019-05-08 20:18:14,312 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2119.644382s left)
2019-05-08 20:18:41,592 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508201841582080
2019-05-08 20:18:41,611 [salt.minion      :1432][INFO    ][7033] Starting a new job with PID 7033
2019-05-08 20:18:41,635 [salt.minion      :1711][INFO    ][7033] Returning information for job: 20190508201841582080
2019-05-08 20:18:46,330 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2087.62741995s left)
2019-05-08 20:19:11,675 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508201911666508
2019-05-08 20:19:11,697 [salt.minion      :1432][INFO    ][7106] Starting a new job with PID 7106
2019-05-08 20:19:11,718 [salt.minion      :1711][INFO    ][7106] Returning information for job: 20190508201911666508
2019-05-08 20:19:18,824 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2055.13214612s left)
2019-05-08 20:19:41,802 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508201941789965
2019-05-08 20:19:41,829 [salt.minion      :1432][INFO    ][7354] Starting a new job with PID 7354
2019-05-08 20:19:41,854 [salt.minion      :1711][INFO    ][7354] Returning information for job: 20190508201941789965
2019-05-08 20:19:50,915 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2023.04124808s left)
2019-05-08 20:20:11,883 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508202011872841
2019-05-08 20:20:11,903 [salt.minion      :1432][INFO    ][7408] Starting a new job with PID 7408
2019-05-08 20:20:11,925 [salt.minion      :1711][INFO    ][7408] Returning information for job: 20190508202011872841
2019-05-08 20:20:22,768 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1991.18831205s left)
2019-05-08 20:20:42,009 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508202041994419
2019-05-08 20:20:42,035 [salt.minion      :1432][INFO    ][7697] Starting a new job with PID 7697
2019-05-08 20:20:42,055 [salt.minion      :1711][INFO    ][7697] Returning information for job: 20190508202041994419
2019-05-08 20:20:54,906 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1959.05043697s left)
2019-05-08 20:21:12,115 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508202112105679
2019-05-08 20:21:12,141 [salt.minion      :1432][INFO    ][7749] Starting a new job with PID 7749
2019-05-08 20:21:12,161 [salt.minion      :1711][INFO    ][7749] Returning information for job: 20190508202112105679
2019-05-08 20:21:27,028 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1926.92894411s left)
2019-05-08 20:21:42,275 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508202142260673
2019-05-08 20:21:42,304 [salt.minion      :1432][INFO    ][8146] Starting a new job with PID 8146
2019-05-08 20:21:42,325 [salt.minion      :1711][INFO    ][8146] Returning information for job: 20190508202142260673
2019-05-08 20:21:59,082 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1894.87451315s left)
2019-05-08 20:22:12,429 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508202212404162
2019-05-08 20:22:12,457 [salt.minion      :1432][INFO    ][8181] Starting a new job with PID 8181
2019-05-08 20:22:12,481 [salt.minion      :1711][INFO    ][8181] Returning information for job: 20190508202212404162
2019-05-08 20:22:31,126 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1862.83060408s left)
2019-05-08 20:22:42,593 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508202242578368
2019-05-08 20:22:42,622 [salt.minion      :1432][INFO    ][8525] Starting a new job with PID 8525
2019-05-08 20:22:42,651 [salt.minion      :1711][INFO    ][8525] Returning information for job: 20190508202242578368
2019-05-08 20:23:03,096 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1830.86099911s left)
2019-05-08 20:23:12,766 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508202312752548
2019-05-08 20:23:12,793 [salt.minion      :1432][INFO    ][8562] Starting a new job with PID 8562
2019-05-08 20:23:12,834 [salt.minion      :1711][INFO    ][8562] Returning information for job: 20190508202312752548
2019-05-08 20:23:35,388 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1798.56822705s left)
2019-05-08 20:23:42,943 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508202342932024
2019-05-08 20:23:42,959 [salt.minion      :1432][INFO    ][8780] Starting a new job with PID 8780
2019-05-08 20:23:42,985 [salt.minion      :1711][INFO    ][8780] Returning information for job: 20190508202342932024
2019-05-08 20:24:07,607 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1766.34949398s left)
2019-05-08 20:24:13,110 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508202413090186
2019-05-08 20:24:13,137 [salt.minion      :1432][INFO    ][8814] Starting a new job with PID 8814
2019-05-08 20:24:13,167 [salt.minion      :1711][INFO    ][8814] Returning information for job: 20190508202413090186
2019-05-08 20:24:39,676 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1734.28036594s left)
2019-05-08 20:24:43,296 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508202443284538
2019-05-08 20:24:43,323 [salt.minion      :1432][INFO    ][9016] Starting a new job with PID 9016
2019-05-08 20:24:43,345 [salt.minion      :1711][INFO    ][9016] Returning information for job: 20190508202443284538
2019-05-08 20:25:11,692 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1702.26483607s left)
2019-05-08 20:25:13,376 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508202513362814
2019-05-08 20:25:13,404 [salt.minion      :1432][INFO    ][9066] Starting a new job with PID 9066
2019-05-08 20:25:13,428 [salt.minion      :1711][INFO    ][9066] Returning information for job: 20190508202513362814
2019-05-08 20:25:43,589 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508202543574770
2019-05-08 20:25:43,613 [salt.minion      :1432][INFO    ][9279] Starting a new job with PID 9279
2019-05-08 20:25:43,650 [salt.minion      :1711][INFO    ][9279] Returning information for job: 20190508202543574770
2019-05-08 20:25:43,981 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:2250s (1669.97603106s left)
2019-05-08 20:26:13,724 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508202613709046
2019-05-08 20:26:13,750 [salt.minion      :1432][INFO    ][9321] Starting a new job with PID 9321
2019-05-08 20:26:13,772 [salt.minion      :1711][INFO    ][9321] Returning information for job: 20190508202613709046
2019-05-08 20:26:15,947 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:2250s (1638.00963616s left)
2019-05-08 20:26:43,925 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508202643911394
2019-05-08 20:26:43,955 [salt.minion      :1432][INFO    ][9510] Starting a new job with PID 9510
2019-05-08 20:26:43,979 [salt.minion      :1711][INFO    ][9510] Returning information for job: 20190508202643911394
2019-05-08 20:26:48,049 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:2250s (1605.90749907s left)
2019-05-08 20:27:14,105 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508202714087594
2019-05-08 20:27:14,132 [salt.minion      :1432][INFO    ][9671] Starting a new job with PID 9671
2019-05-08 20:27:14,153 [salt.minion      :1711][INFO    ][9671] Returning information for job: 20190508202714087594
2019-05-08 20:27:20,228 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:2250s (1573.72876501s left)
2019-05-08 20:27:44,301 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508202744284680
2019-05-08 20:27:44,333 [salt.minion      :1432][INFO    ][9755] Starting a new job with PID 9755
2019-05-08 20:27:44,353 [salt.minion      :1711][INFO    ][9755] Returning information for job: 20190508202744284680
2019-05-08 20:27:52,209 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:2250s (1541.74738908s left)
2019-05-08 20:28:14,482 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508202814471512
2019-05-08 20:28:14,504 [salt.minion      :1432][INFO    ][9803] Starting a new job with PID 9803
2019-05-08 20:28:14,526 [salt.minion      :1711][INFO    ][9803] Returning information for job: 20190508202814471512
2019-05-08 20:28:24,151 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:2250s (1509.80594397s left)
2019-05-08 20:28:44,655 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508202844641052
2019-05-08 20:28:44,680 [salt.minion      :1432][INFO    ][9919] Starting a new job with PID 9919
2019-05-08 20:28:44,700 [salt.minion      :1711][INFO    ][9919] Returning information for job: 20190508202844641052
2019-05-08 20:28:56,146 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1477.810256s left)
2019-05-08 20:29:14,867 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508202914853267
2019-05-08 20:29:14,892 [salt.minion      :1432][INFO    ][9957] Starting a new job with PID 9957
2019-05-08 20:29:14,914 [salt.minion      :1711][INFO    ][9957] Returning information for job: 20190508202914853267
2019-05-08 20:29:28,201 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1445.75578403s left)
2019-05-08 20:29:44,903 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508202944888401
2019-05-08 20:29:44,931 [salt.minion      :1432][INFO    ][10128] Starting a new job with PID 10128
2019-05-08 20:29:44,952 [salt.minion      :1711][INFO    ][10128] Returning information for job: 20190508202944888401
2019-05-08 20:30:00,304 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1413.65262198s left)
2019-05-08 20:30:14,956 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508203014940377
2019-05-08 20:30:14,975 [salt.minion      :1432][INFO    ][10156] Starting a new job with PID 10156
2019-05-08 20:30:14,997 [salt.minion      :1711][INFO    ][10156] Returning information for job: 20190508203014940377
2019-05-08 20:30:32,436 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1381.5210681s left)
2019-05-08 20:30:44,990 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508203044975295
2019-05-08 20:30:45,019 [salt.minion      :1432][INFO    ][10226] Starting a new job with PID 10226
2019-05-08 20:30:45,041 [salt.minion      :1711][INFO    ][10226] Returning information for job: 20190508203044975295
2019-05-08 20:31:04,369 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1349.5875051s left)
2019-05-08 20:31:15,047 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508203115039469
2019-05-08 20:31:15,076 [salt.minion      :1432][INFO    ][10261] Starting a new job with PID 10261
2019-05-08 20:31:15,096 [salt.minion      :1711][INFO    ][10261] Returning information for job: 20190508203115039469
2019-05-08 20:31:36,308 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1317.64825916s left)
2019-05-08 20:31:45,107 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508203145092778
2019-05-08 20:31:45,138 [salt.minion      :1432][INFO    ][10333] Starting a new job with PID 10333
2019-05-08 20:31:45,157 [salt.minion      :1711][INFO    ][10333] Returning information for job: 20190508203145092778
2019-05-08 20:32:08,232 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1285.7244761s left)
2019-05-08 20:32:15,177 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508203215169664
2019-05-08 20:32:15,199 [salt.minion      :1432][INFO    ][10367] Starting a new job with PID 10367
2019-05-08 20:32:15,219 [salt.minion      :1711][INFO    ][10367] Returning information for job: 20190508203215169664
2019-05-08 20:32:40,258 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1253.69865608s left)
2019-05-08 20:32:45,244 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508203245227866
2019-05-08 20:32:45,272 [salt.minion      :1432][INFO    ][10439] Starting a new job with PID 10439
2019-05-08 20:32:45,292 [salt.minion      :1711][INFO    ][10439] Returning information for job: 20190508203245227866
2019-05-08 20:33:12,312 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1221.64443612s left)
2019-05-08 20:33:15,357 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508203315343169
2019-05-08 20:33:15,388 [salt.minion      :1432][INFO    ][10470] Starting a new job with PID 10470
2019-05-08 20:33:15,407 [salt.minion      :1711][INFO    ][10470] Returning information for job: 20190508203315343169
2019-05-08 20:33:44,404 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1189.55284214s left)
2019-05-08 20:33:45,454 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508203345439310
2019-05-08 20:33:45,485 [salt.minion      :1432][INFO    ][10542] Starting a new job with PID 10542
2019-05-08 20:33:45,507 [salt.minion      :1711][INFO    ][10542] Returning information for job: 20190508203345439310
2019-05-08 20:34:15,593 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508203415580719
2019-05-08 20:34:15,618 [salt.minion      :1432][INFO    ][10570] Starting a new job with PID 10570
2019-05-08 20:34:15,642 [salt.minion      :1711][INFO    ][10570] Returning information for job: 20190508203415580719
2019-05-08 20:34:16,353 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1157.60372806s left)
2019-05-08 20:34:45,736 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508203445720688
2019-05-08 20:34:45,764 [salt.minion      :1432][INFO    ][10641] Starting a new job with PID 10641
2019-05-08 20:34:45,791 [salt.minion      :1711][INFO    ][10641] Returning information for job: 20190508203445720688
2019-05-08 20:34:48,307 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1125.64954901s left)
2019-05-08 20:35:15,904 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508203515887885
2019-05-08 20:35:15,933 [salt.minion      :1432][INFO    ][10670] Starting a new job with PID 10670
2019-05-08 20:35:15,955 [salt.minion      :1711][INFO    ][10670] Returning information for job: 20190508203515887885
2019-05-08 20:35:20,311 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1093.64597011s left)
2019-05-08 20:35:46,041 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508203546033680
2019-05-08 20:35:46,063 [salt.minion      :1432][INFO    ][10741] Starting a new job with PID 10741
2019-05-08 20:35:46,085 [salt.minion      :1711][INFO    ][10741] Returning information for job: 20190508203546033680
2019-05-08 20:35:52,198 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1061.75827694s left)
2019-05-08 20:36:16,219 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508203616208794
2019-05-08 20:36:16,236 [salt.minion      :1432][INFO    ][10770] Starting a new job with PID 10770
2019-05-08 20:36:16,264 [salt.minion      :1711][INFO    ][10770] Returning information for job: 20190508203616208794
2019-05-08 20:36:24,217 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1029.73953295s left)
2019-05-08 20:36:46,417 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508203646402918
2019-05-08 20:36:46,446 [salt.minion      :1432][INFO    ][10840] Starting a new job with PID 10840
2019-05-08 20:36:46,469 [salt.minion      :1711][INFO    ][10840] Returning information for job: 20190508203646402918
2019-05-08 20:36:56,106 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (997.851453066s left)
2019-05-08 20:37:16,625 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508203716610720
2019-05-08 20:37:16,652 [salt.minion      :1432][INFO    ][10881] Starting a new job with PID 10881
2019-05-08 20:37:16,674 [salt.minion      :1711][INFO    ][10881] Returning information for job: 20190508203716610720
2019-05-08 20:37:27,971 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (965.985314131s left)
2019-05-08 20:37:46,652 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508203746637214
2019-05-08 20:37:46,679 [salt.minion      :1432][INFO    ][10967] Starting a new job with PID 10967
2019-05-08 20:37:46,701 [salt.minion      :1711][INFO    ][10967] Returning information for job: 20190508203746637214
2019-05-08 20:37:59,730 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (934.226859093s left)
2019-05-08 20:38:16,884 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508203816869417
2019-05-08 20:38:16,915 [salt.minion      :1432][INFO    ][10996] Starting a new job with PID 10996
2019-05-08 20:38:16,938 [salt.minion      :1711][INFO    ][10996] Returning information for job: 20190508203816869417
2019-05-08 20:38:31,635 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (902.321557045s left)
2019-05-08 20:38:46,929 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508203846919904
2019-05-08 20:38:46,951 [salt.minion      :1432][INFO    ][11069] Starting a new job with PID 11069
2019-05-08 20:38:46,975 [salt.minion      :1711][INFO    ][11069] Returning information for job: 20190508203846919904
2019-05-08 20:39:03,599 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (870.357464075s left)
2019-05-08 20:39:16,972 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508203916962582
2019-05-08 20:39:17,002 [salt.minion      :1432][INFO    ][11098] Starting a new job with PID 11098
2019-05-08 20:39:17,025 [salt.minion      :1711][INFO    ][11098] Returning information for job: 20190508203916962582
2019-05-08 20:39:35,518 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (838.438359022s left)
2019-05-08 20:39:47,058 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508203947043943
2019-05-08 20:39:47,085 [salt.minion      :1432][INFO    ][11168] Starting a new job with PID 11168
2019-05-08 20:39:47,111 [salt.minion      :1711][INFO    ][11168] Returning information for job: 20190508203947043943
2019-05-08 20:40:07,456 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (806.500275135s left)
2019-05-08 20:40:17,170 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508204017159888
2019-05-08 20:40:17,196 [salt.minion      :1432][INFO    ][11197] Starting a new job with PID 11197
2019-05-08 20:40:17,218 [salt.minion      :1711][INFO    ][11197] Returning information for job: 20190508204017159888
2019-05-08 20:40:39,380 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (774.5771451s left)
2019-05-08 20:40:47,300 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508204047284019
2019-05-08 20:40:47,326 [salt.minion      :1432][INFO    ][11270] Starting a new job with PID 11270
2019-05-08 20:40:47,352 [salt.minion      :1711][INFO    ][11270] Returning information for job: 20190508204047284019
2019-05-08 20:41:11,316 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (742.640356064s left)
2019-05-08 20:41:17,443 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508204117432203
2019-05-08 20:41:17,465 [salt.minion      :1432][INFO    ][11299] Starting a new job with PID 11299
2019-05-08 20:41:17,491 [salt.minion      :1711][INFO    ][11299] Returning information for job: 20190508204117432203
2019-05-08 20:41:43,201 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (710.755599022s left)
2019-05-08 20:41:47,596 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508204147580946
2019-05-08 20:41:47,629 [salt.minion      :1432][INFO    ][11373] Starting a new job with PID 11373
2019-05-08 20:41:47,655 [salt.minion      :1711][INFO    ][11373] Returning information for job: 20190508204147580946
2019-05-08 20:42:15,090 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (678.86640501s left)
2019-05-08 20:42:17,790 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508204217778966
2019-05-08 20:42:17,805 [salt.minion      :1432][INFO    ][11408] Starting a new job with PID 11408
2019-05-08 20:42:17,843 [salt.minion      :1711][INFO    ][11408] Returning information for job: 20190508204217778966
2019-05-08 20:42:47,128 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (646.828855991s left)
2019-05-08 20:42:47,960 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508204247949530
2019-05-08 20:42:47,989 [salt.minion      :1432][INFO    ][11478] Starting a new job with PID 11478
2019-05-08 20:42:48,019 [salt.minion      :1711][INFO    ][11478] Returning information for job: 20190508204247949530
2019-05-08 20:43:17,996 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508204317985419
2019-05-08 20:43:18,012 [salt.minion      :1432][INFO    ][11515] Starting a new job with PID 11515
2019-05-08 20:43:18,054 [salt.minion      :1711][INFO    ][11515] Returning information for job: 20190508204317985419
2019-05-08 20:43:19,211 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (614.745329142s left)
2019-05-08 20:43:48,032 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508204348022192
2019-05-08 20:43:48,058 [salt.minion      :1432][INFO    ][11575] Starting a new job with PID 11575
2019-05-08 20:43:48,081 [salt.minion      :1711][INFO    ][11575] Returning information for job: 20190508204348022192
2019-05-08 20:43:51,290 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (582.66663599s left)
2019-05-08 20:44:18,092 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508204418083397
2019-05-08 20:44:18,107 [salt.minion      :1432][INFO    ][11621] Starting a new job with PID 11621
2019-05-08 20:44:18,132 [salt.minion      :1711][INFO    ][11621] Returning information for job: 20190508204418083397
2019-05-08 20:44:23,153 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (550.804053068s left)
2019-05-08 20:44:48,328 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508204448308695
2019-05-08 20:44:48,353 [salt.minion      :1432][INFO    ][11675] Starting a new job with PID 11675
2019-05-08 20:44:48,378 [salt.minion      :1711][INFO    ][11675] Returning information for job: 20190508204448308695
2019-05-08 20:44:55,199 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (518.757802963s left)
2019-05-08 20:45:18,441 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508204518434398
2019-05-08 20:45:18,456 [salt.minion      :1432][INFO    ][11722] Starting a new job with PID 11722
2019-05-08 20:45:18,478 [salt.minion      :1711][INFO    ][11722] Returning information for job: 20190508204518434398
2019-05-08 20:45:27,105 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (486.851959944s left)
2019-05-08 20:45:48,499 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508204548483511
2019-05-08 20:45:48,525 [salt.minion      :1432][INFO    ][11777] Starting a new job with PID 11777
2019-05-08 20:45:48,546 [salt.minion      :1711][INFO    ][11777] Returning information for job: 20190508204548483511
2019-05-08 20:45:59,014 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (454.942775011s left)
2019-05-08 20:46:18,661 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508204618653112
2019-05-08 20:46:18,682 [salt.minion      :1432][INFO    ][11832] Starting a new job with PID 11832
2019-05-08 20:46:18,706 [salt.minion      :1711][INFO    ][11832] Returning information for job: 20190508204618653112
2019-05-08 20:46:30,910 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (423.046510935s left)
2019-05-08 20:46:48,787 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508204648775773
2019-05-08 20:46:48,814 [salt.minion      :1432][INFO    ][11880] Starting a new job with PID 11880
2019-05-08 20:46:48,838 [salt.minion      :1711][INFO    ][11880] Returning information for job: 20190508204648775773
2019-05-08 20:47:02,787 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (391.169270992s left)
2019-05-08 20:47:18,992 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508204718977903
2019-05-08 20:47:19,019 [salt.minion      :1432][INFO    ][12073] Starting a new job with PID 12073
2019-05-08 20:47:19,043 [salt.minion      :1711][INFO    ][12073] Returning information for job: 20190508204718977903
2019-05-08 20:47:34,883 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (359.07365799s left)
2019-05-08 20:47:49,215 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508204749201278
2019-05-08 20:47:49,237 [salt.minion      :1432][INFO    ][12129] Starting a new job with PID 12129
2019-05-08 20:47:49,260 [salt.minion      :1711][INFO    ][12129] Returning information for job: 20190508204749201278
2019-05-08 20:48:06,742 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (327.214242935s left)
2019-05-08 20:48:19,412 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508204819396024
2019-05-08 20:48:19,441 [salt.minion      :1432][INFO    ][12182] Starting a new job with PID 12182
2019-05-08 20:48:19,468 [salt.minion      :1711][INFO    ][12182] Returning information for job: 20190508204819396024
2019-05-08 20:48:38,668 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (295.288758993s left)
2019-05-08 20:48:49,503 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508204849488654
2019-05-08 20:48:49,523 [salt.minion      :1432][INFO    ][12228] Starting a new job with PID 12228
2019-05-08 20:48:49,548 [salt.minion      :1711][INFO    ][12228] Returning information for job: 20190508204849488654
2019-05-08 20:49:10,537 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (263.419960022s left)
2019-05-08 20:49:19,723 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508204919712331
2019-05-08 20:49:19,748 [salt.minion      :1432][INFO    ][12282] Starting a new job with PID 12282
2019-05-08 20:49:19,772 [salt.minion      :1711][INFO    ][12282] Returning information for job: 20190508204919712331
2019-05-08 20:49:42,540 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (231.416267157s left)
2019-05-08 20:49:49,800 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508204949783543
2019-05-08 20:49:49,831 [salt.minion      :1432][INFO    ][12332] Starting a new job with PID 12332
2019-05-08 20:49:49,853 [salt.minion      :1711][INFO    ][12332] Returning information for job: 20190508204949783543
2019-05-08 20:50:14,584 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (199.372337103s left)
2019-05-08 20:50:20,035 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508205020022115
2019-05-08 20:50:20,062 [salt.minion      :1432][INFO    ][12385] Starting a new job with PID 12385
2019-05-08 20:50:20,086 [salt.minion      :1711][INFO    ][12385] Returning information for job: 20190508205020022115
2019-05-08 20:50:46,499 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (167.457947969s left)
2019-05-08 20:50:50,136 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508205050121027
2019-05-08 20:50:50,163 [salt.minion      :1432][INFO    ][12431] Starting a new job with PID 12431
2019-05-08 20:50:50,186 [salt.minion      :1711][INFO    ][12431] Returning information for job: 20190508205050121027
2019-05-08 20:51:18,551 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (135.406023979s left)
2019-05-08 20:51:20,237 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508205120224717
2019-05-08 20:51:20,261 [salt.minion      :1432][INFO    ][12486] Starting a new job with PID 12486
2019-05-08 20:51:20,284 [salt.minion      :1711][INFO    ][12486] Returning information for job: 20190508205120224717
2019-05-08 20:51:50,384 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508205150374646
2019-05-08 20:51:50,407 [salt.minion      :1432][INFO    ][12533] Starting a new job with PID 12533
2019-05-08 20:51:50,432 [salt.minion      :1711][INFO    ][12533] Returning information for job: 20190508205150374646
2019-05-08 20:51:50,633 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (103.323722124s left)
2019-05-08 20:52:20,515 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508205220498219
2019-05-08 20:52:20,542 [salt.minion      :1432][INFO    ][12584] Starting a new job with PID 12584
2019-05-08 20:52:20,566 [salt.minion      :1711][INFO    ][12584] Returning information for job: 20190508205220498219
2019-05-08 20:52:22,485 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (71.4712879658s left)
2019-05-08 20:52:50,703 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508205250688658
2019-05-08 20:52:50,732 [salt.minion      :1432][INFO    ][12633] Starting a new job with PID 12633
2019-05-08 20:52:50,759 [salt.minion      :1711][INFO    ][12633] Returning information for job: 20190508205250688658
2019-05-08 20:52:54,380 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (39.5771501064s left)
2019-05-08 20:53:20,743 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508205320733273
2019-05-08 20:53:20,764 [salt.minion      :1432][INFO    ][12686] Starting a new job with PID 12686
2019-05-08 20:53:20,782 [salt.minion      :1711][INFO    ][12686] Returning information for job: 20190508205320733273
2019-05-08 20:53:26,231 [salt.loaded.ext.module.maas:1023][INFO    ][6687] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (7.72530293465s left)
2019-05-08 20:53:50,913 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508205350900253
2019-05-08 20:53:50,937 [salt.minion      :1432][INFO    ][12732] Starting a new job with PID 12732
2019-05-08 20:53:50,965 [salt.minion      :1711][INFO    ][12732] Returning information for job: 20190508205350900253
2019-05-08 20:53:58,105 [salt.state       :302 ][ERROR   ][6687] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['gtw01']not in Deployed state
2019-05-08 20:53:58,107 [salt.state       :1951][INFO    ][6687] Completed state [maas.wait_for_machine_status] at time 20:53:58.107243 duration_in_ms=2274166.7
2019-05-08 20:53:58,112 [salt.minion      :1711][INFO    ][6687] Returning information for job: 20190508201556166360
2019-05-08 20:54:08,943 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command pillar.get with jid 20190508205408927504
2019-05-08 20:54:08,970 [salt.minion      :1432][INFO    ][12758] Starting a new job with PID 12758
2019-05-08 20:54:08,983 [salt.minion      :1711][INFO    ][12758] Returning information for job: 20190508205408927504
2019-05-08 20:54:09,567 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command service.status with jid 20190508205409552960
2019-05-08 20:54:09,593 [salt.minion      :1432][INFO    ][12763] Starting a new job with PID 12763
2019-05-08 20:54:10,113 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12763] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-05-08 20:54:10,152 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12763] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-05-08 20:54:10,175 [salt.minion      :1711][INFO    ][12763] Returning information for job: 20190508205409552960
2019-05-08 20:54:10,747 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command state.apply with jid 20190508205410736347
2019-05-08 20:54:10,775 [salt.minion      :1432][INFO    ][12774] Starting a new job with PID 12774
2019-05-08 20:54:16,590 [salt.state       :915 ][INFO    ][12774] Loading fresh modules for state activity
2019-05-08 20:54:17,171 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12774] Executing command 'salt-minion --version' in directory '/root'
2019-05-08 20:54:17,479 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12774] Executing command 'salt-minion --version' in directory '/root'
2019-05-08 20:54:18,434 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12774] Executing command 'salt-minion --version' in directory '/root'
2019-05-08 20:54:18,746 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12774] Executing command 'salt-minion --version' in directory '/root'
2019-05-08 20:54:20,590 [salt.state       :1780][INFO    ][12774] Running state [salt-minion] at time 20:54:20.590908
2019-05-08 20:54:20,591 [salt.state       :1813][INFO    ][12774] Executing state pkg.installed for [salt-minion]
2019-05-08 20:54:20,592 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12774] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-08 20:54:20,707 [salt.state       :300 ][INFO    ][12774] All specified packages are already installed
2019-05-08 20:54:20,708 [salt.state       :1951][INFO    ][12774] Completed state [salt-minion] at time 20:54:20.708013 duration_in_ms=117.105
2019-05-08 20:54:20,708 [salt.state       :1780][INFO    ][12774] Running state [salt_minion_dependency_packages] at time 20:54:20.708854
2019-05-08 20:54:20,709 [salt.state       :1813][INFO    ][12774] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-05-08 20:54:20,721 [salt.state       :300 ][INFO    ][12774] All specified packages are already installed
2019-05-08 20:54:20,721 [salt.state       :1951][INFO    ][12774] Completed state [salt_minion_dependency_packages] at time 20:54:20.721807 duration_in_ms=12.954
2019-05-08 20:54:20,724 [salt.state       :1780][INFO    ][12774] Running state [/etc/salt/minion.d/minion.conf] at time 20:54:20.724796
2019-05-08 20:54:20,725 [salt.state       :1813][INFO    ][12774] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-05-08 20:54:21,019 [salt.state       :300 ][INFO    ][12774] File /etc/salt/minion.d/minion.conf is in the correct state
2019-05-08 20:54:21,020 [salt.state       :1951][INFO    ][12774] Completed state [/etc/salt/minion.d/minion.conf] at time 20:54:21.020174 duration_in_ms=295.378
2019-05-08 20:54:21,023 [salt.state       :1780][INFO    ][12774] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 20:54:21.023600
2019-05-08 20:54:21,023 [salt.state       :1813][INFO    ][12774] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-05-08 20:54:21,039 [salt.state       :300 ][INFO    ][12774] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-05-08 20:54:21,039 [salt.state       :1951][INFO    ][12774] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 20:54:21.039529 duration_in_ms=15.929
2019-05-08 20:54:21,041 [salt.state       :1780][INFO    ][12774] Running state [salt-minion] at time 20:54:21.041111
2019-05-08 20:54:21,041 [salt.state       :1813][INFO    ][12774] Executing state service.running for [salt-minion]
2019-05-08 20:54:21,042 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12774] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-08 20:54:21,081 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12774] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-05-08 20:54:21,107 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12774] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-05-08 20:54:21,132 [salt.state       :300 ][INFO    ][12774] The service salt-minion is already running
2019-05-08 20:54:21,132 [salt.state       :1951][INFO    ][12774] Completed state [salt-minion] at time 20:54:21.132443 duration_in_ms=91.333
2019-05-08 20:54:21,137 [salt.state       :1780][INFO    ][12774] Running state [/etc/salt/grains.d] at time 20:54:21.137095
2019-05-08 20:54:21,137 [salt.state       :1813][INFO    ][12774] Executing state file.directory for [/etc/salt/grains.d]
2019-05-08 20:54:21,139 [salt.state       :300 ][INFO    ][12774] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-05-08 20:54:21,139 [salt.state       :1951][INFO    ][12774] Completed state [/etc/salt/grains.d] at time 20:54:21.139184 duration_in_ms=2.089
2019-05-08 20:54:21,139 [salt.state       :1780][INFO    ][12774] Running state [/etc/salt/grains] at time 20:54:21.139832
2019-05-08 20:54:21,140 [salt.state       :1813][INFO    ][12774] Executing state file.managed for [/etc/salt/grains]
2019-05-08 20:54:21,140 [salt.state       :300 ][INFO    ][12774] File /etc/salt/grains exists with proper permissions. No changes made.
2019-05-08 20:54:21,140 [salt.state       :1951][INFO    ][12774] Completed state [/etc/salt/grains] at time 20:54:21.140793 duration_in_ms=0.961
2019-05-08 20:54:21,141 [salt.state       :1780][INFO    ][12774] Running state [/etc/salt/grains.d/placeholder] at time 20:54:21.141241
2019-05-08 20:54:21,141 [salt.state       :1813][INFO    ][12774] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-05-08 20:54:21,142 [salt.state       :300 ][INFO    ][12774] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-05-08 20:54:21,142 [salt.state       :1951][INFO    ][12774] Completed state [/etc/salt/grains.d/placeholder] at time 20:54:21.142170 duration_in_ms=0.928
2019-05-08 20:54:21,142 [salt.state       :1780][INFO    ][12774] Running state [/etc/salt/grains.d/sphinx] at time 20:54:21.142621
2019-05-08 20:54:21,142 [salt.state       :1813][INFO    ][12774] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-05-08 20:54:21,144 [salt.state       :300 ][INFO    ][12774] File /etc/salt/grains.d/sphinx is in the correct state
2019-05-08 20:54:21,144 [salt.state       :1951][INFO    ][12774] Completed state [/etc/salt/grains.d/sphinx] at time 20:54:21.144180 duration_in_ms=1.559
2019-05-08 20:54:21,147 [salt.state       :1780][INFO    ][12774] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 20:54:21.147713
2019-05-08 20:54:21,148 [salt.state       :1813][INFO    ][12774] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-05-08 20:54:21,148 [salt.state       :300 ][INFO    ][12774] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-05-08 20:54:21,148 [salt.state       :1951][INFO    ][12774] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 20:54:21.148483 duration_in_ms=0.77
2019-05-08 20:54:21,148 [salt.state       :1780][INFO    ][12774] Running state [/etc/salt/grains.d/dns_records] at time 20:54:21.148944
2019-05-08 20:54:21,149 [salt.state       :1813][INFO    ][12774] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-05-08 20:54:21,151 [salt.state       :300 ][INFO    ][12774] File /etc/salt/grains.d/dns_records is in the correct state
2019-05-08 20:54:21,151 [salt.state       :1951][INFO    ][12774] Completed state [/etc/salt/grains.d/dns_records] at time 20:54:21.151719 duration_in_ms=2.775
2019-05-08 20:54:21,152 [salt.state       :1780][INFO    ][12774] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 20:54:21.152614
2019-05-08 20:54:21,152 [salt.state       :1813][INFO    ][12774] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-05-08 20:54:21,153 [salt.state       :300 ][INFO    ][12774] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-05-08 20:54:21,153 [salt.state       :1951][INFO    ][12774] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 20:54:21.153354 duration_in_ms=0.739
2019-05-08 20:54:21,153 [salt.state       :1780][INFO    ][12774] Running state [/etc/salt/grains.d/salt] at time 20:54:21.153820
2019-05-08 20:54:21,154 [salt.state       :1813][INFO    ][12774] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-05-08 20:54:21,154 [salt.state       :300 ][INFO    ][12774] File /etc/salt/grains.d/salt is in the correct state
2019-05-08 20:54:21,155 [salt.state       :1951][INFO    ][12774] Completed state [/etc/salt/grains.d/salt] at time 20:54:21.155125 duration_in_ms=1.305
2019-05-08 20:54:21,156 [salt.state       :1780][INFO    ][12774] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 20:54:21.155969
2019-05-08 20:54:21,156 [salt.state       :1813][INFO    ][12774] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-05-08 20:54:21,156 [salt.state       :300 ][INFO    ][12774] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-05-08 20:54:21,156 [salt.state       :1951][INFO    ][12774] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 20:54:21.156685 duration_in_ms=0.717
2019-05-08 20:54:21,159 [salt.state       :1780][INFO    ][12774] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 20:54:21.159426
2019-05-08 20:54:21,159 [salt.state       :1813][INFO    ][12774] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-05-08 20:54:21,160 [salt.state       :300 ][INFO    ][12774] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-05-08 20:54:21,160 [salt.state       :1951][INFO    ][12774] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 20:54:21.160180 duration_in_ms=0.754
2019-05-08 20:54:21,160 [salt.state       :1780][INFO    ][12774] Running state [mine.update] at time 20:54:21.160826
2019-05-08 20:54:21,161 [salt.state       :1813][INFO    ][12774] Executing state module.wait for [mine.update]
2019-05-08 20:54:21,161 [salt.state       :300 ][INFO    ][12774] No changes made for mine.update
2019-05-08 20:54:21,161 [salt.state       :1951][INFO    ][12774] Completed state [mine.update] at time 20:54:21.161512 duration_in_ms=0.685
2019-05-08 20:54:21,163 [salt.state       :1780][INFO    ][12774] Running state [ca-certificates] at time 20:54:21.163478
2019-05-08 20:54:21,163 [salt.state       :1813][INFO    ][12774] Executing state pkg.installed for [ca-certificates]
2019-05-08 20:54:21,173 [salt.state       :300 ][INFO    ][12774] All specified packages are already installed
2019-05-08 20:54:21,174 [salt.state       :1951][INFO    ][12774] Completed state [ca-certificates] at time 20:54:21.174625 duration_in_ms=11.147
2019-05-08 20:54:21,175 [salt.state       :1780][INFO    ][12774] Running state [update-ca-certificates] at time 20:54:21.175545
2019-05-08 20:54:21,175 [salt.state       :1813][INFO    ][12774] Executing state cmd.wait for [update-ca-certificates]
2019-05-08 20:54:21,176 [salt.state       :300 ][INFO    ][12774] No changes made for update-ca-certificates
2019-05-08 20:54:21,176 [salt.state       :1951][INFO    ][12774] Completed state [update-ca-certificates] at time 20:54:21.176276 duration_in_ms=0.73
2019-05-08 20:54:21,176 [salt.state       :1780][INFO    ][12774] Running state [iptables] at time 20:54:21.176512
2019-05-08 20:54:21,176 [salt.state       :1813][INFO    ][12774] Executing state pkg.installed for [iptables]
2019-05-08 20:54:21,186 [salt.state       :300 ][INFO    ][12774] All specified packages are already installed
2019-05-08 20:54:21,186 [salt.state       :1951][INFO    ][12774] Completed state [iptables] at time 20:54:21.186361 duration_in_ms=9.849
2019-05-08 20:54:21,186 [salt.state       :1780][INFO    ][12774] Running state [iptables-persistent] at time 20:54:21.186597
2019-05-08 20:54:21,186 [salt.state       :1813][INFO    ][12774] Executing state pkg.installed for [iptables-persistent]
2019-05-08 20:54:21,196 [salt.state       :300 ][INFO    ][12774] All specified packages are already installed
2019-05-08 20:54:21,196 [salt.state       :1951][INFO    ][12774] Completed state [iptables-persistent] at time 20:54:21.196574 duration_in_ms=9.977
2019-05-08 20:54:21,197 [salt.state       :1780][INFO    ][12774] Running state [iptables_modules_v4_load] at time 20:54:21.197514
2019-05-08 20:54:21,198 [salt.state       :1813][INFO    ][12774] Executing state kmod.present for [iptables_modules_v4_load]
2019-05-08 20:54:21,198 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12774] Executing command 'lsmod' in directory '/root'
2019-05-08 20:54:21,219 [salt.state       :300 ][INFO    ][12774] Kernel modules iptable_filter, ip_tables are already present
2019-05-08 20:54:21,220 [salt.state       :1951][INFO    ][12774] Completed state [iptables_modules_v4_load] at time 20:54:21.220249 duration_in_ms=22.735
2019-05-08 20:54:21,220 [salt.state       :1780][INFO    ][12774] Running state [/etc/iptables/rules.v4] at time 20:54:21.220883
2019-05-08 20:54:21,221 [salt.state       :1813][INFO    ][12774] Executing state file.managed for [/etc/iptables/rules.v4]
2019-05-08 20:54:21,315 [salt.state       :300 ][INFO    ][12774] File /etc/iptables/rules.v4 is in the correct state
2019-05-08 20:54:21,316 [salt.state       :1951][INFO    ][12774] Completed state [/etc/iptables/rules.v4] at time 20:54:21.316116 duration_in_ms=95.233
2019-05-08 20:54:21,317 [salt.state       :1780][INFO    ][12774] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 20:54:21.317170
2019-05-08 20:54:21,317 [salt.state       :1813][INFO    ][12774] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-05-08 20:54:21,318 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12774] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-05-08 20:54:21,337 [salt.state       :300 ][INFO    ][12774] onlyif execution failed
2019-05-08 20:54:21,339 [salt.state       :1951][INFO    ][12774] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 20:54:21.339553 duration_in_ms=22.383
2019-05-08 20:54:21,340 [salt.state       :1780][INFO    ][12774] Running state [netfilter-persistent] at time 20:54:21.340588
2019-05-08 20:54:21,340 [salt.state       :1813][INFO    ][12774] Executing state service.running for [netfilter-persistent]
2019-05-08 20:54:21,341 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12774] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-05-08 20:54:21,366 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12774] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-05-08 20:54:21,388 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12774] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-05-08 20:54:21,409 [salt.state       :300 ][INFO    ][12774] The service netfilter-persistent is already running
2019-05-08 20:54:21,409 [salt.state       :1951][INFO    ][12774] Completed state [netfilter-persistent] at time 20:54:21.409365 duration_in_ms=68.777
2019-05-08 20:54:21,412 [salt.state       :1780][INFO    ][12774] Running state [iptables_extra.remove_stale_tables] at time 20:54:21.412440
2019-05-08 20:54:21,412 [salt.state       :1813][INFO    ][12774] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-05-08 20:54:21,413 [salt.state       :300 ][INFO    ][12774] No changes made for iptables_extra.remove_stale_tables
2019-05-08 20:54:21,413 [salt.state       :1951][INFO    ][12774] Completed state [iptables_extra.remove_stale_tables] at time 20:54:21.413257 duration_in_ms=0.817
2019-05-08 20:54:21,413 [salt.state       :1780][INFO    ][12774] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 20:54:21.413497
2019-05-08 20:54:21,413 [salt.state       :1813][INFO    ][12774] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-05-08 20:54:21,414 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12774] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-05-08 20:54:21,431 [salt.state       :300 ][INFO    ][12774] onlyif execution failed
2019-05-08 20:54:21,432 [salt.state       :1951][INFO    ][12774] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 20:54:21.432132 duration_in_ms=18.635
2019-05-08 20:54:21,433 [salt.state       :1780][INFO    ][12774] Running state [/etc/iptables/rules.v6] at time 20:54:21.433067
2019-05-08 20:54:21,433 [salt.state       :1813][INFO    ][12774] Executing state file.absent for [/etc/iptables/rules.v6]
2019-05-08 20:54:21,436 [salt.state       :300 ][INFO    ][12774] File /etc/iptables/rules.v6 is not present
2019-05-08 20:54:21,436 [salt.state       :1951][INFO    ][12774] Completed state [/etc/iptables/rules.v6] at time 20:54:21.436822 duration_in_ms=3.755
2019-05-08 20:54:21,437 [salt.state       :1780][INFO    ][12774] Running state [iptables_extra.flush_all] at time 20:54:21.437498
2019-05-08 20:54:21,437 [salt.state       :1813][INFO    ][12774] Executing state module.wait for [iptables_extra.flush_all]
2019-05-08 20:54:21,438 [salt.state       :300 ][INFO    ][12774] No changes made for iptables_extra.flush_all
2019-05-08 20:54:21,438 [salt.state       :1951][INFO    ][12774] Completed state [iptables_extra.flush_all] at time 20:54:21.438217 duration_in_ms=0.719
2019-05-08 20:54:21,440 [salt.minion      :1711][INFO    ][12774] Returning information for job: 20190508205410736347
2019-05-08 20:54:22,000 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command state.apply with jid 20190508205421987339
2019-05-08 20:54:22,018 [salt.minion      :1432][INFO    ][12878] Starting a new job with PID 12878
2019-05-08 20:54:22,876 [salt.state       :915 ][INFO    ][12878] Loading fresh modules for state activity
2019-05-08 20:54:23,760 [salt.state       :1780][INFO    ][12878] Running state [maas-rack-controller] at time 20:54:23.760009
2019-05-08 20:54:23,760 [salt.state       :1813][INFO    ][12878] Executing state pkg.installed for [maas-rack-controller]
2019-05-08 20:54:23,761 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12878] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-08 20:54:23,869 [salt.state       :300 ][INFO    ][12878] All specified packages are already installed
2019-05-08 20:54:23,870 [salt.state       :1951][INFO    ][12878] Completed state [maas-rack-controller] at time 20:54:23.870594 duration_in_ms=110.584
2019-05-08 20:54:23,871 [salt.state       :1780][INFO    ][12878] Running state [ipmitool] at time 20:54:23.871156
2019-05-08 20:54:23,871 [salt.state       :1813][INFO    ][12878] Executing state pkg.installed for [ipmitool]
2019-05-08 20:54:23,880 [salt.state       :300 ][INFO    ][12878] All specified packages are already installed
2019-05-08 20:54:23,880 [salt.state       :1951][INFO    ][12878] Completed state [ipmitool] at time 20:54:23.880568 duration_in_ms=9.413
2019-05-08 20:54:23,886 [salt.state       :1780][INFO    ][12878] Running state [/etc/maas/rackd.conf] at time 20:54:23.885958
2019-05-08 20:54:23,886 [salt.state       :1813][INFO    ][12878] Executing state file.line for [/etc/maas/rackd.conf]
2019-05-08 20:54:23,887 [salt.state       :300 ][INFO    ][12878] No changes needed to be made
2019-05-08 20:54:23,887 [salt.state       :1951][INFO    ][12878] Completed state [/etc/maas/rackd.conf] at time 20:54:23.887405 duration_in_ms=1.447
2019-05-08 20:54:23,887 [salt.state       :1780][INFO    ][12878] Running state [/etc/maas/rackd.conf] at time 20:54:23.887650
2019-05-08 20:54:23,887 [salt.state       :1813][INFO    ][12878] Executing state file.managed for [/etc/maas/rackd.conf]
2019-05-08 20:54:23,888 [salt.loaded.int.states.file:2298][WARNING ][12878] 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-05-08 20:54:23,888 [salt.state       :300 ][INFO    ][12878] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-05-08 20:54:23,889 [salt.state       :1951][INFO    ][12878] Completed state [/etc/maas/rackd.conf] at time 20:54:23.889150 duration_in_ms=1.5
2019-05-08 20:54:23,890 [salt.state       :1780][INFO    ][12878] Running state [maas-rackd] at time 20:54:23.890188
2019-05-08 20:54:23,890 [salt.state       :1813][INFO    ][12878] Executing state service.running for [maas-rackd]
2019-05-08 20:54:23,891 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12878] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-05-08 20:54:23,934 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12878] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-05-08 20:54:23,951 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12878] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-05-08 20:54:23,970 [salt.state       :300 ][INFO    ][12878] The service maas-rackd is already running
2019-05-08 20:54:23,970 [salt.state       :1951][INFO    ][12878] Completed state [maas-rackd] at time 20:54:23.970517 duration_in_ms=80.328
2019-05-08 20:54:23,973 [salt.minion      :1711][INFO    ][12878] Returning information for job: 20190508205421987339
2019-05-08 20:54:24,545 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command state.apply with jid 20190508205424535917
2019-05-08 20:54:24,558 [salt.minion      :1432][INFO    ][12907] Starting a new job with PID 12907
2019-05-08 20:54:25,432 [salt.state       :915 ][INFO    ][12907] Loading fresh modules for state activity
2019-05-08 20:54:26,476 [salt.state       :1780][INFO    ][12907] Running state [maas-region-controller] at time 20:54:26.476328
2019-05-08 20:54:26,476 [salt.state       :1813][INFO    ][12907] Executing state pkg.installed for [maas-region-controller]
2019-05-08 20:54:26,477 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12907] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-08 20:54:26,579 [salt.state       :300 ][INFO    ][12907] All specified packages are already installed
2019-05-08 20:54:26,580 [salt.state       :1951][INFO    ][12907] Completed state [maas-region-controller] at time 20:54:26.580112 duration_in_ms=103.785
2019-05-08 20:54:26,580 [salt.state       :1780][INFO    ][12907] Running state [python-oauth] at time 20:54:26.580426
2019-05-08 20:54:26,580 [salt.state       :1813][INFO    ][12907] Executing state pkg.installed for [python-oauth]
2019-05-08 20:54:26,590 [salt.state       :300 ][INFO    ][12907] All specified packages are already installed
2019-05-08 20:54:26,590 [salt.state       :1951][INFO    ][12907] Completed state [python-oauth] at time 20:54:26.590349 duration_in_ms=9.923
2019-05-08 20:54:26,593 [salt.state       :1780][INFO    ][12907] Running state [/etc/maas/regiond.conf] at time 20:54:26.593387
2019-05-08 20:54:26,593 [salt.state       :1813][INFO    ][12907] Executing state file.replace for [/etc/maas/regiond.conf]
2019-05-08 20:54:26,598 [salt.state       :300 ][INFO    ][12907] No changes needed to be made
2019-05-08 20:54:26,599 [salt.state       :1951][INFO    ][12907] Completed state [/etc/maas/regiond.conf] at time 20:54:26.599170 duration_in_ms=5.783
2019-05-08 20:54:26,599 [salt.state       :1780][INFO    ][12907] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 20:54:26.599650
2019-05-08 20:54:26,599 [salt.state       :1813][INFO    ][12907] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-05-08 20:54:26,658 [salt.state       :300 ][INFO    ][12907] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-05-08 20:54:26,658 [salt.state       :1951][INFO    ][12907] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 20:54:26.658246 duration_in_ms=58.596
2019-05-08 20:54:26,658 [salt.state       :1780][INFO    ][12907] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 20:54:26.658733
2019-05-08 20:54:26,659 [salt.state       :1813][INFO    ][12907] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-08 20:54:26,663 [salt.state       :300 ][INFO    ][12907] No changes needed to be made
2019-05-08 20:54:26,663 [salt.state       :1951][INFO    ][12907] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 20:54:26.663256 duration_in_ms=4.523
2019-05-08 20:54:26,663 [salt.state       :1780][INFO    ][12907] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 20:54:26.663724
2019-05-08 20:54:26,663 [salt.state       :1813][INFO    ][12907] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-08 20:54:26,668 [salt.state       :300 ][INFO    ][12907] No changes needed to be made
2019-05-08 20:54:26,668 [salt.state       :1951][INFO    ][12907] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 20:54:26.668388 duration_in_ms=4.663
2019-05-08 20:54:26,668 [salt.state       :1780][INFO    ][12907] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 20:54:26.668857
2019-05-08 20:54:26,669 [salt.state       :1813][INFO    ][12907] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-05-08 20:54:26,691 [salt.state       :300 ][INFO    ][12907] No changes needed to be made
2019-05-08 20:54:26,691 [salt.state       :1951][INFO    ][12907] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 20:54:26.691562 duration_in_ms=22.706
2019-05-08 20:54:26,692 [salt.state       :1780][INFO    ][12907] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 20:54:26.692036
2019-05-08 20:54:26,692 [salt.state       :1813][INFO    ][12907] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-05-08 20:54:26,701 [salt.state       :300 ][INFO    ][12907] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-05-08 20:54:26,701 [salt.state       :1951][INFO    ][12907] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 20:54:26.701622 duration_in_ms=9.585
2019-05-08 20:54:26,704 [salt.state       :1780][INFO    ][12907] Running state [a2enmod headers] at time 20:54:26.704854
2019-05-08 20:54:26,705 [salt.state       :1813][INFO    ][12907] Executing state cmd.run for [a2enmod headers]
2019-05-08 20:54:26,705 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12907] Executing command 'a2enmod headers' in directory '/root'
2019-05-08 20:54:26,766 [salt.state       :300 ][INFO    ][12907] {'pid': 12932, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-05-08 20:54:26,767 [salt.state       :1951][INFO    ][12907] Completed state [a2enmod headers] at time 20:54:26.767247 duration_in_ms=62.392
2019-05-08 20:54:26,767 [salt.state       :1780][INFO    ][12907] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 20:54:26.767858
2019-05-08 20:54:26,768 [salt.state       :1813][INFO    ][12907] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-05-08 20:54:26,785 [salt.state       :300 ][INFO    ][12907] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-05-08 20:54:26,785 [salt.state       :1951][INFO    ][12907] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 20:54:26.785465 duration_in_ms=17.607
2019-05-08 20:54:26,788 [salt.state       :1780][INFO    ][12907] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 20:54:26.788865
2019-05-08 20:54:26,789 [salt.state       :1813][INFO    ][12907] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-05-08 20:54:26,841 [salt.state       :300 ][INFO    ][12907] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-05-08 20:54:26,841 [salt.state       :1951][INFO    ][12907] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 20:54:26.841871 duration_in_ms=53.006
2019-05-08 20:54:26,842 [salt.state       :1780][INFO    ][12907] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 20:54:26.842407
2019-05-08 20:54:26,842 [salt.state       :1813][INFO    ][12907] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-05-08 20:54:26,891 [salt.state       :300 ][INFO    ][12907] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-05-08 20:54:26,891 [salt.state       :1951][INFO    ][12907] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 20:54:26.891405 duration_in_ms=48.997
2019-05-08 20:54:26,891 [salt.state       :1780][INFO    ][12907] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 20:54:26.891901
2019-05-08 20:54:26,892 [salt.state       :1813][INFO    ][12907] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-05-08 20:54:26,970 [salt.state       :300 ][INFO    ][12907] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-05-08 20:54:26,970 [salt.state       :1951][INFO    ][12907] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 20:54:26.970329 duration_in_ms=78.427
2019-05-08 20:54:26,970 [salt.state       :1780][INFO    ][12907] Running state [/root/.pgpass] at time 20:54:26.970582
2019-05-08 20:54:26,970 [salt.state       :1813][INFO    ][12907] Executing state file.managed for [/root/.pgpass]
2019-05-08 20:54:27,013 [salt.state       :300 ][INFO    ][12907] File /root/.pgpass is in the correct state
2019-05-08 20:54:27,013 [salt.state       :1951][INFO    ][12907] Completed state [/root/.pgpass] at time 20:54:27.013470 duration_in_ms=42.888
2019-05-08 20:54:27,019 [salt.state       :1780][INFO    ][12907] Running state [maas-region syncdb --noinput] at time 20:54:27.019400
2019-05-08 20:54:27,019 [salt.state       :1813][INFO    ][12907] Executing state cmd.run for [maas-region syncdb --noinput]
2019-05-08 20:54:27,020 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12907] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-05-08 20:54:29,504 [salt.state       :300 ][INFO    ][12907] {'pid': 12945, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: contenttypes, metadataserver, auth, maasserver, sites, piston3, sessions\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-05-08 20:54:29,505 [salt.state       :1951][INFO    ][12907] Completed state [maas-region syncdb --noinput] at time 20:54:29.505103 duration_in_ms=2485.701
2019-05-08 20:54:29,506 [salt.state       :2022][WARNING ][12907] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-08 20:54:29,510 [salt.state       :1780][INFO    ][12907] Running state [maas-regiond] at time 20:54:29.510282
2019-05-08 20:54:29,511 [salt.state       :1813][INFO    ][12907] Executing state service.running for [maas-regiond]
2019-05-08 20:54:29,512 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12907] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-05-08 20:54:29,557 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12907] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-05-08 20:54:29,580 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12907] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-05-08 20:54:29,603 [salt.state       :300 ][INFO    ][12907] The service maas-regiond is already running
2019-05-08 20:54:29,604 [salt.state       :1951][INFO    ][12907] Completed state [maas-regiond] at time 20:54:29.604011 duration_in_ms=93.728
2019-05-08 20:54:29,608 [salt.state       :1780][INFO    ][12907] Running state [bind9] at time 20:54:29.608275
2019-05-08 20:54:29,608 [salt.state       :1813][INFO    ][12907] Executing state service.running for [bind9]
2019-05-08 20:54:29,610 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12907] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-05-08 20:54:29,629 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12907] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-05-08 20:54:29,657 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12907] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-05-08 20:54:29,674 [salt.state       :300 ][INFO    ][12907] The service bind9 is already running
2019-05-08 20:54:29,675 [salt.state       :1951][INFO    ][12907] Completed state [bind9] at time 20:54:29.675322 duration_in_ms=67.047
2019-05-08 20:54:29,677 [salt.state       :1780][INFO    ][12907] Running state [apache2] at time 20:54:29.677570
2019-05-08 20:54:29,680 [salt.state       :1813][INFO    ][12907] Executing state service.running for [apache2]
2019-05-08 20:54:29,681 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12907] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-05-08 20:54:29,705 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12907] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-05-08 20:54:29,724 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12907] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-05-08 20:54:29,746 [salt.state       :300 ][INFO    ][12907] The service apache2 is already running
2019-05-08 20:54:29,747 [salt.state       :1951][INFO    ][12907] Completed state [apache2] at time 20:54:29.747318 duration_in_ms=69.747
2019-05-08 20:54:29,749 [salt.state       :1780][INFO    ][12907] Running state [maasng.wait_for_http_code] at time 20:54:29.749215
2019-05-08 20:54:29,751 [salt.state       :1813][INFO    ][12907] Executing state module.run for [maasng.wait_for_http_code]
2019-05-08 20:54:29,752 [salt.utils.decorators:613 ][WARNING ][12907] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-08 20:54:29,876 [salt.state       :300 ][INFO    ][12907] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-05-08 20:54:29,876 [salt.state       :1951][INFO    ][12907] Completed state [maasng.wait_for_http_code] at time 20:54:29.876570 duration_in_ms=127.353
2019-05-08 20:54:29,878 [salt.state       :1780][INFO    ][12907] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 20:54:29.878485
2019-05-08 20:54:29,879 [salt.state       :1813][INFO    ][12907] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-05-08 20:54:29,879 [salt.state       :300 ][INFO    ][12907] /var/lib/maas/.setup_admin exists
2019-05-08 20:54:29,880 [salt.state       :1951][INFO    ][12907] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 20:54:29.880273 duration_in_ms=1.788
2019-05-08 20:54:29,882 [salt.state       :1780][INFO    ][12907] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:54:29.881619
2019-05-08 20:54:29,883 [salt.state       :1813][INFO    ][12907] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-08 20:54:29,884 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12907] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-08 20:54:31,589 [salt.state       :300 ][INFO    ][12907] {'pid': 12966, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-08 20:54:31,590 [salt.state       :1951][INFO    ][12907] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:54:31.590759 duration_in_ms=1709.139
2019-05-08 20:54:31,600 [salt.state       :1780][INFO    ][12907] Running state [maas_region_boot_source_resources_mirror] at time 20:54:31.600335
2019-05-08 20:54:31,600 [salt.state       :1813][INFO    ][12907] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-05-08 20:54:31,702 [salt.state       :300 ][INFO    ][12907] {'changes': {}}
2019-05-08 20:54:31,702 [salt.state       :1951][INFO    ][12907] Completed state [maas_region_boot_source_resources_mirror] at time 20:54:31.702287 duration_in_ms=101.952
2019-05-08 20:54:31,703 [salt.state       :1780][INFO    ][12907] Running state [maasng.boot_resources_import] at time 20:54:31.703185
2019-05-08 20:54:31,703 [salt.state       :1813][INFO    ][12907] Executing state module.run for [maasng.boot_resources_import]
2019-05-08 20:54:31,704 [salt.utils.decorators:613 ][WARNING ][12907] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-08 20:54:31,806 [salt.loaded.ext.module.maasng:1600][INFO    ][12907] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-05-08 20:54:36,860 [salt.loaded.ext.module.maasng:1600][INFO    ][12907] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-05-08 20:54:39,610 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508205439599049
2019-05-08 20:54:39,634 [salt.minion      :1432][INFO    ][13144] Starting a new job with PID 13144
2019-05-08 20:54:39,662 [salt.minion      :1711][INFO    ][13144] Returning information for job: 20190508205439599049
2019-05-08 20:54:41,965 [salt.state       :300 ][INFO    ][12907] {'ret': True}
2019-05-08 20:54:41,966 [salt.state       :1951][INFO    ][12907] Completed state [maasng.boot_resources_import] at time 20:54:41.966259 duration_in_ms=10263.073
2019-05-08 20:54:41,968 [salt.state       :1780][INFO    ][12907] Running state [maas_region_boot_sources_selection_xenial] at time 20:54:41.967924
2019-05-08 20:54:41,968 [salt.state       :1813][INFO    ][12907] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-05-08 20:54:42,151 [salt.state       :300 ][INFO    ][12907] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-05-08 20:54:42,152 [salt.state       :1951][INFO    ][12907] Completed state [maas_region_boot_sources_selection_xenial] at time 20:54:42.151997 duration_in_ms=184.072
2019-05-08 20:54:42,153 [salt.state       :1780][INFO    ][12907] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 20:54:42.153300
2019-05-08 20:54:42,154 [salt.state       :1813][INFO    ][12907] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-05-08 20:54:42,154 [salt.utils.decorators:613 ][WARNING ][12907] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-08 20:54:42,155 [salt.loaded.ext.module.maasng:1771][INFO    ][12907] boot-sources sync initiated for ALL Rack's
2019-05-08 20:54:43,290 [salt.state       :300 ][INFO    ][12907] {'ret': True}
2019-05-08 20:54:43,290 [salt.state       :1951][INFO    ][12907] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 20:54:43.290528 duration_in_ms=1137.227
2019-05-08 20:54:43,293 [salt.state       :1780][INFO    ][12907] Running state [maas.process_maas_config] at time 20:54:43.293083
2019-05-08 20:54:43,293 [salt.state       :1813][INFO    ][12907] Executing state module.run for [maas.process_maas_config]
2019-05-08 20:54:43,294 [salt.utils.decorators:613 ][WARNING ][12907] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-08 20:54:43,295 [salt.loaded.ext.module.maas:92  ][INFO    ][12907] maasconfig name=enable_http_proxy value=True
2019-05-08 20:54:43,360 [salt.loaded.ext.module.maas:92  ][INFO    ][12907] maasconfig name=upstream_dns value=8.8.8.8
2019-05-08 20:54:44,757 [salt.loaded.ext.module.maas:92  ][INFO    ][12907] maasconfig name=commissioning_distro_series value=xenial
2019-05-08 20:54:44,818 [salt.loaded.ext.module.maas:92  ][INFO    ][12907] maasconfig name=default_osystem value=ubuntu
2019-05-08 20:54:44,875 [salt.loaded.ext.module.maas:92  ][INFO    ][12907] maasconfig name=active_discovery_interval value=600
2019-05-08 20:54:44,920 [salt.loaded.ext.module.maas:92  ][INFO    ][12907] maasconfig name=dnssec_validation value=no
2019-05-08 20:54:44,967 [salt.loaded.ext.module.maas:92  ][INFO    ][12907] maasconfig name=maas_name value=mas01
2019-05-08 20:54:45,032 [salt.loaded.ext.module.maas:92  ][INFO    ][12907] maasconfig name=network_discovery value=enabled
2019-05-08 20:54:45,127 [salt.loaded.ext.module.maas:92  ][INFO    ][12907] maasconfig name=enable_third_party_drivers value=True
2019-05-08 20:54:45,171 [salt.loaded.ext.module.maas:92  ][INFO    ][12907] maasconfig name=default_storage_layout value=lvm
2019-05-08 20:54:45,216 [salt.loaded.ext.module.maas:92  ][INFO    ][12907] maasconfig name=ntp_external_only value=True
2019-05-08 20:54:45,274 [salt.loaded.ext.module.maas:92  ][INFO    ][12907] maasconfig name=disk_erase_with_secure_erase value=False
2019-05-08 20:54:45,340 [salt.loaded.ext.module.maas:92  ][INFO    ][12907] maasconfig name=default_distro_series value=xenial
2019-05-08 20:54:45,400 [salt.loaded.ext.module.maas:92  ][INFO    ][12907] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-05-08 20:54:45,518 [salt.state       :300 ][INFO    ][12907] {'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-05-08 20:54:45,518 [salt.state       :1951][INFO    ][12907] Completed state [maas.process_maas_config] at time 20:54:45.518611 duration_in_ms=2225.527
2019-05-08 20:54:45,519 [salt.state       :1780][INFO    ][12907] Running state [pxe_admin] at time 20:54:45.519568
2019-05-08 20:54:45,520 [salt.state       :1813][INFO    ][12907] Executing state maasng.fabric_present for [pxe_admin]
2019-05-08 20:54:45,706 [salt.loaded.ext.module.maasng:945 ][INFO    ][12907] [{u'id': 0, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'exsxx8', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-08 20:54:45,775 [salt.loaded.ext.module.maasng:1008][WARNING ][12907] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-05-08 20:54:45,776 [salt.loaded.ext.module.maasng:1011][WARNING ][12907] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-05-08 20:54:45,839 [salt.state       :300 ][INFO    ][12907] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-05-08 20:54:45,839 [salt.state       :1951][INFO    ][12907] Completed state [pxe_admin] at time 20:54:45.839447 duration_in_ms=319.879
2019-05-08 20:54:45,839 [salt.state       :1780][INFO    ][12907] Running state [vlan 0] at time 20:54:45.839800
2019-05-08 20:54:45,840 [salt.state       :1813][INFO    ][12907] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-08 20:54:45,890 [salt.loaded.ext.module.maasng:945 ][INFO    ][12907] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'exsxx8', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u''}]
2019-05-08 20:54:45,992 [salt.loaded.ext.module.maasng:945 ][INFO    ][12907] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'exsxx8', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u''}]
2019-05-08 20:54:46,245 [salt.loaded.ext.module.maasng:945 ][INFO    ][12907] [{u'id': 0, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'exsxx8', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-08 20:54:46,331 [salt.state       :300 ][INFO    ][12907] {'new': 'Vlan untagged was updated'}
2019-05-08 20:54:46,332 [salt.state       :1951][INFO    ][12907] Completed state [vlan 0] at time 20:54:46.332138 duration_in_ms=492.338
2019-05-08 20:54:46,333 [salt.state       :1780][INFO    ][12907] Running state [192.168.11.0/24] at time 20:54:46.333361
2019-05-08 20:54:46,336 [salt.state       :1813][INFO    ][12907] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-05-08 20:54:46,546 [salt.loaded.ext.module.maasng:945 ][INFO    ][12907] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'exsxx8', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u''}]
2019-05-08 20:54:46,547 [salt.loaded.ext.module.maasng:1235][WARNING ][12907] Ignoring parameter vlan:0
2019-05-08 20:54:46,620 [salt.state       :300 ][INFO    ][12907] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-05-08 20:54:46,621 [salt.state       :1951][INFO    ][12907] Completed state [192.168.11.0/24] at time 20:54:46.620965 duration_in_ms=287.603
2019-05-08 20:54:46,622 [salt.state       :1780][INFO    ][12907] Running state [maas_create_iprange_1] at time 20:54:46.622498
2019-05-08 20:54:46,623 [salt.state       :1813][INFO    ][12907] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-05-08 20:54:46,817 [salt.state       :300 ][INFO    ][12907] Iprange maas_create_iprange_1 already exist.
2019-05-08 20:54:46,818 [salt.state       :1951][INFO    ][12907] Completed state [maas_create_iprange_1] at time 20:54:46.818090 duration_in_ms=195.592
2019-05-08 20:54:46,818 [salt.state       :1780][INFO    ][12907] Running state [vlan 0] at time 20:54:46.818356
2019-05-08 20:54:46,818 [salt.state       :1813][INFO    ][12907] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-08 20:54:46,864 [salt.loaded.ext.module.maasng:945 ][INFO    ][12907] [{u'id': 0, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'exsxx8', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-08 20:54:46,953 [salt.loaded.ext.module.maasng:945 ][INFO    ][12907] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'exsxx8', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u''}]
2019-05-08 20:54:47,227 [salt.loaded.ext.module.maasng:945 ][INFO    ][12907] [{u'id': 0, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'exsxx8', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-08 20:54:47,312 [salt.state       :300 ][INFO    ][12907] {'new': 'Vlan untagged was updated'}
2019-05-08 20:54:47,313 [salt.state       :1951][INFO    ][12907] Completed state [vlan 0] at time 20:54:47.313235 duration_in_ms=494.87
2019-05-08 20:54:47,315 [salt.state       :1780][INFO    ][12907] Running state [opnfv] at time 20:54:47.314921
2019-05-08 20:54:47,315 [salt.state       :1813][INFO    ][12907] Executing state maasng.sshkey_present for [opnfv]
2019-05-08 20:54:47,360 [salt.loaded.ext.module.maasng:1903][INFO    ][12907] [{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-05-08 20:54:47,360 [salt.state       :300 ][INFO    ][12907] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-05-08 20:54:47,360 [salt.state       :1951][INFO    ][12907] Completed state [opnfv] at time 20:54:47.360690 duration_in_ms=45.769
2019-05-08 20:54:47,361 [salt.state       :1780][INFO    ][12907] Running state [maas.process_tags] at time 20:54:47.361424
2019-05-08 20:54:47,361 [salt.state       :1813][INFO    ][12907] Executing state module.run for [maas.process_tags]
2019-05-08 20:54:47,362 [salt.utils.decorators:613 ][WARNING ][12907] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-08 20:54:47,404 [salt.loaded.ext.module.maas:92  ][INFO    ][12907] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-05-08 20:54:47,470 [salt.state       :300 ][INFO    ][12907] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-05-08 20:54:47,471 [salt.state       :1951][INFO    ][12907] Completed state [maas.process_tags] at time 20:54:47.471197 duration_in_ms=109.773
2019-05-08 20:54:47,476 [salt.minion      :1711][INFO    ][12907] Returning information for job: 20190508205424535917
2019-05-08 20:54:48,085 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command state.apply with jid 20190508205448077500
2019-05-08 20:54:48,106 [salt.minion      :1432][INFO    ][13376] Starting a new job with PID 13376
2019-05-08 20:54:53,979 [salt.state       :915 ][INFO    ][13376] Loading fresh modules for state activity
2019-05-08 20:54:54,081 [salt.state       :1780][INFO    ][13376] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:54:54.081275
2019-05-08 20:54:54,081 [salt.state       :1813][INFO    ][13376] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-08 20:54:54,083 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13376] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-08 20:54:55,815 [salt.state       :300 ][INFO    ][13376] {'pid': 13400, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-08 20:54:55,816 [salt.state       :1951][INFO    ][13376] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:54:55.816630 duration_in_ms=1735.355
2019-05-08 20:54:55,820 [salt.state       :1780][INFO    ][13376] Running state [maas.process_machines] at time 20:54:55.819966
2019-05-08 20:54:55,820 [salt.state       :1813][INFO    ][13376] Executing state module.run for [maas.process_machines]
2019-05-08 20:54:55,821 [salt.utils.decorators:613 ][WARNING ][13376] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-08 20:54:56,323 [salt.loaded.ext.module.maas:412 ][WARNING ][13376] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-08 20:54:56,324 [salt.loaded.ext.module.maas:92  ][INFO    ][13376] 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=rfrs4k architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-08 20:54:57,553 [salt.loaded.ext.module.maas:412 ][WARNING ][13376] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-08 20:54:57,555 [salt.loaded.ext.module.maas:92  ][INFO    ][13376] 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=c6tft3 architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-08 20:54:58,808 [salt.loaded.ext.module.maas:412 ][WARNING ][13376] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-08 20:54:58,809 [salt.loaded.ext.module.maas:92  ][INFO    ][13376] 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=senk7w architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-08 20:55:00,031 [salt.loaded.ext.module.maas:412 ][WARNING ][13376] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-08 20:55:00,032 [salt.loaded.ext.module.maas:92  ][INFO    ][13376] 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=xph4ha architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-08 20:55:01,259 [salt.state       :300 ][INFO    ][13376] {'ret': {'updated': ['gtw01', 'cmp002', 'cmp001', 'ctl01'], 'errors': {}, 'success': []}}
2019-05-08 20:55:01,259 [salt.state       :1951][INFO    ][13376] Completed state [maas.process_machines] at time 20:55:01.259601 duration_in_ms=5439.634
2019-05-08 20:55:01,264 [salt.minion      :1711][INFO    ][13376] Returning information for job: 20190508205448077500
2019-05-08 20:55:33,888 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command state.apply with jid 20190508205533824177
2019-05-08 20:55:33,916 [salt.minion      :1432][INFO    ][13652] Starting a new job with PID 13652
2019-05-08 20:55:39,718 [salt.state       :915 ][INFO    ][13652] Loading fresh modules for state activity
2019-05-08 20:55:39,816 [salt.state       :1780][INFO    ][13652] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:55:39.816145
2019-05-08 20:55:39,816 [salt.state       :1813][INFO    ][13652] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-08 20:55:39,818 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13652] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-08 20:55:41,527 [salt.state       :300 ][INFO    ][13652] {'pid': 13664, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-08 20:55:41,528 [salt.state       :1951][INFO    ][13652] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:55:41.528460 duration_in_ms=1712.314
2019-05-08 20:55:41,532 [salt.state       :1780][INFO    ][13652] Running state [maas.wait_for_machine_status] at time 20:55:41.532406
2019-05-08 20:55:41,533 [salt.state       :1813][INFO    ][13652] Executing state module.run for [maas.wait_for_machine_status]
2019-05-08 20:55:41,533 [salt.utils.decorators:613 ][WARNING ][13652] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-08 20:55:43,679 [salt.state       :300 ][INFO    ][13652] {'ret': True}
2019-05-08 20:55:43,680 [salt.state       :1951][INFO    ][13652] Completed state [maas.wait_for_machine_status] at time 20:55:43.679982 duration_in_ms=2147.574
2019-05-08 20:55:43,685 [salt.minion      :1711][INFO    ][13652] Returning information for job: 20190508205533824177
2019-05-08 20:55:44,263 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command state.apply with jid 20190508205544248796
2019-05-08 20:55:44,288 [salt.minion      :1432][INFO    ][13678] Starting a new job with PID 13678
2019-05-08 20:55:45,143 [salt.state       :915 ][INFO    ][13678] Loading fresh modules for state activity
2019-05-08 20:55:45,299 [salt.state       :1780][INFO    ][13678] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:55:45.299772
2019-05-08 20:55:45,300 [salt.state       :1813][INFO    ][13678] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-08 20:55:45,304 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13678] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-08 20:55:47,036 [salt.state       :300 ][INFO    ][13678] {'pid': 13685, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-08 20:55:47,038 [salt.state       :1951][INFO    ][13678] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:55:47.038173 duration_in_ms=1738.403
2019-05-08 20:55:47,043 [salt.state       :1780][INFO    ][13678] Running state [maas_machines_storage_cmp002_lvm] at time 20:55:47.041711
2019-05-08 20:55:47,043 [salt.state       :1813][INFO    ][13678] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-08 20:55:47,523 [salt.state       :300 ][INFO    ][13678] Machine cmp002 is not in Ready state.
2019-05-08 20:55:47,523 [salt.state       :1951][INFO    ][13678] Completed state [maas_machines_storage_cmp002_lvm] at time 20:55:47.523764 duration_in_ms=482.053
2019-05-08 20:55:47,524 [salt.state       :1780][INFO    ][13678] Running state [maas_machines_storage_cmp001_lvm] at time 20:55:47.524374
2019-05-08 20:55:47,524 [salt.state       :1813][INFO    ][13678] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-08 20:55:48,039 [salt.state       :300 ][INFO    ][13678] Machine cmp001 is not in Ready state.
2019-05-08 20:55:48,039 [salt.state       :1951][INFO    ][13678] Completed state [maas_machines_storage_cmp001_lvm] at time 20:55:48.039439 duration_in_ms=515.066
2019-05-08 20:55:48,042 [salt.minion      :1711][INFO    ][13678] Returning information for job: 20190508205544248796
2019-05-08 20:55:48,585 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command state.apply with jid 20190508205548571193
2019-05-08 20:55:48,615 [salt.minion      :1432][INFO    ][13698] Starting a new job with PID 13698
2019-05-08 20:55:49,484 [salt.state       :915 ][INFO    ][13698] Loading fresh modules for state activity
2019-05-08 20:55:49,576 [salt.state       :1780][INFO    ][13698] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:55:49.576119
2019-05-08 20:55:49,576 [salt.state       :1813][INFO    ][13698] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-08 20:55:49,578 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13698] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-08 20:55:51,295 [salt.state       :300 ][INFO    ][13698] {'pid': 13705, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-08 20:55:51,296 [salt.state       :1951][INFO    ][13698] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:55:51.296784 duration_in_ms=1720.665
2019-05-08 20:55:51,301 [salt.state       :1780][INFO    ][13698] Running state [maas.deploy_machines] at time 20:55:51.301122
2019-05-08 20:55:51,303 [salt.state       :1813][INFO    ][13698] Executing state module.run for [maas.deploy_machines]
2019-05-08 20:55:51,304 [salt.utils.decorators:613 ][WARNING ][13698] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-08 20:55:51,788 [salt.loaded.ext.module.maas:684 ][INFO    ][13698] deploymachines hwe_kernel=hwe-16.04 system_id=rfrs4k distro_series=xenial
2019-05-08 20:55:54,248 [salt.state       :300 ][INFO    ][13698] {'ret': {'updated': ['cmp002', 'cmp001', 'ctl01'], 'errors': {}, 'success': ['gtw01']}}
2019-05-08 20:55:54,249 [salt.state       :1951][INFO    ][13698] Completed state [maas.deploy_machines] at time 20:55:54.249296 duration_in_ms=2948.173
2019-05-08 20:55:54,255 [salt.minion      :1711][INFO    ][13698] Returning information for job: 20190508205548571193
2019-05-08 20:55:54,837 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command state.apply with jid 20190508205554826116
2019-05-08 20:55:54,860 [salt.minion      :1432][INFO    ][13766] Starting a new job with PID 13766
2019-05-08 20:55:55,729 [salt.state       :915 ][INFO    ][13766] Loading fresh modules for state activity
2019-05-08 20:55:55,839 [salt.state       :1780][INFO    ][13766] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:55:55.839702
2019-05-08 20:55:55,840 [salt.state       :1813][INFO    ][13766] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-08 20:55:55,841 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13766] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-08 20:55:57,546 [salt.state       :300 ][INFO    ][13766] {'pid': 13774, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-08 20:55:57,547 [salt.state       :1951][INFO    ][13766] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:55:57.547001 duration_in_ms=1707.299
2019-05-08 20:55:57,551 [salt.state       :1780][INFO    ][13766] Running state [maas.wait_for_machine_status] at time 20:55:57.551293
2019-05-08 20:55:57,552 [salt.state       :1813][INFO    ][13766] Executing state module.run for [maas.wait_for_machine_status]
2019-05-08 20:55:57,552 [salt.utils.decorators:613 ][WARNING ][13766] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-08 20:55:59,447 [salt.loaded.ext.module.maas:1023][INFO    ][13766] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2248.12341404s left)
2019-05-08 20:56:09,907 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508205609895887
2019-05-08 20:56:09,935 [salt.minion      :1432][INFO    ][13801] Starting a new job with PID 13801
2019-05-08 20:56:09,956 [salt.minion      :1711][INFO    ][13801] Returning information for job: 20190508205609895887
2019-05-08 20:56:31,327 [salt.loaded.ext.module.maas:1023][INFO    ][13766] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2216.24256992s left)
2019-05-08 20:56:40,003 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508205639943960
2019-05-08 20:56:40,029 [salt.minion      :1432][INFO    ][13874] Starting a new job with PID 13874
2019-05-08 20:56:40,051 [salt.minion      :1711][INFO    ][13874] Returning information for job: 20190508205639943960
2019-05-08 20:57:03,164 [salt.loaded.ext.module.maas:1023][INFO    ][13766] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2184.4064281s left)
2019-05-08 20:57:10,091 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508205710074153
2019-05-08 20:57:10,116 [salt.minion      :1432][INFO    ][13909] Starting a new job with PID 13909
2019-05-08 20:57:10,143 [salt.minion      :1711][INFO    ][13909] Returning information for job: 20190508205710074153
2019-05-08 20:57:35,058 [salt.loaded.ext.module.maas:1023][INFO    ][13766] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2152.51167512s left)
2019-05-08 20:57:40,182 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508205740169181
2019-05-08 20:57:40,205 [salt.minion      :1432][INFO    ][13996] Starting a new job with PID 13996
2019-05-08 20:57:40,230 [salt.minion      :1711][INFO    ][13996] Returning information for job: 20190508205740169181
2019-05-08 20:58:07,086 [salt.loaded.ext.module.maas:1023][INFO    ][13766] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2120.48391008s left)
2019-05-08 20:58:10,248 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508205810235370
2019-05-08 20:58:10,272 [salt.minion      :1432][INFO    ][14024] Starting a new job with PID 14024
2019-05-08 20:58:10,298 [salt.minion      :1711][INFO    ][14024] Returning information for job: 20190508205810235370
2019-05-08 20:58:38,951 [salt.loaded.ext.module.maas:1023][INFO    ][13766] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2088.61880994s left)
2019-05-08 20:58:40,332 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508205840318880
2019-05-08 20:58:40,362 [salt.minion      :1432][INFO    ][14097] Starting a new job with PID 14097
2019-05-08 20:58:40,386 [salt.minion      :1711][INFO    ][14097] Returning information for job: 20190508205840318880
2019-05-08 20:59:10,408 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508205910396339
2019-05-08 20:59:10,428 [salt.minion      :1432][INFO    ][14129] Starting a new job with PID 14129
2019-05-08 20:59:10,451 [salt.minion      :1711][INFO    ][14129] Returning information for job: 20190508205910396339
2019-05-08 20:59:10,797 [salt.loaded.ext.module.maas:1023][INFO    ][13766] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2056.77287102s left)
2019-05-08 20:59:40,508 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508205940489471
2019-05-08 20:59:40,540 [salt.minion      :1432][INFO    ][14200] Starting a new job with PID 14200
2019-05-08 20:59:40,565 [salt.minion      :1711][INFO    ][14200] Returning information for job: 20190508205940489471
2019-05-08 20:59:42,814 [salt.loaded.ext.module.maas:1023][INFO    ][13766] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2024.75615811s left)
2019-05-08 21:00:10,611 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508210010600318
2019-05-08 21:00:10,629 [salt.minion      :1432][INFO    ][14249] Starting a new job with PID 14249
2019-05-08 21:00:10,666 [salt.minion      :1711][INFO    ][14249] Returning information for job: 20190508210010600318
2019-05-08 21:00:14,658 [salt.loaded.ext.module.maas:1023][INFO    ][13766] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1992.91195989s left)
2019-05-08 21:00:40,693 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508210040685790
2019-05-08 21:00:40,720 [salt.minion      :1432][INFO    ][14326] Starting a new job with PID 14326
2019-05-08 21:00:40,744 [salt.minion      :1711][INFO    ][14326] Returning information for job: 20190508210040685790
2019-05-08 21:00:46,660 [salt.loaded.ext.module.maas:1023][INFO    ][13766] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1960.91050911s left)
2019-05-08 21:01:10,788 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508210110773459
2019-05-08 21:01:10,818 [salt.minion      :1432][INFO    ][14383] Starting a new job with PID 14383
2019-05-08 21:01:10,840 [salt.minion      :1711][INFO    ][14383] Returning information for job: 20190508210110773459
2019-05-08 21:01:19,194 [salt.loaded.ext.module.maas:1023][INFO    ][13766] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1928.37567306s left)
2019-05-08 21:01:40,953 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508210140938659
2019-05-08 21:01:40,980 [salt.minion      :1432][INFO    ][14601] Starting a new job with PID 14601
2019-05-08 21:01:41,003 [salt.minion      :1711][INFO    ][14601] Returning information for job: 20190508210140938659
2019-05-08 21:01:51,304 [salt.loaded.ext.module.maas:1023][INFO    ][13766] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1896.26643395s left)
2019-05-08 21:02:11,076 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508210211062659
2019-05-08 21:02:11,104 [salt.minion      :1432][INFO    ][14629] Starting a new job with PID 14629
2019-05-08 21:02:11,131 [salt.minion      :1711][INFO    ][14629] Returning information for job: 20190508210211062659
2019-05-08 21:02:23,400 [salt.loaded.ext.module.maas:1023][INFO    ][13766] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1864.16957498s left)
2019-05-08 21:02:41,219 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508210241206533
2019-05-08 21:02:41,249 [salt.minion      :1432][INFO    ][14724] Starting a new job with PID 14724
2019-05-08 21:02:41,268 [salt.minion      :1711][INFO    ][14724] Returning information for job: 20190508210241206533
2019-05-08 21:02:55,510 [salt.loaded.ext.module.maas:1023][INFO    ][13766] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1832.05991697s left)
2019-05-08 21:03:11,357 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508210311348477
2019-05-08 21:03:11,375 [salt.minion      :1432][INFO    ][14756] Starting a new job with PID 14756
2019-05-08 21:03:11,399 [salt.minion      :1711][INFO    ][14756] Returning information for job: 20190508210311348477
2019-05-08 21:03:27,455 [salt.loaded.ext.module.maas:1023][INFO    ][13766] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1800.1154871s left)
2019-05-08 21:03:41,507 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508210341491515
2019-05-08 21:03:41,531 [salt.minion      :1432][INFO    ][14931] Starting a new job with PID 14931
2019-05-08 21:03:41,557 [salt.minion      :1711][INFO    ][14931] Returning information for job: 20190508210341491515
2019-05-08 21:03:59,376 [salt.loaded.ext.module.maas:1023][INFO    ][13766] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1768.19358206s left)
2019-05-08 21:04:11,674 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508210411658593
2019-05-08 21:04:11,701 [salt.minion      :1432][INFO    ][14967] Starting a new job with PID 14967
2019-05-08 21:04:11,726 [salt.minion      :1711][INFO    ][14967] Returning information for job: 20190508210411658593
2019-05-08 21:04:31,460 [salt.loaded.ext.module.maas:1023][INFO    ][13766] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1736.10964489s left)
2019-05-08 21:04:41,829 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508210441820077
2019-05-08 21:04:41,855 [salt.minion      :1432][INFO    ][15121] Starting a new job with PID 15121
2019-05-08 21:04:41,876 [salt.minion      :1711][INFO    ][15121] Returning information for job: 20190508210441820077
2019-05-08 21:05:03,459 [salt.loaded.ext.module.maas:1023][INFO    ][13766] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1704.11114311s left)
2019-05-08 21:05:12,000 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508210511985709
2019-05-08 21:05:12,029 [salt.minion      :1432][INFO    ][15149] Starting a new job with PID 15149
2019-05-08 21:05:12,054 [salt.minion      :1711][INFO    ][15149] Returning information for job: 20190508210511985709
2019-05-08 21:05:35,404 [salt.loaded.ext.module.maas:1023][INFO    ][13766] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1672.16566896s left)
2019-05-08 21:05:42,176 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508210542164442
2019-05-08 21:05:42,207 [salt.minion      :1432][INFO    ][15221] Starting a new job with PID 15221
2019-05-08 21:05:42,229 [salt.minion      :1711][INFO    ][15221] Returning information for job: 20190508210542164442
2019-05-08 21:06:07,416 [salt.loaded.ext.module.maas:1023][INFO    ][13766] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1640.1537199s left)
2019-05-08 21:06:12,367 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508210612351222
2019-05-08 21:06:12,397 [salt.minion      :1432][INFO    ][15249] Starting a new job with PID 15249
2019-05-08 21:06:12,420 [salt.minion      :1711][INFO    ][15249] Returning information for job: 20190508210612351222
2019-05-08 21:06:39,383 [salt.loaded.ext.module.maas:1023][INFO    ][13766] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1608.18727207s left)
2019-05-08 21:06:42,540 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508210642526881
2019-05-08 21:06:42,567 [salt.minion      :1432][INFO    ][15321] Starting a new job with PID 15321
2019-05-08 21:06:42,595 [salt.minion      :1711][INFO    ][15321] Returning information for job: 20190508210642526881
2019-05-08 21:07:11,494 [salt.utils.schedule:1377][INFO    ][2934] Running scheduled job: __mine_interval
2019-05-08 21:07:11,500 [salt.loaded.ext.module.maas:1023][INFO    ][13766] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1576.07041907s left)
2019-05-08 21:07:12,725 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508210712710733
2019-05-08 21:07:12,748 [salt.minion      :1432][INFO    ][15366] Starting a new job with PID 15366
2019-05-08 21:07:12,769 [salt.minion      :1711][INFO    ][15366] Returning information for job: 20190508210712710733
2019-05-08 21:07:42,895 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508210742884176
2019-05-08 21:07:42,921 [salt.minion      :1432][INFO    ][15600] Starting a new job with PID 15600
2019-05-08 21:07:42,949 [salt.minion      :1711][INFO    ][15600] Returning information for job: 20190508210742884176
2019-05-08 21:07:43,621 [salt.loaded.ext.module.maas:1023][INFO    ][13766] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1543.94939899s left)
2019-05-08 21:08:13,099 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508210813090600
2019-05-08 21:08:13,126 [salt.minion      :1432][INFO    ][15646] Starting a new job with PID 15646
2019-05-08 21:08:13,150 [salt.minion      :1711][INFO    ][15646] Returning information for job: 20190508210813090600
2019-05-08 21:08:15,565 [salt.loaded.ext.module.maas:1023][INFO    ][13766] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1512.00493598s left)
2019-05-08 21:08:43,301 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command saltutil.find_job with jid 20190508210843288781
2019-05-08 21:08:43,329 [salt.minion      :1432][INFO    ][15785] Starting a new job with PID 15785
2019-05-08 21:08:43,353 [salt.minion      :1711][INFO    ][15785] Returning information for job: 20190508210843288781
2019-05-08 21:08:47,579 [salt.state       :300 ][INFO    ][13766] {'ret': True}
2019-05-08 21:08:47,580 [salt.state       :1951][INFO    ][13766] Completed state [maas.wait_for_machine_status] at time 21:08:47.580220 duration_in_ms=770028.926
2019-05-08 21:08:47,585 [salt.minion      :1711][INFO    ][13766] Returning information for job: 20190508205554826116
2019-05-08 21:59:36,905 [salt.minion      :1308][INFO    ][2934] User sudo_ubuntu Executing command cp.push_dir with jid 20190508215936891836
2019-05-08 21:59:36,935 [salt.minion      :1432][INFO    ][21012] Starting a new job with PID 21012
