2018-09-05 14:07:25,983 [salt.utils       :831 ][ERROR   ][1537] DNS lookup or connection check of 'salt' failed.
2018-09-05 14:07:25,984 [salt.minion      :152 ][ERROR   ][1537] Master hostname: 'salt' not found or not responsive. Retrying in 30 seconds
2018-09-05 14:07:28,326 [salt.utils.parsers:1051][WARNING ][1537] Minion received a SIGTERM. Exiting.
2018-09-05 14:08:10,391 [salt.minion      :870 ][ERROR   ][1742] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2018-09-05 14:08:40,010 [salt.utils.decorators:613 ][WARNING ][1892] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:09:07,121 [salt.utils.decorators:613 ][WARNING ][1892] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:09:17,385 [salt.utils.parsers:1051][WARNING ][1742] Minion received a SIGTERM. Exiting.
2018-09-05 14:11:04,992 [salt.utils.decorators:613 ][WARNING ][3088] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:11:30,779 [salt.utils.decorators:613 ][WARNING ][5926] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:11:35,506 [salt.utils.decorators:613 ][WARNING ][5926] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:13:15,665 [salt.loaded.int.states.file:2150][WARNING ][7150] 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.
2018-09-05 14:17:51,244 [salt.state       :2012][WARNING ][14754] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2018-09-05 14:17:52,823 [salt.utils.decorators:613 ][WARNING ][14754] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:18:01,272 [salt.utils.decorators:613 ][WARNING ][14754] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:19:24,184 [salt.utils.decorators:613 ][WARNING ][14754] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:19:50,940 [salt.loaded.ext.module.maasng:1632][WARNING ][14754] boot_source_selections catch error during processing. Most-probably, streams not imported yet.
Sleep:5sRetry:0/5
2018-09-05 14:19:50,941 [salt.loaded.ext.module.maasng:1633][WARNING ][14754] Message:['{"os": ["OS ubuntu with release xenial has no available images for download"]}']
2018-09-05 14:19:56,071 [salt.loaded.ext.module.maasng:1632][WARNING ][14754] boot_source_selections catch error during processing. Most-probably, streams not imported yet.
Sleep:5sRetry:1/5
2018-09-05 14:19:56,072 [salt.loaded.ext.module.maasng:1633][WARNING ][14754] Message:['{"os": ["OS ubuntu with release xenial has no available images for download"]}']
2018-09-05 14:22:10,350 [salt.utils.decorators:613 ][WARNING ][14754] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:22:35,039 [salt.utils.decorators:613 ][WARNING ][14754] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:22:39,334 [salt.loaded.ext.module.maasng:951 ][WARNING ][14754] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2018-09-05 14:22:39,335 [salt.loaded.ext.module.maasng:954 ][WARNING ][14754] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2018-09-05 14:22:40,124 [salt.loaded.ext.module.maasng:1177][WARNING ][14754] Ignoring parameter vlan:0
2018-09-05 14:22:44,182 [salt.utils.decorators:613 ][WARNING ][14754] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:22:50,675 [salt.utils.decorators:613 ][WARNING ][7801] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:22:51,235 [salt.loaded.ext.module.maas:412 ][WARNING ][7801] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2018-09-05 14:22:52,913 [salt.loaded.ext.module.maas:412 ][WARNING ][7801] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2018-09-05 14:22:54,509 [salt.loaded.ext.module.maas:412 ][WARNING ][7801] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2018-09-05 14:22:56,028 [salt.loaded.ext.module.maas:412 ][WARNING ][7801] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2018-09-05 14:22:58,568 [salt.loaded.ext.module.maas:412 ][WARNING ][7801] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2018-09-05 14:23:02,312 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8600] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2018-09-05 14:23:02,350 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8600] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2018-09-05 14:23:02,387 [salt.utils.parsers:1051][WARNING ][1781] Minion received a SIGTERM. Exiting.
2018-09-05 14:23:03,444 [salt.cli.daemons :293 ][INFO    ][8660] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2018-09-05 14:23:03,598 [salt.cli.daemons :82  ][INFO    ][8660] Starting up the Salt Minion
2018-09-05 14:23:03,599 [salt.utils.event :1017][INFO    ][8660] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2018-09-05 14:23:04,351 [salt.minion      :976 ][INFO    ][8660] Creating minion process manager
2018-09-05 14:23:05,386 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][8660] Executing command ['date', '+%z'] in directory '/root'
2018-09-05 14:23:05,409 [salt.utils.schedule:568 ][INFO    ][8660] Updating job settings for scheduled job: __mine_interval
2018-09-05 14:23:05,414 [salt.minion      :1107][INFO    ][8660] Added mine.update to scheduler
2018-09-05 14:23:05,425 [salt.minion      :1965][INFO    ][8660] Minion is starting as user 'root'
2018-09-05 14:23:05,439 [salt.minion      :2324][INFO    ][8660] Minion is ready to receive requests!
2018-09-05 14:23:41,995 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command pillar.get with jid 20180905142341994025
2018-09-05 14:23:42,021 [salt.minion      :1431][INFO    ][8807] Starting a new job with PID 8807
2018-09-05 14:23:42,033 [salt.minion      :1708][INFO    ][8807] Returning information for job: 20180905142341994025
2018-09-05 14:23:42,565 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command state.apply with jid 20180905142342562677
2018-09-05 14:23:42,586 [salt.minion      :1431][INFO    ][8812] Starting a new job with PID 8812
2018-09-05 14:23:46,396 [salt.state       :905 ][INFO    ][8812] Loading fresh modules for state activity
2018-09-05 14:23:46,436 [salt.fileclient  :1215][INFO    ][8812] Fetching file from saltenv 'base', ** done ** 'maas/machines/status.sls'
2018-09-05 14:23:46,467 [salt.state       :1770][INFO    ][8812] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:23:46.467360
2018-09-05 14:23:46,467 [salt.state       :1803][INFO    ][8812] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-09-05 14:23:46,469 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8812] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-09-05 14:23:47,624 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command saltutil.find_job with jid 20180905142347620433
2018-09-05 14:23:47,664 [salt.minion      :1431][INFO    ][8837] Starting a new job with PID 8837
2018-09-05 14:23:47,704 [salt.minion      :1708][INFO    ][8837] Returning information for job: 20180905142347620433
2018-09-05 14:23:47,991 [salt.state       :290 ][INFO    ][8812] {'pid': 8829, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-09-05 14:23:47,992 [salt.state       :1941][INFO    ][8812] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:23:47.992744 duration_in_ms=1525.384
2018-09-05 14:23:47,996 [salt.state       :1770][INFO    ][8812] Running state [maas.machines_status] at time 14:23:47.996032
2018-09-05 14:23:47,996 [salt.state       :1803][INFO    ][8812] Executing state module.run for [maas.machines_status]
2018-09-05 14:23:47,997 [salt.utils.decorators:613 ][WARNING ][8812] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:23:48,230 [salt.state       :290 ][INFO    ][8812] {'ret': {'machines': [{'status': 'Commissioning', 'hostname': u'cmp002', 'system_id': u'rpcrr6'}, {'status': 'Commissioning', 'hostname': u'cmp001', 'system_id': u'm4c8q7'}, {'status': 'Commissioning', 'hostname': u'kvm01', 'system_id': u'w4dqkr'}, {'status': 'Commissioning', 'hostname': u'kvm03', 'system_id': u'qttwky'}, {'status': 'Commissioning', 'hostname': u'kvm02', 'system_id': u'pt3hbk'}], 'summary': Counter({'Commissioning': 5})}}
2018-09-05 14:23:48,231 [salt.state       :1941][INFO    ][8812] Completed state [maas.machines_status] at time 14:23:48.231447 duration_in_ms=235.414
2018-09-05 14:23:48,236 [salt.minion      :1708][INFO    ][8812] Returning information for job: 20180905142342562677
2018-09-05 14:23:58,771 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command state.apply with jid 20180905142358766085
2018-09-05 14:23:58,805 [salt.minion      :1431][INFO    ][8859] Starting a new job with PID 8859
2018-09-05 14:23:59,466 [salt.state       :905 ][INFO    ][8859] Loading fresh modules for state activity
2018-09-05 14:23:59,564 [salt.state       :1770][INFO    ][8859] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:23:59.563923
2018-09-05 14:23:59,564 [salt.state       :1803][INFO    ][8859] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-09-05 14:23:59,567 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8859] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-09-05 14:24:01,137 [salt.state       :290 ][INFO    ][8859] {'pid': 8866, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-09-05 14:24:01,139 [salt.state       :1941][INFO    ][8859] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:24:01.139644 duration_in_ms=1575.719
2018-09-05 14:24:01,143 [salt.state       :1770][INFO    ][8859] Running state [maas.machines_status] at time 14:24:01.143138
2018-09-05 14:24:01,144 [salt.state       :1803][INFO    ][8859] Executing state module.run for [maas.machines_status]
2018-09-05 14:24:01,145 [salt.utils.decorators:613 ][WARNING ][8859] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:24:01,370 [salt.state       :290 ][INFO    ][8859] {'ret': {'machines': [{'status': 'Commissioning', 'hostname': u'cmp002', 'system_id': u'rpcrr6'}, {'status': 'Commissioning', 'hostname': u'cmp001', 'system_id': u'm4c8q7'}, {'status': 'Commissioning', 'hostname': u'kvm01', 'system_id': u'w4dqkr'}, {'status': 'Commissioning', 'hostname': u'kvm03', 'system_id': u'qttwky'}, {'status': 'Commissioning', 'hostname': u'kvm02', 'system_id': u'pt3hbk'}], 'summary': Counter({'Commissioning': 5})}}
2018-09-05 14:24:01,370 [salt.state       :1941][INFO    ][8859] Completed state [maas.machines_status] at time 14:24:01.370604 duration_in_ms=227.466
2018-09-05 14:24:01,373 [salt.minion      :1708][INFO    ][8859] Returning information for job: 20180905142358766085
2018-09-05 14:24:11,966 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command state.apply with jid 20180905142411964645
2018-09-05 14:24:11,991 [salt.minion      :1431][INFO    ][8894] Starting a new job with PID 8894
2018-09-05 14:24:15,611 [salt.state       :905 ][INFO    ][8894] Loading fresh modules for state activity
2018-09-05 14:24:15,703 [salt.state       :1770][INFO    ][8894] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:24:15.703622
2018-09-05 14:24:15,704 [salt.state       :1803][INFO    ][8894] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-09-05 14:24:15,706 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8894] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-09-05 14:24:17,053 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command saltutil.find_job with jid 20180905142417054347
2018-09-05 14:24:17,085 [salt.minion      :1431][INFO    ][8915] Starting a new job with PID 8915
2018-09-05 14:24:17,107 [salt.minion      :1708][INFO    ][8915] Returning information for job: 20180905142417054347
2018-09-05 14:24:17,253 [salt.state       :290 ][INFO    ][8894] {'pid': 8902, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-09-05 14:24:17,254 [salt.state       :1941][INFO    ][8894] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:24:17.254478 duration_in_ms=1550.856
2018-09-05 14:24:17,255 [salt.state       :1770][INFO    ][8894] Running state [maas.machines_status] at time 14:24:17.255831
2018-09-05 14:24:17,256 [salt.state       :1803][INFO    ][8894] Executing state module.run for [maas.machines_status]
2018-09-05 14:24:17,256 [salt.utils.decorators:613 ][WARNING ][8894] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:24:17,488 [salt.state       :290 ][INFO    ][8894] {'ret': {'machines': [{'status': 'Commissioning', 'hostname': u'cmp002', 'system_id': u'rpcrr6'}, {'status': 'Commissioning', 'hostname': u'cmp001', 'system_id': u'm4c8q7'}, {'status': 'Commissioning', 'hostname': u'kvm01', 'system_id': u'w4dqkr'}, {'status': 'Commissioning', 'hostname': u'kvm03', 'system_id': u'qttwky'}, {'status': 'Commissioning', 'hostname': u'kvm02', 'system_id': u'pt3hbk'}], 'summary': Counter({'Commissioning': 5})}}
2018-09-05 14:24:17,489 [salt.state       :1941][INFO    ][8894] Completed state [maas.machines_status] at time 14:24:17.488917 duration_in_ms=233.085
2018-09-05 14:24:17,494 [salt.minion      :1708][INFO    ][8894] Returning information for job: 20180905142411964645
2018-09-05 14:24:28,039 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command state.apply with jid 20180905142428037806
2018-09-05 14:24:28,077 [salt.minion      :1431][INFO    ][8946] Starting a new job with PID 8946
2018-09-05 14:24:28,676 [salt.state       :905 ][INFO    ][8946] Loading fresh modules for state activity
2018-09-05 14:24:28,741 [salt.state       :1770][INFO    ][8946] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:24:28.741391
2018-09-05 14:24:28,741 [salt.state       :1803][INFO    ][8946] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-09-05 14:24:28,743 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8946] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-09-05 14:24:30,191 [salt.state       :290 ][INFO    ][8946] {'pid': 8953, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-09-05 14:24:30,193 [salt.state       :1941][INFO    ][8946] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:24:30.193203 duration_in_ms=1451.81
2018-09-05 14:24:30,197 [salt.state       :1770][INFO    ][8946] Running state [maas.machines_status] at time 14:24:30.197463
2018-09-05 14:24:30,198 [salt.state       :1803][INFO    ][8946] Executing state module.run for [maas.machines_status]
2018-09-05 14:24:30,198 [salt.utils.decorators:613 ][WARNING ][8946] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:24:30,388 [salt.state       :290 ][INFO    ][8946] {'ret': {'machines': [{'status': 'Commissioning', 'hostname': u'cmp002', 'system_id': u'rpcrr6'}, {'status': 'Commissioning', 'hostname': u'cmp001', 'system_id': u'm4c8q7'}, {'status': 'Commissioning', 'hostname': u'kvm01', 'system_id': u'w4dqkr'}, {'status': 'Commissioning', 'hostname': u'kvm03', 'system_id': u'qttwky'}, {'status': 'Commissioning', 'hostname': u'kvm02', 'system_id': u'pt3hbk'}], 'summary': Counter({'Commissioning': 5})}}
2018-09-05 14:24:30,389 [salt.state       :1941][INFO    ][8946] Completed state [maas.machines_status] at time 14:24:30.389135 duration_in_ms=191.673
2018-09-05 14:24:30,394 [salt.minion      :1708][INFO    ][8946] Returning information for job: 20180905142428037806
2018-09-05 14:24:40,966 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command state.apply with jid 20180905142440922610
2018-09-05 14:24:40,990 [salt.minion      :1431][INFO    ][8969] Starting a new job with PID 8969
2018-09-05 14:24:44,694 [salt.state       :905 ][INFO    ][8969] Loading fresh modules for state activity
2018-09-05 14:24:44,755 [salt.state       :1770][INFO    ][8969] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:24:44.755044
2018-09-05 14:24:44,755 [salt.state       :1803][INFO    ][8969] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-09-05 14:24:44,756 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8969] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-09-05 14:24:46,025 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command saltutil.find_job with jid 20180905142446031269
2018-09-05 14:24:46,048 [salt.minion      :1431][INFO    ][9030] Starting a new job with PID 9030
2018-09-05 14:24:46,070 [salt.minion      :1708][INFO    ][9030] Returning information for job: 20180905142446031269
2018-09-05 14:24:46,208 [salt.state       :290 ][INFO    ][8969] {'pid': 9002, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-09-05 14:24:46,209 [salt.state       :1941][INFO    ][8969] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:24:46.209514 duration_in_ms=1454.468
2018-09-05 14:24:46,212 [salt.state       :1770][INFO    ][8969] Running state [maas.machines_status] at time 14:24:46.212826
2018-09-05 14:24:46,213 [salt.state       :1803][INFO    ][8969] Executing state module.run for [maas.machines_status]
2018-09-05 14:24:46,214 [salt.utils.decorators:613 ][WARNING ][8969] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:24:46,486 [salt.state       :290 ][INFO    ][8969] {'ret': {'machines': [{'status': 'Commissioning', 'hostname': u'cmp002', 'system_id': u'rpcrr6'}, {'status': 'Commissioning', 'hostname': u'cmp001', 'system_id': u'm4c8q7'}, {'status': 'Commissioning', 'hostname': u'kvm01', 'system_id': u'w4dqkr'}, {'status': 'Commissioning', 'hostname': u'kvm03', 'system_id': u'qttwky'}, {'status': 'Commissioning', 'hostname': u'kvm02', 'system_id': u'pt3hbk'}], 'summary': Counter({'Commissioning': 5})}}
2018-09-05 14:24:46,486 [salt.state       :1941][INFO    ][8969] Completed state [maas.machines_status] at time 14:24:46.486806 duration_in_ms=273.981
2018-09-05 14:24:46,561 [salt.minion      :1708][INFO    ][8969] Returning information for job: 20180905142440922610
2018-09-05 14:24:57,038 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command state.apply with jid 20180905142457042688
2018-09-05 14:24:57,055 [salt.minion      :1431][INFO    ][9133] Starting a new job with PID 9133
2018-09-05 14:24:57,558 [salt.state       :905 ][INFO    ][9133] Loading fresh modules for state activity
2018-09-05 14:24:57,637 [salt.state       :1770][INFO    ][9133] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:24:57.637285
2018-09-05 14:24:57,638 [salt.state       :1803][INFO    ][9133] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-09-05 14:24:57,640 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9133] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-09-05 14:24:58,987 [salt.state       :290 ][INFO    ][9133] {'pid': 9140, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-09-05 14:24:58,988 [salt.state       :1941][INFO    ][9133] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:24:58.988065 duration_in_ms=1350.78
2018-09-05 14:24:58,990 [salt.state       :1770][INFO    ][9133] Running state [maas.machines_status] at time 14:24:58.990298
2018-09-05 14:24:58,990 [salt.state       :1803][INFO    ][9133] Executing state module.run for [maas.machines_status]
2018-09-05 14:24:58,991 [salt.utils.decorators:613 ][WARNING ][9133] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:24:59,235 [salt.state       :290 ][INFO    ][9133] {'ret': {'machines': [{'status': 'Commissioning', 'hostname': u'cmp002', 'system_id': u'rpcrr6'}, {'status': 'Commissioning', 'hostname': u'cmp001', 'system_id': u'm4c8q7'}, {'status': 'Commissioning', 'hostname': u'kvm01', 'system_id': u'w4dqkr'}, {'status': 'Commissioning', 'hostname': u'kvm03', 'system_id': u'qttwky'}, {'status': 'Commissioning', 'hostname': u'kvm02', 'system_id': u'pt3hbk'}], 'summary': Counter({'Commissioning': 5})}}
2018-09-05 14:24:59,236 [salt.state       :1941][INFO    ][9133] Completed state [maas.machines_status] at time 14:24:59.236040 duration_in_ms=245.742
2018-09-05 14:24:59,238 [salt.minion      :1708][INFO    ][9133] Returning information for job: 20180905142457042688
2018-09-05 14:25:09,720 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command state.apply with jid 20180905142509726403
2018-09-05 14:25:09,747 [salt.minion      :1431][INFO    ][9172] Starting a new job with PID 9172
2018-09-05 14:25:13,449 [salt.state       :905 ][INFO    ][9172] Loading fresh modules for state activity
2018-09-05 14:25:13,529 [salt.state       :1770][INFO    ][9172] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:25:13.529640
2018-09-05 14:25:13,530 [salt.state       :1803][INFO    ][9172] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-09-05 14:25:13,532 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9172] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-09-05 14:25:14,779 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command saltutil.find_job with jid 20180905142514782456
2018-09-05 14:25:14,817 [salt.minion      :1431][INFO    ][9233] Starting a new job with PID 9233
2018-09-05 14:25:14,854 [salt.minion      :1708][INFO    ][9233] Returning information for job: 20180905142514782456
2018-09-05 14:25:15,091 [salt.state       :290 ][INFO    ][9172] {'pid': 9201, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-09-05 14:25:15,092 [salt.state       :1941][INFO    ][9172] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:25:15.092228 duration_in_ms=1562.585
2018-09-05 14:25:15,095 [salt.state       :1770][INFO    ][9172] Running state [maas.machines_status] at time 14:25:15.095502
2018-09-05 14:25:15,096 [salt.state       :1803][INFO    ][9172] Executing state module.run for [maas.machines_status]
2018-09-05 14:25:15,096 [salt.utils.decorators:613 ][WARNING ][9172] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:25:15,460 [salt.state       :290 ][INFO    ][9172] {'ret': {'machines': [{'status': 'Commissioning', 'hostname': u'cmp002', 'system_id': u'rpcrr6'}, {'status': 'Commissioning', 'hostname': u'cmp001', 'system_id': u'm4c8q7'}, {'status': 'Commissioning', 'hostname': u'kvm01', 'system_id': u'w4dqkr'}, {'status': 'Commissioning', 'hostname': u'kvm03', 'system_id': u'qttwky'}, {'status': 'Commissioning', 'hostname': u'kvm02', 'system_id': u'pt3hbk'}], 'summary': Counter({'Commissioning': 5})}}
2018-09-05 14:25:15,461 [salt.state       :1941][INFO    ][9172] Completed state [maas.machines_status] at time 14:25:15.461477 duration_in_ms=365.975
2018-09-05 14:25:15,466 [salt.minion      :1708][INFO    ][9172] Returning information for job: 20180905142509726403
2018-09-05 14:25:25,991 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command state.apply with jid 20180905142525997180
2018-09-05 14:25:26,019 [salt.minion      :1431][INFO    ][9301] Starting a new job with PID 9301
2018-09-05 14:25:26,720 [salt.state       :905 ][INFO    ][9301] Loading fresh modules for state activity
2018-09-05 14:25:26,818 [salt.state       :1770][INFO    ][9301] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:25:26.818749
2018-09-05 14:25:26,819 [salt.state       :1803][INFO    ][9301] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-09-05 14:25:26,821 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9301] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-09-05 14:25:28,262 [salt.state       :290 ][INFO    ][9301] {'pid': 9308, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-09-05 14:25:28,262 [salt.state       :1941][INFO    ][9301] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:25:28.262625 duration_in_ms=1443.877
2018-09-05 14:25:28,263 [salt.state       :1770][INFO    ][9301] Running state [maas.machines_status] at time 14:25:28.263926
2018-09-05 14:25:28,264 [salt.state       :1803][INFO    ][9301] Executing state module.run for [maas.machines_status]
2018-09-05 14:25:28,264 [salt.utils.decorators:613 ][WARNING ][9301] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:25:28,528 [salt.state       :290 ][INFO    ][9301] {'ret': {'machines': [{'status': 'Commissioning', 'hostname': u'cmp002', 'system_id': u'rpcrr6'}, {'status': 'Commissioning', 'hostname': u'cmp001', 'system_id': u'm4c8q7'}, {'status': 'Commissioning', 'hostname': u'kvm01', 'system_id': u'w4dqkr'}, {'status': 'Commissioning', 'hostname': u'kvm03', 'system_id': u'qttwky'}, {'status': 'Commissioning', 'hostname': u'kvm02', 'system_id': u'pt3hbk'}], 'summary': Counter({'Commissioning': 5})}}
2018-09-05 14:25:28,528 [salt.state       :1941][INFO    ][9301] Completed state [maas.machines_status] at time 14:25:28.528687 duration_in_ms=264.76
2018-09-05 14:25:28,530 [salt.minion      :1708][INFO    ][9301] Returning information for job: 20180905142525997180
2018-09-05 14:25:39,087 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command state.apply with jid 20180905142539084390
2018-09-05 14:25:39,146 [salt.minion      :1431][INFO    ][9331] Starting a new job with PID 9331
2018-09-05 14:25:42,991 [salt.state       :905 ][INFO    ][9331] Loading fresh modules for state activity
2018-09-05 14:25:43,070 [salt.state       :1770][INFO    ][9331] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:25:43.070829
2018-09-05 14:25:43,071 [salt.state       :1803][INFO    ][9331] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-09-05 14:25:43,073 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9331] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-09-05 14:25:44,104 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command saltutil.find_job with jid 20180905142544106034
2018-09-05 14:25:44,143 [salt.minion      :1431][INFO    ][9380] Starting a new job with PID 9380
2018-09-05 14:25:44,189 [salt.minion      :1708][INFO    ][9380] Returning information for job: 20180905142544106034
2018-09-05 14:25:44,859 [salt.state       :290 ][INFO    ][9331] {'pid': 9363, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-09-05 14:25:44,861 [salt.state       :1941][INFO    ][9331] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:25:44.861457 duration_in_ms=1790.625
2018-09-05 14:25:44,866 [salt.state       :1770][INFO    ][9331] Running state [maas.machines_status] at time 14:25:44.866588
2018-09-05 14:25:44,867 [salt.state       :1803][INFO    ][9331] Executing state module.run for [maas.machines_status]
2018-09-05 14:25:44,868 [salt.utils.decorators:613 ][WARNING ][9331] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:25:45,249 [salt.state       :290 ][INFO    ][9331] {'ret': {'machines': [{'status': 'Commissioning', 'hostname': u'cmp002', 'system_id': u'rpcrr6'}, {'status': 'Commissioning', 'hostname': u'cmp001', 'system_id': u'm4c8q7'}, {'status': 'Commissioning', 'hostname': u'kvm01', 'system_id': u'w4dqkr'}, {'status': 'Commissioning', 'hostname': u'kvm03', 'system_id': u'qttwky'}, {'status': 'Commissioning', 'hostname': u'kvm02', 'system_id': u'pt3hbk'}], 'summary': Counter({'Commissioning': 5})}}
2018-09-05 14:25:45,250 [salt.state       :1941][INFO    ][9331] Completed state [maas.machines_status] at time 14:25:45.250579 duration_in_ms=383.991
2018-09-05 14:25:45,255 [salt.minion      :1708][INFO    ][9331] Returning information for job: 20180905142539084390
2018-09-05 14:25:55,842 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command state.apply with jid 20180905142555846543
2018-09-05 14:25:55,885 [salt.minion      :1431][INFO    ][9627] Starting a new job with PID 9627
2018-09-05 14:25:56,460 [salt.state       :905 ][INFO    ][9627] Loading fresh modules for state activity
2018-09-05 14:25:56,530 [salt.state       :1770][INFO    ][9627] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:25:56.530273
2018-09-05 14:25:56,530 [salt.state       :1803][INFO    ][9627] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-09-05 14:25:56,532 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9627] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-09-05 14:25:57,901 [salt.state       :290 ][INFO    ][9627] {'pid': 9703, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-09-05 14:25:57,903 [salt.state       :1941][INFO    ][9627] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:25:57.903071 duration_in_ms=1372.799
2018-09-05 14:25:57,905 [salt.state       :1770][INFO    ][9627] Running state [maas.machines_status] at time 14:25:57.904971
2018-09-05 14:25:57,905 [salt.state       :1803][INFO    ][9627] Executing state module.run for [maas.machines_status]
2018-09-05 14:25:57,905 [salt.utils.decorators:613 ][WARNING ][9627] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:25:58,232 [salt.state       :290 ][INFO    ][9627] {'ret': {'machines': [{'status': 'Commissioning', 'hostname': u'cmp002', 'system_id': u'rpcrr6'}, {'status': 'Commissioning', 'hostname': u'cmp001', 'system_id': u'm4c8q7'}, {'status': 'Commissioning', 'hostname': u'kvm01', 'system_id': u'w4dqkr'}, {'status': 'Commissioning', 'hostname': u'kvm03', 'system_id': u'qttwky'}, {'status': 'Commissioning', 'hostname': u'kvm02', 'system_id': u'pt3hbk'}], 'summary': Counter({'Commissioning': 5})}}
2018-09-05 14:25:58,233 [salt.state       :1941][INFO    ][9627] Completed state [maas.machines_status] at time 14:25:58.233314 duration_in_ms=328.341
2018-09-05 14:25:58,239 [salt.minion      :1708][INFO    ][9627] Returning information for job: 20180905142555846543
2018-09-05 14:26:08,921 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command state.apply with jid 20180905142608928524
2018-09-05 14:26:08,941 [salt.minion      :1431][INFO    ][9997] Starting a new job with PID 9997
2018-09-05 14:26:12,660 [salt.state       :905 ][INFO    ][9997] Loading fresh modules for state activity
2018-09-05 14:26:12,756 [salt.state       :1770][INFO    ][9997] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:26:12.756385
2018-09-05 14:26:12,756 [salt.state       :1803][INFO    ][9997] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-09-05 14:26:12,759 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9997] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-09-05 14:26:13,994 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command saltutil.find_job with jid 20180905142613997194
2018-09-05 14:26:14,031 [salt.minion      :1431][INFO    ][10108] Starting a new job with PID 10108
2018-09-05 14:26:14,067 [salt.minion      :1708][INFO    ][10108] Returning information for job: 20180905142613997194
2018-09-05 14:26:14,295 [salt.state       :290 ][INFO    ][9997] {'pid': 10099, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-09-05 14:26:14,296 [salt.state       :1941][INFO    ][9997] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:26:14.295934 duration_in_ms=1539.548
2018-09-05 14:26:14,299 [salt.state       :1770][INFO    ][9997] Running state [maas.machines_status] at time 14:26:14.299345
2018-09-05 14:26:14,299 [salt.state       :1803][INFO    ][9997] Executing state module.run for [maas.machines_status]
2018-09-05 14:26:14,300 [salt.utils.decorators:613 ][WARNING ][9997] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:26:14,714 [salt.state       :290 ][INFO    ][9997] {'ret': {'machines': [{'status': 'Commissioning', 'hostname': u'cmp002', 'system_id': u'rpcrr6'}, {'status': 'Commissioning', 'hostname': u'cmp001', 'system_id': u'm4c8q7'}, {'status': 'Commissioning', 'hostname': u'kvm01', 'system_id': u'w4dqkr'}, {'status': 'Commissioning', 'hostname': u'kvm03', 'system_id': u'qttwky'}, {'status': 'Commissioning', 'hostname': u'kvm02', 'system_id': u'pt3hbk'}], 'summary': Counter({'Commissioning': 5})}}
2018-09-05 14:26:14,715 [salt.state       :1941][INFO    ][9997] Completed state [maas.machines_status] at time 14:26:14.715192 duration_in_ms=415.847
2018-09-05 14:26:14,718 [salt.minion      :1708][INFO    ][9997] Returning information for job: 20180905142608928524
2018-09-05 14:26:25,271 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command state.apply with jid 20180905142625274600
2018-09-05 14:26:25,299 [salt.minion      :1431][INFO    ][10124] Starting a new job with PID 10124
2018-09-05 14:26:25,847 [salt.state       :905 ][INFO    ][10124] Loading fresh modules for state activity
2018-09-05 14:26:25,940 [salt.state       :1770][INFO    ][10124] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:26:25.940436
2018-09-05 14:26:25,940 [salt.state       :1803][INFO    ][10124] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-09-05 14:26:25,943 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10124] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-09-05 14:26:27,361 [salt.state       :290 ][INFO    ][10124] {'pid': 10131, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-09-05 14:26:27,362 [salt.state       :1941][INFO    ][10124] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:26:27.362523 duration_in_ms=1422.086
2018-09-05 14:26:27,365 [salt.state       :1770][INFO    ][10124] Running state [maas.machines_status] at time 14:26:27.365534
2018-09-05 14:26:27,366 [salt.state       :1803][INFO    ][10124] Executing state module.run for [maas.machines_status]
2018-09-05 14:26:27,367 [salt.utils.decorators:613 ][WARNING ][10124] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:26:27,813 [salt.state       :290 ][INFO    ][10124] {'ret': {'machines': [{'status': 'Commissioning', 'hostname': u'cmp002', 'system_id': u'rpcrr6'}, {'status': 'Commissioning', 'hostname': u'cmp001', 'system_id': u'm4c8q7'}, {'status': 'Commissioning', 'hostname': u'kvm01', 'system_id': u'w4dqkr'}, {'status': 'Commissioning', 'hostname': u'kvm03', 'system_id': u'qttwky'}, {'status': 'Commissioning', 'hostname': u'kvm02', 'system_id': u'pt3hbk'}], 'summary': Counter({'Commissioning': 5})}}
2018-09-05 14:26:27,813 [salt.state       :1941][INFO    ][10124] Completed state [maas.machines_status] at time 14:26:27.813739 duration_in_ms=448.204
2018-09-05 14:26:27,817 [salt.minion      :1708][INFO    ][10124] Returning information for job: 20180905142625274600
2018-09-05 14:26:38,352 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command state.apply with jid 20180905142638356646
2018-09-05 14:26:38,366 [salt.minion      :1431][INFO    ][10145] Starting a new job with PID 10145
2018-09-05 14:26:42,105 [salt.state       :905 ][INFO    ][10145] Loading fresh modules for state activity
2018-09-05 14:26:42,209 [salt.state       :1770][INFO    ][10145] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:26:42.209741
2018-09-05 14:26:42,210 [salt.state       :1803][INFO    ][10145] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-09-05 14:26:42,213 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10145] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-09-05 14:26:43,438 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command saltutil.find_job with jid 20180905142643441027
2018-09-05 14:26:43,480 [salt.minion      :1431][INFO    ][10162] Starting a new job with PID 10162
2018-09-05 14:26:43,522 [salt.minion      :1708][INFO    ][10162] Returning information for job: 20180905142643441027
2018-09-05 14:26:43,724 [salt.state       :290 ][INFO    ][10145] {'pid': 10153, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-09-05 14:26:43,725 [salt.state       :1941][INFO    ][10145] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:26:43.725500 duration_in_ms=1515.758
2018-09-05 14:26:43,728 [salt.state       :1770][INFO    ][10145] Running state [maas.machines_status] at time 14:26:43.728848
2018-09-05 14:26:43,730 [salt.state       :1803][INFO    ][10145] Executing state module.run for [maas.machines_status]
2018-09-05 14:26:43,731 [salt.utils.decorators:613 ][WARNING ][10145] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:26:44,293 [salt.state       :290 ][INFO    ][10145] {'ret': {'machines': [{'status': 'Commissioning', 'hostname': u'cmp002', 'system_id': u'rpcrr6'}, {'status': 'Commissioning', 'hostname': u'cmp001', 'system_id': u'm4c8q7'}, {'status': 'Commissioning', 'hostname': u'kvm01', 'system_id': u'w4dqkr'}, {'status': 'Commissioning', 'hostname': u'kvm03', 'system_id': u'qttwky'}, {'status': 'Commissioning', 'hostname': u'kvm02', 'system_id': u'pt3hbk'}], 'summary': Counter({'Commissioning': 5})}}
2018-09-05 14:26:44,295 [salt.state       :1941][INFO    ][10145] Completed state [maas.machines_status] at time 14:26:44.294919 duration_in_ms=566.069
2018-09-05 14:26:44,299 [salt.minion      :1708][INFO    ][10145] Returning information for job: 20180905142638356646
2018-09-05 14:26:54,932 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command state.apply with jid 20180905142654936965
2018-09-05 14:26:54,958 [salt.minion      :1431][INFO    ][10412] Starting a new job with PID 10412
2018-09-05 14:26:55,557 [salt.state       :905 ][INFO    ][10412] Loading fresh modules for state activity
2018-09-05 14:26:55,611 [salt.state       :1770][INFO    ][10412] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:26:55.611645
2018-09-05 14:26:55,612 [salt.state       :1803][INFO    ][10412] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-09-05 14:26:55,613 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10412] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-09-05 14:26:57,367 [salt.state       :290 ][INFO    ][10412] {'pid': 10436, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-09-05 14:26:57,368 [salt.state       :1941][INFO    ][10412] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:26:57.368813 duration_in_ms=1757.166
2018-09-05 14:26:57,372 [salt.state       :1770][INFO    ][10412] Running state [maas.machines_status] at time 14:26:57.372156
2018-09-05 14:26:57,372 [salt.state       :1803][INFO    ][10412] Executing state module.run for [maas.machines_status]
2018-09-05 14:26:57,373 [salt.utils.decorators:613 ][WARNING ][10412] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:26:58,379 [salt.state       :290 ][INFO    ][10412] {'ret': {'machines': [{'status': 'Ready', 'hostname': u'cmp002', 'system_id': u'rpcrr6'}, {'status': 'Ready', 'hostname': u'cmp001', 'system_id': u'm4c8q7'}, {'status': 'Ready', 'hostname': u'kvm01', 'system_id': u'w4dqkr'}, {'status': 'Testing', 'hostname': u'kvm03', 'system_id': u'qttwky'}, {'status': 'Testing', 'hostname': u'kvm02', 'system_id': u'pt3hbk'}], 'summary': Counter({'Ready': 3, 'Testing': 2})}}
2018-09-05 14:26:58,379 [salt.state       :1941][INFO    ][10412] Completed state [maas.machines_status] at time 14:26:58.379839 duration_in_ms=1007.682
2018-09-05 14:26:58,385 [salt.minion      :1708][INFO    ][10412] Returning information for job: 20180905142654936965
2018-09-05 14:27:08,912 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command state.apply with jid 20180905142708916229
2018-09-05 14:27:08,942 [salt.minion      :1431][INFO    ][10599] Starting a new job with PID 10599
2018-09-05 14:27:12,678 [salt.state       :905 ][INFO    ][10599] Loading fresh modules for state activity
2018-09-05 14:27:12,789 [salt.state       :1770][INFO    ][10599] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:27:12.789175
2018-09-05 14:27:12,789 [salt.state       :1803][INFO    ][10599] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-09-05 14:27:12,792 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10599] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-09-05 14:27:14,009 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command saltutil.find_job with jid 20180905142714014567
2018-09-05 14:27:14,052 [salt.minion      :1431][INFO    ][10645] Starting a new job with PID 10645
2018-09-05 14:27:14,082 [salt.minion      :1708][INFO    ][10645] Returning information for job: 20180905142714014567
2018-09-05 14:27:14,607 [salt.state       :290 ][INFO    ][10599] {'pid': 10619, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-09-05 14:27:14,609 [salt.state       :1941][INFO    ][10599] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:27:14.609047 duration_in_ms=1819.872
2018-09-05 14:27:14,612 [salt.state       :1770][INFO    ][10599] Running state [maas.machines_status] at time 14:27:14.612717
2018-09-05 14:27:14,613 [salt.state       :1803][INFO    ][10599] Executing state module.run for [maas.machines_status]
2018-09-05 14:27:14,615 [salt.utils.decorators:613 ][WARNING ][10599] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:27:15,415 [salt.state       :290 ][INFO    ][10599] {'ret': {'machines': [{'status': 'Ready', 'hostname': u'cmp002', 'system_id': u'rpcrr6'}, {'status': 'Ready', 'hostname': u'cmp001', 'system_id': u'm4c8q7'}, {'status': 'Ready', 'hostname': u'kvm01', 'system_id': u'w4dqkr'}, {'status': 'Ready', 'hostname': u'kvm03', 'system_id': u'qttwky'}, {'status': 'Ready', 'hostname': u'kvm02', 'system_id': u'pt3hbk'}], 'summary': Counter({'Ready': 5})}}
2018-09-05 14:27:15,416 [salt.state       :1941][INFO    ][10599] Completed state [maas.machines_status] at time 14:27:15.416874 duration_in_ms=804.157
2018-09-05 14:27:15,421 [salt.minion      :1708][INFO    ][10599] Returning information for job: 20180905142708916229
2018-09-05 14:27:16,068 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command state.apply with jid 20180905142716072248
2018-09-05 14:27:16,097 [salt.minion      :1431][INFO    ][10654] Starting a new job with PID 10654
2018-09-05 14:27:16,675 [salt.state       :905 ][INFO    ][10654] Loading fresh modules for state activity
2018-09-05 14:27:16,776 [salt.state       :1770][INFO    ][10654] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:27:16.776322
2018-09-05 14:27:16,776 [salt.state       :1803][INFO    ][10654] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-09-05 14:27:16,779 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10654] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-09-05 14:27:18,198 [salt.state       :290 ][INFO    ][10654] {'pid': 10664, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-09-05 14:27:18,199 [salt.state       :1941][INFO    ][10654] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:27:18.198959 duration_in_ms=1422.636
2018-09-05 14:27:18,201 [salt.state       :1770][INFO    ][10654] Running state [maas.machines_status] at time 14:27:18.201896
2018-09-05 14:27:18,202 [salt.state       :1803][INFO    ][10654] Executing state module.run for [maas.machines_status]
2018-09-05 14:27:18,203 [salt.utils.decorators:613 ][WARNING ][10654] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:27:18,678 [salt.state       :290 ][INFO    ][10654] {'ret': {'machines': [{'status': 'Ready', 'hostname': u'cmp002', 'system_id': u'rpcrr6'}, {'status': 'Ready', 'hostname': u'cmp001', 'system_id': u'm4c8q7'}, {'status': 'Ready', 'hostname': u'kvm01', 'system_id': u'w4dqkr'}, {'status': 'Ready', 'hostname': u'kvm03', 'system_id': u'qttwky'}, {'status': 'Ready', 'hostname': u'kvm02', 'system_id': u'pt3hbk'}], 'summary': Counter({'Ready': 5})}}
2018-09-05 14:27:18,679 [salt.state       :1941][INFO    ][10654] Completed state [maas.machines_status] at time 14:27:18.679288 duration_in_ms=477.392
2018-09-05 14:27:18,683 [salt.minion      :1708][INFO    ][10654] Returning information for job: 20180905142716072248
2018-09-05 14:27:19,222 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command state.apply with jid 20180905142719228428
2018-09-05 14:27:19,244 [salt.minion      :1431][INFO    ][10687] Starting a new job with PID 10687
2018-09-05 14:27:19,872 [salt.state       :905 ][INFO    ][10687] Loading fresh modules for state activity
2018-09-05 14:27:19,932 [salt.fileclient  :1215][INFO    ][10687] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2018-09-05 14:27:20,028 [salt.state       :1770][INFO    ][10687] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:27:20.028685
2018-09-05 14:27:20,029 [salt.state       :1803][INFO    ][10687] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-09-05 14:27:20,031 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10687] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-09-05 14:27:21,451 [salt.state       :290 ][INFO    ][10687] {'pid': 10694, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-09-05 14:27:21,453 [salt.state       :1941][INFO    ][10687] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:27:21.452974 duration_in_ms=1424.288
2018-09-05 14:27:21,456 [salt.state       :1770][INFO    ][10687] Running state [maas_machines_storage_cmp002_lvm] at time 14:27:21.456262
2018-09-05 14:27:21,457 [salt.state       :1803][INFO    ][10687] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2018-09-05 14:27:23,131 [salt.loaded.ext.module.maasng:553 ][INFO    ][10687] rpcrr6
2018-09-05 14:27:23,131 [salt.loaded.ext.module.maasng:569 ][INFO    ][10687] sda
2018-09-05 14:27:23,976 [salt.loaded.ext.module.maasng:304 ][INFO    ][10687] rpcrr6
2018-09-05 14:27:24,156 [salt.loaded.ext.module.maasng:310 ][INFO    ][10687] [{u'size': 2397998940160, u'resource_uri': u'/MAAS/api/2.0/nodes/rpcrr6/blockdevices/3/', u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x618e7283727559801cc788a9059af6a9', u'system_id': u'rpcrr6', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id': 3, u'available_size': 0, u'serial': u'618e7283727559801cc788a9059af6a9', u'block_size': 4096, u'type': u'physical', u'model': u'UCSB-MRAID12G', u'partitions': [{u'uuid': u'0be0c045-dd6a-4b39-9066-62d762132b6c', u'resource_uri': u'/MAAS/api/2.0/nodes/rpcrr6/blockdevices/3/partition/4', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'rpcrr6', u'filesystem': {u'mount_options': None, u'uuid': u'c3ed5e6c-7d31-4028-8bbe-e5384614b417', u'label': None, u'mount_point': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 4, u'device_id': 3}]}, {u'size': 2397988454400, u'resource_uri': u'/MAAS/api/2.0/nodes/rpcrr6/blockdevices/10/', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 2397988454400, u'filesystem': {u'mount_options': None, u'uuid': u'06747200-7b13-4b09-8be9-91f3a53f43ea', u'label': u'root', u'mount_point': u'/', u'fstype': u'ext4'}, u'uuid': u'9fd46490-67f2-4b31-9f24-3dc4e750ca65', u'used_for': u'ext4 formatted filesystem mounted at /', u'id_path': None, u'system_id': u'rpcrr6', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id': 10, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'model': None, u'partitions': []}]
2018-09-05 14:27:24,160 [salt.loaded.ext.module.maasng:575 ][INFO    ][10687] vgroot
2018-09-05 14:27:24,160 [salt.loaded.ext.module.maasng:578 ][INFO    ][10687] lvroot
2018-09-05 14:27:24,160 [salt.loaded.ext.module.maasng:582 ][INFO    ][10687] 107374182400
2018-09-05 14:27:24,304 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command saltutil.find_job with jid 20180905142724303295
2018-09-05 14:27:24,335 [salt.minion      :1431][INFO    ][10714] Starting a new job with PID 10714
2018-09-05 14:27:24,376 [salt.minion      :1708][INFO    ][10714] Returning information for job: 20180905142724303295
2018-09-05 14:27:24,760 [salt.loaded.ext.module.maasng:588 ][INFO    ][10687] {u'domain': {u'resource_record_count': 2, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'swap_size': None, u'disable_ipv4': False, u'cpu_count': 16, u'power_type': u'ipmi', u'hwe_kernel': u'', u'boot_interface': {u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ykqp8f', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ykqp8f', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 33}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6a', u'enabled': True, u'id': 6, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ykqp8f', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'rpcrr6', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rpcrr6/interfaces/6/'}, u'fqdn': u'cmp002.maas', u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 2397998940160, u'resource_uri': u'/MAAS/api/2.0/nodes/rpcrr6/blockdevices/3/', u'name': u'sda', u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'used_size': 2397998940160, u'filesystem': None, u'uuid': None, u'id': 3, u'system_id': u'rpcrr6', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e7283727559801cc788a9059af6a9', u'available_size': 0, u'serial': u'618e7283727559801cc788a9059af6a9', u'block_size': 4096, u'type': u'physical', u'model': u'UCSB-MRAID12G', u'partitions': [{u'uuid': u'981fad18-b1bb-411f-8cac-69b0c14a7cb8', u'resource_uri': u'/MAAS/api/2.0/nodes/rpcrr6/blockdevices/3/partition/8', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'rpcrr6', u'filesystem': {u'mount_options': None, u'uuid': u'bb6ee951-ad44-4f30-af6a-232a599e3dae', u'label': None, u'mount_point': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 8, u'device_id': 3}]}, u'blockdevice_set': [{u'size': 2397998940160, u'resource_uri': u'/MAAS/api/2.0/nodes/rpcrr6/blockdevices/3/', u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x618e7283727559801cc788a9059af6a9', u'system_id': u'rpcrr6', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id': 3, u'available_size': 0, u'serial': u'618e7283727559801cc788a9059af6a9', u'block_size': 4096, u'type': u'physical', u'model': u'UCSB-MRAID12G', u'partitions': [{u'uuid': u'981fad18-b1bb-411f-8cac-69b0c14a7cb8', u'resource_uri': u'/MAAS/api/2.0/nodes/rpcrr6/blockdevices/3/partition/8', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'rpcrr6', u'filesystem': {u'mount_options': None, u'uuid': u'bb6ee951-ad44-4f30-af6a-232a599e3dae', u'label': None, u'mount_point': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 8, u'device_id': 3}]}, {u'size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/rpcrr6/blockdevices/14/', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'filesystem': {u'mount_options': None, u'uuid': u'0932cb3e-e711-486c-aa57-a82c84320d3e', u'label': u'root', u'mount_point': u'/', u'fstype': u'ext4'}, u'uuid': u'317a442c-5aab-4ff2-b631-237ad90987dd', u'used_for': u'ext4 formatted filesystem mounted at /', u'id_path': None, u'system_id': u'rpcrr6', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id': 14, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'model': None, u'partitions': []}], u'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'id': 1}, u'current_commissioning_result_id': 2, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'commissioning_status': 2, u'testing_status': 2, u'system_id': u'rpcrr6', u'raids': [], u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, 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'resource_uri': u'/MAAS/api/2.0/nodes/rpcrr6/blockdevices/14/', u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'filesystem': {u'mount_options': None, u'uuid': u'0932cb3e-e711-486c-aa57-a82c84320d3e', u'label': u'root', u'mount_point': u'/', u'fstype': u'ext4'}, u'uuid': u'317a442c-5aab-4ff2-b631-237ad90987dd', u'id': 14, u'system_id': u'rpcrr6', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'model': None, u'partitions': []}], u'architecture': u'amd64/generic', u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'power_state': u'off', u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'rpcrr6', u'id': 8}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'commissioning_status_name': u'Passed', u'current_testing_result_id': 3, u'cpu_test_status': -1, u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 2397998940160, u'resource_uri': u'/MAAS/api/2.0/nodes/rpcrr6/blockdevices/3/', u'name': u'sda', u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'used_size': 2397998940160, u'filesystem': None, u'uuid': None, u'id': 3, u'system_id': u'rpcrr6', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e7283727559801cc788a9059af6a9', u'available_size': 0, u'serial': u'618e7283727559801cc788a9059af6a9', u'block_size': 4096, u'type': u'physical', u'model': u'UCSB-MRAID12G', u'partitions': [{u'uuid': u'981fad18-b1bb-411f-8cac-69b0c14a7cb8', u'resource_uri': u'/MAAS/api/2.0/nodes/rpcrr6/blockdevices/3/partition/8', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'rpcrr6', u'filesystem': {u'mount_options': None, u'uuid': u'bb6ee951-ad44-4f30-af6a-232a599e3dae', u'label': None, u'mount_point': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 8, u'device_id': 3}]}], u'netboot': True, u'osystem': u'', u'status_action': u'', u'memory_test_status_name': u'Unknown', u'ip_addresses': [u'192.168.11.38'], u'min_hwe_kernel': u'hwe-16.04', u'interface_set': [{u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ykqp8f', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ykqp8f', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 33}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6a', u'enabled': True, u'id': 6, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ykqp8f', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'rpcrr6', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rpcrr6/interfaces/6/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 34}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6c', u'enabled': True, u'id': 18, u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'rpcrr6', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rpcrr6/interfaces/18/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 36}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6b', u'enabled': True, u'id': 19, u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'rpcrr6', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rpcrr6/interfaces/19/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 39}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6d', u'enabled': True, u'id': 20, u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'rpcrr6', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rpcrr6/interfaces/20/'}], u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/rpcrr6/'}
2018-09-05 14:27:24,762 [salt.state       :290 ][INFO    ][10687] {'new': {'storage_layout': 'lvm'}}
2018-09-05 14:27:24,762 [salt.state       :1941][INFO    ][10687] Completed state [maas_machines_storage_cmp002_lvm] at time 14:27:24.762605 duration_in_ms=3306.343
2018-09-05 14:27:24,763 [salt.state       :1770][INFO    ][10687] Running state [maas_machines_storage_cmp001_lvm] at time 14:27:24.762996
2018-09-05 14:27:24,763 [salt.state       :1803][INFO    ][10687] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2018-09-05 14:27:26,291 [salt.loaded.ext.module.maasng:553 ][INFO    ][10687] m4c8q7
2018-09-05 14:27:26,292 [salt.loaded.ext.module.maasng:569 ][INFO    ][10687] sda
2018-09-05 14:27:27,096 [salt.loaded.ext.module.maasng:304 ][INFO    ][10687] m4c8q7
2018-09-05 14:27:27,256 [salt.loaded.ext.module.maasng:310 ][INFO    ][10687] [{u'resource_uri': u'/MAAS/api/2.0/nodes/m4c8q7/blockdevices/4/', u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'2a13b5da-4fbf-4ced-b1d8-7ecd926de80f', u'resource_uri': u'/MAAS/api/2.0/nodes/m4c8q7/blockdevices/4/partition/5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'm4c8q7', u'filesystem': {u'label': None, u'uuid': u'9894d3c6-3b09-42c5-a1d5-d49dc7fb45fc', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 4, u'type': u'partition', u'id': 5, u'size': 2397992648704}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'm4c8q7', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'used_size': 2397998940160, u'id': 4, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}, {u'resource_uri': u'/MAAS/api/2.0/nodes/m4c8q7/blockdevices/11/', u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'fe5b1154-8c58-44bc-b257-803a9e2db2fe', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'm4c8q7', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'uuid': u'9ace9e11-3bc7-4db4-8faf-34da223777f7', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'used_size': 2397988454400, u'id': 11, u'serial': None, u'size': 2397988454400}]
2018-09-05 14:27:27,257 [salt.loaded.ext.module.maasng:575 ][INFO    ][10687] vgroot
2018-09-05 14:27:27,258 [salt.loaded.ext.module.maasng:578 ][INFO    ][10687] lvroot
2018-09-05 14:27:27,258 [salt.loaded.ext.module.maasng:582 ][INFO    ][10687] 107374182400
2018-09-05 14:27:28,098 [salt.loaded.ext.module.maasng:588 ][INFO    ][10687] {u'domain': {u'resource_record_count': 2, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'swap_size': None, u'memory_test_status': -1, u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'364145f5-ac68-42d8-9ced-1fd6894dfd00', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'm4c8q7', u'device_id': 4, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'16d7fba6-1fa9-42d4-8109-b344d888d93d', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/m4c8q7/blockdevices/4/partition/9', u'type': u'partition', u'id': 9, u'size': 2397992648704}], u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'm4c8q7', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'path': u'/dev/disk/by-dname/sda', u'resource_uri': u'/MAAS/api/2.0/nodes/m4c8q7/blockdevices/4/', u'used_size': 2397998940160, u'id': 4, u'serial': u'618e72837274f1901cc7889705aa1b02', u'uuid': None}, u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'testing_status': 2, u'address_ttl': None, u'system_id': u'm4c8q7', u'power_state': u'off', u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'm4c8q7', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'b7212bd5-d859-4ce5-a665-139dbf9297fe', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/m4c8q7/blockdevices/15/', u'used_size': 107374182400, u'id': 15, u'serial': None, u'uuid': u'5da4aabf-7f0c-45a9-8e48-5827dd684a35'}], u'architecture': u'amd64/generic', u'status': 4, u'storage_test_status': 2, u'cpu_count': 16, u'raids': [], u'owner_data': {}, u'ip_addresses': [u'192.168.11.39'], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'm4c8q7', u'id': 9}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'node_type_name': u'Machine', u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ykqp8f', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 35}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ykqp8f', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ykqp8f', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'mac_address': u'00:25:b5:a0:00:5a', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'm4c8q7', u'type': u'physical', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/m4c8q7/interfaces/7/'}, {u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 37}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5d', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'm4c8q7', u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/m4c8q7/interfaces/15/'}, {u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 38}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5c', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'm4c8q7', u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/m4c8q7/interfaces/16/'}, {u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 40}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5b', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'm4c8q7', u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/m4c8q7/interfaces/17/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'364145f5-ac68-42d8-9ced-1fd6894dfd00', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'm4c8q7', u'device_id': 4, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'16d7fba6-1fa9-42d4-8109-b344d888d93d', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/m4c8q7/blockdevices/4/partition/9', u'type': u'partition', u'id': 9, u'size': 2397992648704}], u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'm4c8q7', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'path': u'/dev/disk/by-dname/sda', u'resource_uri': u'/MAAS/api/2.0/nodes/m4c8q7/blockdevices/4/', u'used_size': 2397998940160, u'id': 4, u'serial': u'618e72837274f1901cc7889705aa1b02', u'uuid': None}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'node_type': 0, u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ykqp8f', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 35}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ykqp8f', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ykqp8f', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'mac_address': u'00:25:b5:a0:00:5a', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'm4c8q7', u'type': u'physical', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/m4c8q7/interfaces/7/'}, u'current_commissioning_result_id': 4, u'commissioning_status_name': u'Passed', u'blockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'364145f5-ac68-42d8-9ced-1fd6894dfd00', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'm4c8q7', u'device_id': 4, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'16d7fba6-1fa9-42d4-8109-b344d888d93d', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/m4c8q7/blockdevices/4/partition/9', u'type': u'partition', u'id': 9, u'size': 2397992648704}], u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'm4c8q7', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'path': u'/dev/disk/by-dname/sda', u'resource_uri': u'/MAAS/api/2.0/nodes/m4c8q7/blockdevices/4/', u'used_size': 2397998940160, u'id': 4, u'serial': u'618e72837274f1901cc7889705aa1b02', u'uuid': None}, {u'size': 107374182400, u'model': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'm4c8q7', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'b7212bd5-d859-4ce5-a665-139dbf9297fe', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'path': u'/dev/disk/by-dname/lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/m4c8q7/blockdevices/15/', u'used_size': 107374182400, u'id': 15, u'serial': None, u'uuid': u'5da4aabf-7f0c-45a9-8e48-5827dd684a35'}], u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/m4c8q7/'}
2018-09-05 14:27:28,100 [salt.state       :290 ][INFO    ][10687] {'new': {'storage_layout': 'lvm'}}
2018-09-05 14:27:28,101 [salt.state       :1941][INFO    ][10687] Completed state [maas_machines_storage_cmp001_lvm] at time 14:27:28.101087 duration_in_ms=3338.089
2018-09-05 14:27:28,108 [salt.minion      :1708][INFO    ][10687] Returning information for job: 20180905142719228428
2018-09-05 14:27:28,774 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command state.apply with jid 20180905142728777514
2018-09-05 14:27:28,806 [salt.minion      :1431][INFO    ][10726] Starting a new job with PID 10726
2018-09-05 14:27:29,426 [salt.state       :905 ][INFO    ][10726] Loading fresh modules for state activity
2018-09-05 14:27:29,490 [salt.fileclient  :1215][INFO    ][10726] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2018-09-05 14:27:29,534 [salt.state       :1770][INFO    ][10726] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:27:29.534028
2018-09-05 14:27:29,534 [salt.state       :1803][INFO    ][10726] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-09-05 14:27:29,536 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10726] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-09-05 14:27:30,967 [salt.state       :290 ][INFO    ][10726] {'pid': 10733, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-09-05 14:27:30,968 [salt.state       :1941][INFO    ][10726] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:27:30.968698 duration_in_ms=1434.669
2018-09-05 14:27:30,971 [salt.state       :1770][INFO    ][10726] Running state [maas.deploy_machines] at time 14:27:30.971383
2018-09-05 14:27:30,971 [salt.state       :1803][INFO    ][10726] Executing state module.run for [maas.deploy_machines]
2018-09-05 14:27:30,972 [salt.utils.decorators:613 ][WARNING ][10726] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:27:31,761 [salt.loaded.ext.module.maas:654 ][INFO    ][10726] deploymachines hwe_kernel=hwe-16.04 system_id=rpcrr6 distro_series=xenial
2018-09-05 14:27:33,857 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command saltutil.find_job with jid 20180905142733861660
2018-09-05 14:27:33,895 [salt.minion      :1431][INFO    ][10785] Starting a new job with PID 10785
2018-09-05 14:27:33,927 [salt.minion      :1708][INFO    ][10785] Returning information for job: 20180905142733861660
2018-09-05 14:27:35,015 [salt.loaded.ext.module.maas:654 ][INFO    ][10726] deploymachines hwe_kernel=hwe-16.04 system_id=m4c8q7 distro_series=xenial
2018-09-05 14:27:37,697 [salt.loaded.ext.module.maas:654 ][INFO    ][10726] deploymachines hwe_kernel=hwe-16.04 system_id=w4dqkr distro_series=xenial
2018-09-05 14:27:41,005 [salt.loaded.ext.module.maas:654 ][INFO    ][10726] deploymachines hwe_kernel=hwe-16.04 system_id=qttwky distro_series=xenial
2018-09-05 14:27:43,208 [salt.loaded.ext.module.maas:654 ][INFO    ][10726] deploymachines hwe_kernel=hwe-16.04 system_id=pt3hbk distro_series=xenial
2018-09-05 14:27:43,877 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command saltutil.find_job with jid 20180905142743883030
2018-09-05 14:27:43,907 [salt.minion      :1431][INFO    ][10956] Starting a new job with PID 10956
2018-09-05 14:27:43,953 [salt.minion      :1708][INFO    ][10956] Returning information for job: 20180905142743883030
2018-09-05 14:27:45,952 [salt.state       :290 ][INFO    ][10726] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2018-09-05 14:27:45,953 [salt.state       :1941][INFO    ][10726] Completed state [maas.deploy_machines] at time 14:27:45.953398 duration_in_ms=14982.013
2018-09-05 14:27:45,961 [salt.minion      :1708][INFO    ][10726] Returning information for job: 20180905142728777514
2018-09-05 14:27:56,558 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command pillar.get with jid 20180905142756565281
2018-09-05 14:27:56,582 [salt.minion      :1431][INFO    ][11058] Starting a new job with PID 11058
2018-09-05 14:27:56,595 [salt.minion      :1708][INFO    ][11058] Returning information for job: 20180905142756565281
2018-09-05 14:27:57,129 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command state.apply with jid 20180905142757135741
2018-09-05 14:27:57,154 [salt.minion      :1431][INFO    ][11064] Starting a new job with PID 11064
2018-09-05 14:28:00,954 [salt.state       :905 ][INFO    ][11064] Loading fresh modules for state activity
2018-09-05 14:28:01,061 [salt.state       :1770][INFO    ][11064] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:28:01.061175
2018-09-05 14:28:01,061 [salt.state       :1803][INFO    ][11064] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-09-05 14:28:01,064 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11064] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-09-05 14:28:02,171 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command saltutil.find_job with jid 20180905142802180488
2018-09-05 14:28:02,202 [salt.minion      :1431][INFO    ][11085] Starting a new job with PID 11085
2018-09-05 14:28:02,248 [salt.minion      :1708][INFO    ][11085] Returning information for job: 20180905142802180488
2018-09-05 14:28:02,640 [salt.state       :290 ][INFO    ][11064] {'pid': 11073, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-09-05 14:28:02,641 [salt.state       :1941][INFO    ][11064] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:28:02.641275 duration_in_ms=1580.099
2018-09-05 14:28:02,644 [salt.state       :1770][INFO    ][11064] Running state [maas.machines_status] at time 14:28:02.644678
2018-09-05 14:28:02,645 [salt.state       :1803][INFO    ][11064] Executing state module.run for [maas.machines_status]
2018-09-05 14:28:02,646 [salt.utils.decorators:613 ][WARNING ][11064] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:28:03,538 [salt.state       :290 ][INFO    ][11064] {'ret': {'machines': [{'status': 'Deploying', 'hostname': u'cmp002', 'system_id': u'rpcrr6'}, {'status': 'Deploying', 'hostname': u'cmp001', 'system_id': u'm4c8q7'}, {'status': 'Deploying', 'hostname': u'kvm01', 'system_id': u'w4dqkr'}, {'status': 'Deploying', 'hostname': u'kvm03', 'system_id': u'qttwky'}, {'status': 'Deploying', 'hostname': u'kvm02', 'system_id': u'pt3hbk'}], 'summary': Counter({'Deploying': 5})}}
2018-09-05 14:28:03,539 [salt.state       :1941][INFO    ][11064] Completed state [maas.machines_status] at time 14:28:03.539328 duration_in_ms=894.65
2018-09-05 14:28:03,544 [salt.minion      :1708][INFO    ][11064] Returning information for job: 20180905142757135741
2018-09-05 14:28:14,153 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command state.apply with jid 20180905142814158021
2018-09-05 14:28:14,186 [salt.minion      :1431][INFO    ][11117] Starting a new job with PID 11117
2018-09-05 14:28:14,855 [salt.state       :905 ][INFO    ][11117] Loading fresh modules for state activity
2018-09-05 14:28:14,956 [salt.state       :1770][INFO    ][11117] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:28:14.956196
2018-09-05 14:28:14,956 [salt.state       :1803][INFO    ][11117] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-09-05 14:28:14,959 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11117] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-09-05 14:28:16,508 [salt.state       :290 ][INFO    ][11117] {'pid': 11125, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-09-05 14:28:16,508 [salt.state       :1941][INFO    ][11117] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:28:16.508895 duration_in_ms=1552.7
2018-09-05 14:28:16,510 [salt.state       :1770][INFO    ][11117] Running state [maas.machines_status] at time 14:28:16.510339
2018-09-05 14:28:16,510 [salt.state       :1803][INFO    ][11117] Executing state module.run for [maas.machines_status]
2018-09-05 14:28:16,510 [salt.utils.decorators:613 ][WARNING ][11117] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:28:17,377 [salt.state       :290 ][INFO    ][11117] {'ret': {'machines': [{'status': 'Deploying', 'hostname': u'cmp002', 'system_id': u'rpcrr6'}, {'status': 'Deploying', 'hostname': u'cmp001', 'system_id': u'm4c8q7'}, {'status': 'Deploying', 'hostname': u'kvm01', 'system_id': u'w4dqkr'}, {'status': 'Deploying', 'hostname': u'kvm03', 'system_id': u'qttwky'}, {'status': 'Deploying', 'hostname': u'kvm02', 'system_id': u'pt3hbk'}], 'summary': Counter({'Deploying': 5})}}
2018-09-05 14:28:17,378 [salt.state       :1941][INFO    ][11117] Completed state [maas.machines_status] at time 14:28:17.378389 duration_in_ms=868.049
2018-09-05 14:28:17,384 [salt.minion      :1708][INFO    ][11117] Returning information for job: 20180905142814158021
2018-09-05 14:28:27,979 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command state.apply with jid 20180905142827991768
2018-09-05 14:28:28,000 [salt.minion      :1431][INFO    ][11143] Starting a new job with PID 11143
2018-09-05 14:28:31,725 [salt.state       :905 ][INFO    ][11143] Loading fresh modules for state activity
2018-09-05 14:28:31,826 [salt.state       :1770][INFO    ][11143] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:28:31.826696
2018-09-05 14:28:31,827 [salt.state       :1803][INFO    ][11143] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-09-05 14:28:31,829 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11143] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-09-05 14:28:33,053 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command saltutil.find_job with jid 20180905142833063655
2018-09-05 14:28:33,085 [salt.minion      :1431][INFO    ][11159] Starting a new job with PID 11159
2018-09-05 14:28:33,115 [salt.minion      :1708][INFO    ][11159] Returning information for job: 20180905142833063655
2018-09-05 14:28:33,278 [salt.state       :290 ][INFO    ][11143] {'pid': 11150, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-09-05 14:28:33,279 [salt.state       :1941][INFO    ][11143] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:28:33.279293 duration_in_ms=1452.597
2018-09-05 14:28:33,282 [salt.state       :1770][INFO    ][11143] Running state [maas.machines_status] at time 14:28:33.282027
2018-09-05 14:28:33,282 [salt.state       :1803][INFO    ][11143] Executing state module.run for [maas.machines_status]
2018-09-05 14:28:33,283 [salt.utils.decorators:613 ][WARNING ][11143] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:28:33,893 [salt.state       :290 ][INFO    ][11143] {'ret': {'machines': [{'status': 'Deploying', 'hostname': u'cmp002', 'system_id': u'rpcrr6'}, {'status': 'Deploying', 'hostname': u'cmp001', 'system_id': u'm4c8q7'}, {'status': 'Deploying', 'hostname': u'kvm01', 'system_id': u'w4dqkr'}, {'status': 'Deploying', 'hostname': u'kvm03', 'system_id': u'qttwky'}, {'status': 'Deploying', 'hostname': u'kvm02', 'system_id': u'pt3hbk'}], 'summary': Counter({'Deploying': 5})}}
2018-09-05 14:28:33,893 [salt.state       :1941][INFO    ][11143] Completed state [maas.machines_status] at time 14:28:33.893352 duration_in_ms=611.324
2018-09-05 14:28:33,896 [salt.minion      :1708][INFO    ][11143] Returning information for job: 20180905142827991768
2018-09-05 14:28:44,497 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command state.apply with jid 20180905142844505381
2018-09-05 14:28:44,525 [salt.minion      :1431][INFO    ][11169] Starting a new job with PID 11169
2018-09-05 14:28:45,198 [salt.state       :905 ][INFO    ][11169] Loading fresh modules for state activity
2018-09-05 14:28:45,292 [salt.state       :1770][INFO    ][11169] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:28:45.292632
2018-09-05 14:28:45,293 [salt.state       :1803][INFO    ][11169] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-09-05 14:28:45,296 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11169] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-09-05 14:28:46,743 [salt.state       :290 ][INFO    ][11169] {'pid': 11176, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-09-05 14:28:46,744 [salt.state       :1941][INFO    ][11169] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:28:46.744700 duration_in_ms=1452.066
2018-09-05 14:28:46,748 [salt.state       :1770][INFO    ][11169] Running state [maas.machines_status] at time 14:28:46.748204
2018-09-05 14:28:46,748 [salt.state       :1803][INFO    ][11169] Executing state module.run for [maas.machines_status]
2018-09-05 14:28:46,749 [salt.utils.decorators:613 ][WARNING ][11169] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:28:47,639 [salt.state       :290 ][INFO    ][11169] {'ret': {'machines': [{'status': 'Deploying', 'hostname': u'cmp002', 'system_id': u'rpcrr6'}, {'status': 'Deploying', 'hostname': u'cmp001', 'system_id': u'm4c8q7'}, {'status': 'Deploying', 'hostname': u'kvm01', 'system_id': u'w4dqkr'}, {'status': 'Deploying', 'hostname': u'kvm03', 'system_id': u'qttwky'}, {'status': 'Deploying', 'hostname': u'kvm02', 'system_id': u'pt3hbk'}], 'summary': Counter({'Deploying': 5})}}
2018-09-05 14:28:47,640 [salt.state       :1941][INFO    ][11169] Completed state [maas.machines_status] at time 14:28:47.640193 duration_in_ms=891.989
2018-09-05 14:28:47,644 [salt.minion      :1708][INFO    ][11169] Returning information for job: 20180905142844505381
2018-09-05 14:28:58,274 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command state.apply with jid 20180905142858282651
2018-09-05 14:28:58,303 [salt.minion      :1431][INFO    ][11213] Starting a new job with PID 11213
2018-09-05 14:29:02,039 [salt.state       :905 ][INFO    ][11213] Loading fresh modules for state activity
2018-09-05 14:29:02,138 [salt.state       :1770][INFO    ][11213] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:29:02.138067
2018-09-05 14:29:02,138 [salt.state       :1803][INFO    ][11213] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-09-05 14:29:02,142 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11213] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-09-05 14:29:03,369 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command saltutil.find_job with jid 20180905142903379114
2018-09-05 14:29:03,390 [salt.minion      :1431][INFO    ][11232] Starting a new job with PID 11232
2018-09-05 14:29:03,424 [salt.minion      :1708][INFO    ][11232] Returning information for job: 20180905142903379114
2018-09-05 14:29:03,601 [salt.state       :290 ][INFO    ][11213] {'pid': 11221, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-09-05 14:29:03,602 [salt.state       :1941][INFO    ][11213] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:29:03.602585 duration_in_ms=1464.516
2018-09-05 14:29:03,606 [salt.state       :1770][INFO    ][11213] Running state [maas.machines_status] at time 14:29:03.606080
2018-09-05 14:29:03,607 [salt.state       :1803][INFO    ][11213] Executing state module.run for [maas.machines_status]
2018-09-05 14:29:03,608 [salt.utils.decorators:613 ][WARNING ][11213] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:29:04,418 [salt.state       :290 ][INFO    ][11213] {'ret': {'machines': [{'status': 'Deploying', 'hostname': u'cmp002', 'system_id': u'rpcrr6'}, {'status': 'Deploying', 'hostname': u'cmp001', 'system_id': u'm4c8q7'}, {'status': 'Deploying', 'hostname': u'kvm01', 'system_id': u'w4dqkr'}, {'status': 'Deploying', 'hostname': u'kvm03', 'system_id': u'qttwky'}, {'status': 'Deploying', 'hostname': u'kvm02', 'system_id': u'pt3hbk'}], 'summary': Counter({'Deploying': 5})}}
2018-09-05 14:29:04,419 [salt.state       :1941][INFO    ][11213] Completed state [maas.machines_status] at time 14:29:04.419742 duration_in_ms=813.661
2018-09-05 14:29:04,424 [salt.minion      :1708][INFO    ][11213] Returning information for job: 20180905142858282651
2018-09-05 14:29:14,960 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command state.apply with jid 20180905142914971433
2018-09-05 14:29:14,989 [salt.minion      :1431][INFO    ][11292] Starting a new job with PID 11292
2018-09-05 14:29:15,415 [salt.state       :905 ][INFO    ][11292] Loading fresh modules for state activity
2018-09-05 14:29:15,464 [salt.state       :1770][INFO    ][11292] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:29:15.464718
2018-09-05 14:29:15,465 [salt.state       :1803][INFO    ][11292] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-09-05 14:29:15,466 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11292] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-09-05 14:29:17,168 [salt.state       :290 ][INFO    ][11292] {'pid': 11299, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-09-05 14:29:17,169 [salt.state       :1941][INFO    ][11292] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:29:17.169279 duration_in_ms=1704.56
2018-09-05 14:29:17,172 [salt.state       :1770][INFO    ][11292] Running state [maas.machines_status] at time 14:29:17.172013
2018-09-05 14:29:17,172 [salt.state       :1803][INFO    ][11292] Executing state module.run for [maas.machines_status]
2018-09-05 14:29:17,172 [salt.utils.decorators:613 ][WARNING ][11292] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:29:17,651 [salt.state       :290 ][INFO    ][11292] {'ret': {'machines': [{'status': 'Deploying', 'hostname': u'cmp002', 'system_id': u'rpcrr6'}, {'status': 'Deploying', 'hostname': u'cmp001', 'system_id': u'm4c8q7'}, {'status': 'Deploying', 'hostname': u'kvm01', 'system_id': u'w4dqkr'}, {'status': 'Deploying', 'hostname': u'kvm03', 'system_id': u'qttwky'}, {'status': 'Deploying', 'hostname': u'kvm02', 'system_id': u'pt3hbk'}], 'summary': Counter({'Deploying': 5})}}
2018-09-05 14:29:17,652 [salt.state       :1941][INFO    ][11292] Completed state [maas.machines_status] at time 14:29:17.652117 duration_in_ms=480.104
2018-09-05 14:29:17,654 [salt.minion      :1708][INFO    ][11292] Returning information for job: 20180905142914971433
2018-09-05 14:29:28,232 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command state.apply with jid 20180905142928246447
2018-09-05 14:29:28,282 [salt.minion      :1431][INFO    ][11370] Starting a new job with PID 11370
2018-09-05 14:29:31,496 [salt.state       :905 ][INFO    ][11370] Loading fresh modules for state activity
2018-09-05 14:29:31,546 [salt.state       :1770][INFO    ][11370] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:29:31.546635
2018-09-05 14:29:31,547 [salt.state       :1803][INFO    ][11370] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-09-05 14:29:31,549 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11370] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-09-05 14:29:33,197 [salt.state       :290 ][INFO    ][11370] {'pid': 11378, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-09-05 14:29:33,197 [salt.state       :1941][INFO    ][11370] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:29:33.197784 duration_in_ms=1651.148
2018-09-05 14:29:33,199 [salt.state       :1770][INFO    ][11370] Running state [maas.machines_status] at time 14:29:33.199793
2018-09-05 14:29:33,200 [salt.state       :1803][INFO    ][11370] Executing state module.run for [maas.machines_status]
2018-09-05 14:29:33,200 [salt.utils.decorators:613 ][WARNING ][11370] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:29:33,273 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command saltutil.find_job with jid 20180905142933287574
2018-09-05 14:29:33,287 [salt.minion      :1431][INFO    ][11388] Starting a new job with PID 11388
2018-09-05 14:29:33,304 [salt.minion      :1708][INFO    ][11388] Returning information for job: 20180905142933287574
2018-09-05 14:29:33,697 [salt.state       :290 ][INFO    ][11370] {'ret': {'machines': [{'status': 'Deploying', 'hostname': u'cmp002', 'system_id': u'rpcrr6'}, {'status': 'Deploying', 'hostname': u'cmp001', 'system_id': u'm4c8q7'}, {'status': 'Deploying', 'hostname': u'kvm01', 'system_id': u'w4dqkr'}, {'status': 'Deploying', 'hostname': u'kvm03', 'system_id': u'qttwky'}, {'status': 'Deploying', 'hostname': u'kvm02', 'system_id': u'pt3hbk'}], 'summary': Counter({'Deploying': 5})}}
2018-09-05 14:29:33,698 [salt.state       :1941][INFO    ][11370] Completed state [maas.machines_status] at time 14:29:33.698368 duration_in_ms=498.574
2018-09-05 14:29:33,706 [salt.minion      :1708][INFO    ][11370] Returning information for job: 20180905142928246447
2018-09-05 14:29:44,190 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command state.apply with jid 20180905142944197588
2018-09-05 14:29:44,235 [salt.minion      :1431][INFO    ][11452] Starting a new job with PID 11452
2018-09-05 14:29:44,868 [salt.state       :905 ][INFO    ][11452] Loading fresh modules for state activity
2018-09-05 14:29:44,964 [salt.state       :1770][INFO    ][11452] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:29:44.964345
2018-09-05 14:29:44,964 [salt.state       :1803][INFO    ][11452] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-09-05 14:29:44,967 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11452] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-09-05 14:29:46,589 [salt.state       :290 ][INFO    ][11452] {'pid': 11459, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-09-05 14:29:46,590 [salt.state       :1941][INFO    ][11452] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:29:46.590665 duration_in_ms=1626.318
2018-09-05 14:29:46,593 [salt.state       :1770][INFO    ][11452] Running state [maas.machines_status] at time 14:29:46.593527
2018-09-05 14:29:46,594 [salt.state       :1803][INFO    ][11452] Executing state module.run for [maas.machines_status]
2018-09-05 14:29:46,594 [salt.utils.decorators:613 ][WARNING ][11452] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:29:47,584 [salt.state       :290 ][INFO    ][11452] {'ret': {'machines': [{'status': 'Deploying', 'hostname': u'cmp002', 'system_id': u'rpcrr6'}, {'status': 'Deploying', 'hostname': u'cmp001', 'system_id': u'm4c8q7'}, {'status': 'Deploying', 'hostname': u'kvm01', 'system_id': u'w4dqkr'}, {'status': 'Deploying', 'hostname': u'kvm03', 'system_id': u'qttwky'}, {'status': 'Deploying', 'hostname': u'kvm02', 'system_id': u'pt3hbk'}], 'summary': Counter({'Deploying': 5})}}
2018-09-05 14:29:47,585 [salt.state       :1941][INFO    ][11452] Completed state [maas.machines_status] at time 14:29:47.585542 duration_in_ms=992.016
2018-09-05 14:29:47,591 [salt.minion      :1708][INFO    ][11452] Returning information for job: 20180905142944197588
2018-09-05 14:29:58,120 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command state.apply with jid 20180905142958129910
2018-09-05 14:29:58,152 [salt.minion      :1431][INFO    ][11803] Starting a new job with PID 11803
2018-09-05 14:30:02,002 [salt.state       :905 ][INFO    ][11803] Loading fresh modules for state activity
2018-09-05 14:30:02,098 [salt.state       :1770][INFO    ][11803] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:30:02.097932
2018-09-05 14:30:02,098 [salt.state       :1803][INFO    ][11803] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-09-05 14:30:02,101 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11803] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-09-05 14:30:03,226 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command saltutil.find_job with jid 20180905143003232920
2018-09-05 14:30:03,287 [salt.minion      :1431][INFO    ][11826] Starting a new job with PID 11826
2018-09-05 14:30:03,337 [salt.minion      :1708][INFO    ][11826] Returning information for job: 20180905143003232920
2018-09-05 14:30:03,713 [salt.state       :290 ][INFO    ][11803] {'pid': 11812, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-09-05 14:30:03,715 [salt.state       :1941][INFO    ][11803] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:30:03.715104 duration_in_ms=1617.171
2018-09-05 14:30:03,717 [salt.state       :1770][INFO    ][11803] Running state [maas.machines_status] at time 14:30:03.717928
2018-09-05 14:30:03,718 [salt.state       :1803][INFO    ][11803] Executing state module.run for [maas.machines_status]
2018-09-05 14:30:03,718 [salt.utils.decorators:613 ][WARNING ][11803] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:30:04,801 [salt.state       :290 ][INFO    ][11803] {'ret': {'machines': [{'status': 'Deploying', 'hostname': u'cmp002', 'system_id': u'rpcrr6'}, {'status': 'Deploying', 'hostname': u'cmp001', 'system_id': u'm4c8q7'}, {'status': 'Deploying', 'hostname': u'kvm01', 'system_id': u'w4dqkr'}, {'status': 'Deploying', 'hostname': u'kvm03', 'system_id': u'qttwky'}, {'status': 'Deploying', 'hostname': u'kvm02', 'system_id': u'pt3hbk'}], 'summary': Counter({'Deploying': 5})}}
2018-09-05 14:30:04,802 [salt.state       :1941][INFO    ][11803] Completed state [maas.machines_status] at time 14:30:04.802596 duration_in_ms=1084.667
2018-09-05 14:30:04,809 [salt.minion      :1708][INFO    ][11803] Returning information for job: 20180905142958129910
2018-09-05 14:30:15,349 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command state.apply with jid 20180905143015364061
2018-09-05 14:30:15,383 [salt.minion      :1431][INFO    ][11956] Starting a new job with PID 11956
2018-09-05 14:30:16,019 [salt.state       :905 ][INFO    ][11956] Loading fresh modules for state activity
2018-09-05 14:30:16,120 [salt.state       :1770][INFO    ][11956] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:30:16.120348
2018-09-05 14:30:16,120 [salt.state       :1803][INFO    ][11956] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-09-05 14:30:16,124 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11956] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-09-05 14:30:17,799 [salt.state       :290 ][INFO    ][11956] {'pid': 11963, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-09-05 14:30:17,800 [salt.state       :1941][INFO    ][11956] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:30:17.800662 duration_in_ms=1680.313
2018-09-05 14:30:17,803 [salt.state       :1770][INFO    ][11956] Running state [maas.machines_status] at time 14:30:17.803607
2018-09-05 14:30:17,804 [salt.state       :1803][INFO    ][11956] Executing state module.run for [maas.machines_status]
2018-09-05 14:30:17,804 [salt.utils.decorators:613 ][WARNING ][11956] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:30:18,918 [salt.state       :290 ][INFO    ][11956] {'ret': {'machines': [{'status': 'Deploying', 'hostname': u'cmp002', 'system_id': u'rpcrr6'}, {'status': 'Deploying', 'hostname': u'cmp001', 'system_id': u'm4c8q7'}, {'status': 'Deploying', 'hostname': u'kvm01', 'system_id': u'w4dqkr'}, {'status': 'Deploying', 'hostname': u'kvm03', 'system_id': u'qttwky'}, {'status': 'Deploying', 'hostname': u'kvm02', 'system_id': u'pt3hbk'}], 'summary': Counter({'Deploying': 5})}}
2018-09-05 14:30:18,918 [salt.state       :1941][INFO    ][11956] Completed state [maas.machines_status] at time 14:30:18.918806 duration_in_ms=1115.198
2018-09-05 14:30:18,923 [salt.minion      :1708][INFO    ][11956] Returning information for job: 20180905143015364061
2018-09-05 14:30:29,555 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command state.apply with jid 20180905143029567709
2018-09-05 14:30:29,582 [salt.minion      :1431][INFO    ][11992] Starting a new job with PID 11992
2018-09-05 14:30:33,154 [salt.state       :905 ][INFO    ][11992] Loading fresh modules for state activity
2018-09-05 14:30:33,250 [salt.state       :1770][INFO    ][11992] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:30:33.250787
2018-09-05 14:30:33,251 [salt.state       :1803][INFO    ][11992] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-09-05 14:30:33,253 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11992] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-09-05 14:30:34,592 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command saltutil.find_job with jid 20180905143034608662
2018-09-05 14:30:34,622 [salt.minion      :1431][INFO    ][12009] Starting a new job with PID 12009
2018-09-05 14:30:34,659 [salt.minion      :1708][INFO    ][12009] Returning information for job: 20180905143034608662
2018-09-05 14:30:34,828 [salt.state       :290 ][INFO    ][11992] {'pid': 12000, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-09-05 14:30:34,829 [salt.state       :1941][INFO    ][11992] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:30:34.829204 duration_in_ms=1578.417
2018-09-05 14:30:34,830 [salt.state       :1770][INFO    ][11992] Running state [maas.machines_status] at time 14:30:34.830709
2018-09-05 14:30:34,830 [salt.state       :1803][INFO    ][11992] Executing state module.run for [maas.machines_status]
2018-09-05 14:30:34,831 [salt.utils.decorators:613 ][WARNING ][11992] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:30:35,431 [salt.state       :290 ][INFO    ][11992] {'ret': {'machines': [{'status': 'Deploying', 'hostname': u'cmp002', 'system_id': u'rpcrr6'}, {'status': 'Deploying', 'hostname': u'cmp001', 'system_id': u'm4c8q7'}, {'status': 'Deploying', 'hostname': u'kvm01', 'system_id': u'w4dqkr'}, {'status': 'Deploying', 'hostname': u'kvm03', 'system_id': u'qttwky'}, {'status': 'Deploying', 'hostname': u'kvm02', 'system_id': u'pt3hbk'}], 'summary': Counter({'Deploying': 5})}}
2018-09-05 14:30:35,431 [salt.state       :1941][INFO    ][11992] Completed state [maas.machines_status] at time 14:30:35.431597 duration_in_ms=600.886
2018-09-05 14:30:35,435 [salt.minion      :1708][INFO    ][11992] Returning information for job: 20180905143029567709
2018-09-05 14:30:46,000 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command state.apply with jid 20180905143046008891
2018-09-05 14:30:46,062 [salt.minion      :1431][INFO    ][12019] Starting a new job with PID 12019
2018-09-05 14:30:46,689 [salt.state       :905 ][INFO    ][12019] Loading fresh modules for state activity
2018-09-05 14:30:46,785 [salt.state       :1770][INFO    ][12019] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:30:46.785608
2018-09-05 14:30:46,786 [salt.state       :1803][INFO    ][12019] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-09-05 14:30:46,788 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12019] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-09-05 14:30:48,373 [salt.state       :290 ][INFO    ][12019] {'pid': 12039, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-09-05 14:30:48,374 [salt.state       :1941][INFO    ][12019] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:30:48.374369 duration_in_ms=1588.762
2018-09-05 14:30:48,375 [salt.state       :1770][INFO    ][12019] Running state [maas.machines_status] at time 14:30:48.375767
2018-09-05 14:30:48,376 [salt.state       :1803][INFO    ][12019] Executing state module.run for [maas.machines_status]
2018-09-05 14:30:48,376 [salt.utils.decorators:613 ][WARNING ][12019] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:30:49,207 [salt.state       :290 ][INFO    ][12019] {'ret': {'machines': [{'status': 'Deploying', 'hostname': u'cmp002', 'system_id': u'rpcrr6'}, {'status': 'Deploying', 'hostname': u'cmp001', 'system_id': u'm4c8q7'}, {'status': 'Deploying', 'hostname': u'kvm01', 'system_id': u'w4dqkr'}, {'status': 'Deploying', 'hostname': u'kvm03', 'system_id': u'qttwky'}, {'status': 'Deploying', 'hostname': u'kvm02', 'system_id': u'pt3hbk'}], 'summary': Counter({'Deploying': 5})}}
2018-09-05 14:30:49,208 [salt.state       :1941][INFO    ][12019] Completed state [maas.machines_status] at time 14:30:49.208544 duration_in_ms=832.776
2018-09-05 14:30:49,212 [salt.minion      :1708][INFO    ][12019] Returning information for job: 20180905143046008891
2018-09-05 14:30:59,746 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command state.apply with jid 20180905143059762142
2018-09-05 14:30:59,775 [salt.minion      :1431][INFO    ][12430] Starting a new job with PID 12430
2018-09-05 14:31:03,304 [salt.state       :905 ][INFO    ][12430] Loading fresh modules for state activity
2018-09-05 14:31:03,377 [salt.state       :1770][INFO    ][12430] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:31:03.377783
2018-09-05 14:31:03,378 [salt.state       :1803][INFO    ][12430] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-09-05 14:31:03,380 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12430] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-09-05 14:31:04,857 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command saltutil.find_job with jid 20180905143104869656
2018-09-05 14:31:04,939 [salt.minion      :1431][INFO    ][12448] Starting a new job with PID 12448
2018-09-05 14:31:04,963 [salt.minion      :1708][INFO    ][12448] Returning information for job: 20180905143104869656
2018-09-05 14:31:05,068 [salt.state       :290 ][INFO    ][12430] {'pid': 12439, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-09-05 14:31:05,070 [salt.state       :1941][INFO    ][12430] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:31:05.070159 duration_in_ms=1692.375
2018-09-05 14:31:05,073 [salt.state       :1770][INFO    ][12430] Running state [maas.machines_status] at time 14:31:05.073635
2018-09-05 14:31:05,074 [salt.state       :1803][INFO    ][12430] Executing state module.run for [maas.machines_status]
2018-09-05 14:31:05,074 [salt.utils.decorators:613 ][WARNING ][12430] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:31:05,916 [salt.state       :290 ][INFO    ][12430] {'ret': {'machines': [{'status': 'Deploying', 'hostname': u'cmp002', 'system_id': u'rpcrr6'}, {'status': 'Deploying', 'hostname': u'cmp001', 'system_id': u'm4c8q7'}, {'status': 'Deploying', 'hostname': u'kvm01', 'system_id': u'w4dqkr'}, {'status': 'Deploying', 'hostname': u'kvm03', 'system_id': u'qttwky'}, {'status': 'Deploying', 'hostname': u'kvm02', 'system_id': u'pt3hbk'}], 'summary': Counter({'Deploying': 5})}}
2018-09-05 14:31:05,916 [salt.state       :1941][INFO    ][12430] Completed state [maas.machines_status] at time 14:31:05.916552 duration_in_ms=842.916
2018-09-05 14:31:05,921 [salt.minion      :1708][INFO    ][12430] Returning information for job: 20180905143059762142
2018-09-05 14:31:16,570 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command state.apply with jid 20180905143116581236
2018-09-05 14:31:16,602 [salt.minion      :1431][INFO    ][12583] Starting a new job with PID 12583
2018-09-05 14:31:17,275 [salt.state       :905 ][INFO    ][12583] Loading fresh modules for state activity
2018-09-05 14:31:17,395 [salt.state       :1770][INFO    ][12583] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:31:17.395256
2018-09-05 14:31:17,396 [salt.state       :1803][INFO    ][12583] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-09-05 14:31:17,404 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12583] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-09-05 14:31:19,178 [salt.state       :290 ][INFO    ][12583] {'pid': 12590, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-09-05 14:31:19,180 [salt.state       :1941][INFO    ][12583] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:31:19.180760 duration_in_ms=1785.503
2018-09-05 14:31:19,184 [salt.state       :1770][INFO    ][12583] Running state [maas.machines_status] at time 14:31:19.184591
2018-09-05 14:31:19,185 [salt.state       :1803][INFO    ][12583] Executing state module.run for [maas.machines_status]
2018-09-05 14:31:19,187 [salt.utils.decorators:613 ][WARNING ][12583] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:31:20,062 [salt.state       :290 ][INFO    ][12583] {'ret': {'machines': [{'status': 'Deploying', 'hostname': u'cmp002', 'system_id': u'rpcrr6'}, {'status': 'Deploying', 'hostname': u'cmp001', 'system_id': u'm4c8q7'}, {'status': 'Deploying', 'hostname': u'kvm01', 'system_id': u'w4dqkr'}, {'status': 'Deploying', 'hostname': u'kvm03', 'system_id': u'qttwky'}, {'status': 'Deploying', 'hostname': u'kvm02', 'system_id': u'pt3hbk'}], 'summary': Counter({'Deploying': 5})}}
2018-09-05 14:31:20,063 [salt.state       :1941][INFO    ][12583] Completed state [maas.machines_status] at time 14:31:20.063309 duration_in_ms=878.718
2018-09-05 14:31:20,068 [salt.minion      :1708][INFO    ][12583] Returning information for job: 20180905143116581236
2018-09-05 14:31:30,609 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command state.apply with jid 20180905143130624116
2018-09-05 14:31:30,644 [salt.minion      :1431][INFO    ][12607] Starting a new job with PID 12607
2018-09-05 14:31:34,546 [salt.state       :905 ][INFO    ][12607] Loading fresh modules for state activity
2018-09-05 14:31:34,672 [salt.state       :1770][INFO    ][12607] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:31:34.672744
2018-09-05 14:31:34,673 [salt.state       :1803][INFO    ][12607] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-09-05 14:31:34,682 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12607] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-09-05 14:31:35,646 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command saltutil.find_job with jid 20180905143135659363
2018-09-05 14:31:35,675 [salt.minion      :1431][INFO    ][12623] Starting a new job with PID 12623
2018-09-05 14:31:35,702 [salt.minion      :1708][INFO    ][12623] Returning information for job: 20180905143135659363
2018-09-05 14:31:36,327 [salt.state       :290 ][INFO    ][12607] {'pid': 12615, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-09-05 14:31:36,328 [salt.state       :1941][INFO    ][12607] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:31:36.328701 duration_in_ms=1655.956
2018-09-05 14:31:36,331 [salt.state       :1770][INFO    ][12607] Running state [maas.machines_status] at time 14:31:36.331867
2018-09-05 14:31:36,332 [salt.state       :1803][INFO    ][12607] Executing state module.run for [maas.machines_status]
2018-09-05 14:31:36,333 [salt.utils.decorators:613 ][WARNING ][12607] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:31:37,272 [salt.state       :290 ][INFO    ][12607] {'ret': {'machines': [{'status': 'Deploying', 'hostname': u'cmp002', 'system_id': u'rpcrr6'}, {'status': 'Deploying', 'hostname': u'cmp001', 'system_id': u'm4c8q7'}, {'status': 'Deploying', 'hostname': u'kvm01', 'system_id': u'w4dqkr'}, {'status': 'Deploying', 'hostname': u'kvm03', 'system_id': u'qttwky'}, {'status': 'Deploying', 'hostname': u'kvm02', 'system_id': u'pt3hbk'}], 'summary': Counter({'Deploying': 5})}}
2018-09-05 14:31:37,273 [salt.state       :1941][INFO    ][12607] Completed state [maas.machines_status] at time 14:31:37.272962 duration_in_ms=941.094
2018-09-05 14:31:37,280 [salt.minion      :1708][INFO    ][12607] Returning information for job: 20180905143130624116
2018-09-05 14:31:47,940 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command state.apply with jid 20180905143147952429
2018-09-05 14:31:47,970 [salt.minion      :1431][INFO    ][12657] Starting a new job with PID 12657
2018-09-05 14:31:48,629 [salt.state       :905 ][INFO    ][12657] Loading fresh modules for state activity
2018-09-05 14:31:48,723 [salt.state       :1770][INFO    ][12657] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:31:48.723674
2018-09-05 14:31:48,724 [salt.state       :1803][INFO    ][12657] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-09-05 14:31:48,729 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12657] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-09-05 14:31:50,306 [salt.state       :290 ][INFO    ][12657] {'pid': 12664, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-09-05 14:31:50,307 [salt.state       :1941][INFO    ][12657] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:31:50.307804 duration_in_ms=1584.13
2018-09-05 14:31:50,310 [salt.state       :1770][INFO    ][12657] Running state [maas.machines_status] at time 14:31:50.310657
2018-09-05 14:31:50,311 [salt.state       :1803][INFO    ][12657] Executing state module.run for [maas.machines_status]
2018-09-05 14:31:50,312 [salt.utils.decorators:613 ][WARNING ][12657] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:31:51,107 [salt.state       :290 ][INFO    ][12657] {'ret': {'machines': [{'status': 'Deploying', 'hostname': u'cmp002', 'system_id': u'rpcrr6'}, {'status': 'Deploying', 'hostname': u'cmp001', 'system_id': u'm4c8q7'}, {'status': 'Deploying', 'hostname': u'kvm01', 'system_id': u'w4dqkr'}, {'status': 'Deploying', 'hostname': u'kvm03', 'system_id': u'qttwky'}, {'status': 'Deploying', 'hostname': u'kvm02', 'system_id': u'pt3hbk'}], 'summary': Counter({'Deploying': 5})}}
2018-09-05 14:31:51,107 [salt.state       :1941][INFO    ][12657] Completed state [maas.machines_status] at time 14:31:51.107627 duration_in_ms=796.969
2018-09-05 14:31:51,112 [salt.minion      :1708][INFO    ][12657] Returning information for job: 20180905143147952429
2018-09-05 14:32:01,595 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command state.apply with jid 20180905143201611752
2018-09-05 14:32:01,617 [salt.minion      :1431][INFO    ][12789] Starting a new job with PID 12789
2018-09-05 14:32:05,335 [salt.state       :905 ][INFO    ][12789] Loading fresh modules for state activity
2018-09-05 14:32:05,401 [salt.state       :1770][INFO    ][12789] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:32:05.400969
2018-09-05 14:32:05,401 [salt.state       :1803][INFO    ][12789] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-09-05 14:32:05,405 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12789] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-09-05 14:32:06,664 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command saltutil.find_job with jid 20180905143206683880
2018-09-05 14:32:06,695 [salt.minion      :1431][INFO    ][12809] Starting a new job with PID 12809
2018-09-05 14:32:06,729 [salt.minion      :1708][INFO    ][12809] Returning information for job: 20180905143206683880
2018-09-05 14:32:06,806 [salt.state       :290 ][INFO    ][12789] {'pid': 12800, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-09-05 14:32:06,808 [salt.state       :1941][INFO    ][12789] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:32:06.808397 duration_in_ms=1407.426
2018-09-05 14:32:06,812 [salt.state       :1770][INFO    ][12789] Running state [maas.machines_status] at time 14:32:06.811942
2018-09-05 14:32:06,812 [salt.state       :1803][INFO    ][12789] Executing state module.run for [maas.machines_status]
2018-09-05 14:32:06,814 [salt.utils.decorators:613 ][WARNING ][12789] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:32:07,590 [salt.state       :290 ][INFO    ][12789] {'ret': {'machines': [{'status': 'Deploying', 'hostname': u'cmp002', 'system_id': u'rpcrr6'}, {'status': 'Deploying', 'hostname': u'cmp001', 'system_id': u'm4c8q7'}, {'status': 'Deploying', 'hostname': u'kvm01', 'system_id': u'w4dqkr'}, {'status': 'Deploying', 'hostname': u'kvm03', 'system_id': u'qttwky'}, {'status': 'Deploying', 'hostname': u'kvm02', 'system_id': u'pt3hbk'}], 'summary': Counter({'Deploying': 5})}}
2018-09-05 14:32:07,592 [salt.state       :1941][INFO    ][12789] Completed state [maas.machines_status] at time 14:32:07.592571 duration_in_ms=780.628
2018-09-05 14:32:07,597 [salt.minion      :1708][INFO    ][12789] Returning information for job: 20180905143201611752
2018-09-05 14:32:18,141 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command state.apply with jid 20180905143218153203
2018-09-05 14:32:18,199 [salt.minion      :1431][INFO    ][12899] Starting a new job with PID 12899
2018-09-05 14:32:18,856 [salt.state       :905 ][INFO    ][12899] Loading fresh modules for state activity
2018-09-05 14:32:18,954 [salt.state       :1770][INFO    ][12899] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:32:18.953958
2018-09-05 14:32:18,954 [salt.state       :1803][INFO    ][12899] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-09-05 14:32:18,960 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12899] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-09-05 14:32:20,465 [salt.state       :290 ][INFO    ][12899] {'pid': 12906, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-09-05 14:32:20,466 [salt.state       :1941][INFO    ][12899] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:32:20.466799 duration_in_ms=1512.84
2018-09-05 14:32:20,469 [salt.state       :1770][INFO    ][12899] Running state [maas.machines_status] at time 14:32:20.469667
2018-09-05 14:32:20,470 [salt.state       :1803][INFO    ][12899] Executing state module.run for [maas.machines_status]
2018-09-05 14:32:20,471 [salt.utils.decorators:613 ][WARNING ][12899] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:32:21,333 [salt.state       :290 ][INFO    ][12899] {'ret': {'machines': [{'status': 'Deploying', 'hostname': u'cmp002', 'system_id': u'rpcrr6'}, {'status': 'Deploying', 'hostname': u'cmp001', 'system_id': u'm4c8q7'}, {'status': 'Deploying', 'hostname': u'kvm01', 'system_id': u'w4dqkr'}, {'status': 'Deploying', 'hostname': u'kvm03', 'system_id': u'qttwky'}, {'status': 'Deploying', 'hostname': u'kvm02', 'system_id': u'pt3hbk'}], 'summary': Counter({'Deploying': 5})}}
2018-09-05 14:32:21,335 [salt.state       :1941][INFO    ][12899] Completed state [maas.machines_status] at time 14:32:21.335334 duration_in_ms=865.666
2018-09-05 14:32:21,345 [salt.minion      :1708][INFO    ][12899] Returning information for job: 20180905143218153203
2018-09-05 14:32:31,874 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command state.apply with jid 20180905143231891559
2018-09-05 14:32:31,906 [salt.minion      :1431][INFO    ][12924] Starting a new job with PID 12924
2018-09-05 14:32:35,628 [salt.state       :905 ][INFO    ][12924] Loading fresh modules for state activity
2018-09-05 14:32:35,735 [salt.state       :1770][INFO    ][12924] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:32:35.735442
2018-09-05 14:32:35,736 [salt.state       :1803][INFO    ][12924] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-09-05 14:32:35,742 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12924] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-09-05 14:32:36,961 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command saltutil.find_job with jid 20180905143236977061
2018-09-05 14:32:36,985 [salt.minion      :1431][INFO    ][12941] Starting a new job with PID 12941
2018-09-05 14:32:37,001 [salt.minion      :1708][INFO    ][12941] Returning information for job: 20180905143236977061
2018-09-05 14:32:37,266 [salt.state       :290 ][INFO    ][12924] {'pid': 12931, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-09-05 14:32:37,267 [salt.state       :1941][INFO    ][12924] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:32:37.267406 duration_in_ms=1531.964
2018-09-05 14:32:37,269 [salt.state       :1770][INFO    ][12924] Running state [maas.machines_status] at time 14:32:37.269786
2018-09-05 14:32:37,270 [salt.state       :1803][INFO    ][12924] Executing state module.run for [maas.machines_status]
2018-09-05 14:32:37,271 [salt.utils.decorators:613 ][WARNING ][12924] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:32:37,945 [salt.state       :290 ][INFO    ][12924] {'ret': {'machines': [{'status': 'Deploying', 'hostname': u'cmp002', 'system_id': u'rpcrr6'}, {'status': 'Deploying', 'hostname': u'cmp001', 'system_id': u'm4c8q7'}, {'status': 'Deploying', 'hostname': u'kvm01', 'system_id': u'w4dqkr'}, {'status': 'Deploying', 'hostname': u'kvm03', 'system_id': u'qttwky'}, {'status': 'Deploying', 'hostname': u'kvm02', 'system_id': u'pt3hbk'}], 'summary': Counter({'Deploying': 5})}}
2018-09-05 14:32:37,946 [salt.state       :1941][INFO    ][12924] Completed state [maas.machines_status] at time 14:32:37.946448 duration_in_ms=676.659
2018-09-05 14:32:37,951 [salt.minion      :1708][INFO    ][12924] Returning information for job: 20180905143231891559
2018-09-05 14:32:48,495 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command state.apply with jid 20180905143248511340
2018-09-05 14:32:48,525 [salt.minion      :1431][INFO    ][12975] Starting a new job with PID 12975
2018-09-05 14:32:49,152 [salt.state       :905 ][INFO    ][12975] Loading fresh modules for state activity
2018-09-05 14:32:49,246 [salt.state       :1770][INFO    ][12975] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:32:49.246007
2018-09-05 14:32:49,246 [salt.state       :1803][INFO    ][12975] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-09-05 14:32:49,251 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12975] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-09-05 14:32:50,837 [salt.state       :290 ][INFO    ][12975] {'pid': 12985, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-09-05 14:32:50,838 [salt.state       :1941][INFO    ][12975] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:32:50.838417 duration_in_ms=1592.409
2018-09-05 14:32:50,841 [salt.state       :1770][INFO    ][12975] Running state [maas.machines_status] at time 14:32:50.840996
2018-09-05 14:32:50,841 [salt.state       :1803][INFO    ][12975] Executing state module.run for [maas.machines_status]
2018-09-05 14:32:50,842 [salt.utils.decorators:613 ][WARNING ][12975] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:32:51,677 [salt.state       :290 ][INFO    ][12975] {'ret': {'machines': [{'status': 'Deploying', 'hostname': u'cmp002', 'system_id': u'rpcrr6'}, {'status': 'Deploying', 'hostname': u'cmp001', 'system_id': u'm4c8q7'}, {'status': 'Deploying', 'hostname': u'kvm01', 'system_id': u'w4dqkr'}, {'status': 'Deploying', 'hostname': u'kvm03', 'system_id': u'qttwky'}, {'status': 'Deploying', 'hostname': u'kvm02', 'system_id': u'pt3hbk'}], 'summary': Counter({'Deploying': 5})}}
2018-09-05 14:32:51,677 [salt.state       :1941][INFO    ][12975] Completed state [maas.machines_status] at time 14:32:51.677735 duration_in_ms=836.737
2018-09-05 14:32:51,682 [salt.minion      :1708][INFO    ][12975] Returning information for job: 20180905143248511340
2018-09-05 14:33:02,339 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command state.apply with jid 20180905143302352052
2018-09-05 14:33:02,369 [salt.minion      :1431][INFO    ][13342] Starting a new job with PID 13342
2018-09-05 14:33:05,922 [salt.state       :905 ][INFO    ][13342] Loading fresh modules for state activity
2018-09-05 14:33:06,042 [salt.state       :1770][INFO    ][13342] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:33:06.042675
2018-09-05 14:33:06,043 [salt.state       :1803][INFO    ][13342] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-09-05 14:33:06,050 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13342] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-09-05 14:33:07,366 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command saltutil.find_job with jid 20180905143307382669
2018-09-05 14:33:07,401 [salt.minion      :1431][INFO    ][13383] Starting a new job with PID 13383
2018-09-05 14:33:07,439 [salt.minion      :1708][INFO    ][13383] Returning information for job: 20180905143307382669
2018-09-05 14:33:07,585 [salt.state       :290 ][INFO    ][13342] {'pid': 13350, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-09-05 14:33:07,587 [salt.state       :1941][INFO    ][13342] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:33:07.586919 duration_in_ms=1544.244
2018-09-05 14:33:07,589 [salt.state       :1770][INFO    ][13342] Running state [maas.machines_status] at time 14:33:07.589296
2018-09-05 14:33:07,589 [salt.state       :1803][INFO    ][13342] Executing state module.run for [maas.machines_status]
2018-09-05 14:33:07,590 [salt.utils.decorators:613 ][WARNING ][13342] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:33:08,314 [salt.state       :290 ][INFO    ][13342] {'ret': {'machines': [{'status': 'Deploying', 'hostname': u'cmp002', 'system_id': u'rpcrr6'}, {'status': 'Deploying', 'hostname': u'cmp001', 'system_id': u'm4c8q7'}, {'status': 'Deploying', 'hostname': u'kvm01', 'system_id': u'w4dqkr'}, {'status': 'Deploying', 'hostname': u'kvm03', 'system_id': u'qttwky'}, {'status': 'Deploying', 'hostname': u'kvm02', 'system_id': u'pt3hbk'}], 'summary': Counter({'Deploying': 5})}}
2018-09-05 14:33:08,315 [salt.state       :1941][INFO    ][13342] Completed state [maas.machines_status] at time 14:33:08.315938 duration_in_ms=726.643
2018-09-05 14:33:08,319 [salt.minion      :1708][INFO    ][13342] Returning information for job: 20180905143302352052
2018-09-05 14:33:18,851 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command state.apply with jid 20180905143318868007
2018-09-05 14:33:18,888 [salt.minion      :1431][INFO    ][13466] Starting a new job with PID 13466
2018-09-05 14:33:19,520 [salt.state       :905 ][INFO    ][13466] Loading fresh modules for state activity
2018-09-05 14:33:19,620 [salt.state       :1770][INFO    ][13466] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:33:19.620310
2018-09-05 14:33:19,620 [salt.state       :1803][INFO    ][13466] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-09-05 14:33:19,623 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13466] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-09-05 14:33:21,161 [salt.state       :290 ][INFO    ][13466] {'pid': 13473, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-09-05 14:33:21,162 [salt.state       :1941][INFO    ][13466] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:33:21.162790 duration_in_ms=1542.479
2018-09-05 14:33:21,167 [salt.state       :1770][INFO    ][13466] Running state [maas.machines_status] at time 14:33:21.167044
2018-09-05 14:33:21,167 [salt.state       :1803][INFO    ][13466] Executing state module.run for [maas.machines_status]
2018-09-05 14:33:21,168 [salt.utils.decorators:613 ][WARNING ][13466] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:33:22,023 [salt.state       :290 ][INFO    ][13466] {'ret': {'machines': [{'status': 'Deploying', 'hostname': u'cmp002', 'system_id': u'rpcrr6'}, {'status': 'Deploying', 'hostname': u'cmp001', 'system_id': u'm4c8q7'}, {'status': 'Deploying', 'hostname': u'kvm01', 'system_id': u'w4dqkr'}, {'status': 'Deploying', 'hostname': u'kvm03', 'system_id': u'qttwky'}, {'status': 'Deploying', 'hostname': u'kvm02', 'system_id': u'pt3hbk'}], 'summary': Counter({'Deploying': 5})}}
2018-09-05 14:33:22,024 [salt.state       :1941][INFO    ][13466] Completed state [maas.machines_status] at time 14:33:22.024422 duration_in_ms=857.377
2018-09-05 14:33:22,034 [salt.minion      :1708][INFO    ][13466] Returning information for job: 20180905143318868007
2018-09-05 14:33:32,617 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command state.apply with jid 20180905143332635455
2018-09-05 14:33:32,645 [salt.minion      :1431][INFO    ][13490] Starting a new job with PID 13490
2018-09-05 14:33:36,241 [salt.state       :905 ][INFO    ][13490] Loading fresh modules for state activity
2018-09-05 14:33:36,329 [salt.state       :1770][INFO    ][13490] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:33:36.329457
2018-09-05 14:33:36,329 [salt.state       :1803][INFO    ][13490] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-09-05 14:33:36,331 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13490] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-09-05 14:33:37,685 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command saltutil.find_job with jid 20180905143337703502
2018-09-05 14:33:37,719 [salt.minion      :1431][INFO    ][13507] Starting a new job with PID 13507
2018-09-05 14:33:37,756 [salt.minion      :1708][INFO    ][13507] Returning information for job: 20180905143337703502
2018-09-05 14:33:37,871 [salt.state       :290 ][INFO    ][13490] {'pid': 13497, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-09-05 14:33:37,872 [salt.state       :1941][INFO    ][13490] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:33:37.872232 duration_in_ms=1542.773
2018-09-05 14:33:37,875 [salt.state       :1770][INFO    ][13490] Running state [maas.machines_status] at time 14:33:37.875392
2018-09-05 14:33:37,876 [salt.state       :1803][INFO    ][13490] Executing state module.run for [maas.machines_status]
2018-09-05 14:33:37,876 [salt.utils.decorators:613 ][WARNING ][13490] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:33:38,631 [salt.state       :290 ][INFO    ][13490] {'ret': {'machines': [{'status': 'Deploying', 'hostname': u'cmp002', 'system_id': u'rpcrr6'}, {'status': 'Deploying', 'hostname': u'cmp001', 'system_id': u'm4c8q7'}, {'status': 'Deploying', 'hostname': u'kvm01', 'system_id': u'w4dqkr'}, {'status': 'Deploying', 'hostname': u'kvm03', 'system_id': u'qttwky'}, {'status': 'Deploying', 'hostname': u'kvm02', 'system_id': u'pt3hbk'}], 'summary': Counter({'Deploying': 5})}}
2018-09-05 14:33:38,632 [salt.state       :1941][INFO    ][13490] Completed state [maas.machines_status] at time 14:33:38.632053 duration_in_ms=756.66
2018-09-05 14:33:38,636 [salt.minion      :1708][INFO    ][13490] Returning information for job: 20180905143332635455
2018-09-05 14:33:49,238 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command state.apply with jid 20180905143349254831
2018-09-05 14:33:49,266 [salt.minion      :1431][INFO    ][13529] Starting a new job with PID 13529
2018-09-05 14:33:49,907 [salt.state       :905 ][INFO    ][13529] Loading fresh modules for state activity
2018-09-05 14:33:50,009 [salt.state       :1770][INFO    ][13529] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:33:50.009342
2018-09-05 14:33:50,009 [salt.state       :1803][INFO    ][13529] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-09-05 14:33:50,012 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13529] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-09-05 14:33:51,406 [salt.state       :290 ][INFO    ][13529] {'pid': 13536, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-09-05 14:33:51,407 [salt.state       :1941][INFO    ][13529] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:33:51.407596 duration_in_ms=1398.254
2018-09-05 14:33:51,410 [salt.state       :1770][INFO    ][13529] Running state [maas.machines_status] at time 14:33:51.410445
2018-09-05 14:33:51,411 [salt.state       :1803][INFO    ][13529] Executing state module.run for [maas.machines_status]
2018-09-05 14:33:51,411 [salt.utils.decorators:613 ][WARNING ][13529] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:33:52,086 [salt.state       :290 ][INFO    ][13529] {'ret': {'machines': [{'status': 'Deploying', 'hostname': u'cmp002', 'system_id': u'rpcrr6'}, {'status': 'Deploying', 'hostname': u'cmp001', 'system_id': u'm4c8q7'}, {'status': 'Deploying', 'hostname': u'kvm01', 'system_id': u'w4dqkr'}, {'status': 'Deploying', 'hostname': u'kvm03', 'system_id': u'qttwky'}, {'status': 'Deploying', 'hostname': u'kvm02', 'system_id': u'pt3hbk'}], 'summary': Counter({'Deploying': 5})}}
2018-09-05 14:33:52,086 [salt.state       :1941][INFO    ][13529] Completed state [maas.machines_status] at time 14:33:52.086576 duration_in_ms=676.131
2018-09-05 14:33:52,090 [salt.minion      :1708][INFO    ][13529] Returning information for job: 20180905143349254831
2018-09-05 14:34:02,620 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command state.apply with jid 20180905143402638811
2018-09-05 14:34:02,643 [salt.minion      :1431][INFO    ][13565] Starting a new job with PID 13565
2018-09-05 14:34:06,305 [salt.state       :905 ][INFO    ][13565] Loading fresh modules for state activity
2018-09-05 14:34:06,401 [salt.state       :1770][INFO    ][13565] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:34:06.401300
2018-09-05 14:34:06,401 [salt.state       :1803][INFO    ][13565] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-09-05 14:34:06,404 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13565] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-09-05 14:34:07,632 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command saltutil.find_job with jid 20180905143407657106
2018-09-05 14:34:07,656 [salt.minion      :1431][INFO    ][13597] Starting a new job with PID 13597
2018-09-05 14:34:07,692 [salt.minion      :1708][INFO    ][13597] Returning information for job: 20180905143407657106
2018-09-05 14:34:07,880 [salt.state       :290 ][INFO    ][13565] {'pid': 13572, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-09-05 14:34:07,881 [salt.state       :1941][INFO    ][13565] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:34:07.881858 duration_in_ms=1480.558
2018-09-05 14:34:07,885 [salt.state       :1770][INFO    ][13565] Running state [maas.machines_status] at time 14:34:07.885198
2018-09-05 14:34:07,886 [salt.state       :1803][INFO    ][13565] Executing state module.run for [maas.machines_status]
2018-09-05 14:34:07,887 [salt.utils.decorators:613 ][WARNING ][13565] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:34:08,728 [salt.state       :290 ][INFO    ][13565] {'ret': {'machines': [{'status': 'Deploying', 'hostname': u'cmp002', 'system_id': u'rpcrr6'}, {'status': 'Deploying', 'hostname': u'cmp001', 'system_id': u'm4c8q7'}, {'status': 'Deploying', 'hostname': u'kvm01', 'system_id': u'w4dqkr'}, {'status': 'Deploying', 'hostname': u'kvm03', 'system_id': u'qttwky'}, {'status': 'Deploying', 'hostname': u'kvm02', 'system_id': u'pt3hbk'}], 'summary': Counter({'Deploying': 5})}}
2018-09-05 14:34:08,729 [salt.state       :1941][INFO    ][13565] Completed state [maas.machines_status] at time 14:34:08.729286 duration_in_ms=844.087
2018-09-05 14:34:08,733 [salt.minion      :1708][INFO    ][13565] Returning information for job: 20180905143402638811
2018-09-05 14:34:19,277 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command state.apply with jid 20180905143419295709
2018-09-05 14:34:19,302 [salt.minion      :1431][INFO    ][13614] Starting a new job with PID 13614
2018-09-05 14:34:19,978 [salt.state       :905 ][INFO    ][13614] Loading fresh modules for state activity
2018-09-05 14:34:20,073 [salt.state       :1770][INFO    ][13614] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:34:20.073452
2018-09-05 14:34:20,074 [salt.state       :1803][INFO    ][13614] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-09-05 14:34:20,076 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13614] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-09-05 14:34:21,459 [salt.state       :290 ][INFO    ][13614] {'pid': 13621, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-09-05 14:34:21,461 [salt.state       :1941][INFO    ][13614] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:34:21.461397 duration_in_ms=1387.944
2018-09-05 14:34:21,464 [salt.state       :1770][INFO    ][13614] Running state [maas.machines_status] at time 14:34:21.464481
2018-09-05 14:34:21,465 [salt.state       :1803][INFO    ][13614] Executing state module.run for [maas.machines_status]
2018-09-05 14:34:21,466 [salt.utils.decorators:613 ][WARNING ][13614] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:34:22,374 [salt.state       :290 ][INFO    ][13614] {'ret': {'machines': [{'status': 'Deploying', 'hostname': u'cmp002', 'system_id': u'rpcrr6'}, {'status': 'Deploying', 'hostname': u'cmp001', 'system_id': u'm4c8q7'}, {'status': 'Deploying', 'hostname': u'kvm01', 'system_id': u'w4dqkr'}, {'status': 'Deploying', 'hostname': u'kvm03', 'system_id': u'qttwky'}, {'status': 'Deploying', 'hostname': u'kvm02', 'system_id': u'pt3hbk'}], 'summary': Counter({'Deploying': 5})}}
2018-09-05 14:34:22,375 [salt.state       :1941][INFO    ][13614] Completed state [maas.machines_status] at time 14:34:22.375338 duration_in_ms=910.856
2018-09-05 14:34:22,379 [salt.minion      :1708][INFO    ][13614] Returning information for job: 20180905143419295709
2018-09-05 14:34:32,912 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command state.apply with jid 20180905143432932287
2018-09-05 14:34:32,938 [salt.minion      :1431][INFO    ][13680] Starting a new job with PID 13680
2018-09-05 14:34:36,553 [salt.state       :905 ][INFO    ][13680] Loading fresh modules for state activity
2018-09-05 14:34:36,652 [salt.state       :1770][INFO    ][13680] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:34:36.652230
2018-09-05 14:34:36,653 [salt.state       :1803][INFO    ][13680] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-09-05 14:34:36,656 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13680] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-09-05 14:34:37,996 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command saltutil.find_job with jid 20180905143438014618
2018-09-05 14:34:38,016 [salt.minion      :1431][INFO    ][13760] Starting a new job with PID 13760
2018-09-05 14:34:38,041 [salt.state       :290 ][INFO    ][13680] {'pid': 13750, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-09-05 14:34:38,043 [salt.state       :1941][INFO    ][13680] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:34:38.043084 duration_in_ms=1390.853
2018-09-05 14:34:38,046 [salt.state       :1770][INFO    ][13680] Running state [maas.machines_status] at time 14:34:38.046179
2018-09-05 14:34:38,047 [salt.state       :1803][INFO    ][13680] Executing state module.run for [maas.machines_status]
2018-09-05 14:34:38,048 [salt.utils.decorators:613 ][WARNING ][13680] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:34:38,049 [salt.minion      :1708][INFO    ][13760] Returning information for job: 20180905143438014618
2018-09-05 14:34:38,579 [salt.state       :290 ][INFO    ][13680] {'ret': {'machines': [{'status': 'Deploying', 'hostname': u'cmp002', 'system_id': u'rpcrr6'}, {'status': 'Deploying', 'hostname': u'cmp001', 'system_id': u'm4c8q7'}, {'status': 'Deploying', 'hostname': u'kvm01', 'system_id': u'w4dqkr'}, {'status': 'Deploying', 'hostname': u'kvm03', 'system_id': u'qttwky'}, {'status': 'Deploying', 'hostname': u'kvm02', 'system_id': u'pt3hbk'}], 'summary': Counter({'Deploying': 5})}}
2018-09-05 14:34:38,580 [salt.state       :1941][INFO    ][13680] Completed state [maas.machines_status] at time 14:34:38.580593 duration_in_ms=534.413
2018-09-05 14:34:38,584 [salt.minion      :1708][INFO    ][13680] Returning information for job: 20180905143432932287
2018-09-05 14:34:49,168 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command state.apply with jid 20180905143449186837
2018-09-05 14:34:49,204 [salt.minion      :1431][INFO    ][13825] Starting a new job with PID 13825
2018-09-05 14:34:49,827 [salt.state       :905 ][INFO    ][13825] Loading fresh modules for state activity
2018-09-05 14:34:49,924 [salt.state       :1770][INFO    ][13825] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:34:49.924593
2018-09-05 14:34:49,925 [salt.state       :1803][INFO    ][13825] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-09-05 14:34:49,927 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13825] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-09-05 14:34:51,862 [salt.state       :290 ][INFO    ][13825] {'pid': 13837, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-09-05 14:34:51,865 [salt.state       :1941][INFO    ][13825] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:34:51.865248 duration_in_ms=1940.653
2018-09-05 14:34:51,873 [salt.state       :1770][INFO    ][13825] Running state [maas.machines_status] at time 14:34:51.873513
2018-09-05 14:34:51,874 [salt.state       :1803][INFO    ][13825] Executing state module.run for [maas.machines_status]
2018-09-05 14:34:51,875 [salt.utils.decorators:613 ][WARNING ][13825] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:34:52,747 [salt.state       :290 ][INFO    ][13825] {'ret': {'machines': [{'status': 'Deployed', 'hostname': u'cmp002', 'system_id': u'rpcrr6'}, {'status': 'Deployed', 'hostname': u'cmp001', 'system_id': u'm4c8q7'}, {'status': 'Deployed', 'hostname': u'kvm01', 'system_id': u'w4dqkr'}, {'status': 'Deployed', 'hostname': u'kvm03', 'system_id': u'qttwky'}, {'status': 'Deployed', 'hostname': u'kvm02', 'system_id': u'pt3hbk'}], 'summary': Counter({'Deployed': 5})}}
2018-09-05 14:34:52,748 [salt.state       :1941][INFO    ][13825] Completed state [maas.machines_status] at time 14:34:52.748583 duration_in_ms=875.069
2018-09-05 14:34:52,759 [salt.minion      :1708][INFO    ][13825] Returning information for job: 20180905143449186837
2018-09-05 14:34:53,192 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command state.apply with jid 20180905143453211485
2018-09-05 14:34:53,214 [salt.minion      :1431][INFO    ][13875] Starting a new job with PID 13875
2018-09-05 14:34:53,818 [salt.state       :905 ][INFO    ][13875] Loading fresh modules for state activity
2018-09-05 14:34:53,893 [salt.state       :1770][INFO    ][13875] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:34:53.893314
2018-09-05 14:34:53,893 [salt.state       :1803][INFO    ][13875] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-09-05 14:34:53,895 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13875] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-09-05 14:34:55,398 [salt.state       :290 ][INFO    ][13875] {'pid': 13914, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-09-05 14:34:55,398 [salt.state       :1941][INFO    ][13875] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:34:55.398672 duration_in_ms=1505.358
2018-09-05 14:34:55,400 [salt.state       :1770][INFO    ][13875] Running state [maas.machines_status] at time 14:34:55.400353
2018-09-05 14:34:55,400 [salt.state       :1803][INFO    ][13875] Executing state module.run for [maas.machines_status]
2018-09-05 14:34:55,401 [salt.utils.decorators:613 ][WARNING ][13875] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-09-05 14:34:55,892 [salt.state       :290 ][INFO    ][13875] {'ret': {'machines': [{'status': 'Deployed', 'hostname': u'cmp002', 'system_id': u'rpcrr6'}, {'status': 'Deployed', 'hostname': u'cmp001', 'system_id': u'm4c8q7'}, {'status': 'Deployed', 'hostname': u'kvm01', 'system_id': u'w4dqkr'}, {'status': 'Deployed', 'hostname': u'kvm03', 'system_id': u'qttwky'}, {'status': 'Deployed', 'hostname': u'kvm02', 'system_id': u'pt3hbk'}], 'summary': Counter({'Deployed': 5})}}
2018-09-05 14:34:55,892 [salt.state       :1941][INFO    ][13875] Completed state [maas.machines_status] at time 14:34:55.892864 duration_in_ms=492.51
2018-09-05 14:34:55,896 [salt.minion      :1708][INFO    ][13875] Returning information for job: 20180905143453211485
2018-09-05 14:34:56,404 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command pillar.item with jid 20180905143456425705
2018-09-05 14:34:56,425 [salt.minion      :1431][INFO    ][14100] Starting a new job with PID 14100
2018-09-05 14:34:56,436 [salt.minion      :1708][INFO    ][14100] Returning information for job: 20180905143456425705
2018-09-05 15:23:06,440 [salt.utils.schedule:1375][INFO    ][8660] Running scheduled job: __mine_interval
2018-09-05 16:13:54,954 [salt.minion      :1307][INFO    ][8660] User sudo_ubuntu Executing command cp.push_dir with jid 20180905161354928046
2018-09-05 16:13:54,987 [salt.minion      :1431][INFO    ][22104] Starting a new job with PID 22104
