2019-06-18 02:07:23,500 [salt.minion      :870 ][ERROR   ][362] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-06-18 02:08:13,551 [salt.minion      :870 ][ERROR   ][362] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-06-18 02:09:03,598 [salt.minion      :870 ][ERROR   ][362] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-06-18 02:09:53,642 [salt.minion      :870 ][ERROR   ][362] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-06-18 02:10:43,692 [salt.minion      :870 ][ERROR   ][362] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-06-18 02:12:52,732 [salt.utils.decorators:613 ][WARNING ][2833] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-18 02:12:53,336 [salt.utils.decorators:613 ][WARNING ][2833] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-18 02:12:55,654 [salt.loaded.int.states.file:2298][WARNING ][2972] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-06-18 02:13:01,217 [salt.state       :2022][WARNING ][3087] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-06-18 02:13:03,808 [salt.utils.decorators:613 ][WARNING ][3087] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-18 02:28:08,200 [salt.utils.decorators:613 ][WARNING ][3087] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-18 02:54:23,267 [salt.utils.decorators:613 ][WARNING ][3087] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-18 03:09:28,476 [salt.utils.decorators:613 ][WARNING ][3087] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-18 03:09:32,027 [salt.loaded.ext.module.maasng:1008][WARNING ][3087] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-06-18 03:09:32,027 [salt.loaded.ext.module.maasng:1011][WARNING ][3087] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-06-18 03:09:32,739 [salt.loaded.ext.module.maasng:1235][WARNING ][3087] Ignoring parameter vlan:0
2019-06-18 03:09:39,472 [salt.utils.decorators:613 ][WARNING ][7833] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-18 03:09:39,544 [salt.loaded.ext.module.maas:412 ][WARNING ][7833] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-18 03:09:44,394 [salt.loaded.ext.module.maas:412 ][WARNING ][7833] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-18 03:09:48,446 [salt.loaded.ext.module.maas:412 ][WARNING ][7833] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-18 03:09:52,285 [salt.loaded.ext.module.maas:412 ][WARNING ][7833] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-18 03:09:56,545 [salt.loaded.ext.module.maas:412 ][WARNING ][7833] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-18 03:10:03,968 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8841] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-06-18 03:10:04,001 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8841] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-06-18 03:10:04,025 [salt.utils.parsers:1051][WARNING ][362] Minion received a SIGTERM. Exiting.
2019-06-18 03:10:04,991 [salt.cli.daemons :293 ][INFO    ][8916] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-06-18 03:10:05,075 [salt.cli.daemons :82  ][INFO    ][8916] Starting up the Salt Minion
2019-06-18 03:10:05,076 [salt.utils.event :1017][INFO    ][8916] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-06-18 03:10:05,960 [salt.minion      :976 ][INFO    ][8916] Creating minion process manager
2019-06-18 03:10:07,326 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][8916] Executing command ['date', '+%z'] in directory '/root'
2019-06-18 03:10:07,345 [salt.utils.schedule:568 ][INFO    ][8916] Updating job settings for scheduled job: __mine_interval
2019-06-18 03:10:07,347 [salt.minion      :1108][INFO    ][8916] Added mine.update to scheduler
2019-06-18 03:10:07,350 [salt.minion      :1975][INFO    ][8916] Minion is starting as user 'root'
2019-06-18 03:10:07,364 [salt.minion      :2336][INFO    ][8916] Minion is ready to receive requests!
2019-06-18 03:10:31,344 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command state.apply with jid 20190618031031332096
2019-06-18 03:10:31,356 [salt.minion      :1432][INFO    ][9007] Starting a new job with PID 9007
2019-06-18 03:10:34,693 [salt.state       :915 ][INFO    ][9007] Loading fresh modules for state activity
2019-06-18 03:10:34,743 [salt.fileclient  :1219][INFO    ][9007] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-06-18 03:10:34,786 [salt.state       :1780][INFO    ][9007] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:10:34.786257
2019-06-18 03:10:34,786 [salt.state       :1813][INFO    ][9007] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-18 03:10:34,788 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9007] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-18 03:10:36,168 [salt.state       :300 ][INFO    ][9007] {'pid': 9015, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-18 03:10:36,169 [salt.state       :1951][INFO    ][9007] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:10:36.169345 duration_in_ms=1383.089
2019-06-18 03:10:36,170 [salt.state       :1780][INFO    ][9007] Running state [maas.wait_for_machine_status] at time 03:10:36.170395
2019-06-18 03:10:36,170 [salt.state       :1813][INFO    ][9007] Executing state module.run for [maas.wait_for_machine_status]
2019-06-18 03:10:36,170 [salt.utils.decorators:613 ][WARNING ][9007] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-18 03:10:37,161 [salt.loaded.ext.module.maas:1023][INFO    ][9007] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.01411009s left)
2019-06-18 03:10:46,414 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618031046401686
2019-06-18 03:10:46,436 [salt.minion      :1432][INFO    ][9027] Starting a new job with PID 9027
2019-06-18 03:10:46,459 [salt.minion      :1711][INFO    ][9027] Returning information for job: 20190618031046401686
2019-06-18 03:11:08,197 [salt.loaded.ext.module.maas:1023][INFO    ][9007] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1467.97775698s left)
2019-06-18 03:11:16,455 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618031116442455
2019-06-18 03:11:16,478 [salt.minion      :1432][INFO    ][9084] Starting a new job with PID 9084
2019-06-18 03:11:16,502 [salt.minion      :1711][INFO    ][9084] Returning information for job: 20190618031116442455
2019-06-18 03:11:39,270 [salt.loaded.ext.module.maas:1023][INFO    ][9007] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1436.9047451s left)
2019-06-18 03:11:46,570 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618031146557305
2019-06-18 03:11:46,592 [salt.minion      :1432][INFO    ][9243] Starting a new job with PID 9243
2019-06-18 03:11:46,614 [salt.minion      :1711][INFO    ][9243] Returning information for job: 20190618031146557305
2019-06-18 03:12:10,678 [salt.loaded.ext.module.maas:1023][INFO    ][9007] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1405.49671912s left)
2019-06-18 03:12:16,620 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618031216607579
2019-06-18 03:12:16,642 [salt.minion      :1432][INFO    ][9723] Starting a new job with PID 9723
2019-06-18 03:12:16,666 [salt.minion      :1711][INFO    ][9723] Returning information for job: 20190618031216607579
2019-06-18 03:12:42,382 [salt.loaded.ext.module.maas:1023][INFO    ][9007] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1373.79263306s left)
2019-06-18 03:12:46,680 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618031246667236
2019-06-18 03:12:46,702 [salt.minion      :1432][INFO    ][9875] Starting a new job with PID 9875
2019-06-18 03:12:46,726 [salt.minion      :1711][INFO    ][9875] Returning information for job: 20190618031246667236
2019-06-18 03:13:14,140 [salt.loaded.ext.module.maas:1023][INFO    ][9007] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1342.03519011s left)
2019-06-18 03:13:16,742 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618031316729726
2019-06-18 03:13:16,765 [salt.minion      :1432][INFO    ][10222] Starting a new job with PID 10222
2019-06-18 03:13:16,790 [salt.minion      :1711][INFO    ][10222] Returning information for job: 20190618031316729726
2019-06-18 03:13:46,856 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618031346845149
2019-06-18 03:13:46,878 [salt.minion      :1432][INFO    ][10508] Starting a new job with PID 10508
2019-06-18 03:13:46,900 [salt.minion      :1711][INFO    ][10508] Returning information for job: 20190618031346845149
2019-06-18 03:14:16,955 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618031416943130
2019-06-18 03:14:16,976 [salt.minion      :1432][INFO    ][10583] Starting a new job with PID 10583
2019-06-18 03:14:16,994 [salt.minion      :1711][INFO    ][10583] Returning information for job: 20190618031416943130
2019-06-18 03:14:47,055 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618031447043033
2019-06-18 03:14:47,075 [salt.minion      :1432][INFO    ][10633] Starting a new job with PID 10633
2019-06-18 03:14:47,098 [salt.minion      :1711][INFO    ][10633] Returning information for job: 20190618031447043033
2019-06-18 03:15:17,146 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618031517132987
2019-06-18 03:15:17,169 [salt.minion      :1432][INFO    ][10688] Starting a new job with PID 10688
2019-06-18 03:15:17,192 [salt.minion      :1711][INFO    ][10688] Returning information for job: 20190618031517132987
2019-06-18 03:15:47,253 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618031547241419
2019-06-18 03:15:47,274 [salt.minion      :1432][INFO    ][10727] Starting a new job with PID 10727
2019-06-18 03:15:47,296 [salt.minion      :1711][INFO    ][10727] Returning information for job: 20190618031547241419
2019-06-18 03:16:17,357 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618031617344160
2019-06-18 03:16:17,381 [salt.minion      :1432][INFO    ][10772] Starting a new job with PID 10772
2019-06-18 03:16:17,401 [salt.minion      :1711][INFO    ][10772] Returning information for job: 20190618031617344160
2019-06-18 03:16:47,462 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618031647449430
2019-06-18 03:16:47,484 [salt.minion      :1432][INFO    ][10805] Starting a new job with PID 10805
2019-06-18 03:16:47,506 [salt.minion      :1711][INFO    ][10805] Returning information for job: 20190618031647449430
2019-06-18 03:17:17,566 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618031717557318
2019-06-18 03:17:17,586 [salt.minion      :1432][INFO    ][10864] Starting a new job with PID 10864
2019-06-18 03:17:17,605 [salt.minion      :1711][INFO    ][10864] Returning information for job: 20190618031717557318
2019-06-18 03:17:47,671 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618031747658927
2019-06-18 03:17:47,691 [salt.minion      :1432][INFO    ][10889] Starting a new job with PID 10889
2019-06-18 03:17:47,715 [salt.minion      :1711][INFO    ][10889] Returning information for job: 20190618031747658927
2019-06-18 03:18:17,791 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618031817778524
2019-06-18 03:18:17,812 [salt.minion      :1432][INFO    ][10924] Starting a new job with PID 10924
2019-06-18 03:18:17,835 [salt.minion      :1711][INFO    ][10924] Returning information for job: 20190618031817778524
2019-06-18 03:18:47,922 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618031847910281
2019-06-18 03:18:47,946 [salt.minion      :1432][INFO    ][10952] Starting a new job with PID 10952
2019-06-18 03:18:47,967 [salt.minion      :1711][INFO    ][10952] Returning information for job: 20190618031847910281
2019-06-18 03:19:15,495 [salt.state       :302 ][ERROR   ][9007] Module function maas.wait_for_machine_status threw an exception. Exception: HTTP Error 401: OK
2019-06-18 03:19:15,495 [salt.state       :1951][INFO    ][9007] Completed state [maas.wait_for_machine_status] at time 03:19:15.495800 duration_in_ms=519325.404
2019-06-18 03:19:15,499 [salt.minion      :1711][INFO    ][9007] Returning information for job: 20190618031031332096
2019-06-18 03:19:26,183 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command pillar.get with jid 20190618031926176021
2019-06-18 03:19:26,201 [salt.minion      :1432][INFO    ][11253] Starting a new job with PID 11253
2019-06-18 03:19:26,207 [salt.minion      :1711][INFO    ][11253] Returning information for job: 20190618031926176021
2019-06-18 03:19:26,695 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command service.status with jid 20190618031926688746
2019-06-18 03:19:26,714 [salt.minion      :1432][INFO    ][11265] Starting a new job with PID 11265
2019-06-18 03:19:27,083 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][11265] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-06-18 03:19:27,116 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][11265] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-06-18 03:19:27,129 [salt.minion      :1711][INFO    ][11265] Returning information for job: 20190618031926688746
2019-06-18 03:19:27,599 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command state.apply with jid 20190618031927590960
2019-06-18 03:19:27,618 [salt.minion      :1432][INFO    ][11293] Starting a new job with PID 11293
2019-06-18 03:19:31,379 [salt.state       :915 ][INFO    ][11293] Loading fresh modules for state activity
2019-06-18 03:19:31,784 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11293] Executing command 'salt-minion --version' in directory '/root'
2019-06-18 03:19:32,166 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11293] Executing command 'salt-minion --version' in directory '/root'
2019-06-18 03:19:33,023 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11293] Executing command 'salt-minion --version' in directory '/root'
2019-06-18 03:19:33,391 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11293] Executing command 'salt-minion --version' in directory '/root'
2019-06-18 03:19:34,776 [salt.state       :1780][INFO    ][11293] Running state [salt-minion] at time 03:19:34.776728
2019-06-18 03:19:34,777 [salt.state       :1813][INFO    ][11293] Executing state pkg.installed for [salt-minion]
2019-06-18 03:19:34,777 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11293] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-18 03:19:34,869 [salt.state       :300 ][INFO    ][11293] All specified packages are already installed
2019-06-18 03:19:34,870 [salt.state       :1951][INFO    ][11293] Completed state [salt-minion] at time 03:19:34.870109 duration_in_ms=93.382
2019-06-18 03:19:34,870 [salt.state       :1780][INFO    ][11293] Running state [salt_minion_dependency_packages] at time 03:19:34.870452
2019-06-18 03:19:34,870 [salt.state       :1813][INFO    ][11293] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-06-18 03:19:34,877 [salt.state       :300 ][INFO    ][11293] All specified packages are already installed
2019-06-18 03:19:34,877 [salt.state       :1951][INFO    ][11293] Completed state [salt_minion_dependency_packages] at time 03:19:34.877855 duration_in_ms=7.403
2019-06-18 03:19:34,881 [salt.state       :1780][INFO    ][11293] Running state [/etc/salt/minion.d/minion.conf] at time 03:19:34.881037
2019-06-18 03:19:34,881 [salt.state       :1813][INFO    ][11293] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-06-18 03:19:35,085 [salt.state       :300 ][INFO    ][11293] File /etc/salt/minion.d/minion.conf is in the correct state
2019-06-18 03:19:35,085 [salt.state       :1951][INFO    ][11293] Completed state [/etc/salt/minion.d/minion.conf] at time 03:19:35.085390 duration_in_ms=204.353
2019-06-18 03:19:35,085 [salt.state       :1780][INFO    ][11293] Running state [python-netaddr] at time 03:19:35.085593
2019-06-18 03:19:35,085 [salt.state       :1813][INFO    ][11293] Executing state pkg.installed for [python-netaddr]
2019-06-18 03:19:35,090 [salt.state       :300 ][INFO    ][11293] All specified packages are already installed
2019-06-18 03:19:35,090 [salt.state       :1951][INFO    ][11293] Completed state [python-netaddr] at time 03:19:35.090346 duration_in_ms=4.752
2019-06-18 03:19:35,092 [salt.state       :1780][INFO    ][11293] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 03:19:35.092453
2019-06-18 03:19:35,092 [salt.state       :1813][INFO    ][11293] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-06-18 03:19:35,101 [salt.state       :300 ][INFO    ][11293] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-06-18 03:19:35,101 [salt.state       :1951][INFO    ][11293] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 03:19:35.101161 duration_in_ms=8.709
2019-06-18 03:19:35,101 [salt.state       :1780][INFO    ][11293] Running state [salt-minion] at time 03:19:35.101819
2019-06-18 03:19:35,102 [salt.state       :1813][INFO    ][11293] Executing state service.running for [salt-minion]
2019-06-18 03:19:35,102 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11293] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-06-18 03:19:35,140 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11293] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-06-18 03:19:35,159 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11293] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-06-18 03:19:35,180 [salt.state       :300 ][INFO    ][11293] The service salt-minion is already running
2019-06-18 03:19:35,180 [salt.state       :1951][INFO    ][11293] Completed state [salt-minion] at time 03:19:35.180570 duration_in_ms=78.75
2019-06-18 03:19:35,182 [salt.state       :1780][INFO    ][11293] Running state [/etc/salt/grains.d] at time 03:19:35.182733
2019-06-18 03:19:35,183 [salt.state       :1813][INFO    ][11293] Executing state file.directory for [/etc/salt/grains.d]
2019-06-18 03:19:35,184 [salt.state       :300 ][INFO    ][11293] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-06-18 03:19:35,184 [salt.state       :1951][INFO    ][11293] Completed state [/etc/salt/grains.d] at time 03:19:35.184695 duration_in_ms=1.962
2019-06-18 03:19:35,185 [salt.state       :1780][INFO    ][11293] Running state [/etc/salt/grains] at time 03:19:35.185562
2019-06-18 03:19:35,185 [salt.state       :1813][INFO    ][11293] Executing state file.managed for [/etc/salt/grains]
2019-06-18 03:19:35,186 [salt.state       :300 ][INFO    ][11293] File /etc/salt/grains exists with proper permissions. No changes made.
2019-06-18 03:19:35,186 [salt.state       :1951][INFO    ][11293] Completed state [/etc/salt/grains] at time 03:19:35.186919 duration_in_ms=1.357
2019-06-18 03:19:35,187 [salt.state       :1780][INFO    ][11293] Running state [/etc/salt/grains.d/placeholder] at time 03:19:35.187515
2019-06-18 03:19:35,187 [salt.state       :1813][INFO    ][11293] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-06-18 03:19:35,188 [salt.state       :300 ][INFO    ][11293] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-06-18 03:19:35,188 [salt.state       :1951][INFO    ][11293] Completed state [/etc/salt/grains.d/placeholder] at time 03:19:35.188806 duration_in_ms=1.291
2019-06-18 03:19:35,189 [salt.state       :1780][INFO    ][11293] Running state [/etc/salt/grains.d/sphinx] at time 03:19:35.189398
2019-06-18 03:19:35,189 [salt.state       :1813][INFO    ][11293] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-06-18 03:19:35,205 [salt.state       :300 ][INFO    ][11293] File /etc/salt/grains.d/sphinx is in the correct state
2019-06-18 03:19:35,205 [salt.state       :1951][INFO    ][11293] Completed state [/etc/salt/grains.d/sphinx] at time 03:19:35.205666 duration_in_ms=16.276
2019-06-18 03:19:35,210 [salt.state       :1780][INFO    ][11293] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 03:19:35.210026
2019-06-18 03:19:35,210 [salt.state       :1813][INFO    ][11293] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-06-18 03:19:35,211 [salt.state       :300 ][INFO    ][11293] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-06-18 03:19:35,211 [salt.state       :1951][INFO    ][11293] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 03:19:35.211638 duration_in_ms=1.612
2019-06-18 03:19:35,212 [salt.state       :1780][INFO    ][11293] Running state [/etc/salt/grains.d/dns_records] at time 03:19:35.212630
2019-06-18 03:19:35,213 [salt.state       :1813][INFO    ][11293] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-06-18 03:19:35,228 [salt.state       :300 ][INFO    ][11293] File /etc/salt/grains.d/dns_records is in the correct state
2019-06-18 03:19:35,229 [salt.state       :1951][INFO    ][11293] Completed state [/etc/salt/grains.d/dns_records] at time 03:19:35.229051 duration_in_ms=16.423
2019-06-18 03:19:35,230 [salt.state       :1780][INFO    ][11293] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 03:19:35.230436
2019-06-18 03:19:35,230 [salt.state       :1813][INFO    ][11293] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-06-18 03:19:35,231 [salt.state       :300 ][INFO    ][11293] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-06-18 03:19:35,231 [salt.state       :1951][INFO    ][11293] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 03:19:35.231458 duration_in_ms=1.022
2019-06-18 03:19:35,232 [salt.state       :1780][INFO    ][11293] Running state [/etc/salt/grains.d/salt] at time 03:19:35.232024
2019-06-18 03:19:35,232 [salt.state       :1813][INFO    ][11293] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-06-18 03:19:35,247 [salt.state       :300 ][INFO    ][11293] File /etc/salt/grains.d/salt is in the correct state
2019-06-18 03:19:35,247 [salt.state       :1951][INFO    ][11293] Completed state [/etc/salt/grains.d/salt] at time 03:19:35.247389 duration_in_ms=15.365
2019-06-18 03:19:35,248 [salt.state       :1780][INFO    ][11293] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 03:19:35.248757
2019-06-18 03:19:35,249 [salt.state       :1813][INFO    ][11293] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-06-18 03:19:35,249 [salt.state       :300 ][INFO    ][11293] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-06-18 03:19:35,250 [salt.state       :1951][INFO    ][11293] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 03:19:35.249965 duration_in_ms=1.209
2019-06-18 03:19:35,252 [salt.state       :1780][INFO    ][11293] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 03:19:35.252691
2019-06-18 03:19:35,253 [salt.state       :1813][INFO    ][11293] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-06-18 03:19:35,253 [salt.state       :300 ][INFO    ][11293] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-06-18 03:19:35,253 [salt.state       :1951][INFO    ][11293] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 03:19:35.253889 duration_in_ms=1.198
2019-06-18 03:19:35,254 [salt.state       :1780][INFO    ][11293] Running state [mine.update] at time 03:19:35.254872
2019-06-18 03:19:35,255 [salt.state       :1813][INFO    ][11293] Executing state module.wait for [mine.update]
2019-06-18 03:19:35,255 [salt.state       :300 ][INFO    ][11293] No changes made for mine.update
2019-06-18 03:19:35,256 [salt.state       :1951][INFO    ][11293] Completed state [mine.update] at time 03:19:35.255957 duration_in_ms=1.084
2019-06-18 03:19:35,256 [salt.state       :1780][INFO    ][11293] Running state [ca-certificates] at time 03:19:35.256292
2019-06-18 03:19:35,256 [salt.state       :1813][INFO    ][11293] Executing state pkg.installed for [ca-certificates]
2019-06-18 03:19:35,267 [salt.state       :300 ][INFO    ][11293] All specified packages are already installed
2019-06-18 03:19:35,267 [salt.state       :1951][INFO    ][11293] Completed state [ca-certificates] at time 03:19:35.267340 duration_in_ms=11.048
2019-06-18 03:19:35,268 [salt.state       :1780][INFO    ][11293] Running state [update-ca-certificates] at time 03:19:35.268347
2019-06-18 03:19:35,268 [salt.state       :1813][INFO    ][11293] Executing state cmd.wait for [update-ca-certificates]
2019-06-18 03:19:35,269 [salt.state       :300 ][INFO    ][11293] No changes made for update-ca-certificates
2019-06-18 03:19:35,269 [salt.state       :1951][INFO    ][11293] Completed state [update-ca-certificates] at time 03:19:35.269408 duration_in_ms=1.062
2019-06-18 03:19:35,269 [salt.state       :1780][INFO    ][11293] Running state [iptables] at time 03:19:35.269751
2019-06-18 03:19:35,270 [salt.state       :1813][INFO    ][11293] Executing state pkg.installed for [iptables]
2019-06-18 03:19:35,279 [salt.state       :300 ][INFO    ][11293] All specified packages are already installed
2019-06-18 03:19:35,279 [salt.state       :1951][INFO    ][11293] Completed state [iptables] at time 03:19:35.279390 duration_in_ms=9.639
2019-06-18 03:19:35,279 [salt.state       :1780][INFO    ][11293] Running state [iptables-persistent] at time 03:19:35.279723
2019-06-18 03:19:35,280 [salt.state       :1813][INFO    ][11293] Executing state pkg.installed for [iptables-persistent]
2019-06-18 03:19:35,288 [salt.state       :300 ][INFO    ][11293] All specified packages are already installed
2019-06-18 03:19:35,289 [salt.state       :1951][INFO    ][11293] Completed state [iptables-persistent] at time 03:19:35.289110 duration_in_ms=9.39
2019-06-18 03:19:35,290 [salt.state       :1780][INFO    ][11293] Running state [iptables_modules_v4_load] at time 03:19:35.290612
2019-06-18 03:19:35,290 [salt.state       :1813][INFO    ][11293] Executing state kmod.present for [iptables_modules_v4_load]
2019-06-18 03:19:35,291 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11293] Executing command 'lsmod' in directory '/root'
2019-06-18 03:19:35,313 [salt.state       :300 ][INFO    ][11293] Kernel modules iptable_filter, ip_tables are already present
2019-06-18 03:19:35,313 [salt.state       :1951][INFO    ][11293] Completed state [iptables_modules_v4_load] at time 03:19:35.313907 duration_in_ms=23.295
2019-06-18 03:19:35,314 [salt.state       :1780][INFO    ][11293] Running state [/etc/iptables/rules.v4] at time 03:19:35.314596
2019-06-18 03:19:35,314 [salt.state       :1813][INFO    ][11293] Executing state file.managed for [/etc/iptables/rules.v4]
2019-06-18 03:19:35,414 [salt.state       :300 ][INFO    ][11293] File /etc/iptables/rules.v4 is in the correct state
2019-06-18 03:19:35,414 [salt.state       :1951][INFO    ][11293] Completed state [/etc/iptables/rules.v4] at time 03:19:35.414899 duration_in_ms=100.303
2019-06-18 03:19:35,415 [salt.state       :1780][INFO    ][11293] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 03:19:35.415844
2019-06-18 03:19:35,416 [salt.state       :1813][INFO    ][11293] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-06-18 03:19:35,416 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11293] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-06-18 03:19:35,436 [salt.state       :300 ][INFO    ][11293] onlyif execution failed
2019-06-18 03:19:35,437 [salt.state       :1951][INFO    ][11293] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 03:19:35.436965 duration_in_ms=21.12
2019-06-18 03:19:35,438 [salt.state       :1780][INFO    ][11293] Running state [netfilter-persistent] at time 03:19:35.438638
2019-06-18 03:19:35,439 [salt.state       :1813][INFO    ][11293] Executing state service.running for [netfilter-persistent]
2019-06-18 03:19:35,440 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11293] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-06-18 03:19:35,460 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11293] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-06-18 03:19:35,477 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11293] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-06-18 03:19:35,493 [salt.state       :300 ][INFO    ][11293] The service netfilter-persistent is already running
2019-06-18 03:19:35,494 [salt.state       :1951][INFO    ][11293] Completed state [netfilter-persistent] at time 03:19:35.494175 duration_in_ms=55.537
2019-06-18 03:19:35,495 [salt.state       :1780][INFO    ][11293] Running state [iptables_extra.remove_stale_tables] at time 03:19:35.495016
2019-06-18 03:19:35,495 [salt.state       :1813][INFO    ][11293] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-06-18 03:19:35,495 [salt.state       :300 ][INFO    ][11293] No changes made for iptables_extra.remove_stale_tables
2019-06-18 03:19:35,495 [salt.state       :1951][INFO    ][11293] Completed state [iptables_extra.remove_stale_tables] at time 03:19:35.495951 duration_in_ms=0.934
2019-06-18 03:19:35,496 [salt.state       :1780][INFO    ][11293] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 03:19:35.496195
2019-06-18 03:19:35,496 [salt.state       :1813][INFO    ][11293] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-06-18 03:19:35,497 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11293] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-06-18 03:19:35,511 [salt.state       :300 ][INFO    ][11293] onlyif execution failed
2019-06-18 03:19:35,512 [salt.state       :1951][INFO    ][11293] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 03:19:35.511960 duration_in_ms=15.764
2019-06-18 03:19:35,513 [salt.state       :1780][INFO    ][11293] Running state [/etc/iptables/rules.v6] at time 03:19:35.513350
2019-06-18 03:19:35,513 [salt.state       :1813][INFO    ][11293] Executing state file.absent for [/etc/iptables/rules.v6]
2019-06-18 03:19:35,514 [salt.state       :300 ][INFO    ][11293] File /etc/iptables/rules.v6 is not present
2019-06-18 03:19:35,514 [salt.state       :1951][INFO    ][11293] Completed state [/etc/iptables/rules.v6] at time 03:19:35.514822 duration_in_ms=1.472
2019-06-18 03:19:35,515 [salt.state       :1780][INFO    ][11293] Running state [iptables_extra.flush_all] at time 03:19:35.515816
2019-06-18 03:19:35,516 [salt.state       :1813][INFO    ][11293] Executing state module.wait for [iptables_extra.flush_all]
2019-06-18 03:19:35,516 [salt.state       :300 ][INFO    ][11293] No changes made for iptables_extra.flush_all
2019-06-18 03:19:35,517 [salt.state       :1951][INFO    ][11293] Completed state [iptables_extra.flush_all] at time 03:19:35.516975 duration_in_ms=1.158
2019-06-18 03:19:35,521 [salt.minion      :1711][INFO    ][11293] Returning information for job: 20190618031927590960
2019-06-18 03:19:36,118 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command state.apply with jid 20190618031936105368
2019-06-18 03:19:36,139 [salt.minion      :1432][INFO    ][11378] Starting a new job with PID 11378
2019-06-18 03:19:36,883 [salt.state       :915 ][INFO    ][11378] Loading fresh modules for state activity
2019-06-18 03:19:37,545 [salt.state       :1780][INFO    ][11378] Running state [maas-rack-controller] at time 03:19:37.545714
2019-06-18 03:19:37,546 [salt.state       :1813][INFO    ][11378] Executing state pkg.installed for [maas-rack-controller]
2019-06-18 03:19:37,546 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11378] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-18 03:19:37,671 [salt.state       :300 ][INFO    ][11378] All specified packages are already installed
2019-06-18 03:19:37,671 [salt.state       :1951][INFO    ][11378] Completed state [maas-rack-controller] at time 03:19:37.671917 duration_in_ms=126.203
2019-06-18 03:19:37,672 [salt.state       :1780][INFO    ][11378] Running state [ipmitool] at time 03:19:37.672185
2019-06-18 03:19:37,672 [salt.state       :1813][INFO    ][11378] Executing state pkg.installed for [ipmitool]
2019-06-18 03:19:37,677 [salt.state       :300 ][INFO    ][11378] All specified packages are already installed
2019-06-18 03:19:37,677 [salt.state       :1951][INFO    ][11378] Completed state [ipmitool] at time 03:19:37.677338 duration_in_ms=5.153
2019-06-18 03:19:37,679 [salt.state       :1780][INFO    ][11378] Running state [/etc/maas/rackd.conf] at time 03:19:37.679665
2019-06-18 03:19:37,679 [salt.state       :1813][INFO    ][11378] Executing state file.line for [/etc/maas/rackd.conf]
2019-06-18 03:19:37,680 [salt.state       :300 ][INFO    ][11378] No changes needed to be made
2019-06-18 03:19:37,680 [salt.state       :1951][INFO    ][11378] Completed state [/etc/maas/rackd.conf] at time 03:19:37.680777 duration_in_ms=1.111
2019-06-18 03:19:37,680 [salt.state       :1780][INFO    ][11378] Running state [/etc/maas/rackd.conf] at time 03:19:37.680954
2019-06-18 03:19:37,681 [salt.state       :1813][INFO    ][11378] Executing state file.managed for [/etc/maas/rackd.conf]
2019-06-18 03:19:37,681 [salt.loaded.int.states.file:2298][WARNING ][11378] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-06-18 03:19:37,681 [salt.state       :300 ][INFO    ][11378] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-06-18 03:19:37,681 [salt.state       :1951][INFO    ][11378] Completed state [/etc/maas/rackd.conf] at time 03:19:37.681882 duration_in_ms=0.929
2019-06-18 03:19:37,682 [salt.state       :1780][INFO    ][11378] Running state [maas-rackd] at time 03:19:37.682619
2019-06-18 03:19:37,682 [salt.state       :1813][INFO    ][11378] Executing state service.running for [maas-rackd]
2019-06-18 03:19:37,683 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11378] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-06-18 03:19:37,716 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11378] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-06-18 03:19:37,732 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11378] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-06-18 03:19:37,746 [salt.state       :300 ][INFO    ][11378] The service maas-rackd is already running
2019-06-18 03:19:37,747 [salt.state       :1951][INFO    ][11378] Completed state [maas-rackd] at time 03:19:37.747287 duration_in_ms=64.667
2019-06-18 03:19:37,749 [salt.minion      :1711][INFO    ][11378] Returning information for job: 20190618031936105368
2019-06-18 03:19:38,346 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command state.apply with jid 20190618031938336449
2019-06-18 03:19:38,365 [salt.minion      :1432][INFO    ][11415] Starting a new job with PID 11415
2019-06-18 03:19:39,045 [salt.state       :915 ][INFO    ][11415] Loading fresh modules for state activity
2019-06-18 03:19:39,687 [salt.state       :1780][INFO    ][11415] Running state [maas-region-controller] at time 03:19:39.687815
2019-06-18 03:19:39,688 [salt.state       :1813][INFO    ][11415] Executing state pkg.installed for [maas-region-controller]
2019-06-18 03:19:39,688 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11415] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-18 03:19:39,764 [salt.state       :300 ][INFO    ][11415] All specified packages are already installed
2019-06-18 03:19:39,765 [salt.state       :1951][INFO    ][11415] Completed state [maas-region-controller] at time 03:19:39.765186 duration_in_ms=77.371
2019-06-18 03:19:39,765 [salt.state       :1780][INFO    ][11415] Running state [python-oauth] at time 03:19:39.765426
2019-06-18 03:19:39,765 [salt.state       :1813][INFO    ][11415] Executing state pkg.installed for [python-oauth]
2019-06-18 03:19:39,770 [salt.state       :300 ][INFO    ][11415] All specified packages are already installed
2019-06-18 03:19:39,770 [salt.state       :1951][INFO    ][11415] Completed state [python-oauth] at time 03:19:39.770320 duration_in_ms=4.894
2019-06-18 03:19:39,772 [salt.state       :1780][INFO    ][11415] Running state [/etc/maas/regiond.conf] at time 03:19:39.772688
2019-06-18 03:19:39,772 [salt.state       :1813][INFO    ][11415] Executing state file.replace for [/etc/maas/regiond.conf]
2019-06-18 03:19:39,822 [salt.state       :300 ][INFO    ][11415] No changes needed to be made
2019-06-18 03:19:39,822 [salt.state       :1951][INFO    ][11415] Completed state [/etc/maas/regiond.conf] at time 03:19:39.822929 duration_in_ms=50.239
2019-06-18 03:19:39,823 [salt.state       :1780][INFO    ][11415] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 03:19:39.823459
2019-06-18 03:19:39,823 [salt.state       :1813][INFO    ][11415] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-06-18 03:19:39,887 [salt.state       :300 ][INFO    ][11415] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-06-18 03:19:39,888 [salt.state       :1951][INFO    ][11415] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 03:19:39.888233 duration_in_ms=64.774
2019-06-18 03:19:39,888 [salt.state       :1780][INFO    ][11415] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 03:19:39.888761
2019-06-18 03:19:39,889 [salt.state       :1813][INFO    ][11415] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-06-18 03:19:39,901 [salt.state       :300 ][INFO    ][11415] No changes needed to be made
2019-06-18 03:19:39,901 [salt.state       :1951][INFO    ][11415] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 03:19:39.901612 duration_in_ms=12.852
2019-06-18 03:19:39,902 [salt.state       :1780][INFO    ][11415] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 03:19:39.902115
2019-06-18 03:19:39,902 [salt.state       :1813][INFO    ][11415] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-06-18 03:19:39,925 [salt.state       :300 ][INFO    ][11415] No changes needed to be made
2019-06-18 03:19:39,925 [salt.state       :1951][INFO    ][11415] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 03:19:39.925625 duration_in_ms=23.51
2019-06-18 03:19:39,926 [salt.state       :1780][INFO    ][11415] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 03:19:39.926129
2019-06-18 03:19:39,926 [salt.state       :1813][INFO    ][11415] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-06-18 03:19:39,950 [salt.state       :300 ][INFO    ][11415] No changes needed to be made
2019-06-18 03:19:39,950 [salt.state       :1951][INFO    ][11415] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 03:19:39.950564 duration_in_ms=24.435
2019-06-18 03:19:39,951 [salt.state       :1780][INFO    ][11415] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 03:19:39.951022
2019-06-18 03:19:39,951 [salt.state       :1813][INFO    ][11415] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-06-18 03:19:39,962 [salt.state       :300 ][INFO    ][11415] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-06-18 03:19:39,962 [salt.state       :1951][INFO    ][11415] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 03:19:39.962475 duration_in_ms=11.453
2019-06-18 03:19:39,963 [salt.state       :1780][INFO    ][11415] Running state [a2enmod headers] at time 03:19:39.963618
2019-06-18 03:19:39,963 [salt.state       :1813][INFO    ][11415] Executing state cmd.run for [a2enmod headers]
2019-06-18 03:19:39,964 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11415] Executing command 'a2enmod headers' in directory '/root'
2019-06-18 03:19:40,038 [salt.state       :300 ][INFO    ][11415] {'pid': 11435, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-06-18 03:19:40,039 [salt.state       :1951][INFO    ][11415] Completed state [a2enmod headers] at time 03:19:40.039565 duration_in_ms=75.945
2019-06-18 03:19:40,040 [salt.state       :1780][INFO    ][11415] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 03:19:40.040140
2019-06-18 03:19:40,040 [salt.state       :1813][INFO    ][11415] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-06-18 03:19:40,059 [salt.state       :300 ][INFO    ][11415] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-06-18 03:19:40,060 [salt.state       :1951][INFO    ][11415] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 03:19:40.060198 duration_in_ms=20.057
2019-06-18 03:19:40,061 [salt.state       :1780][INFO    ][11415] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 03:19:40.061114
2019-06-18 03:19:40,061 [salt.state       :1813][INFO    ][11415] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-06-18 03:19:40,145 [salt.state       :300 ][INFO    ][11415] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-06-18 03:19:40,146 [salt.state       :1951][INFO    ][11415] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 03:19:40.146228 duration_in_ms=85.114
2019-06-18 03:19:40,147 [salt.state       :1780][INFO    ][11415] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 03:19:40.146981
2019-06-18 03:19:40,147 [salt.state       :1813][INFO    ][11415] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-06-18 03:19:40,217 [salt.state       :300 ][INFO    ][11415] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-06-18 03:19:40,218 [salt.state       :1951][INFO    ][11415] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 03:19:40.217976 duration_in_ms=70.995
2019-06-18 03:19:40,218 [salt.state       :1780][INFO    ][11415] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 03:19:40.218581
2019-06-18 03:19:40,218 [salt.state       :1813][INFO    ][11415] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-06-18 03:19:40,289 [salt.state       :300 ][INFO    ][11415] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-06-18 03:19:40,290 [salt.state       :1951][INFO    ][11415] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 03:19:40.290371 duration_in_ms=71.789
2019-06-18 03:19:40,290 [salt.state       :1780][INFO    ][11415] Running state [/root/.pgpass] at time 03:19:40.290831
2019-06-18 03:19:40,291 [salt.state       :1813][INFO    ][11415] Executing state file.managed for [/root/.pgpass]
2019-06-18 03:19:40,343 [salt.state       :300 ][INFO    ][11415] File /root/.pgpass is in the correct state
2019-06-18 03:19:40,343 [salt.state       :1951][INFO    ][11415] Completed state [/root/.pgpass] at time 03:19:40.343590 duration_in_ms=52.759
2019-06-18 03:19:40,349 [salt.state       :1780][INFO    ][11415] Running state [maas-region syncdb --noinput] at time 03:19:40.348995
2019-06-18 03:19:40,349 [salt.state       :1813][INFO    ][11415] Executing state cmd.run for [maas-region syncdb --noinput]
2019-06-18 03:19:40,350 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11415] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-06-18 03:19:42,288 [salt.state       :300 ][INFO    ][11415] {'pid': 11448, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: maasserver, piston3, sessions, auth, sites, metadataserver, contenttypes\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-06-18 03:19:42,288 [salt.state       :1951][INFO    ][11415] Completed state [maas-region syncdb --noinput] at time 03:19:42.288749 duration_in_ms=1939.753
2019-06-18 03:19:42,288 [salt.state       :2022][WARNING ][11415] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-06-18 03:19:42,290 [salt.state       :1780][INFO    ][11415] Running state [maas-regiond] at time 03:19:42.289979
2019-06-18 03:19:42,290 [salt.state       :1813][INFO    ][11415] Executing state service.running for [maas-regiond]
2019-06-18 03:19:42,290 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11415] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-06-18 03:19:42,323 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11415] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-06-18 03:19:42,338 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11415] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-06-18 03:19:42,353 [salt.state       :300 ][INFO    ][11415] The service maas-regiond is already running
2019-06-18 03:19:42,353 [salt.state       :1951][INFO    ][11415] Completed state [maas-regiond] at time 03:19:42.353683 duration_in_ms=63.704
2019-06-18 03:19:42,354 [salt.state       :1780][INFO    ][11415] Running state [bind9] at time 03:19:42.354679
2019-06-18 03:19:42,354 [salt.state       :1813][INFO    ][11415] Executing state service.running for [bind9]
2019-06-18 03:19:42,355 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11415] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-06-18 03:19:42,371 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11415] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-06-18 03:19:42,385 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11415] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-06-18 03:19:42,399 [salt.state       :300 ][INFO    ][11415] The service bind9 is already running
2019-06-18 03:19:42,399 [salt.state       :1951][INFO    ][11415] Completed state [bind9] at time 03:19:42.399402 duration_in_ms=44.722
2019-06-18 03:19:42,400 [salt.state       :1780][INFO    ][11415] Running state [apache2] at time 03:19:42.400387
2019-06-18 03:19:42,400 [salt.state       :1813][INFO    ][11415] Executing state service.running for [apache2]
2019-06-18 03:19:42,401 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11415] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-06-18 03:19:42,415 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11415] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-06-18 03:19:42,429 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11415] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-06-18 03:19:42,445 [salt.state       :300 ][INFO    ][11415] The service apache2 is already running
2019-06-18 03:19:42,446 [salt.state       :1951][INFO    ][11415] Completed state [apache2] at time 03:19:42.446418 duration_in_ms=46.03
2019-06-18 03:19:42,448 [salt.state       :1780][INFO    ][11415] Running state [maasng.wait_for_http_code] at time 03:19:42.448516
2019-06-18 03:19:42,449 [salt.state       :1813][INFO    ][11415] Executing state module.run for [maasng.wait_for_http_code]
2019-06-18 03:19:42,449 [salt.utils.decorators:613 ][WARNING ][11415] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-18 03:19:42,549 [salt.state       :300 ][INFO    ][11415] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-06-18 03:19:42,550 [salt.state       :1951][INFO    ][11415] Completed state [maasng.wait_for_http_code] at time 03:19:42.550299 duration_in_ms=101.783
2019-06-18 03:19:42,551 [salt.state       :1780][INFO    ][11415] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 03:19:42.551679
2019-06-18 03:19:42,552 [salt.state       :1813][INFO    ][11415] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-06-18 03:19:42,552 [salt.state       :300 ][INFO    ][11415] /var/lib/maas/.setup_admin exists
2019-06-18 03:19:42,553 [salt.state       :1951][INFO    ][11415] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 03:19:42.553227 duration_in_ms=1.548
2019-06-18 03:19:42,554 [salt.state       :1780][INFO    ][11415] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:19:42.554387
2019-06-18 03:19:42,554 [salt.state       :1813][INFO    ][11415] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-18 03:19:42,555 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11415] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-18 03:19:43,859 [salt.state       :300 ][INFO    ][11415] {'pid': 11470, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-18 03:19:43,860 [salt.state       :1951][INFO    ][11415] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:19:43.860314 duration_in_ms=1305.926
2019-06-18 03:19:43,869 [salt.state       :1780][INFO    ][11415] Running state [maas_region_boot_source_resources_mirror] at time 03:19:43.869109
2019-06-18 03:19:43,869 [salt.state       :1813][INFO    ][11415] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-06-18 03:19:44,016 [salt.state       :300 ][INFO    ][11415] {'changes': {}}
2019-06-18 03:19:44,016 [salt.state       :1951][INFO    ][11415] Completed state [maas_region_boot_source_resources_mirror] at time 03:19:44.016642 duration_in_ms=147.532
2019-06-18 03:19:44,017 [salt.state       :1780][INFO    ][11415] Running state [maasng.boot_resources_import] at time 03:19:44.017756
2019-06-18 03:19:44,018 [salt.state       :1813][INFO    ][11415] Executing state module.run for [maasng.boot_resources_import]
2019-06-18 03:19:44,018 [salt.utils.decorators:613 ][WARNING ][11415] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-18 03:19:44,154 [salt.loaded.ext.module.maasng:1600][INFO    ][11415] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-06-18 03:19:49,213 [salt.loaded.ext.module.maasng:1600][INFO    ][11415] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-06-18 03:19:53,427 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618031953415290
2019-06-18 03:19:53,449 [salt.minion      :1432][INFO    ][11503] Starting a new job with PID 11503
2019-06-18 03:19:53,472 [salt.minion      :1711][INFO    ][11503] Returning information for job: 20190618031953415290
2019-06-18 03:19:54,282 [salt.loaded.ext.module.maasng:1600][INFO    ][11415] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-06-18 03:19:59,394 [salt.state       :300 ][INFO    ][11415] {'ret': True}
2019-06-18 03:19:59,394 [salt.state       :1951][INFO    ][11415] Completed state [maasng.boot_resources_import] at time 03:19:59.394614 duration_in_ms=15376.858
2019-06-18 03:19:59,395 [salt.state       :1780][INFO    ][11415] Running state [maas_region_boot_sources_selection_xenial] at time 03:19:59.395742
2019-06-18 03:19:59,396 [salt.state       :1813][INFO    ][11415] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-06-18 03:19:59,615 [salt.state       :300 ][INFO    ][11415] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-06-18 03:19:59,615 [salt.state       :1951][INFO    ][11415] Completed state [maas_region_boot_sources_selection_xenial] at time 03:19:59.615840 duration_in_ms=220.097
2019-06-18 03:19:59,617 [salt.state       :1780][INFO    ][11415] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 03:19:59.617179
2019-06-18 03:19:59,617 [salt.state       :1813][INFO    ][11415] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-06-18 03:19:59,618 [salt.utils.decorators:613 ][WARNING ][11415] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-18 03:19:59,618 [salt.loaded.ext.module.maasng:1771][INFO    ][11415] boot-sources sync initiated for ALL Rack's
2019-06-18 03:20:00,617 [salt.state       :300 ][INFO    ][11415] {'ret': True}
2019-06-18 03:20:00,618 [salt.state       :1951][INFO    ][11415] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 03:20:00.618347 duration_in_ms=1001.168
2019-06-18 03:20:00,620 [salt.state       :1780][INFO    ][11415] Running state [maas.process_maas_config] at time 03:20:00.620063
2019-06-18 03:20:00,620 [salt.state       :1813][INFO    ][11415] Executing state module.run for [maas.process_maas_config]
2019-06-18 03:20:00,620 [salt.utils.decorators:613 ][WARNING ][11415] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-18 03:20:00,621 [salt.loaded.ext.module.maas:92  ][INFO    ][11415] maasconfig name=enable_http_proxy value=True
2019-06-18 03:20:00,679 [salt.loaded.ext.module.maas:92  ][INFO    ][11415] maasconfig name=upstream_dns value=8.8.8.8
2019-06-18 03:20:00,750 [salt.loaded.ext.module.maas:92  ][INFO    ][11415] maasconfig name=commissioning_distro_series value=xenial
2019-06-18 03:20:04,002 [salt.loaded.ext.module.maas:92  ][INFO    ][11415] maasconfig name=default_osystem value=ubuntu
2019-06-18 03:20:04,049 [salt.loaded.ext.module.maas:92  ][INFO    ][11415] maasconfig name=active_discovery_interval value=600
2019-06-18 03:20:04,087 [salt.loaded.ext.module.maas:92  ][INFO    ][11415] maasconfig name=dnssec_validation value=no
2019-06-18 03:20:04,120 [salt.loaded.ext.module.maas:92  ][INFO    ][11415] maasconfig name=maas_name value=mas01
2019-06-18 03:20:04,157 [salt.loaded.ext.module.maas:92  ][INFO    ][11415] maasconfig name=network_discovery value=enabled
2019-06-18 03:20:04,250 [salt.loaded.ext.module.maas:92  ][INFO    ][11415] maasconfig name=enable_third_party_drivers value=True
2019-06-18 03:20:04,299 [salt.loaded.ext.module.maas:92  ][INFO    ][11415] maasconfig name=default_storage_layout value=lvm
2019-06-18 03:20:04,352 [salt.loaded.ext.module.maas:92  ][INFO    ][11415] maasconfig name=ntp_external_only value=True
2019-06-18 03:20:04,407 [salt.loaded.ext.module.maas:92  ][INFO    ][11415] maasconfig name=disk_erase_with_secure_erase value=False
2019-06-18 03:20:04,460 [salt.loaded.ext.module.maas:92  ][INFO    ][11415] maasconfig name=default_distro_series value=xenial
2019-06-18 03:20:04,526 [salt.loaded.ext.module.maas:92  ][INFO    ][11415] maasconfig name=default_min_hwe_kernel value=ga-16.04
2019-06-18 03:20:04,663 [salt.state       :300 ][INFO    ][11415] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-06-18 03:20:04,663 [salt.state       :1951][INFO    ][11415] Completed state [maas.process_maas_config] at time 03:20:04.663856 duration_in_ms=4043.791
2019-06-18 03:20:04,664 [salt.state       :1780][INFO    ][11415] Running state [pxe_admin] at time 03:20:04.664742
2019-06-18 03:20:04,665 [salt.state       :1813][INFO    ][11415] Executing state maasng.fabric_present for [pxe_admin]
2019-06-18 03:20:04,726 [salt.loaded.ext.module.maasng:945 ][INFO    ][11415] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'mmqacw', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-06-18 03:20:04,804 [salt.loaded.ext.module.maasng:1008][WARNING ][11415] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-06-18 03:20:04,804 [salt.loaded.ext.module.maasng:1011][WARNING ][11415] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-06-18 03:20:04,882 [salt.state       :300 ][INFO    ][11415] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-06-18 03:20:04,883 [salt.state       :1951][INFO    ][11415] Completed state [pxe_admin] at time 03:20:04.883218 duration_in_ms=218.475
2019-06-18 03:20:04,883 [salt.state       :1780][INFO    ][11415] Running state [vlan 0] at time 03:20:04.883744
2019-06-18 03:20:04,884 [salt.state       :1813][INFO    ][11415] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-06-18 03:20:04,948 [salt.loaded.ext.module.maasng:945 ][INFO    ][11415] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'mmqacw', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-06-18 03:20:05,085 [salt.loaded.ext.module.maasng:945 ][INFO    ][11415] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'mmqacw', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-06-18 03:20:05,394 [salt.loaded.ext.module.maasng:945 ][INFO    ][11415] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'mmqacw', 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'fabric': u'pxe_admin'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-06-18 03:20:05,504 [salt.state       :300 ][INFO    ][11415] {'new': 'Vlan untagged was updated'}
2019-06-18 03:20:05,504 [salt.state       :1951][INFO    ][11415] Completed state [vlan 0] at time 03:20:05.504900 duration_in_ms=621.155
2019-06-18 03:20:05,506 [salt.state       :1780][INFO    ][11415] Running state [192.168.11.0/24] at time 03:20:05.506452
2019-06-18 03:20:05,506 [salt.state       :1813][INFO    ][11415] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-06-18 03:20:05,754 [salt.loaded.ext.module.maasng:945 ][INFO    ][11415] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'mmqacw', 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'fabric': u'pxe_admin'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-06-18 03:20:05,755 [salt.loaded.ext.module.maasng:1235][WARNING ][11415] Ignoring parameter vlan:0
2019-06-18 03:20:05,838 [salt.state       :300 ][INFO    ][11415] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-06-18 03:20:05,839 [salt.state       :1951][INFO    ][11415] Completed state [192.168.11.0/24] at time 03:20:05.839221 duration_in_ms=332.767
2019-06-18 03:20:05,840 [salt.state       :1780][INFO    ][11415] Running state [maas_create_iprange_1] at time 03:20:05.840606
2019-06-18 03:20:05,841 [salt.state       :1813][INFO    ][11415] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-06-18 03:20:05,912 [salt.state       :300 ][INFO    ][11415] Iprange maas_create_iprange_1 already exist.
2019-06-18 03:20:05,912 [salt.state       :1951][INFO    ][11415] Completed state [maas_create_iprange_1] at time 03:20:05.912881 duration_in_ms=72.274
2019-06-18 03:20:05,913 [salt.state       :1780][INFO    ][11415] Running state [vlan 0] at time 03:20:05.913339
2019-06-18 03:20:05,913 [salt.state       :1813][INFO    ][11415] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-06-18 03:20:05,972 [salt.loaded.ext.module.maasng:945 ][INFO    ][11415] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'mmqacw', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-06-18 03:20:06,093 [salt.loaded.ext.module.maasng:945 ][INFO    ][11415] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'mmqacw', 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'fabric': u'pxe_admin'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-06-18 03:20:06,289 [salt.loaded.ext.module.maasng:945 ][INFO    ][11415] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'mmqacw', 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'fabric': u'pxe_admin'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-06-18 03:20:06,389 [salt.state       :300 ][INFO    ][11415] {'new': 'Vlan untagged was updated'}
2019-06-18 03:20:06,389 [salt.state       :1951][INFO    ][11415] Completed state [vlan 0] at time 03:20:06.389914 duration_in_ms=476.574
2019-06-18 03:20:06,390 [salt.state       :1780][INFO    ][11415] Running state [opnfv] at time 03:20:06.390673
2019-06-18 03:20:06,391 [salt.state       :1813][INFO    ][11415] Executing state maasng.sshkey_present for [opnfv]
2019-06-18 03:20:06,430 [salt.loaded.ext.module.maasng:1903][INFO    ][11415] [{u'keysource': u'', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP', u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/'}]
2019-06-18 03:20:06,430 [salt.state       :300 ][INFO    ][11415] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-06-18 03:20:06,431 [salt.state       :1951][INFO    ][11415] Completed state [opnfv] at time 03:20:06.431150 duration_in_ms=40.477
2019-06-18 03:20:06,434 [salt.minion      :1711][INFO    ][11415] Returning information for job: 20190618031938336449
2019-06-18 03:20:06,979 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command state.apply with jid 20190618032006973570
2019-06-18 03:20:06,997 [salt.minion      :1432][INFO    ][11929] Starting a new job with PID 11929
2019-06-18 03:20:10,563 [salt.state       :915 ][INFO    ][11929] Loading fresh modules for state activity
2019-06-18 03:20:10,649 [salt.state       :1780][INFO    ][11929] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:20:10.649645
2019-06-18 03:20:10,650 [salt.state       :1813][INFO    ][11929] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-18 03:20:10,652 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11929] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-18 03:20:11,977 [salt.state       :300 ][INFO    ][11929] {'pid': 11952, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-18 03:20:11,978 [salt.state       :1951][INFO    ][11929] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:20:11.978751 duration_in_ms=1329.106
2019-06-18 03:20:11,981 [salt.state       :1780][INFO    ][11929] Running state [maas.process_machines] at time 03:20:11.981319
2019-06-18 03:20:11,981 [salt.state       :1813][INFO    ][11929] Executing state module.run for [maas.process_machines]
2019-06-18 03:20:11,982 [salt.utils.decorators:613 ][WARNING ][11929] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-18 03:20:12,319 [salt.loaded.ext.module.maas:412 ][WARNING ][11929] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-18 03:20:12,323 [salt.loaded.ext.module.maas:92  ][INFO    ][11929] machine hostname=cmp002 power_type=ipmi mac_addresses=[u'00:25:b5:a0:00:6a'] power_parameters_power_address=172.30.8.72 power_parameters_power_pass=octopus system_id=b3tadp architecture=amd64/generic power_parameters_power_user=admin
2019-06-18 03:20:13,369 [salt.loaded.ext.module.maas:412 ][WARNING ][11929] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-18 03:20:13,370 [salt.loaded.ext.module.maas:92  ][INFO    ][11929] machine hostname=cmp001 power_type=ipmi mac_addresses=['00:25:b5:a0:00:5a'] power_parameters_power_address=172.30.8.73 power_parameters_power_pass=octopus system_id=4mtg38 architecture=amd64/generic power_parameters_power_user=admin
2019-06-18 03:20:14,465 [salt.loaded.ext.module.maas:412 ][WARNING ][11929] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-18 03:20:14,466 [salt.loaded.ext.module.maas:92  ][INFO    ][11929] machine hostname=kvm01 power_type=ipmi mac_addresses=['00:25:b5:a0:00:2a'] power_parameters_power_address=172.30.8.75 power_parameters_power_pass=octopus system_id=xwq3cf architecture=amd64/generic power_parameters_power_user=admin
2019-06-18 03:20:15,838 [salt.loaded.ext.module.maas:412 ][WARNING ][11929] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-18 03:20:15,839 [salt.loaded.ext.module.maas:92  ][INFO    ][11929] machine hostname=kvm03 power_type=ipmi mac_addresses=['00:25:b5:a0:00:4a'] power_parameters_power_address=172.30.8.74 power_parameters_power_pass=octopus system_id=cacw3y architecture=amd64/generic power_parameters_power_user=admin
2019-06-18 03:20:16,722 [salt.loaded.ext.module.maas:412 ][WARNING ][11929] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-18 03:20:16,723 [salt.loaded.ext.module.maas:92  ][INFO    ][11929] machine hostname=kvm02 power_type=ipmi mac_addresses=['00:25:b5:a0:00:3a'] power_parameters_power_address=172.30.8.65 power_parameters_power_pass=octopus system_id=4dscpf architecture=amd64/generic power_parameters_power_user=admin
2019-06-18 03:20:18,208 [salt.state       :300 ][INFO    ][11929] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-06-18 03:20:18,208 [salt.state       :1951][INFO    ][11929] Completed state [maas.process_machines] at time 03:20:18.208598 duration_in_ms=6227.278
2019-06-18 03:20:18,219 [salt.minion      :1711][INFO    ][11929] Returning information for job: 20190618032006973570
2019-06-18 03:20:49,267 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command state.apply with jid 20190618032049253438
2019-06-18 03:20:49,288 [salt.minion      :1432][INFO    ][12178] Starting a new job with PID 12178
2019-06-18 03:20:53,008 [salt.state       :915 ][INFO    ][12178] Loading fresh modules for state activity
2019-06-18 03:20:53,097 [salt.state       :1780][INFO    ][12178] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:20:53.096997
2019-06-18 03:20:53,097 [salt.state       :1813][INFO    ][12178] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-18 03:20:53,099 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12178] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-18 03:20:54,674 [salt.state       :300 ][INFO    ][12178] {'pid': 12185, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-18 03:20:54,675 [salt.state       :1951][INFO    ][12178] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:20:54.675512 duration_in_ms=1578.514
2019-06-18 03:20:54,679 [salt.state       :1780][INFO    ][12178] Running state [maas.wait_for_machine_status] at time 03:20:54.678938
2019-06-18 03:20:54,679 [salt.state       :1813][INFO    ][12178] Executing state module.run for [maas.wait_for_machine_status]
2019-06-18 03:20:54,680 [salt.utils.decorators:613 ][WARNING ][12178] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-18 03:20:55,187 [salt.loaded.ext.module.maas:981 ][INFO    ][12178] Machine b3tadp deleted
2019-06-18 03:20:55,943 [salt.loaded.ext.module.maas:412 ][WARNING ][12178] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-18 03:20:55,943 [salt.loaded.ext.module.maas:92  ][INFO    ][12178] machine hostname=cmp002 power_type=ipmi mac_addresses=00:25:b5:a0:00:6a power_parameters_power_address=172.30.8.72 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-06-18 03:20:57,280 [salt.loaded.ext.module.maas:412 ][WARNING ][12178] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-18 03:20:57,281 [salt.loaded.ext.module.maas:92  ][INFO    ][12178] machine hostname=cmp001 power_type=ipmi mac_addresses=['00:25:b5:a0:00:5a'] power_parameters_power_address=172.30.8.73 power_parameters_power_pass=octopus system_id=4mtg38 architecture=amd64/generic power_parameters_power_user=admin
2019-06-18 03:20:58,175 [salt.loaded.ext.module.maas:412 ][WARNING ][12178] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-18 03:20:58,175 [salt.loaded.ext.module.maas:92  ][INFO    ][12178] machine hostname=kvm01 power_type=ipmi mac_addresses=['00:25:b5:a0:00:2a'] power_parameters_power_address=172.30.8.75 power_parameters_power_pass=octopus system_id=xwq3cf architecture=amd64/generic power_parameters_power_user=admin
2019-06-18 03:20:59,440 [salt.loaded.ext.module.maas:412 ][WARNING ][12178] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-18 03:20:59,441 [salt.loaded.ext.module.maas:92  ][INFO    ][12178] machine hostname=kvm03 power_type=ipmi mac_addresses=['00:25:b5:a0:00:4a'] power_parameters_power_address=172.30.8.74 power_parameters_power_pass=octopus system_id=cacw3y architecture=amd64/generic power_parameters_power_user=admin
2019-06-18 03:21:00,527 [salt.loaded.ext.module.maas:412 ][WARNING ][12178] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-18 03:21:00,528 [salt.loaded.ext.module.maas:92  ][INFO    ][12178] machine hostname=kvm02 power_type=ipmi mac_addresses=['00:25:b5:a0:00:3a'] power_parameters_power_address=172.30.8.65 power_parameters_power_pass=octopus system_id=4dscpf architecture=amd64/generic power_parameters_power_user=admin
2019-06-18 03:21:01,916 [salt.loaded.ext.module.maas:981 ][INFO    ][12178] Machine 4mtg38 deleted
2019-06-18 03:21:02,735 [salt.loaded.ext.module.maas:412 ][WARNING ][12178] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-18 03:21:02,735 [salt.loaded.ext.module.maas:92  ][INFO    ][12178] machine hostname=cmp002 power_type=ipmi mac_addresses=[u'00:25:b5:a0:00:6a'] power_parameters_power_address=172.30.8.72 power_parameters_power_pass=octopus system_id=cethd6 architecture=amd64/generic power_parameters_power_user=admin
2019-06-18 03:21:03,624 [salt.loaded.ext.module.maas:412 ][WARNING ][12178] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-18 03:21:03,624 [salt.loaded.ext.module.maas:92  ][INFO    ][12178] machine hostname=cmp001 power_type=ipmi mac_addresses=00:25:b5:a0:00:5a power_parameters_power_address=172.30.8.73 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-06-18 03:21:04,297 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618032104285946
2019-06-18 03:21:04,317 [salt.minion      :1432][INFO    ][12586] Starting a new job with PID 12586
2019-06-18 03:21:04,337 [salt.minion      :1711][INFO    ][12586] Returning information for job: 20190618032104285946
2019-06-18 03:21:04,899 [salt.loaded.ext.module.maas:412 ][WARNING ][12178] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-18 03:21:04,900 [salt.loaded.ext.module.maas:92  ][INFO    ][12178] machine hostname=kvm01 power_type=ipmi mac_addresses=['00:25:b5:a0:00:2a'] power_parameters_power_address=172.30.8.75 power_parameters_power_pass=octopus system_id=xwq3cf architecture=amd64/generic power_parameters_power_user=admin
2019-06-18 03:21:06,219 [salt.loaded.ext.module.maas:412 ][WARNING ][12178] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-18 03:21:06,220 [salt.loaded.ext.module.maas:92  ][INFO    ][12178] machine hostname=kvm03 power_type=ipmi mac_addresses=['00:25:b5:a0:00:4a'] power_parameters_power_address=172.30.8.74 power_parameters_power_pass=octopus system_id=cacw3y architecture=amd64/generic power_parameters_power_user=admin
2019-06-18 03:21:07,291 [salt.loaded.ext.module.maas:412 ][WARNING ][12178] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-18 03:21:07,292 [salt.loaded.ext.module.maas:92  ][INFO    ][12178] machine hostname=kvm02 power_type=ipmi mac_addresses=['00:25:b5:a0:00:3a'] power_parameters_power_address=172.30.8.65 power_parameters_power_pass=octopus system_id=4dscpf architecture=amd64/generic power_parameters_power_user=admin
2019-06-18 03:21:09,499 [salt.loaded.ext.module.maas:981 ][INFO    ][12178] Machine cacw3y deleted
2019-06-18 03:21:10,264 [salt.loaded.ext.module.maas:412 ][WARNING ][12178] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-18 03:21:10,265 [salt.loaded.ext.module.maas:92  ][INFO    ][12178] machine hostname=cmp002 power_type=ipmi mac_addresses=[u'00:25:b5:a0:00:6a'] power_parameters_power_address=172.30.8.72 power_parameters_power_pass=octopus system_id=cethd6 architecture=amd64/generic power_parameters_power_user=admin
2019-06-18 03:21:11,063 [salt.loaded.ext.module.maas:412 ][WARNING ][12178] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-18 03:21:11,064 [salt.loaded.ext.module.maas:92  ][INFO    ][12178] machine hostname=cmp001 power_type=ipmi mac_addresses=[u'00:25:b5:a0:00:5a'] power_parameters_power_address=172.30.8.73 power_parameters_power_pass=octopus system_id=wsf6h8 architecture=amd64/generic power_parameters_power_user=admin
2019-06-18 03:21:11,895 [salt.loaded.ext.module.maas:412 ][WARNING ][12178] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-18 03:21:11,896 [salt.loaded.ext.module.maas:92  ][INFO    ][12178] machine hostname=kvm01 power_type=ipmi mac_addresses=['00:25:b5:a0:00:2a'] power_parameters_power_address=172.30.8.75 power_parameters_power_pass=octopus system_id=xwq3cf architecture=amd64/generic power_parameters_power_user=admin
2019-06-18 03:21:12,938 [salt.loaded.ext.module.maas:412 ][WARNING ][12178] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-18 03:21:12,939 [salt.loaded.ext.module.maas:92  ][INFO    ][12178] machine hostname=kvm03 power_type=ipmi mac_addresses=00:25:b5:a0:00:4a power_parameters_power_address=172.30.8.74 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-06-18 03:21:14,021 [salt.loaded.ext.module.maas:412 ][WARNING ][12178] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-18 03:21:14,022 [salt.loaded.ext.module.maas:92  ][INFO    ][12178] machine hostname=kvm02 power_type=ipmi mac_addresses=['00:25:b5:a0:00:3a'] power_parameters_power_address=172.30.8.65 power_parameters_power_pass=octopus system_id=4dscpf architecture=amd64/generic power_parameters_power_user=admin
2019-06-18 03:21:15,313 [salt.loaded.ext.module.maas:1023][INFO    ][12178] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm03']
sleep for:30s Timeout:1500s (1479.37735295s left)
2019-06-18 03:21:34,345 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618032134333009
2019-06-18 03:21:34,369 [salt.minion      :1432][INFO    ][12982] Starting a new job with PID 12982
2019-06-18 03:21:34,393 [salt.minion      :1711][INFO    ][12982] Returning information for job: 20190618032134333009
2019-06-18 03:21:47,455 [salt.loaded.ext.module.maas:1023][INFO    ][12178] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm03']
sleep for:30s Timeout:1500s (1447.23615408s left)
2019-06-18 03:22:04,444 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618032204431475
2019-06-18 03:22:04,467 [salt.minion      :1432][INFO    ][13052] Starting a new job with PID 13052
2019-06-18 03:22:04,489 [salt.minion      :1711][INFO    ][13052] Returning information for job: 20190618032204431475
2019-06-18 03:22:19,308 [salt.loaded.ext.module.maas:1023][INFO    ][12178] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm03']
sleep for:30s Timeout:1500s (1415.38274598s left)
2019-06-18 03:22:34,487 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618032234475152
2019-06-18 03:22:34,510 [salt.minion      :1432][INFO    ][13073] Starting a new job with PID 13073
2019-06-18 03:22:34,535 [salt.minion      :1711][INFO    ][13073] Returning information for job: 20190618032234475152
2019-06-18 03:22:51,069 [salt.loaded.ext.module.maas:1023][INFO    ][12178] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm03']
sleep for:30s Timeout:1500s (1383.6215241s left)
2019-06-18 03:23:04,544 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618032304532543
2019-06-18 03:23:04,564 [salt.minion      :1432][INFO    ][13349] Starting a new job with PID 13349
2019-06-18 03:23:04,585 [salt.minion      :1711][INFO    ][13349] Returning information for job: 20190618032304532543
2019-06-18 03:23:23,301 [salt.loaded.ext.module.maas:1023][INFO    ][12178] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm03']
sleep for:30s Timeout:1500s (1351.38958097s left)
2019-06-18 03:23:34,595 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618032334583335
2019-06-18 03:23:34,617 [salt.minion      :1432][INFO    ][13421] Starting a new job with PID 13421
2019-06-18 03:23:34,639 [salt.minion      :1711][INFO    ][13421] Returning information for job: 20190618032334583335
2019-06-18 03:23:55,836 [salt.loaded.ext.module.maas:1023][INFO    ][12178] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm03']
sleep for:30s Timeout:1500s (1318.85502911s left)
2019-06-18 03:24:04,642 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618032404637216
2019-06-18 03:24:04,658 [salt.minion      :1432][INFO    ][13689] Starting a new job with PID 13689
2019-06-18 03:24:04,670 [salt.minion      :1711][INFO    ][13689] Returning information for job: 20190618032404637216
2019-06-18 03:24:27,701 [salt.loaded.ext.module.maas:1023][INFO    ][12178] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm03']
sleep for:30s Timeout:1500s (1286.98997712s left)
2019-06-18 03:24:34,678 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618032434666420
2019-06-18 03:24:34,701 [salt.minion      :1432][INFO    ][13830] Starting a new job with PID 13830
2019-06-18 03:24:34,724 [salt.minion      :1711][INFO    ][13830] Returning information for job: 20190618032434666420
2019-06-18 03:25:00,895 [salt.state       :300 ][INFO    ][12178] {'ret': True}
2019-06-18 03:25:00,896 [salt.state       :1951][INFO    ][12178] Completed state [maas.wait_for_machine_status] at time 03:25:00.896319 duration_in_ms=246217.38
2019-06-18 03:25:00,900 [salt.minion      :1711][INFO    ][12178] Returning information for job: 20190618032049253438
2019-06-18 03:25:01,535 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command state.apply with jid 20190618032501522549
2019-06-18 03:25:01,557 [salt.minion      :1432][INFO    ][13945] Starting a new job with PID 13945
2019-06-18 03:25:05,063 [salt.state       :915 ][INFO    ][13945] Loading fresh modules for state activity
2019-06-18 03:25:05,117 [salt.fileclient  :1219][INFO    ][13945] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-06-18 03:25:05,213 [salt.state       :1780][INFO    ][13945] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:25:05.213032
2019-06-18 03:25:05,213 [salt.state       :1813][INFO    ][13945] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-18 03:25:05,215 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13945] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-18 03:25:06,784 [salt.state       :300 ][INFO    ][13945] {'pid': 14034, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-18 03:25:06,785 [salt.state       :1951][INFO    ][13945] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:25:06.784966 duration_in_ms=1571.933
2019-06-18 03:25:06,788 [salt.state       :1780][INFO    ][13945] Running state [maas_machines_storage_cmp002_lvm] at time 03:25:06.788290
2019-06-18 03:25:06,788 [salt.state       :1813][INFO    ][13945] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-06-18 03:25:08,111 [salt.loaded.ext.module.maasng:610 ][INFO    ][13945] cethd6
2019-06-18 03:25:08,112 [salt.loaded.ext.module.maasng:626 ][INFO    ][13945] sda
2019-06-18 03:25:08,716 [salt.loaded.ext.module.maasng:361 ][INFO    ][13945] cethd6
2019-06-18 03:25:08,819 [salt.loaded.ext.module.maasng:367 ][INFO    ][13945] [{u'size': 2397998940160, u'block_size': 4096, u'available_size': 0, u'uuid': None, u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'used_size': 2397998940160, u'id': 9, u'name': u'sda', u'system_id': u'cethd6', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/cethd6/blockdevices/9/', u'type': u'physical', u'model': u'UCSB-MRAID12G', u'partitions': [{u'size': 2397992648704, u'uuid': u'7e24528b-974e-4f4f-9d4e-890da9012601', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'cethd6', u'filesystem': {u'label': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'ca76114c-0cf8-430a-9609-129ec1a9e35f', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 9, u'type': u'partition', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/cethd6/blockdevices/9/partition/4'}]}, {u'size': 2397988454400, u'block_size': 4096, u'available_size': 0, u'uuid': u'cdaeced0-c6da-4fd9-9fac-9f5998576072', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 2397988454400, u'id': 12, u'name': u'vgroot-lvroot', u'system_id': u'cethd6', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'2cf05671-7963-4253-a537-f722cb2ec17a', u'mount_options': None}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/cethd6/blockdevices/12/', u'type': u'virtual', u'model': None, u'partitions': []}]
2019-06-18 03:25:08,819 [salt.loaded.ext.module.maasng:632 ][INFO    ][13945] vgroot
2019-06-18 03:25:08,819 [salt.loaded.ext.module.maasng:635 ][INFO    ][13945] lvroot
2019-06-18 03:25:08,820 [salt.loaded.ext.module.maasng:639 ][INFO    ][13945] 107374182400
2019-06-18 03:25:09,534 [salt.loaded.ext.module.maasng:645 ][INFO    ][13945] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'ip_addresses': [u'192.168.11.40'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'boot_interface': {u'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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'mmqacw', 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'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40', u'id': 41, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'mmqacw', 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'fabric': u'pxe_admin'}, u'enabled': True, u'effective_mtu': 1500, u'id': 21, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'mmqacw', 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'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40'}], u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:6a', u'system_id': u'cethd6', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/cethd6/interfaces/21/'}, u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'testing_status': 2, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'resource_uri': u'/MAAS/api/2.0/nodes/cethd6/blockdevices/9/', u'uuid': None, u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'1cb7dadb-b3e2-46e5-8b5f-17195953a0de', u'resource_uri': u'/MAAS/api/2.0/nodes/cethd6/blockdevices/9/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'cethd6', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'6475d1a1-f6d7-439c-b3aa-964ac6678293', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 6, u'device_id': 9}], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'system_id': u'cethd6', u'block_size': 4096, u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id': 9, u'available_size': 0, u'model': u'UCSB-MRAID12G', u'size': 2397998940160, u'type': u'physical', u'serial': u'618e728372755980239b15112698bc66', u'name': u'sda'}, u'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default'}, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'node_type': 0, u'owner_data': {}, u'system_id': u'cethd6', 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'disable_ipv4': False, u'blockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/cethd6/blockdevices/9/', u'uuid': None, u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'1cb7dadb-b3e2-46e5-8b5f-17195953a0de', u'resource_uri': u'/MAAS/api/2.0/nodes/cethd6/blockdevices/9/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'cethd6', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'6475d1a1-f6d7-439c-b3aa-964ac6678293', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 6, u'device_id': 9}], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'system_id': u'cethd6', u'block_size': 4096, u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id': 9, u'available_size': 0, u'model': u'UCSB-MRAID12G', u'size': 2397998940160, u'type': u'physical', u'serial': u'618e728372755980239b15112698bc66', u'name': u'sda'}, {u'resource_uri': u'/MAAS/api/2.0/nodes/cethd6/blockdevices/14/', u'uuid': u'e94255be-e758-49b2-b0b1-541266c38af7', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'a14f86c7-d5d3-4f03-b775-25edab718289', u'fstype': u'ext4', u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'id_path': None, u'system_id': u'cethd6', u'block_size': 4096, u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id': 14, u'available_size': 0, u'model': None, u'size': 107374182400, u'type': u'virtual', u'serial': None, u'name': u'vgroot-lvroot'}], u'status': 4, u'bcaches': [], u'cpu_count': 16, u'power_state': u'off', u'physicalblockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/cethd6/blockdevices/9/', u'uuid': None, u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'1cb7dadb-b3e2-46e5-8b5f-17195953a0de', u'resource_uri': u'/MAAS/api/2.0/nodes/cethd6/blockdevices/9/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'cethd6', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'6475d1a1-f6d7-439c-b3aa-964ac6678293', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 6, u'device_id': 9}], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'system_id': u'cethd6', u'block_size': 4096, u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id': 9, u'available_size': 0, u'model': u'UCSB-MRAID12G', u'size': 2397998940160, u'type': u'physical', u'serial': u'618e728372755980239b15112698bc66', u'name': u'sda'}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'cethd6', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 12, u'commissioning_status_name': u'Passed', 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'mmqacw', 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'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40', u'id': 41, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'mmqacw', 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'fabric': u'pxe_admin'}, u'enabled': True, u'effective_mtu': 1500, u'id': 21, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'mmqacw', 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'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40'}], u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:6a', u'system_id': u'cethd6', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/cethd6/interfaces/21/'}, {u'name': u'enp7s0', u'links': [{u'id': 42, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'effective_mtu': 1500, u'id': 27, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:6b', u'system_id': u'cethd6', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/cethd6/interfaces/27/'}, {u'name': u'enp9s0', u'links': [{u'id': 43, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'effective_mtu': 1500, u'id': 28, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:6d', u'system_id': u'cethd6', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/cethd6/interfaces/28/'}, {u'name': u'enp8s0', u'links': [{u'id': 44, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'effective_mtu': 1500, u'id': 29, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:6c', u'system_id': u'cethd6', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/cethd6/interfaces/29/'}], u'current_testing_result_id': 13, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/cethd6/blockdevices/14/', u'uuid': u'e94255be-e758-49b2-b0b1-541266c38af7', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'a14f86c7-d5d3-4f03-b775-25edab718289', u'fstype': u'ext4', u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'id_path': None, u'system_id': u'cethd6', u'block_size': 4096, u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id': 14, u'available_size': 0, u'model': None, u'size': 107374182400, u'type': u'virtual', u'serial': None, u'name': u'vgroot-lvroot'}], u'commissioning_status': 2, u'min_hwe_kernel': u'ga-16.04', u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/cethd6/'}
2019-06-18 03:25:09,536 [salt.state       :300 ][INFO    ][13945] {'new': {'storage_layout': 'lvm'}}
2019-06-18 03:25:09,536 [salt.state       :1951][INFO    ][13945] Completed state [maas_machines_storage_cmp002_lvm] at time 03:25:09.536888 duration_in_ms=2748.597
2019-06-18 03:25:09,537 [salt.state       :1780][INFO    ][13945] Running state [maas_machines_storage_cmp001_lvm] at time 03:25:09.537415
2019-06-18 03:25:09,537 [salt.state       :1813][INFO    ][13945] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-06-18 03:25:10,962 [salt.loaded.ext.module.maasng:610 ][INFO    ][13945] wsf6h8
2019-06-18 03:25:10,962 [salt.loaded.ext.module.maasng:626 ][INFO    ][13945] sda
2019-06-18 03:25:11,579 [salt.loaded.ext.module.maasng:361 ][INFO    ][13945] wsf6h8
2019-06-18 03:25:11,717 [salt.loaded.ext.module.maasng:367 ][INFO    ][13945] [{u'resource_uri': u'/MAAS/api/2.0/nodes/wsf6h8/blockdevices/8/', u'uuid': None, u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'127639aa-c9dd-46d6-85b5-2f52d0e01803', u'resource_uri': u'/MAAS/api/2.0/nodes/wsf6h8/blockdevices/8/partition/3', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'wsf6h8', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'806cfb80-f5ef-4fac-a4b5-ff2ad91e872f', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 3, u'device_id': 8}], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'system_id': u'wsf6h8', u'block_size': 4096, u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id': 8, u'available_size': 0, u'model': u'UCSB-MRAID12G', u'size': 2397998940160, u'type': u'physical', u'serial': u'618e72837274f1901cc7889705aa1b02', u'name': u'sda'}, {u'resource_uri': u'/MAAS/api/2.0/nodes/wsf6h8/blockdevices/11/', u'uuid': u'798aaf59-f068-49fc-84f8-e0cdcae59f41', u'tags': [], u'used_size': 2397988454400, u'partitions': [], u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'9b0ddd67-3f90-41d8-a89b-7b3db4bda463', u'fstype': u'ext4', u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'id_path': None, u'system_id': u'wsf6h8', u'block_size': 4096, u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id': 11, u'available_size': 0, u'model': None, u'size': 2397988454400, u'type': u'virtual', u'serial': None, u'name': u'vgroot-lvroot'}]
2019-06-18 03:25:11,718 [salt.loaded.ext.module.maasng:632 ][INFO    ][13945] vgroot
2019-06-18 03:25:11,719 [salt.loaded.ext.module.maasng:635 ][INFO    ][13945] lvroot
2019-06-18 03:25:11,719 [salt.loaded.ext.module.maasng:639 ][INFO    ][13945] 107374182400
2019-06-18 03:25:12,426 [salt.loaded.ext.module.maasng:645 ][INFO    ][13945] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'ip_addresses': [u'192.168.11.38'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'boot_interface': {u'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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'mmqacw', 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'fabric': u'pxe_admin'}, 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'id': 36, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'mmqacw', 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'fabric': u'pxe_admin'}, u'enabled': True, u'effective_mtu': 1500, u'id': 22, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'mmqacw', 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'fabric': u'pxe_admin'}, 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'mac_address': u'00:25:b5:a0:00:5a', u'system_id': u'wsf6h8', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/wsf6h8/interfaces/22/'}, u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'testing_status': 2, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'resource_uri': u'/MAAS/api/2.0/nodes/wsf6h8/blockdevices/8/', u'uuid': None, u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'd6cea993-c612-4943-b695-4c11ba71b231', u'resource_uri': u'/MAAS/api/2.0/nodes/wsf6h8/blockdevices/8/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'wsf6h8', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'660d5994-f5ee-412e-9b9c-1011004b3525', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 7, u'device_id': 8}], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'system_id': u'wsf6h8', u'block_size': 4096, u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id': 8, u'available_size': 0, u'model': u'UCSB-MRAID12G', u'size': 2397998940160, u'type': u'physical', u'serial': u'618e72837274f1901cc7889705aa1b02', u'name': u'sda'}, u'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default'}, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'node_type': 0, u'owner_data': {}, u'system_id': u'wsf6h8', 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'disable_ipv4': False, u'blockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/wsf6h8/blockdevices/8/', u'uuid': None, u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'd6cea993-c612-4943-b695-4c11ba71b231', u'resource_uri': u'/MAAS/api/2.0/nodes/wsf6h8/blockdevices/8/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'wsf6h8', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'660d5994-f5ee-412e-9b9c-1011004b3525', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 7, u'device_id': 8}], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'system_id': u'wsf6h8', u'block_size': 4096, u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id': 8, u'available_size': 0, u'model': u'UCSB-MRAID12G', u'size': 2397998940160, u'type': u'physical', u'serial': u'618e72837274f1901cc7889705aa1b02', u'name': u'sda'}, {u'resource_uri': u'/MAAS/api/2.0/nodes/wsf6h8/blockdevices/15/', u'uuid': u'6b1684ac-b869-4bf8-a55e-4100979cfd36', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'6e91c02e-b6ad-4e5a-8e60-e6ffb4bad00a', u'fstype': u'ext4', u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'id_path': None, u'system_id': u'wsf6h8', u'block_size': 4096, u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id': 15, u'available_size': 0, u'model': None, u'size': 107374182400, u'type': u'virtual', u'serial': None, u'name': u'vgroot-lvroot'}], u'status': 4, u'bcaches': [], u'cpu_count': 16, u'power_state': u'off', u'physicalblockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/wsf6h8/blockdevices/8/', u'uuid': None, u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'd6cea993-c612-4943-b695-4c11ba71b231', u'resource_uri': u'/MAAS/api/2.0/nodes/wsf6h8/blockdevices/8/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'wsf6h8', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'660d5994-f5ee-412e-9b9c-1011004b3525', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 7, u'device_id': 8}], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'system_id': u'wsf6h8', u'block_size': 4096, u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id': 8, u'available_size': 0, u'model': u'UCSB-MRAID12G', u'size': 2397998940160, u'type': u'physical', u'serial': u'618e72837274f1901cc7889705aa1b02', u'name': u'sda'}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'wsf6h8', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 14, u'commissioning_status_name': u'Passed', 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'mmqacw', 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'fabric': u'pxe_admin'}, 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'id': 36, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'mmqacw', 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'fabric': u'pxe_admin'}, u'enabled': True, u'effective_mtu': 1500, u'id': 22, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'mmqacw', 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'fabric': u'pxe_admin'}, 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'mac_address': u'00:25:b5:a0:00:5a', u'system_id': u'wsf6h8', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/wsf6h8/interfaces/22/'}, {u'name': u'enp9s0', u'links': [{u'id': 37, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'effective_mtu': 1500, u'id': 24, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:5d', u'system_id': u'wsf6h8', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/wsf6h8/interfaces/24/'}, {u'name': u'enp7s0', u'links': [{u'id': 38, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'effective_mtu': 1500, u'id': 25, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:5b', u'system_id': u'wsf6h8', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/wsf6h8/interfaces/25/'}, {u'name': u'enp8s0', u'links': [{u'id': 39, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'effective_mtu': 1500, u'id': 26, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:5c', u'system_id': u'wsf6h8', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/wsf6h8/interfaces/26/'}], u'current_testing_result_id': 15, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/wsf6h8/blockdevices/15/', u'uuid': u'6b1684ac-b869-4bf8-a55e-4100979cfd36', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'6e91c02e-b6ad-4e5a-8e60-e6ffb4bad00a', u'fstype': u'ext4', u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'id_path': None, u'system_id': u'wsf6h8', u'block_size': 4096, u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id': 15, u'available_size': 0, u'model': None, u'size': 107374182400, u'type': u'virtual', u'serial': None, u'name': u'vgroot-lvroot'}], u'commissioning_status': 2, u'min_hwe_kernel': u'ga-16.04', u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/wsf6h8/'}
2019-06-18 03:25:12,428 [salt.state       :300 ][INFO    ][13945] {'new': {'storage_layout': 'lvm'}}
2019-06-18 03:25:12,429 [salt.state       :1951][INFO    ][13945] Completed state [maas_machines_storage_cmp001_lvm] at time 03:25:12.429123 duration_in_ms=2891.707
2019-06-18 03:25:12,433 [salt.minion      :1711][INFO    ][13945] Returning information for job: 20190618032501522549
2019-06-18 03:25:13,057 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command state.apply with jid 20190618032513045114
2019-06-18 03:25:13,078 [salt.minion      :1432][INFO    ][14063] Starting a new job with PID 14063
2019-06-18 03:25:13,777 [salt.state       :915 ][INFO    ][14063] Loading fresh modules for state activity
2019-06-18 03:25:13,807 [salt.fileclient  :1219][INFO    ][14063] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-06-18 03:25:13,833 [salt.state       :1780][INFO    ][14063] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:25:13.833652
2019-06-18 03:25:13,833 [salt.state       :1813][INFO    ][14063] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-18 03:25:13,835 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14063] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-18 03:25:15,143 [salt.state       :300 ][INFO    ][14063] {'pid': 14070, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-18 03:25:15,144 [salt.state       :1951][INFO    ][14063] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:25:15.143992 duration_in_ms=1310.339
2019-06-18 03:25:15,145 [salt.state       :1780][INFO    ][14063] Running state [maas.deploy_machines] at time 03:25:15.145198
2019-06-18 03:25:15,145 [salt.state       :1813][INFO    ][14063] Executing state module.run for [maas.deploy_machines]
2019-06-18 03:25:15,146 [salt.utils.decorators:613 ][WARNING ][14063] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-18 03:25:15,774 [salt.loaded.ext.module.maas:684 ][INFO    ][14063] deploymachines hwe_kernel=ga-16.04 system_id=cethd6 distro_series=xenial
2019-06-18 03:25:18,569 [salt.loaded.ext.module.maas:684 ][INFO    ][14063] deploymachines hwe_kernel=ga-16.04 system_id=wsf6h8 distro_series=xenial
2019-06-18 03:25:21,204 [salt.loaded.ext.module.maas:684 ][INFO    ][14063] deploymachines hwe_kernel=ga-16.04 system_id=xwq3cf distro_series=xenial
2019-06-18 03:25:23,905 [salt.loaded.ext.module.maas:684 ][INFO    ][14063] deploymachines hwe_kernel=ga-16.04 system_id=xrynhb distro_series=xenial
2019-06-18 03:25:26,778 [salt.loaded.ext.module.maas:684 ][INFO    ][14063] deploymachines hwe_kernel=ga-16.04 system_id=4dscpf distro_series=xenial
2019-06-18 03:25:28,165 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618032528152971
2019-06-18 03:25:28,184 [salt.minion      :1432][INFO    ][14335] Starting a new job with PID 14335
2019-06-18 03:25:28,202 [salt.minion      :1711][INFO    ][14335] Returning information for job: 20190618032528152971
2019-06-18 03:25:29,355 [salt.state       :300 ][INFO    ][14063] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-06-18 03:25:29,355 [salt.state       :1951][INFO    ][14063] Completed state [maas.deploy_machines] at time 03:25:29.355864 duration_in_ms=14210.663
2019-06-18 03:25:29,359 [salt.minion      :1711][INFO    ][14063] Returning information for job: 20190618032513045114
2019-06-18 03:25:29,909 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command state.apply with jid 20190618032529895777
2019-06-18 03:25:29,928 [salt.minion      :1432][INFO    ][14352] Starting a new job with PID 14352
2019-06-18 03:25:33,587 [salt.state       :915 ][INFO    ][14352] Loading fresh modules for state activity
2019-06-18 03:25:33,638 [salt.fileclient  :1219][INFO    ][14352] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-06-18 03:25:33,683 [salt.state       :1780][INFO    ][14352] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:25:33.683155
2019-06-18 03:25:33,683 [salt.state       :1813][INFO    ][14352] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-18 03:25:33,685 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14352] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-18 03:25:35,117 [salt.state       :300 ][INFO    ][14352] {'pid': 14365, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-18 03:25:35,117 [salt.state       :1951][INFO    ][14352] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:25:35.117471 duration_in_ms=1434.317
2019-06-18 03:25:35,118 [salt.state       :1780][INFO    ][14352] Running state [maas.wait_for_machine_status] at time 03:25:35.118945
2019-06-18 03:25:35,119 [salt.state       :1813][INFO    ][14352] Executing state module.run for [maas.wait_for_machine_status]
2019-06-18 03:25:35,119 [salt.utils.decorators:613 ][WARNING ][14352] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-18 03:25:38,531 [salt.loaded.ext.module.maas:1023][INFO    ][14352] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.59253788s left)
2019-06-18 03:25:44,985 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618032544972234
2019-06-18 03:25:45,008 [salt.minion      :1432][INFO    ][14380] Starting a new job with PID 14380
2019-06-18 03:25:45,031 [salt.minion      :1711][INFO    ][14380] Returning information for job: 20190618032544972234
2019-06-18 03:26:12,074 [salt.loaded.ext.module.maas:1023][INFO    ][14352] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.04978085s left)
2019-06-18 03:26:15,033 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618032615020636
2019-06-18 03:26:15,056 [salt.minion      :1432][INFO    ][14435] Starting a new job with PID 14435
2019-06-18 03:26:15,080 [salt.minion      :1711][INFO    ][14435] Returning information for job: 20190618032615020636
2019-06-18 03:26:45,112 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618032645100816
2019-06-18 03:26:45,135 [salt.minion      :1432][INFO    ][14455] Starting a new job with PID 14455
2019-06-18 03:26:45,158 [salt.minion      :1711][INFO    ][14455] Returning information for job: 20190618032645100816
2019-06-18 03:26:45,760 [salt.loaded.ext.module.maas:1023][INFO    ][14352] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2179.36327386s left)
2019-06-18 03:27:15,155 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618032715143301
2019-06-18 03:27:15,177 [salt.minion      :1432][INFO    ][14606] Starting a new job with PID 14606
2019-06-18 03:27:15,201 [salt.minion      :1711][INFO    ][14606] Returning information for job: 20190618032715143301
2019-06-18 03:27:19,223 [salt.loaded.ext.module.maas:1023][INFO    ][14352] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2145.90049005s left)
2019-06-18 03:27:45,206 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618032745193753
2019-06-18 03:27:45,229 [salt.minion      :1432][INFO    ][14774] Starting a new job with PID 14774
2019-06-18 03:27:45,253 [salt.minion      :1711][INFO    ][14774] Returning information for job: 20190618032745193753
2019-06-18 03:27:52,612 [salt.loaded.ext.module.maas:1023][INFO    ][14352] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2112.51192093s left)
2019-06-18 03:28:15,268 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618032815255914
2019-06-18 03:28:15,286 [salt.minion      :1432][INFO    ][15575] Starting a new job with PID 15575
2019-06-18 03:28:15,298 [salt.minion      :1711][INFO    ][15575] Returning information for job: 20190618032815255914
2019-06-18 03:28:26,206 [salt.loaded.ext.module.maas:1023][INFO    ][14352] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2078.91725802s left)
2019-06-18 03:28:45,316 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618032845303193
2019-06-18 03:28:45,335 [salt.minion      :1432][INFO    ][15595] Starting a new job with PID 15595
2019-06-18 03:28:45,358 [salt.minion      :1711][INFO    ][15595] Returning information for job: 20190618032845303193
2019-06-18 03:29:00,010 [salt.loaded.ext.module.maas:1023][INFO    ][14352] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2045.11333895s left)
2019-06-18 03:29:15,374 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618032915361708
2019-06-18 03:29:15,397 [salt.minion      :1432][INFO    ][15737] Starting a new job with PID 15737
2019-06-18 03:29:15,421 [salt.minion      :1711][INFO    ][15737] Returning information for job: 20190618032915361708
2019-06-18 03:29:33,657 [salt.loaded.ext.module.maas:1023][INFO    ][14352] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2011.46619987s left)
2019-06-18 03:29:45,441 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618032945428396
2019-06-18 03:29:45,460 [salt.minion      :1432][INFO    ][15767] Starting a new job with PID 15767
2019-06-18 03:29:45,484 [salt.minion      :1711][INFO    ][15767] Returning information for job: 20190618032945428396
2019-06-18 03:30:06,214 [salt.loaded.ext.module.maas:1023][INFO    ][14352] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1978.90950894s left)
2019-06-18 03:30:15,514 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618033015501592
2019-06-18 03:30:15,536 [salt.minion      :1432][INFO    ][16050] Starting a new job with PID 16050
2019-06-18 03:30:15,560 [salt.minion      :1711][INFO    ][16050] Returning information for job: 20190618033015501592
2019-06-18 03:30:39,963 [salt.loaded.ext.module.maas:1023][INFO    ][14352] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1945.16006684s left)
2019-06-18 03:30:45,595 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618033045581897
2019-06-18 03:30:45,618 [salt.minion      :1432][INFO    ][16070] Starting a new job with PID 16070
2019-06-18 03:30:45,643 [salt.minion      :1711][INFO    ][16070] Returning information for job: 20190618033045581897
2019-06-18 03:31:13,335 [salt.loaded.ext.module.maas:1023][INFO    ][14352] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1911.78809905s left)
2019-06-18 03:31:15,673 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618033115660636
2019-06-18 03:31:15,695 [salt.minion      :1432][INFO    ][16424] Starting a new job with PID 16424
2019-06-18 03:31:15,718 [salt.minion      :1711][INFO    ][16424] Returning information for job: 20190618033115660636
2019-06-18 03:31:45,758 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618033145746814
2019-06-18 03:31:45,779 [salt.minion      :1432][INFO    ][16449] Starting a new job with PID 16449
2019-06-18 03:31:45,799 [salt.minion      :1711][INFO    ][16449] Returning information for job: 20190618033145746814
2019-06-18 03:31:46,827 [salt.loaded.ext.module.maas:1023][INFO    ][14352] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1878.29694605s left)
2019-06-18 03:32:15,841 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618033215828826
2019-06-18 03:32:15,864 [salt.minion      :1432][INFO    ][16908] Starting a new job with PID 16908
2019-06-18 03:32:15,888 [salt.minion      :1711][INFO    ][16908] Returning information for job: 20190618033215828826
2019-06-18 03:32:20,408 [salt.loaded.ext.module.maas:1023][INFO    ][14352] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1844.71500897s left)
2019-06-18 03:32:45,935 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618033245921853
2019-06-18 03:32:45,957 [salt.minion      :1432][INFO    ][16928] Starting a new job with PID 16928
2019-06-18 03:32:45,982 [salt.minion      :1711][INFO    ][16928] Returning information for job: 20190618033245921853
2019-06-18 03:32:53,781 [salt.loaded.ext.module.maas:1023][INFO    ][14352] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1811.34292388s left)
2019-06-18 03:33:16,039 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618033316027674
2019-06-18 03:33:16,064 [salt.minion      :1432][INFO    ][17001] Starting a new job with PID 17001
2019-06-18 03:33:16,087 [salt.minion      :1711][INFO    ][17001] Returning information for job: 20190618033316027674
2019-06-18 03:33:27,494 [salt.loaded.ext.module.maas:1023][INFO    ][14352] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1777.62904596s left)
2019-06-18 03:33:46,152 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618033346140515
2019-06-18 03:33:46,176 [salt.minion      :1432][INFO    ][17118] Starting a new job with PID 17118
2019-06-18 03:33:46,203 [salt.minion      :1711][INFO    ][17118] Returning information for job: 20190618033346140515
2019-06-18 03:34:00,566 [salt.loaded.ext.module.maas:1023][INFO    ][14352] Waiting status:Deployed for machines:['cmp002', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1744.55714703s left)
2019-06-18 03:34:16,252 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618033416240552
2019-06-18 03:34:16,276 [salt.minion      :1432][INFO    ][17464] Starting a new job with PID 17464
2019-06-18 03:34:16,301 [salt.minion      :1711][INFO    ][17464] Returning information for job: 20190618033416240552
2019-06-18 03:34:33,868 [salt.loaded.ext.module.maas:1023][INFO    ][14352] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1711.25558901s left)
2019-06-18 03:34:46,371 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618033446358282
2019-06-18 03:34:46,395 [salt.minion      :1432][INFO    ][17516] Starting a new job with PID 17516
2019-06-18 03:34:46,424 [salt.minion      :1711][INFO    ][17516] Returning information for job: 20190618033446358282
2019-06-18 03:35:06,399 [salt.loaded.ext.module.maas:1023][INFO    ][14352] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1678.72460985s left)
2019-06-18 03:35:16,497 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618033516484976
2019-06-18 03:35:16,521 [salt.minion      :1432][INFO    ][17886] Starting a new job with PID 17886
2019-06-18 03:35:16,548 [salt.minion      :1711][INFO    ][17886] Returning information for job: 20190618033516484976
2019-06-18 03:35:39,933 [salt.loaded.ext.module.maas:1023][INFO    ][14352] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1645.19010687s left)
2019-06-18 03:35:46,638 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618033546626251
2019-06-18 03:35:46,661 [salt.minion      :1432][INFO    ][17903] Starting a new job with PID 17903
2019-06-18 03:35:46,687 [salt.minion      :1711][INFO    ][17903] Returning information for job: 20190618033546626251
2019-06-18 03:36:12,807 [salt.loaded.ext.module.maas:1023][INFO    ][14352] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1612.31644583s left)
2019-06-18 03:36:16,783 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618033616770612
2019-06-18 03:36:16,806 [salt.minion      :1432][INFO    ][17957] Starting a new job with PID 17957
2019-06-18 03:36:16,831 [salt.minion      :1711][INFO    ][17957] Returning information for job: 20190618033616770612
2019-06-18 03:36:46,551 [salt.loaded.ext.module.maas:1023][INFO    ][14352] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1578.57224202s left)
2019-06-18 03:36:46,934 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618033646922118
2019-06-18 03:36:46,956 [salt.minion      :1432][INFO    ][17976] Starting a new job with PID 17976
2019-06-18 03:36:46,984 [salt.minion      :1711][INFO    ][17976] Returning information for job: 20190618033646922118
2019-06-18 03:37:17,093 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618033717081409
2019-06-18 03:37:17,111 [salt.minion      :1432][INFO    ][18028] Starting a new job with PID 18028
2019-06-18 03:37:17,141 [salt.minion      :1711][INFO    ][18028] Returning information for job: 20190618033717081409
2019-06-18 03:37:19,594 [salt.loaded.ext.module.maas:1023][INFO    ][14352] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1545.53055286s left)
2019-06-18 03:37:47,263 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618033747251162
2019-06-18 03:37:47,287 [salt.minion      :1432][INFO    ][18046] Starting a new job with PID 18046
2019-06-18 03:37:47,313 [salt.minion      :1711][INFO    ][18046] Returning information for job: 20190618033747251162
2019-06-18 03:37:53,162 [salt.loaded.ext.module.maas:1023][INFO    ][14352] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1511.96171904s left)
2019-06-18 03:38:17,452 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618033817439551
2019-06-18 03:38:17,475 [salt.minion      :1432][INFO    ][18234] Starting a new job with PID 18234
2019-06-18 03:38:17,501 [salt.minion      :1711][INFO    ][18234] Returning information for job: 20190618033817439551
2019-06-18 03:38:26,696 [salt.loaded.ext.module.maas:1023][INFO    ][14352] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1478.42716193s left)
2019-06-18 03:38:47,650 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618033847638004
2019-06-18 03:38:47,673 [salt.minion      :1432][INFO    ][18258] Starting a new job with PID 18258
2019-06-18 03:38:47,699 [salt.minion      :1711][INFO    ][18258] Returning information for job: 20190618033847638004
2019-06-18 03:39:00,133 [salt.loaded.ext.module.maas:1023][INFO    ][14352] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1444.99030495s left)
2019-06-18 03:39:17,853 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618033917841663
2019-06-18 03:39:17,877 [salt.minion      :1432][INFO    ][18314] Starting a new job with PID 18314
2019-06-18 03:39:17,903 [salt.minion      :1711][INFO    ][18314] Returning information for job: 20190618033917841663
2019-06-18 03:39:33,829 [salt.loaded.ext.module.maas:1023][INFO    ][14352] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1411.294806s left)
2019-06-18 03:39:48,064 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618033948052126
2019-06-18 03:39:48,086 [salt.minion      :1432][INFO    ][18334] Starting a new job with PID 18334
2019-06-18 03:39:48,114 [salt.minion      :1711][INFO    ][18334] Returning information for job: 20190618033948052126
2019-06-18 03:40:07,390 [salt.loaded.ext.module.maas:1023][INFO    ][14352] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1377.73315883s left)
2019-06-18 03:40:18,083 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618034018070323
2019-06-18 03:40:18,107 [salt.minion      :1432][INFO    ][18392] Starting a new job with PID 18392
2019-06-18 03:40:18,134 [salt.minion      :1711][INFO    ][18392] Returning information for job: 20190618034018070323
2019-06-18 03:40:41,000 [salt.loaded.ext.module.maas:1023][INFO    ][14352] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1344.12340188s left)
2019-06-18 03:40:48,113 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618034048101009
2019-06-18 03:40:48,136 [salt.minion      :1432][INFO    ][18412] Starting a new job with PID 18412
2019-06-18 03:40:48,161 [salt.minion      :1711][INFO    ][18412] Returning information for job: 20190618034048101009
2019-06-18 03:41:14,219 [salt.loaded.ext.module.maas:993 ][INFO    ][14352] Machine 4dscpf mark broken
2019-06-18 03:41:15,009 [salt.loaded.ext.module.maas:996 ][INFO    ][14352] Machine 4dscpf mark fixed
2019-06-18 03:41:16,145 [salt.loaded.ext.module.maas:684 ][INFO    ][14352] deploymachines hwe_kernel=ga-16.04 system_id=4dscpf distro_series=xenial
2019-06-18 03:41:18,144 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618034118131896
2019-06-18 03:41:18,166 [salt.minion      :1432][INFO    ][18520] Starting a new job with PID 18520
2019-06-18 03:41:18,191 [salt.minion      :1711][INFO    ][18520] Returning information for job: 20190618034118131896
2019-06-18 03:41:18,618 [salt.loaded.ext.module.maas:160 ][ERROR   ][14352] Failed for object kvm02 reason Unable to change power state to 'cycle' for node kvm02: another action is already in progress for that node.
2019-06-18 03:41:18,620 [salt.state       :302 ][ERROR   ][14352] Module function maas.wait_for_machine_status threw an exception. Exception: {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {'kvm02': "Unable to change power state to 'cycle' for node kvm02: another action is already in progress for that node."}, 'success': []}
2019-06-18 03:41:18,620 [salt.state       :1951][INFO    ][14352] Completed state [maas.wait_for_machine_status] at time 03:41:18.620403 duration_in_ms=943501.451
2019-06-18 03:41:18,626 [salt.minion      :1711][INFO    ][14352] Returning information for job: 20190618032529895777
2019-06-18 03:41:29,336 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command pillar.get with jid 20190618034129323913
2019-06-18 03:41:29,356 [salt.minion      :1432][INFO    ][18551] Starting a new job with PID 18551
2019-06-18 03:41:29,360 [salt.minion      :1711][INFO    ][18551] Returning information for job: 20190618034129323913
2019-06-18 03:41:29,854 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command service.status with jid 20190618034129842915
2019-06-18 03:41:29,876 [salt.minion      :1432][INFO    ][18556] Starting a new job with PID 18556
2019-06-18 03:41:30,277 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][18556] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-06-18 03:41:30,312 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][18556] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-06-18 03:41:30,329 [salt.minion      :1711][INFO    ][18556] Returning information for job: 20190618034129842915
2019-06-18 03:41:30,814 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command state.apply with jid 20190618034130803355
2019-06-18 03:41:30,831 [salt.minion      :1432][INFO    ][18567] Starting a new job with PID 18567
2019-06-18 03:41:34,343 [salt.state       :915 ][INFO    ][18567] Loading fresh modules for state activity
2019-06-18 03:41:34,764 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18567] Executing command 'salt-minion --version' in directory '/root'
2019-06-18 03:41:35,138 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18567] Executing command 'salt-minion --version' in directory '/root'
2019-06-18 03:41:36,023 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18567] Executing command 'salt-minion --version' in directory '/root'
2019-06-18 03:41:36,399 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18567] Executing command 'salt-minion --version' in directory '/root'
2019-06-18 03:41:37,743 [salt.state       :1780][INFO    ][18567] Running state [salt-minion] at time 03:41:37.742950
2019-06-18 03:41:37,743 [salt.state       :1813][INFO    ][18567] Executing state pkg.installed for [salt-minion]
2019-06-18 03:41:37,743 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18567] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-18 03:41:37,821 [salt.state       :300 ][INFO    ][18567] All specified packages are already installed
2019-06-18 03:41:37,821 [salt.state       :1951][INFO    ][18567] Completed state [salt-minion] at time 03:41:37.821513 duration_in_ms=78.563
2019-06-18 03:41:37,821 [salt.state       :1780][INFO    ][18567] Running state [salt_minion_dependency_packages] at time 03:41:37.821808
2019-06-18 03:41:37,822 [salt.state       :1813][INFO    ][18567] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-06-18 03:41:37,827 [salt.state       :300 ][INFO    ][18567] All specified packages are already installed
2019-06-18 03:41:37,827 [salt.state       :1951][INFO    ][18567] Completed state [salt_minion_dependency_packages] at time 03:41:37.827565 duration_in_ms=5.757
2019-06-18 03:41:37,830 [salt.state       :1780][INFO    ][18567] Running state [/etc/salt/minion.d/minion.conf] at time 03:41:37.830163
2019-06-18 03:41:37,830 [salt.state       :1813][INFO    ][18567] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-06-18 03:41:38,031 [salt.state       :300 ][INFO    ][18567] File /etc/salt/minion.d/minion.conf is in the correct state
2019-06-18 03:41:38,032 [salt.state       :1951][INFO    ][18567] Completed state [/etc/salt/minion.d/minion.conf] at time 03:41:38.032019 duration_in_ms=201.856
2019-06-18 03:41:38,032 [salt.state       :1780][INFO    ][18567] Running state [python-netaddr] at time 03:41:38.032346
2019-06-18 03:41:38,032 [salt.state       :1813][INFO    ][18567] Executing state pkg.installed for [python-netaddr]
2019-06-18 03:41:38,040 [salt.state       :300 ][INFO    ][18567] All specified packages are already installed
2019-06-18 03:41:38,040 [salt.state       :1951][INFO    ][18567] Completed state [python-netaddr] at time 03:41:38.040279 duration_in_ms=7.933
2019-06-18 03:41:38,043 [salt.state       :1780][INFO    ][18567] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 03:41:38.043649
2019-06-18 03:41:38,043 [salt.state       :1813][INFO    ][18567] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-06-18 03:41:38,054 [salt.state       :300 ][INFO    ][18567] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-06-18 03:41:38,054 [salt.state       :1951][INFO    ][18567] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 03:41:38.054361 duration_in_ms=10.712
2019-06-18 03:41:38,055 [salt.state       :1780][INFO    ][18567] Running state [salt-minion] at time 03:41:38.055269
2019-06-18 03:41:38,055 [salt.state       :1813][INFO    ][18567] Executing state service.running for [salt-minion]
2019-06-18 03:41:38,056 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18567] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-06-18 03:41:38,092 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18567] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-06-18 03:41:38,109 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18567] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-06-18 03:41:38,126 [salt.state       :300 ][INFO    ][18567] The service salt-minion is already running
2019-06-18 03:41:38,126 [salt.state       :1951][INFO    ][18567] Completed state [salt-minion] at time 03:41:38.126732 duration_in_ms=71.462
2019-06-18 03:41:38,128 [salt.state       :1780][INFO    ][18567] Running state [/etc/salt/grains.d] at time 03:41:38.128238
2019-06-18 03:41:38,128 [salt.state       :1813][INFO    ][18567] Executing state file.directory for [/etc/salt/grains.d]
2019-06-18 03:41:38,129 [salt.state       :300 ][INFO    ][18567] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-06-18 03:41:38,129 [salt.state       :1951][INFO    ][18567] Completed state [/etc/salt/grains.d] at time 03:41:38.129795 duration_in_ms=1.557
2019-06-18 03:41:38,130 [salt.state       :1780][INFO    ][18567] Running state [/etc/salt/grains] at time 03:41:38.130515
2019-06-18 03:41:38,130 [salt.state       :1813][INFO    ][18567] Executing state file.managed for [/etc/salt/grains]
2019-06-18 03:41:38,131 [salt.state       :300 ][INFO    ][18567] File /etc/salt/grains exists with proper permissions. No changes made.
2019-06-18 03:41:38,131 [salt.state       :1951][INFO    ][18567] Completed state [/etc/salt/grains] at time 03:41:38.131648 duration_in_ms=1.132
2019-06-18 03:41:38,132 [salt.state       :1780][INFO    ][18567] Running state [/etc/salt/grains.d/placeholder] at time 03:41:38.132122
2019-06-18 03:41:38,132 [salt.state       :1813][INFO    ][18567] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-06-18 03:41:38,133 [salt.state       :300 ][INFO    ][18567] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-06-18 03:41:38,133 [salt.state       :1951][INFO    ][18567] Completed state [/etc/salt/grains.d/placeholder] at time 03:41:38.133194 duration_in_ms=1.072
2019-06-18 03:41:38,133 [salt.state       :1780][INFO    ][18567] Running state [/etc/salt/grains.d/sphinx] at time 03:41:38.133682
2019-06-18 03:41:38,134 [salt.state       :1813][INFO    ][18567] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-06-18 03:41:38,145 [salt.state       :300 ][INFO    ][18567] File /etc/salt/grains.d/sphinx is in the correct state
2019-06-18 03:41:38,145 [salt.state       :1951][INFO    ][18567] Completed state [/etc/salt/grains.d/sphinx] at time 03:41:38.145858 duration_in_ms=12.176
2019-06-18 03:41:38,147 [salt.state       :1780][INFO    ][18567] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 03:41:38.147731
2019-06-18 03:41:38,148 [salt.state       :1813][INFO    ][18567] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-06-18 03:41:38,148 [salt.state       :300 ][INFO    ][18567] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-06-18 03:41:38,148 [salt.state       :1951][INFO    ][18567] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 03:41:38.148493 duration_in_ms=0.762
2019-06-18 03:41:38,149 [salt.state       :1780][INFO    ][18567] Running state [/etc/salt/grains.d/dns_records] at time 03:41:38.148970
2019-06-18 03:41:38,149 [salt.state       :1813][INFO    ][18567] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-06-18 03:41:38,163 [salt.state       :300 ][INFO    ][18567] File /etc/salt/grains.d/dns_records is in the correct state
2019-06-18 03:41:38,163 [salt.state       :1951][INFO    ][18567] Completed state [/etc/salt/grains.d/dns_records] at time 03:41:38.163747 duration_in_ms=14.777
2019-06-18 03:41:38,164 [salt.state       :1780][INFO    ][18567] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 03:41:38.164605
2019-06-18 03:41:38,164 [salt.state       :1813][INFO    ][18567] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-06-18 03:41:38,165 [salt.state       :300 ][INFO    ][18567] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-06-18 03:41:38,165 [salt.state       :1951][INFO    ][18567] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 03:41:38.165418 duration_in_ms=0.813
2019-06-18 03:41:38,165 [salt.state       :1780][INFO    ][18567] Running state [/etc/salt/grains.d/salt] at time 03:41:38.165900
2019-06-18 03:41:38,166 [salt.state       :1813][INFO    ][18567] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-06-18 03:41:38,181 [salt.state       :300 ][INFO    ][18567] File /etc/salt/grains.d/salt is in the correct state
2019-06-18 03:41:38,181 [salt.state       :1951][INFO    ][18567] Completed state [/etc/salt/grains.d/salt] at time 03:41:38.181584 duration_in_ms=15.684
2019-06-18 03:41:38,182 [salt.state       :1780][INFO    ][18567] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 03:41:38.182393
2019-06-18 03:41:38,182 [salt.state       :1813][INFO    ][18567] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-06-18 03:41:38,183 [salt.state       :300 ][INFO    ][18567] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-06-18 03:41:38,183 [salt.state       :1951][INFO    ][18567] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 03:41:38.183227 duration_in_ms=0.834
2019-06-18 03:41:38,184 [salt.state       :1780][INFO    ][18567] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 03:41:38.184872
2019-06-18 03:41:38,185 [salt.state       :1813][INFO    ][18567] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-06-18 03:41:38,185 [salt.state       :300 ][INFO    ][18567] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-06-18 03:41:38,185 [salt.state       :1951][INFO    ][18567] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 03:41:38.185703 duration_in_ms=0.831
2019-06-18 03:41:38,186 [salt.state       :1780][INFO    ][18567] Running state [mine.update] at time 03:41:38.186334
2019-06-18 03:41:38,186 [salt.state       :1813][INFO    ][18567] Executing state module.wait for [mine.update]
2019-06-18 03:41:38,186 [salt.state       :300 ][INFO    ][18567] No changes made for mine.update
2019-06-18 03:41:38,187 [salt.state       :1951][INFO    ][18567] Completed state [mine.update] at time 03:41:38.187125 duration_in_ms=0.791
2019-06-18 03:41:38,187 [salt.state       :1780][INFO    ][18567] Running state [ca-certificates] at time 03:41:38.187357
2019-06-18 03:41:38,187 [salt.state       :1813][INFO    ][18567] Executing state pkg.installed for [ca-certificates]
2019-06-18 03:41:38,193 [salt.state       :300 ][INFO    ][18567] All specified packages are already installed
2019-06-18 03:41:38,194 [salt.state       :1951][INFO    ][18567] Completed state [ca-certificates] at time 03:41:38.194081 duration_in_ms=6.724
2019-06-18 03:41:38,194 [salt.state       :1780][INFO    ][18567] Running state [update-ca-certificates] at time 03:41:38.194749
2019-06-18 03:41:38,195 [salt.state       :1813][INFO    ][18567] Executing state cmd.wait for [update-ca-certificates]
2019-06-18 03:41:38,195 [salt.state       :300 ][INFO    ][18567] No changes made for update-ca-certificates
2019-06-18 03:41:38,195 [salt.state       :1951][INFO    ][18567] Completed state [update-ca-certificates] at time 03:41:38.195523 duration_in_ms=0.773
2019-06-18 03:41:38,195 [salt.state       :1780][INFO    ][18567] Running state [iptables] at time 03:41:38.195749
2019-06-18 03:41:38,196 [salt.state       :1813][INFO    ][18567] Executing state pkg.installed for [iptables]
2019-06-18 03:41:38,201 [salt.state       :300 ][INFO    ][18567] All specified packages are already installed
2019-06-18 03:41:38,201 [salt.state       :1951][INFO    ][18567] Completed state [iptables] at time 03:41:38.201827 duration_in_ms=6.078
2019-06-18 03:41:38,202 [salt.state       :1780][INFO    ][18567] Running state [iptables-persistent] at time 03:41:38.202068
2019-06-18 03:41:38,202 [salt.state       :1813][INFO    ][18567] Executing state pkg.installed for [iptables-persistent]
2019-06-18 03:41:38,207 [salt.state       :300 ][INFO    ][18567] All specified packages are already installed
2019-06-18 03:41:38,208 [salt.state       :1951][INFO    ][18567] Completed state [iptables-persistent] at time 03:41:38.208011 duration_in_ms=5.942
2019-06-18 03:41:38,208 [salt.state       :1780][INFO    ][18567] Running state [iptables_modules_v4_load] at time 03:41:38.208904
2019-06-18 03:41:38,209 [salt.state       :1813][INFO    ][18567] Executing state kmod.present for [iptables_modules_v4_load]
2019-06-18 03:41:38,209 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18567] Executing command 'lsmod' in directory '/root'
2019-06-18 03:41:38,233 [salt.state       :300 ][INFO    ][18567] Kernel modules iptable_filter, ip_tables are already present
2019-06-18 03:41:38,233 [salt.state       :1951][INFO    ][18567] Completed state [iptables_modules_v4_load] at time 03:41:38.233810 duration_in_ms=24.905
2019-06-18 03:41:38,234 [salt.state       :1780][INFO    ][18567] Running state [/etc/iptables/rules.v4] at time 03:41:38.234443
2019-06-18 03:41:38,234 [salt.state       :1813][INFO    ][18567] Executing state file.managed for [/etc/iptables/rules.v4]
2019-06-18 03:41:38,319 [salt.state       :300 ][INFO    ][18567] File /etc/iptables/rules.v4 is in the correct state
2019-06-18 03:41:38,319 [salt.state       :1951][INFO    ][18567] Completed state [/etc/iptables/rules.v4] at time 03:41:38.319452 duration_in_ms=85.009
2019-06-18 03:41:38,320 [salt.state       :1780][INFO    ][18567] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 03:41:38.320311
2019-06-18 03:41:38,320 [salt.state       :1813][INFO    ][18567] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-06-18 03:41:38,321 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18567] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-06-18 03:41:38,340 [salt.state       :300 ][INFO    ][18567] onlyif execution failed
2019-06-18 03:41:38,341 [salt.state       :1951][INFO    ][18567] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 03:41:38.341218 duration_in_ms=20.906
2019-06-18 03:41:38,342 [salt.state       :1780][INFO    ][18567] Running state [netfilter-persistent] at time 03:41:38.342019
2019-06-18 03:41:38,342 [salt.state       :1813][INFO    ][18567] Executing state service.running for [netfilter-persistent]
2019-06-18 03:41:38,342 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18567] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-06-18 03:41:38,361 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18567] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-06-18 03:41:38,378 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18567] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-06-18 03:41:38,394 [salt.state       :300 ][INFO    ][18567] The service netfilter-persistent is already running
2019-06-18 03:41:38,394 [salt.state       :1951][INFO    ][18567] Completed state [netfilter-persistent] at time 03:41:38.394377 duration_in_ms=52.358
2019-06-18 03:41:38,395 [salt.state       :1780][INFO    ][18567] Running state [iptables_extra.remove_stale_tables] at time 03:41:38.395153
2019-06-18 03:41:38,395 [salt.state       :1813][INFO    ][18567] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-06-18 03:41:38,395 [salt.state       :300 ][INFO    ][18567] No changes made for iptables_extra.remove_stale_tables
2019-06-18 03:41:38,396 [salt.state       :1951][INFO    ][18567] Completed state [iptables_extra.remove_stale_tables] at time 03:41:38.396044 duration_in_ms=0.891
2019-06-18 03:41:38,396 [salt.state       :1780][INFO    ][18567] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 03:41:38.396293
2019-06-18 03:41:38,396 [salt.state       :1813][INFO    ][18567] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-06-18 03:41:38,397 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18567] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-06-18 03:41:38,410 [salt.state       :300 ][INFO    ][18567] onlyif execution failed
2019-06-18 03:41:38,411 [salt.state       :1951][INFO    ][18567] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 03:41:38.411099 duration_in_ms=14.806
2019-06-18 03:41:38,411 [salt.state       :1780][INFO    ][18567] Running state [/etc/iptables/rules.v6] at time 03:41:38.411888
2019-06-18 03:41:38,412 [salt.state       :1813][INFO    ][18567] Executing state file.absent for [/etc/iptables/rules.v6]
2019-06-18 03:41:38,412 [salt.state       :300 ][INFO    ][18567] File /etc/iptables/rules.v6 is not present
2019-06-18 03:41:38,412 [salt.state       :1951][INFO    ][18567] Completed state [/etc/iptables/rules.v6] at time 03:41:38.412775 duration_in_ms=0.887
2019-06-18 03:41:38,413 [salt.state       :1780][INFO    ][18567] Running state [iptables_extra.flush_all] at time 03:41:38.413312
2019-06-18 03:41:38,413 [salt.state       :1813][INFO    ][18567] Executing state module.wait for [iptables_extra.flush_all]
2019-06-18 03:41:38,413 [salt.state       :300 ][INFO    ][18567] No changes made for iptables_extra.flush_all
2019-06-18 03:41:38,414 [salt.state       :1951][INFO    ][18567] Completed state [iptables_extra.flush_all] at time 03:41:38.414011 duration_in_ms=0.699
2019-06-18 03:41:38,416 [salt.minion      :1711][INFO    ][18567] Returning information for job: 20190618034130803355
2019-06-18 03:41:39,006 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command state.apply with jid 20190618034138994636
2019-06-18 03:41:39,026 [salt.minion      :1432][INFO    ][18643] Starting a new job with PID 18643
2019-06-18 03:41:39,725 [salt.state       :915 ][INFO    ][18643] Loading fresh modules for state activity
2019-06-18 03:41:40,333 [salt.state       :1780][INFO    ][18643] Running state [maas-rack-controller] at time 03:41:40.333630
2019-06-18 03:41:40,333 [salt.state       :1813][INFO    ][18643] Executing state pkg.installed for [maas-rack-controller]
2019-06-18 03:41:40,334 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18643] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-18 03:41:40,432 [salt.state       :300 ][INFO    ][18643] All specified packages are already installed
2019-06-18 03:41:40,432 [salt.state       :1951][INFO    ][18643] Completed state [maas-rack-controller] at time 03:41:40.432529 duration_in_ms=98.898
2019-06-18 03:41:40,432 [salt.state       :1780][INFO    ][18643] Running state [ipmitool] at time 03:41:40.432929
2019-06-18 03:41:40,433 [salt.state       :1813][INFO    ][18643] Executing state pkg.installed for [ipmitool]
2019-06-18 03:41:40,440 [salt.state       :300 ][INFO    ][18643] All specified packages are already installed
2019-06-18 03:41:40,440 [salt.state       :1951][INFO    ][18643] Completed state [ipmitool] at time 03:41:40.440796 duration_in_ms=7.867
2019-06-18 03:41:40,444 [salt.state       :1780][INFO    ][18643] Running state [/etc/maas/rackd.conf] at time 03:41:40.444276
2019-06-18 03:41:40,444 [salt.state       :1813][INFO    ][18643] Executing state file.line for [/etc/maas/rackd.conf]
2019-06-18 03:41:40,445 [salt.state       :300 ][INFO    ][18643] No changes needed to be made
2019-06-18 03:41:40,445 [salt.state       :1951][INFO    ][18643] Completed state [/etc/maas/rackd.conf] at time 03:41:40.445819 duration_in_ms=1.543
2019-06-18 03:41:40,446 [salt.state       :1780][INFO    ][18643] Running state [/etc/maas/rackd.conf] at time 03:41:40.446076
2019-06-18 03:41:40,446 [salt.state       :1813][INFO    ][18643] Executing state file.managed for [/etc/maas/rackd.conf]
2019-06-18 03:41:40,446 [salt.loaded.int.states.file:2298][WARNING ][18643] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-06-18 03:41:40,447 [salt.state       :300 ][INFO    ][18643] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-06-18 03:41:40,447 [salt.state       :1951][INFO    ][18643] Completed state [/etc/maas/rackd.conf] at time 03:41:40.447728 duration_in_ms=1.652
2019-06-18 03:41:40,448 [salt.state       :1780][INFO    ][18643] Running state [maas-rackd] at time 03:41:40.448798
2019-06-18 03:41:40,449 [salt.state       :1813][INFO    ][18643] Executing state service.running for [maas-rackd]
2019-06-18 03:41:40,449 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18643] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-06-18 03:41:40,485 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18643] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-06-18 03:41:40,502 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18643] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-06-18 03:41:40,519 [salt.state       :300 ][INFO    ][18643] The service maas-rackd is already running
2019-06-18 03:41:40,519 [salt.state       :1951][INFO    ][18643] Completed state [maas-rackd] at time 03:41:40.519785 duration_in_ms=70.987
2019-06-18 03:41:40,521 [salt.minion      :1711][INFO    ][18643] Returning information for job: 20190618034138994636
2019-06-18 03:41:41,072 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command state.apply with jid 20190618034141059383
2019-06-18 03:41:41,092 [salt.minion      :1432][INFO    ][18666] Starting a new job with PID 18666
2019-06-18 03:41:41,877 [salt.state       :915 ][INFO    ][18666] Loading fresh modules for state activity
2019-06-18 03:41:42,566 [salt.state       :1780][INFO    ][18666] Running state [maas-region-controller] at time 03:41:42.566783
2019-06-18 03:41:42,567 [salt.state       :1813][INFO    ][18666] Executing state pkg.installed for [maas-region-controller]
2019-06-18 03:41:42,567 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18666] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-18 03:41:42,650 [salt.state       :300 ][INFO    ][18666] All specified packages are already installed
2019-06-18 03:41:42,651 [salt.state       :1951][INFO    ][18666] Completed state [maas-region-controller] at time 03:41:42.650950 duration_in_ms=84.167
2019-06-18 03:41:42,651 [salt.state       :1780][INFO    ][18666] Running state [python-oauth] at time 03:41:42.651253
2019-06-18 03:41:42,651 [salt.state       :1813][INFO    ][18666] Executing state pkg.installed for [python-oauth]
2019-06-18 03:41:42,657 [salt.state       :300 ][INFO    ][18666] All specified packages are already installed
2019-06-18 03:41:42,657 [salt.state       :1951][INFO    ][18666] Completed state [python-oauth] at time 03:41:42.657636 duration_in_ms=6.383
2019-06-18 03:41:42,660 [salt.state       :1780][INFO    ][18666] Running state [/etc/maas/regiond.conf] at time 03:41:42.660618
2019-06-18 03:41:42,660 [salt.state       :1813][INFO    ][18666] Executing state file.replace for [/etc/maas/regiond.conf]
2019-06-18 03:41:42,709 [salt.state       :300 ][INFO    ][18666] No changes needed to be made
2019-06-18 03:41:42,709 [salt.state       :1951][INFO    ][18666] Completed state [/etc/maas/regiond.conf] at time 03:41:42.709893 duration_in_ms=49.275
2019-06-18 03:41:42,710 [salt.state       :1780][INFO    ][18666] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 03:41:42.710388
2019-06-18 03:41:42,710 [salt.state       :1813][INFO    ][18666] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-06-18 03:41:42,774 [salt.state       :300 ][INFO    ][18666] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-06-18 03:41:42,775 [salt.state       :1951][INFO    ][18666] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 03:41:42.774953 duration_in_ms=64.565
2019-06-18 03:41:42,775 [salt.state       :1780][INFO    ][18666] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 03:41:42.775471
2019-06-18 03:41:42,775 [salt.state       :1813][INFO    ][18666] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-06-18 03:41:42,788 [salt.state       :300 ][INFO    ][18666] No changes needed to be made
2019-06-18 03:41:42,788 [salt.state       :1951][INFO    ][18666] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 03:41:42.788509 duration_in_ms=13.038
2019-06-18 03:41:42,789 [salt.state       :1780][INFO    ][18666] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 03:41:42.789041
2019-06-18 03:41:42,789 [salt.state       :1813][INFO    ][18666] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-06-18 03:41:42,818 [salt.state       :300 ][INFO    ][18666] No changes needed to be made
2019-06-18 03:41:42,818 [salt.state       :1951][INFO    ][18666] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 03:41:42.818360 duration_in_ms=29.319
2019-06-18 03:41:42,818 [salt.state       :1780][INFO    ][18666] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 03:41:42.818907
2019-06-18 03:41:42,819 [salt.state       :1813][INFO    ][18666] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-06-18 03:41:42,844 [salt.state       :300 ][INFO    ][18666] No changes needed to be made
2019-06-18 03:41:42,844 [salt.state       :1951][INFO    ][18666] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 03:41:42.844308 duration_in_ms=25.401
2019-06-18 03:41:42,844 [salt.state       :1780][INFO    ][18666] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 03:41:42.844808
2019-06-18 03:41:42,845 [salt.state       :1813][INFO    ][18666] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-06-18 03:41:42,857 [salt.state       :300 ][INFO    ][18666] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-06-18 03:41:42,857 [salt.state       :1951][INFO    ][18666] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 03:41:42.857389 duration_in_ms=12.581
2019-06-18 03:41:42,858 [salt.state       :1780][INFO    ][18666] Running state [a2enmod headers] at time 03:41:42.858649
2019-06-18 03:41:42,858 [salt.state       :1813][INFO    ][18666] Executing state cmd.run for [a2enmod headers]
2019-06-18 03:41:42,859 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18666] Executing command 'a2enmod headers' in directory '/root'
2019-06-18 03:41:42,934 [salt.state       :300 ][INFO    ][18666] {'pid': 18686, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-06-18 03:41:42,935 [salt.state       :1951][INFO    ][18666] Completed state [a2enmod headers] at time 03:41:42.934988 duration_in_ms=76.338
2019-06-18 03:41:42,935 [salt.state       :1780][INFO    ][18666] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 03:41:42.935620
2019-06-18 03:41:42,936 [salt.state       :1813][INFO    ][18666] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-06-18 03:41:42,955 [salt.state       :300 ][INFO    ][18666] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-06-18 03:41:42,956 [salt.state       :1951][INFO    ][18666] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 03:41:42.956193 duration_in_ms=20.574
2019-06-18 03:41:42,957 [salt.state       :1780][INFO    ][18666] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 03:41:42.957111
2019-06-18 03:41:42,957 [salt.state       :1813][INFO    ][18666] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-06-18 03:41:43,051 [salt.state       :300 ][INFO    ][18666] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-06-18 03:41:43,051 [salt.state       :1951][INFO    ][18666] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 03:41:43.051805 duration_in_ms=94.694
2019-06-18 03:41:43,053 [salt.state       :1780][INFO    ][18666] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 03:41:43.052948
2019-06-18 03:41:43,053 [salt.state       :1813][INFO    ][18666] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-06-18 03:41:43,116 [salt.state       :300 ][INFO    ][18666] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-06-18 03:41:43,116 [salt.state       :1951][INFO    ][18666] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 03:41:43.116347 duration_in_ms=63.399
2019-06-18 03:41:43,116 [salt.state       :1780][INFO    ][18666] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 03:41:43.116907
2019-06-18 03:41:43,117 [salt.state       :1813][INFO    ][18666] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-06-18 03:41:43,176 [salt.state       :300 ][INFO    ][18666] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-06-18 03:41:43,176 [salt.state       :1951][INFO    ][18666] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 03:41:43.176404 duration_in_ms=59.497
2019-06-18 03:41:43,176 [salt.state       :1780][INFO    ][18666] Running state [/root/.pgpass] at time 03:41:43.176761
2019-06-18 03:41:43,177 [salt.state       :1813][INFO    ][18666] Executing state file.managed for [/root/.pgpass]
2019-06-18 03:41:43,223 [salt.state       :300 ][INFO    ][18666] File /root/.pgpass is in the correct state
2019-06-18 03:41:43,224 [salt.state       :1951][INFO    ][18666] Completed state [/root/.pgpass] at time 03:41:43.224177 duration_in_ms=47.416
2019-06-18 03:41:43,230 [salt.state       :1780][INFO    ][18666] Running state [maas-region syncdb --noinput] at time 03:41:43.230531
2019-06-18 03:41:43,230 [salt.state       :1813][INFO    ][18666] Executing state cmd.run for [maas-region syncdb --noinput]
2019-06-18 03:41:43,231 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18666] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-06-18 03:41:45,249 [salt.state       :300 ][INFO    ][18666] {'pid': 18699, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: maasserver, sites, sessions, metadataserver, piston3, auth, contenttypes\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-06-18 03:41:45,250 [salt.state       :1951][INFO    ][18666] Completed state [maas-region syncdb --noinput] at time 03:41:45.250630 duration_in_ms=2020.098
2019-06-18 03:41:45,251 [salt.state       :2022][WARNING ][18666] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-06-18 03:41:45,254 [salt.state       :1780][INFO    ][18666] Running state [maas-regiond] at time 03:41:45.254186
2019-06-18 03:41:45,254 [salt.state       :1813][INFO    ][18666] Executing state service.running for [maas-regiond]
2019-06-18 03:41:45,256 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18666] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-06-18 03:41:45,297 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18666] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-06-18 03:41:45,317 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18666] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-06-18 03:41:45,335 [salt.state       :300 ][INFO    ][18666] The service maas-regiond is already running
2019-06-18 03:41:45,335 [salt.state       :1951][INFO    ][18666] Completed state [maas-regiond] at time 03:41:45.335699 duration_in_ms=81.513
2019-06-18 03:41:45,338 [salt.state       :1780][INFO    ][18666] Running state [bind9] at time 03:41:45.338397
2019-06-18 03:41:45,338 [salt.state       :1813][INFO    ][18666] Executing state service.running for [bind9]
2019-06-18 03:41:45,340 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18666] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-06-18 03:41:45,359 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18666] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-06-18 03:41:45,378 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18666] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-06-18 03:41:45,397 [salt.state       :300 ][INFO    ][18666] The service bind9 is already running
2019-06-18 03:41:45,398 [salt.state       :1951][INFO    ][18666] Completed state [bind9] at time 03:41:45.398049 duration_in_ms=59.652
2019-06-18 03:41:45,400 [salt.state       :1780][INFO    ][18666] Running state [apache2] at time 03:41:45.400722
2019-06-18 03:41:45,401 [salt.state       :1813][INFO    ][18666] Executing state service.running for [apache2]
2019-06-18 03:41:45,402 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18666] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-06-18 03:41:45,422 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18666] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-06-18 03:41:45,441 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18666] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-06-18 03:41:45,463 [salt.state       :300 ][INFO    ][18666] The service apache2 is already running
2019-06-18 03:41:45,464 [salt.state       :1951][INFO    ][18666] Completed state [apache2] at time 03:41:45.464131 duration_in_ms=63.409
2019-06-18 03:41:45,466 [salt.state       :1780][INFO    ][18666] Running state [maasng.wait_for_http_code] at time 03:41:45.466148
2019-06-18 03:41:45,466 [salt.state       :1813][INFO    ][18666] Executing state module.run for [maasng.wait_for_http_code]
2019-06-18 03:41:45,467 [salt.utils.decorators:613 ][WARNING ][18666] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-18 03:41:45,592 [salt.state       :300 ][INFO    ][18666] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-06-18 03:41:45,592 [salt.state       :1951][INFO    ][18666] Completed state [maasng.wait_for_http_code] at time 03:41:45.592862 duration_in_ms=126.714
2019-06-18 03:41:45,594 [salt.state       :1780][INFO    ][18666] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 03:41:45.594362
2019-06-18 03:41:45,594 [salt.state       :1813][INFO    ][18666] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-06-18 03:41:45,595 [salt.state       :300 ][INFO    ][18666] /var/lib/maas/.setup_admin exists
2019-06-18 03:41:45,596 [salt.state       :1951][INFO    ][18666] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 03:41:45.596047 duration_in_ms=1.685
2019-06-18 03:41:45,597 [salt.state       :1780][INFO    ][18666] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:41:45.597249
2019-06-18 03:41:45,597 [salt.state       :1813][INFO    ][18666] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-18 03:41:45,598 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18666] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-18 03:41:47,031 [salt.state       :300 ][INFO    ][18666] {'pid': 18720, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-18 03:41:47,032 [salt.state       :1951][INFO    ][18666] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:41:47.032395 duration_in_ms=1435.145
2019-06-18 03:41:47,042 [salt.state       :1780][INFO    ][18666] Running state [maas_region_boot_source_resources_mirror] at time 03:41:47.042542
2019-06-18 03:41:47,043 [salt.state       :1813][INFO    ][18666] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-06-18 03:41:47,135 [salt.state       :300 ][INFO    ][18666] {'changes': {}}
2019-06-18 03:41:47,135 [salt.state       :1951][INFO    ][18666] Completed state [maas_region_boot_source_resources_mirror] at time 03:41:47.135842 duration_in_ms=93.298
2019-06-18 03:41:47,136 [salt.state       :1780][INFO    ][18666] Running state [maasng.boot_resources_import] at time 03:41:47.136847
2019-06-18 03:41:47,137 [salt.state       :1813][INFO    ][18666] Executing state module.run for [maasng.boot_resources_import]
2019-06-18 03:41:47,137 [salt.utils.decorators:613 ][WARNING ][18666] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-18 03:41:47,250 [salt.loaded.ext.module.maasng:1600][INFO    ][18666] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-06-18 03:41:52,310 [salt.loaded.ext.module.maasng:1600][INFO    ][18666] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-06-18 03:41:56,157 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618034156144391
2019-06-18 03:41:56,181 [salt.minion      :1432][INFO    ][18739] Starting a new job with PID 18739
2019-06-18 03:41:56,206 [salt.minion      :1711][INFO    ][18739] Returning information for job: 20190618034156144391
2019-06-18 03:41:57,407 [salt.loaded.ext.module.maasng:1600][INFO    ][18666] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-06-18 03:42:02,475 [salt.loaded.ext.module.maasng:1600][INFO    ][18666] Waiting boot-resources import done
sleep for:5s Left:885.0/900s
2019-06-18 03:42:07,538 [salt.loaded.ext.module.maasng:1600][INFO    ][18666] Waiting boot-resources import done
sleep for:5s Left:880.0/900s
2019-06-18 03:42:12,658 [salt.state       :300 ][INFO    ][18666] {'ret': True}
2019-06-18 03:42:12,659 [salt.state       :1951][INFO    ][18666] Completed state [maasng.boot_resources_import] at time 03:42:12.659330 duration_in_ms=25522.483
2019-06-18 03:42:12,660 [salt.state       :1780][INFO    ][18666] Running state [maas_region_boot_sources_selection_xenial] at time 03:42:12.660580
2019-06-18 03:42:12,661 [salt.state       :1813][INFO    ][18666] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-06-18 03:42:12,873 [salt.state       :300 ][INFO    ][18666] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-06-18 03:42:12,874 [salt.state       :1951][INFO    ][18666] Completed state [maas_region_boot_sources_selection_xenial] at time 03:42:12.874258 duration_in_ms=213.677
2019-06-18 03:42:12,875 [salt.state       :1780][INFO    ][18666] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 03:42:12.875602
2019-06-18 03:42:12,876 [salt.state       :1813][INFO    ][18666] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-06-18 03:42:12,876 [salt.utils.decorators:613 ][WARNING ][18666] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-18 03:42:12,877 [salt.loaded.ext.module.maasng:1771][INFO    ][18666] boot-sources sync initiated for ALL Rack's
2019-06-18 03:42:13,861 [salt.state       :300 ][INFO    ][18666] {'ret': True}
2019-06-18 03:42:13,861 [salt.state       :1951][INFO    ][18666] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 03:42:13.861555 duration_in_ms=985.953
2019-06-18 03:42:13,863 [salt.state       :1780][INFO    ][18666] Running state [maas.process_maas_config] at time 03:42:13.863610
2019-06-18 03:42:13,864 [salt.state       :1813][INFO    ][18666] Executing state module.run for [maas.process_maas_config]
2019-06-18 03:42:13,864 [salt.utils.decorators:613 ][WARNING ][18666] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-18 03:42:13,865 [salt.loaded.ext.module.maas:92  ][INFO    ][18666] maasconfig name=enable_http_proxy value=True
2019-06-18 03:42:13,929 [salt.loaded.ext.module.maas:92  ][INFO    ][18666] maasconfig name=upstream_dns value=8.8.8.8
2019-06-18 03:42:19,830 [salt.loaded.ext.module.maas:92  ][INFO    ][18666] maasconfig name=commissioning_distro_series value=xenial
2019-06-18 03:42:19,907 [salt.loaded.ext.module.maas:92  ][INFO    ][18666] maasconfig name=default_osystem value=ubuntu
2019-06-18 03:42:19,975 [salt.loaded.ext.module.maas:92  ][INFO    ][18666] maasconfig name=active_discovery_interval value=600
2019-06-18 03:42:20,049 [salt.loaded.ext.module.maas:92  ][INFO    ][18666] maasconfig name=dnssec_validation value=no
2019-06-18 03:42:20,108 [salt.loaded.ext.module.maas:92  ][INFO    ][18666] maasconfig name=maas_name value=mas01
2019-06-18 03:42:20,161 [salt.loaded.ext.module.maas:92  ][INFO    ][18666] maasconfig name=network_discovery value=enabled
2019-06-18 03:42:20,272 [salt.loaded.ext.module.maas:92  ][INFO    ][18666] maasconfig name=enable_third_party_drivers value=True
2019-06-18 03:42:20,337 [salt.loaded.ext.module.maas:92  ][INFO    ][18666] maasconfig name=default_storage_layout value=lvm
2019-06-18 03:42:20,392 [salt.loaded.ext.module.maas:92  ][INFO    ][18666] maasconfig name=ntp_external_only value=True
2019-06-18 03:42:20,443 [salt.loaded.ext.module.maas:92  ][INFO    ][18666] maasconfig name=disk_erase_with_secure_erase value=False
2019-06-18 03:42:20,524 [salt.loaded.ext.module.maas:92  ][INFO    ][18666] maasconfig name=default_distro_series value=xenial
2019-06-18 03:42:20,589 [salt.loaded.ext.module.maas:92  ][INFO    ][18666] maasconfig name=default_min_hwe_kernel value=ga-16.04
2019-06-18 03:42:20,715 [salt.state       :300 ][INFO    ][18666] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-06-18 03:42:20,716 [salt.state       :1951][INFO    ][18666] Completed state [maas.process_maas_config] at time 03:42:20.715968 duration_in_ms=6852.356
2019-06-18 03:42:20,716 [salt.state       :1780][INFO    ][18666] Running state [pxe_admin] at time 03:42:20.716901
2019-06-18 03:42:20,717 [salt.state       :1813][INFO    ][18666] Executing state maasng.fabric_present for [pxe_admin]
2019-06-18 03:42:20,780 [salt.loaded.ext.module.maasng:945 ][INFO    ][18666] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'mmqacw', 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'fabric': u'pxe_admin'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-06-18 03:42:20,846 [salt.loaded.ext.module.maasng:1008][WARNING ][18666] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-06-18 03:42:20,847 [salt.loaded.ext.module.maasng:1011][WARNING ][18666] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-06-18 03:42:20,954 [salt.state       :300 ][INFO    ][18666] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-06-18 03:42:20,955 [salt.state       :1951][INFO    ][18666] Completed state [pxe_admin] at time 03:42:20.955024 duration_in_ms=238.121
2019-06-18 03:42:20,955 [salt.state       :1780][INFO    ][18666] Running state [vlan 0] at time 03:42:20.955534
2019-06-18 03:42:20,956 [salt.state       :1813][INFO    ][18666] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-06-18 03:42:21,020 [salt.loaded.ext.module.maasng:945 ][INFO    ][18666] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'mmqacw', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-06-18 03:42:21,155 [salt.loaded.ext.module.maasng:945 ][INFO    ][18666] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'mmqacw', 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'fabric': u'pxe_admin'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-06-18 03:42:21,445 [salt.loaded.ext.module.maasng:945 ][INFO    ][18666] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'mmqacw', 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'fabric': u'pxe_admin'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-06-18 03:42:21,541 [salt.state       :300 ][INFO    ][18666] {'new': 'Vlan untagged was updated'}
2019-06-18 03:42:21,542 [salt.state       :1951][INFO    ][18666] Completed state [vlan 0] at time 03:42:21.542234 duration_in_ms=586.701
2019-06-18 03:42:21,543 [salt.state       :1780][INFO    ][18666] Running state [192.168.11.0/24] at time 03:42:21.543807
2019-06-18 03:42:21,544 [salt.state       :1813][INFO    ][18666] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-06-18 03:42:21,799 [salt.loaded.ext.module.maasng:945 ][INFO    ][18666] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'mmqacw', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-06-18 03:42:21,800 [salt.loaded.ext.module.maasng:1235][WARNING ][18666] Ignoring parameter vlan:0
2019-06-18 03:42:21,865 [salt.state       :300 ][INFO    ][18666] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-06-18 03:42:21,865 [salt.state       :1951][INFO    ][18666] Completed state [192.168.11.0/24] at time 03:42:21.865419 duration_in_ms=321.612
2019-06-18 03:42:21,866 [salt.state       :1780][INFO    ][18666] Running state [maas_create_iprange_1] at time 03:42:21.866680
2019-06-18 03:42:21,867 [salt.state       :1813][INFO    ][18666] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-06-18 03:42:21,918 [salt.state       :300 ][INFO    ][18666] Iprange maas_create_iprange_1 already exist.
2019-06-18 03:42:21,918 [salt.state       :1951][INFO    ][18666] Completed state [maas_create_iprange_1] at time 03:42:21.918909 duration_in_ms=52.229
2019-06-18 03:42:21,919 [salt.state       :1780][INFO    ][18666] Running state [vlan 0] at time 03:42:21.919322
2019-06-18 03:42:21,919 [salt.state       :1813][INFO    ][18666] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-06-18 03:42:21,966 [salt.loaded.ext.module.maasng:945 ][INFO    ][18666] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'mmqacw', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-06-18 03:42:22,086 [salt.loaded.ext.module.maasng:945 ][INFO    ][18666] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'mmqacw', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-06-18 03:42:22,305 [salt.loaded.ext.module.maasng:945 ][INFO    ][18666] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'mmqacw', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-06-18 03:42:22,359 [salt.state       :300 ][INFO    ][18666] {'new': 'Vlan untagged was updated'}
2019-06-18 03:42:22,360 [salt.state       :1951][INFO    ][18666] Completed state [vlan 0] at time 03:42:22.359942 duration_in_ms=440.62
2019-06-18 03:42:22,360 [salt.state       :1780][INFO    ][18666] Running state [opnfv] at time 03:42:22.360627
2019-06-18 03:42:22,360 [salt.state       :1813][INFO    ][18666] Executing state maasng.sshkey_present for [opnfv]
2019-06-18 03:42:22,389 [salt.loaded.ext.module.maasng:1903][INFO    ][18666] [{u'keysource': u'', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP', u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/'}]
2019-06-18 03:42:22,389 [salt.state       :300 ][INFO    ][18666] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-06-18 03:42:22,389 [salt.state       :1951][INFO    ][18666] Completed state [opnfv] at time 03:42:22.389587 duration_in_ms=28.96
2019-06-18 03:42:22,392 [salt.minion      :1711][INFO    ][18666] Returning information for job: 20190618034141059383
2019-06-18 03:42:22,931 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command state.apply with jid 20190618034222917008
2019-06-18 03:42:22,954 [salt.minion      :1432][INFO    ][19147] Starting a new job with PID 19147
2019-06-18 03:42:26,550 [salt.state       :915 ][INFO    ][19147] Loading fresh modules for state activity
2019-06-18 03:42:26,644 [salt.state       :1780][INFO    ][19147] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:42:26.644272
2019-06-18 03:42:26,644 [salt.state       :1813][INFO    ][19147] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-18 03:42:26,646 [salt.loaded.int.module.cmdmod:395 ][INFO    ][19147] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-18 03:42:28,070 [salt.state       :300 ][INFO    ][19147] {'pid': 19170, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-18 03:42:28,071 [salt.state       :1951][INFO    ][19147] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:42:28.071197 duration_in_ms=1426.925
2019-06-18 03:42:28,072 [salt.state       :1780][INFO    ][19147] Running state [maas.process_machines] at time 03:42:28.072377
2019-06-18 03:42:28,072 [salt.state       :1813][INFO    ][19147] Executing state module.run for [maas.process_machines]
2019-06-18 03:42:28,073 [salt.utils.decorators:613 ][WARNING ][19147] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-18 03:42:28,811 [salt.loaded.ext.module.maas:412 ][WARNING ][19147] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-18 03:42:28,812 [salt.loaded.ext.module.maas:92  ][INFO    ][19147] machine hostname=cmp002 power_type=ipmi mac_addresses=['00:25:b5:a0:00:6a'] power_parameters_power_address=172.30.8.72 power_parameters_power_pass=octopus system_id=cethd6 architecture=amd64/generic power_parameters_power_user=admin
2019-06-18 03:42:30,010 [salt.loaded.ext.module.maas:412 ][WARNING ][19147] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-18 03:42:30,011 [salt.loaded.ext.module.maas:92  ][INFO    ][19147] machine hostname=cmp001 power_type=ipmi mac_addresses=['00:25:b5:a0:00:5a'] power_parameters_power_address=172.30.8.73 power_parameters_power_pass=octopus system_id=wsf6h8 architecture=amd64/generic power_parameters_power_user=admin
2019-06-18 03:42:31,392 [salt.loaded.ext.module.maas:412 ][WARNING ][19147] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-18 03:42:31,393 [salt.loaded.ext.module.maas:92  ][INFO    ][19147] machine hostname=kvm01 power_type=ipmi mac_addresses=['00:25:b5:a0:00:2a'] power_parameters_power_address=172.30.8.75 power_parameters_power_pass=octopus system_id=xwq3cf architecture=amd64/generic power_parameters_power_user=admin
2019-06-18 03:42:32,562 [salt.loaded.ext.module.maas:412 ][WARNING ][19147] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-18 03:42:32,563 [salt.loaded.ext.module.maas:92  ][INFO    ][19147] machine hostname=kvm03 power_type=ipmi mac_addresses=['00:25:b5:a0:00:4a'] power_parameters_power_address=172.30.8.74 power_parameters_power_pass=octopus system_id=xrynhb architecture=amd64/generic power_parameters_power_user=admin
2019-06-18 03:42:33,849 [salt.loaded.ext.module.maas:412 ][WARNING ][19147] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-18 03:42:33,850 [salt.loaded.ext.module.maas:92  ][INFO    ][19147] machine hostname=kvm02 power_type=ipmi mac_addresses=['00:25:b5:a0:00:3a'] power_parameters_power_address=172.30.8.65 power_parameters_power_pass=octopus system_id=4dscpf architecture=amd64/generic power_parameters_power_user=admin
2019-06-18 03:42:35,100 [salt.state       :300 ][INFO    ][19147] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-06-18 03:42:35,101 [salt.state       :1951][INFO    ][19147] Completed state [maas.process_machines] at time 03:42:35.101271 duration_in_ms=7028.892
2019-06-18 03:42:35,104 [salt.minion      :1711][INFO    ][19147] Returning information for job: 20190618034222917008
2019-06-18 03:43:08,364 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command state.apply with jid 20190618034308351939
2019-06-18 03:43:08,388 [salt.minion      :1432][INFO    ][19454] Starting a new job with PID 19454
2019-06-18 03:43:12,041 [salt.state       :915 ][INFO    ][19454] Loading fresh modules for state activity
2019-06-18 03:43:12,089 [salt.state       :1780][INFO    ][19454] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:43:12.089108
2019-06-18 03:43:12,089 [salt.state       :1813][INFO    ][19454] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-18 03:43:12,090 [salt.loaded.int.module.cmdmod:395 ][INFO    ][19454] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-18 03:43:13,571 [salt.state       :300 ][INFO    ][19454] {'pid': 19463, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-18 03:43:13,571 [salt.state       :1951][INFO    ][19454] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:43:13.571506 duration_in_ms=1482.398
2019-06-18 03:43:13,572 [salt.state       :1780][INFO    ][19454] Running state [maas.wait_for_machine_status] at time 03:43:13.572951
2019-06-18 03:43:13,573 [salt.state       :1813][INFO    ][19454] Executing state module.run for [maas.wait_for_machine_status]
2019-06-18 03:43:13,573 [salt.utils.decorators:613 ][WARNING ][19454] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-18 03:43:16,829 [salt.loaded.ext.module.maas:993 ][INFO    ][19454] Machine 4dscpf mark broken
2019-06-18 03:43:17,459 [salt.loaded.ext.module.maas:996 ][INFO    ][19454] Machine 4dscpf mark fixed
2019-06-18 03:43:18,661 [salt.loaded.ext.module.maas:684 ][INFO    ][19454] deploymachines hwe_kernel=ga-16.04 system_id=4dscpf distro_series=xenial
2019-06-18 03:43:21,362 [salt.loaded.ext.module.maas:1023][INFO    ][19454] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1492.21756315s left)
2019-06-18 03:43:23,444 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618034323431878
2019-06-18 03:43:23,468 [salt.minion      :1432][INFO    ][19533] Starting a new job with PID 19533
2019-06-18 03:43:23,502 [salt.minion      :1711][INFO    ][19533] Returning information for job: 20190618034323431878
2019-06-18 03:43:53,710 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618034353648924
2019-06-18 03:43:53,730 [salt.minion      :1432][INFO    ][19570] Starting a new job with PID 19570
2019-06-18 03:43:53,758 [salt.minion      :1711][INFO    ][19570] Returning information for job: 20190618034353648924
2019-06-18 03:43:54,888 [salt.loaded.ext.module.maas:1023][INFO    ][19454] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1458.69114208s left)
2019-06-18 03:44:23,759 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618034423746310
2019-06-18 03:44:23,783 [salt.minion      :1432][INFO    ][19622] Starting a new job with PID 19622
2019-06-18 03:44:23,813 [salt.minion      :1711][INFO    ][19622] Returning information for job: 20190618034423746310
2019-06-18 03:44:28,425 [salt.loaded.ext.module.maas:1023][INFO    ][19454] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1425.15401816s left)
2019-06-18 03:44:53,811 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618034453800101
2019-06-18 03:44:53,835 [salt.minion      :1432][INFO    ][19642] Starting a new job with PID 19642
2019-06-18 03:44:53,859 [salt.minion      :1711][INFO    ][19642] Returning information for job: 20190618034453800101
2019-06-18 03:45:01,818 [salt.loaded.ext.module.maas:1023][INFO    ][19454] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1391.7611382s left)
2019-06-18 03:45:23,860 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618034523845103
2019-06-18 03:45:23,883 [salt.minion      :1432][INFO    ][19749] Starting a new job with PID 19749
2019-06-18 03:45:23,908 [salt.minion      :1711][INFO    ][19749] Returning information for job: 20190618034523845103
2019-06-18 03:45:35,415 [salt.loaded.ext.module.maas:1023][INFO    ][19454] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1358.16440105s left)
2019-06-18 03:45:53,917 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618034553904253
2019-06-18 03:45:53,940 [salt.minion      :1432][INFO    ][19775] Starting a new job with PID 19775
2019-06-18 03:45:53,966 [salt.minion      :1711][INFO    ][19775] Returning information for job: 20190618034553904253
2019-06-18 03:46:09,008 [salt.loaded.ext.module.maas:1023][INFO    ][19454] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1324.57199502s left)
2019-06-18 03:46:23,980 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618034623967250
2019-06-18 03:46:24,002 [salt.minion      :1432][INFO    ][19976] Starting a new job with PID 19976
2019-06-18 03:46:24,027 [salt.minion      :1711][INFO    ][19976] Returning information for job: 20190618034623967250
2019-06-18 03:46:42,494 [salt.loaded.ext.module.maas:1023][INFO    ][19454] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1291.08566308s left)
2019-06-18 03:46:54,039 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618034654027226
2019-06-18 03:46:54,060 [salt.minion      :1432][INFO    ][19999] Starting a new job with PID 19999
2019-06-18 03:46:54,084 [salt.minion      :1711][INFO    ][19999] Returning information for job: 20190618034654027226
2019-06-18 03:47:15,783 [salt.loaded.ext.module.maas:1023][INFO    ][19454] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1257.79673123s left)
2019-06-18 03:47:24,105 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618034724091669
2019-06-18 03:47:24,128 [salt.minion      :1432][INFO    ][20070] Starting a new job with PID 20070
2019-06-18 03:47:24,154 [salt.minion      :1711][INFO    ][20070] Returning information for job: 20190618034724091669
2019-06-18 03:47:49,549 [salt.loaded.ext.module.maas:1023][INFO    ][19454] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1224.03000116s left)
2019-06-18 03:47:54,175 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618034754162386
2019-06-18 03:47:54,198 [salt.minion      :1432][INFO    ][20097] Starting a new job with PID 20097
2019-06-18 03:47:54,222 [salt.minion      :1711][INFO    ][20097] Returning information for job: 20190618034754162386
2019-06-18 03:48:23,167 [salt.loaded.ext.module.maas:1023][INFO    ][19454] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1190.41300511s left)
2019-06-18 03:48:24,249 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618034824236770
2019-06-18 03:48:24,272 [salt.minion      :1432][INFO    ][20207] Starting a new job with PID 20207
2019-06-18 03:48:24,297 [salt.minion      :1711][INFO    ][20207] Returning information for job: 20190618034824236770
2019-06-18 03:48:54,322 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618034854312857
2019-06-18 03:48:54,345 [salt.minion      :1432][INFO    ][20224] Starting a new job with PID 20224
2019-06-18 03:48:54,370 [salt.minion      :1711][INFO    ][20224] Returning information for job: 20190618034854312857
2019-06-18 03:48:56,641 [salt.loaded.ext.module.maas:1023][INFO    ][19454] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1156.93844008s left)
2019-06-18 03:49:24,404 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618034924391260
2019-06-18 03:49:24,428 [salt.minion      :1432][INFO    ][20323] Starting a new job with PID 20323
2019-06-18 03:49:24,456 [salt.minion      :1711][INFO    ][20323] Returning information for job: 20190618034924391260
2019-06-18 03:49:30,361 [salt.loaded.ext.module.maas:1023][INFO    ][19454] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1123.2181921s left)
2019-06-18 03:49:54,496 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618034954483028
2019-06-18 03:49:54,520 [salt.minion      :1432][INFO    ][20347] Starting a new job with PID 20347
2019-06-18 03:49:54,547 [salt.minion      :1711][INFO    ][20347] Returning information for job: 20190618034954483028
2019-06-18 03:50:03,901 [salt.loaded.ext.module.maas:1023][INFO    ][19454] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1089.67863703s left)
2019-06-18 03:50:24,591 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618035024578949
2019-06-18 03:50:24,615 [salt.minion      :1432][INFO    ][20476] Starting a new job with PID 20476
2019-06-18 03:50:24,641 [salt.minion      :1711][INFO    ][20476] Returning information for job: 20190618035024578949
2019-06-18 03:50:37,696 [salt.loaded.ext.module.maas:1023][INFO    ][19454] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1055.88302016s left)
2019-06-18 03:50:54,696 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618035054683398
2019-06-18 03:50:54,719 [salt.minion      :1432][INFO    ][20497] Starting a new job with PID 20497
2019-06-18 03:50:54,746 [salt.minion      :1711][INFO    ][20497] Returning information for job: 20190618035054683398
2019-06-18 03:51:10,926 [salt.loaded.ext.module.maas:1023][INFO    ][19454] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1022.65343618s left)
2019-06-18 03:51:24,806 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618035124793516
2019-06-18 03:51:24,829 [salt.minion      :1432][INFO    ][20554] Starting a new job with PID 20554
2019-06-18 03:51:24,855 [salt.minion      :1711][INFO    ][20554] Returning information for job: 20190618035124793516
2019-06-18 03:51:44,434 [salt.loaded.ext.module.maas:1023][INFO    ][19454] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (989.145755053s left)
2019-06-18 03:51:54,921 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618035154909850
2019-06-18 03:51:54,945 [salt.minion      :1432][INFO    ][20608] Starting a new job with PID 20608
2019-06-18 03:51:54,971 [salt.minion      :1711][INFO    ][20608] Returning information for job: 20190618035154909850
2019-06-18 03:52:17,917 [salt.state       :300 ][INFO    ][19454] {'ret': True}
2019-06-18 03:52:17,918 [salt.state       :1951][INFO    ][19454] Completed state [maas.wait_for_machine_status] at time 03:52:17.918064 duration_in_ms=544345.109
2019-06-18 03:52:17,921 [salt.minion      :1711][INFO    ][19454] Returning information for job: 20190618034308351939
2019-06-18 03:52:18,538 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command state.apply with jid 20190618035218524885
2019-06-18 03:52:18,560 [salt.minion      :1432][INFO    ][20741] Starting a new job with PID 20741
2019-06-18 03:52:22,133 [salt.state       :915 ][INFO    ][20741] Loading fresh modules for state activity
2019-06-18 03:52:22,276 [salt.state       :1780][INFO    ][20741] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:52:22.276709
2019-06-18 03:52:22,277 [salt.state       :1813][INFO    ][20741] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-18 03:52:22,279 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20741] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-18 03:52:23,740 [salt.state       :300 ][INFO    ][20741] {'pid': 20750, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-18 03:52:23,741 [salt.state       :1951][INFO    ][20741] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:52:23.741281 duration_in_ms=1464.573
2019-06-18 03:52:23,742 [salt.state       :1780][INFO    ][20741] Running state [maas_machines_storage_cmp002_lvm] at time 03:52:23.742788
2019-06-18 03:52:23,743 [salt.state       :1813][INFO    ][20741] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-06-18 03:52:24,478 [salt.state       :300 ][INFO    ][20741] Machine cmp002 is not in Ready state.
2019-06-18 03:52:24,479 [salt.state       :1951][INFO    ][20741] Completed state [maas_machines_storage_cmp002_lvm] at time 03:52:24.478908 duration_in_ms=736.118
2019-06-18 03:52:24,479 [salt.state       :1780][INFO    ][20741] Running state [maas_machines_storage_cmp001_lvm] at time 03:52:24.479448
2019-06-18 03:52:24,479 [salt.state       :1813][INFO    ][20741] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-06-18 03:52:25,274 [salt.state       :300 ][INFO    ][20741] Machine cmp001 is not in Ready state.
2019-06-18 03:52:25,275 [salt.state       :1951][INFO    ][20741] Completed state [maas_machines_storage_cmp001_lvm] at time 03:52:25.275160 duration_in_ms=795.711
2019-06-18 03:52:25,279 [salt.minion      :1711][INFO    ][20741] Returning information for job: 20190618035218524885
2019-06-18 03:52:25,910 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command state.apply with jid 20190618035225897881
2019-06-18 03:52:25,932 [salt.minion      :1432][INFO    ][20760] Starting a new job with PID 20760
2019-06-18 03:52:26,678 [salt.state       :915 ][INFO    ][20760] Loading fresh modules for state activity
2019-06-18 03:52:26,767 [salt.state       :1780][INFO    ][20760] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:52:26.767358
2019-06-18 03:52:26,767 [salt.state       :1813][INFO    ][20760] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-18 03:52:26,770 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20760] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-18 03:52:28,193 [salt.state       :300 ][INFO    ][20760] {'pid': 20767, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-18 03:52:28,194 [salt.state       :1951][INFO    ][20760] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:52:28.194047 duration_in_ms=1426.689
2019-06-18 03:52:28,195 [salt.state       :1780][INFO    ][20760] Running state [maas.deploy_machines] at time 03:52:28.195282
2019-06-18 03:52:28,195 [salt.state       :1813][INFO    ][20760] Executing state module.run for [maas.deploy_machines]
2019-06-18 03:52:28,196 [salt.utils.decorators:613 ][WARNING ][20760] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-18 03:52:28,955 [salt.state       :300 ][INFO    ][20760] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-06-18 03:52:28,956 [salt.state       :1951][INFO    ][20760] Completed state [maas.deploy_machines] at time 03:52:28.956353 duration_in_ms=761.069
2019-06-18 03:52:28,960 [salt.minion      :1711][INFO    ][20760] Returning information for job: 20190618035225897881
2019-06-18 03:52:29,588 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command state.apply with jid 20190618035229575108
2019-06-18 03:52:29,614 [salt.minion      :1432][INFO    ][20784] Starting a new job with PID 20784
2019-06-18 03:52:30,383 [salt.state       :915 ][INFO    ][20784] Loading fresh modules for state activity
2019-06-18 03:52:30,468 [salt.state       :1780][INFO    ][20784] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:52:30.468572
2019-06-18 03:52:30,468 [salt.state       :1813][INFO    ][20784] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-18 03:52:30,471 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20784] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-18 03:52:31,813 [salt.state       :300 ][INFO    ][20784] {'pid': 20791, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-18 03:52:31,814 [salt.state       :1951][INFO    ][20784] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:52:31.814762 duration_in_ms=1346.189
2019-06-18 03:52:31,818 [salt.state       :1780][INFO    ][20784] Running state [maas.wait_for_machine_status] at time 03:52:31.818065
2019-06-18 03:52:31,818 [salt.state       :1813][INFO    ][20784] Executing state module.run for [maas.wait_for_machine_status]
2019-06-18 03:52:31,819 [salt.utils.decorators:613 ][WARNING ][20784] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-18 03:52:35,495 [salt.state       :300 ][INFO    ][20784] {'ret': True}
2019-06-18 03:52:35,496 [salt.state       :1951][INFO    ][20784] Completed state [maas.wait_for_machine_status] at time 03:52:35.496164 duration_in_ms=3678.098
2019-06-18 03:52:35,499 [salt.minion      :1711][INFO    ][20784] Returning information for job: 20190618035229575108
2019-06-18 04:10:08,366 [salt.utils.schedule:1377][INFO    ][8916] Running scheduled job: __mine_interval
2019-06-18 05:10:08,366 [salt.utils.schedule:1377][INFO    ][8916] Running scheduled job: __mine_interval
2019-06-18 05:36:06,354 [salt.minion      :1308][INFO    ][8916] User sudo_ubuntu Executing command cp.push_dir with jid 20190618053606342158
2019-06-18 05:36:06,373 [salt.minion      :1432][INFO    ][28185] Starting a new job with PID 28185
