2019-05-20 20:07:23,211 [salt.minion      :870 ][ERROR   ][364] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-05-20 20:08:13,260 [salt.minion      :870 ][ERROR   ][364] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-05-20 20:09:03,306 [salt.minion      :870 ][ERROR   ][364] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-05-20 20:09:53,355 [salt.minion      :870 ][ERROR   ][364] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-05-20 20:10:43,406 [salt.minion      :870 ][ERROR   ][364] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-05-20 20:12:52,272 [salt.utils.decorators:613 ][WARNING ][2846] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-20 20:12:52,851 [salt.utils.decorators:613 ][WARNING ][2846] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-20 20:12:54,934 [salt.loaded.int.states.file:2298][WARNING ][2989] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-05-20 20:13:00,615 [salt.state       :2022][WARNING ][3096] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-20 20:13:03,206 [salt.utils.decorators:613 ][WARNING ][3096] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-20 20:28:10,275 [salt.utils.decorators:613 ][WARNING ][3096] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-20 20:58:20,508 [salt.utils.decorators:613 ][WARNING ][3096] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-20 21:19:19,198 [salt.state       :302 ][ERROR   ][3096] Module function maasng.sync_and_wait_bs_to_all_racks threw an exception. Exception: HTTP Error 401: OK
2019-05-20 21:19:19,372 [salt.loaded.ext.module.maasng:1008][WARNING ][3096] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-05-20 21:19:19,372 [salt.loaded.ext.module.maasng:1011][WARNING ][3096] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-05-20 21:19:23,527 [salt.loaded.ext.module.maasng:1235][WARNING ][3096] Ignoring parameter vlan:0
2019-05-20 21:19:29,317 [salt.loaded.int.module.cmdmod:395 ][INFO    ][27040] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-20 21:19:29,344 [salt.loaded.int.module.cmdmod:395 ][INFO    ][27040] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-05-20 21:19:29,368 [salt.utils.parsers:1051][WARNING ][364] Minion received a SIGTERM. Exiting.
2019-05-20 21:19:30,411 [salt.cli.daemons :293 ][INFO    ][27096] Setting up the Salt Minion "mas01.mcp-ovs-ha.local"
2019-05-20 21:19:30,510 [salt.cli.daemons :82  ][INFO    ][27096] Starting up the Salt Minion
2019-05-20 21:19:30,510 [salt.utils.event :1017][INFO    ][27096] Starting pull socket on /var/run/salt/minion/minion_event_501f9ec045_pull.ipc
2019-05-20 21:19:31,348 [salt.minion      :976 ][INFO    ][27096] Creating minion process manager
2019-05-20 21:19:32,834 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][27096] Executing command ['date', '+%z'] in directory '/root'
2019-05-20 21:19:32,855 [salt.utils.schedule:568 ][INFO    ][27096] Updating job settings for scheduled job: __mine_interval
2019-05-20 21:19:32,856 [salt.minion      :1108][INFO    ][27096] Added mine.update to scheduler
2019-05-20 21:19:32,860 [salt.minion      :1975][INFO    ][27096] Minion is starting as user 'root'
2019-05-20 21:19:32,870 [salt.minion      :2336][INFO    ][27096] Minion is ready to receive requests!
2019-05-20 21:19:36,989 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command state.apply with jid 20190520211936976620
2019-05-20 21:19:37,012 [salt.minion      :1432][INFO    ][27194] Starting a new job with PID 27194
2019-05-20 21:19:40,708 [salt.state       :915 ][INFO    ][27194] Loading fresh modules for state activity
2019-05-20 21:19:41,386 [salt.state       :1780][INFO    ][27194] Running state [maas-region-controller] at time 21:19:41.386706
2019-05-20 21:19:41,387 [salt.state       :1813][INFO    ][27194] Executing state pkg.installed for [maas-region-controller]
2019-05-20 21:19:41,387 [salt.loaded.int.module.cmdmod:395 ][INFO    ][27194] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-20 21:19:41,460 [salt.state       :300 ][INFO    ][27194] All specified packages are already installed
2019-05-20 21:19:41,460 [salt.state       :1951][INFO    ][27194] Completed state [maas-region-controller] at time 21:19:41.460688 duration_in_ms=73.983
2019-05-20 21:19:41,460 [salt.state       :1780][INFO    ][27194] Running state [python-oauth] at time 21:19:41.460918
2019-05-20 21:19:41,461 [salt.state       :1813][INFO    ][27194] Executing state pkg.installed for [python-oauth]
2019-05-20 21:19:41,465 [salt.state       :300 ][INFO    ][27194] All specified packages are already installed
2019-05-20 21:19:41,465 [salt.state       :1951][INFO    ][27194] Completed state [python-oauth] at time 21:19:41.465625 duration_in_ms=4.707
2019-05-20 21:19:41,467 [salt.state       :1780][INFO    ][27194] Running state [/etc/maas/regiond.conf] at time 21:19:41.467843
2019-05-20 21:19:41,468 [salt.state       :1813][INFO    ][27194] Executing state file.replace for [/etc/maas/regiond.conf]
2019-05-20 21:19:41,661 [salt.state       :300 ][INFO    ][27194] No changes needed to be made
2019-05-20 21:19:41,661 [salt.state       :1951][INFO    ][27194] Completed state [/etc/maas/regiond.conf] at time 21:19:41.661707 duration_in_ms=193.864
2019-05-20 21:19:41,662 [salt.state       :1780][INFO    ][27194] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 21:19:41.662208
2019-05-20 21:19:41,662 [salt.state       :1813][INFO    ][27194] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-05-20 21:19:41,720 [salt.state       :300 ][INFO    ][27194] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-05-20 21:19:41,721 [salt.state       :1951][INFO    ][27194] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 21:19:41.720953 duration_in_ms=58.745
2019-05-20 21:19:41,721 [salt.state       :1780][INFO    ][27194] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 21:19:41.721427
2019-05-20 21:19:41,721 [salt.state       :1813][INFO    ][27194] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-20 21:19:41,734 [salt.state       :300 ][INFO    ][27194] No changes needed to be made
2019-05-20 21:19:41,734 [salt.state       :1951][INFO    ][27194] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 21:19:41.734356 duration_in_ms=12.929
2019-05-20 21:19:41,734 [salt.state       :1780][INFO    ][27194] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 21:19:41.734851
2019-05-20 21:19:41,735 [salt.state       :1813][INFO    ][27194] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-20 21:19:41,757 [salt.state       :300 ][INFO    ][27194] No changes needed to be made
2019-05-20 21:19:41,758 [salt.state       :1951][INFO    ][27194] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 21:19:41.758160 duration_in_ms=23.308
2019-05-20 21:19:41,758 [salt.state       :1780][INFO    ][27194] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 21:19:41.758662
2019-05-20 21:19:41,758 [salt.state       :1813][INFO    ][27194] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-05-20 21:19:41,782 [salt.state       :300 ][INFO    ][27194] No changes needed to be made
2019-05-20 21:19:41,782 [salt.state       :1951][INFO    ][27194] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 21:19:41.782682 duration_in_ms=24.02
2019-05-20 21:19:41,783 [salt.state       :1780][INFO    ][27194] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 21:19:41.783115
2019-05-20 21:19:41,783 [salt.state       :1813][INFO    ][27194] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-05-20 21:19:41,794 [salt.state       :300 ][INFO    ][27194] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-05-20 21:19:41,794 [salt.state       :1951][INFO    ][27194] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 21:19:41.794385 duration_in_ms=11.27
2019-05-20 21:19:41,795 [salt.state       :1780][INFO    ][27194] Running state [a2enmod headers] at time 21:19:41.795563
2019-05-20 21:19:41,795 [salt.state       :1813][INFO    ][27194] Executing state cmd.run for [a2enmod headers]
2019-05-20 21:19:41,796 [salt.loaded.int.module.cmdmod:395 ][INFO    ][27194] Executing command 'a2enmod headers' in directory '/root'
2019-05-20 21:19:41,863 [salt.state       :300 ][INFO    ][27194] {'pid': 27214, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-05-20 21:19:41,864 [salt.state       :1951][INFO    ][27194] Completed state [a2enmod headers] at time 21:19:41.864153 duration_in_ms=68.59
2019-05-20 21:19:41,864 [salt.state       :1780][INFO    ][27194] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 21:19:41.864512
2019-05-20 21:19:41,864 [salt.state       :1813][INFO    ][27194] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-05-20 21:19:41,878 [salt.state       :300 ][INFO    ][27194] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-05-20 21:19:41,878 [salt.state       :1951][INFO    ][27194] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 21:19:41.878566 duration_in_ms=14.054
2019-05-20 21:19:41,879 [salt.state       :1780][INFO    ][27194] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 21:19:41.879003
2019-05-20 21:19:41,879 [salt.state       :1813][INFO    ][27194] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-05-20 21:19:41,936 [salt.state       :300 ][INFO    ][27194] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-05-20 21:19:41,936 [salt.state       :1951][INFO    ][27194] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 21:19:41.936554 duration_in_ms=57.551
2019-05-20 21:19:41,936 [salt.state       :1780][INFO    ][27194] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 21:19:41.936918
2019-05-20 21:19:41,937 [salt.state       :1813][INFO    ][27194] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-05-20 21:19:42,021 [salt.state       :300 ][INFO    ][27194] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-05-20 21:19:42,021 [salt.state       :1951][INFO    ][27194] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 21:19:42.021669 duration_in_ms=84.747
2019-05-20 21:19:42,022 [salt.state       :1780][INFO    ][27194] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 21:19:42.022860
2019-05-20 21:19:42,023 [salt.state       :1813][INFO    ][27194] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-05-20 21:19:42,104 [salt.state       :300 ][INFO    ][27194] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-05-20 21:19:42,104 [salt.state       :1951][INFO    ][27194] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 21:19:42.104653 duration_in_ms=81.793
2019-05-20 21:19:42,105 [salt.state       :1780][INFO    ][27194] Running state [/root/.pgpass] at time 21:19:42.104983
2019-05-20 21:19:42,105 [salt.state       :1813][INFO    ][27194] Executing state file.managed for [/root/.pgpass]
2019-05-20 21:19:42,158 [salt.state       :300 ][INFO    ][27194] File /root/.pgpass is in the correct state
2019-05-20 21:19:42,158 [salt.state       :1951][INFO    ][27194] Completed state [/root/.pgpass] at time 21:19:42.158502 duration_in_ms=53.518
2019-05-20 21:19:42,165 [salt.state       :1780][INFO    ][27194] Running state [maas-region syncdb --noinput] at time 21:19:42.165258
2019-05-20 21:19:42,165 [salt.state       :1813][INFO    ][27194] Executing state cmd.run for [maas-region syncdb --noinput]
2019-05-20 21:19:42,166 [salt.loaded.int.module.cmdmod:395 ][INFO    ][27194] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-05-20 21:19:44,146 [salt.state       :300 ][INFO    ][27194] {'pid': 27227, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: sites, piston3, maasserver, contenttypes, metadataserver, sessions, auth\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-05-20 21:19:44,146 [salt.state       :1951][INFO    ][27194] Completed state [maas-region syncdb --noinput] at time 21:19:44.146676 duration_in_ms=1981.417
2019-05-20 21:19:44,146 [salt.state       :2022][WARNING ][27194] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-20 21:19:44,148 [salt.state       :1780][INFO    ][27194] Running state [maas-regiond] at time 21:19:44.148067
2019-05-20 21:19:44,148 [salt.state       :1813][INFO    ][27194] Executing state service.running for [maas-regiond]
2019-05-20 21:19:44,149 [salt.loaded.int.module.cmdmod:395 ][INFO    ][27194] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-05-20 21:19:44,182 [salt.loaded.int.module.cmdmod:395 ][INFO    ][27194] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-05-20 21:19:44,197 [salt.loaded.int.module.cmdmod:395 ][INFO    ][27194] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-05-20 21:19:44,212 [salt.state       :300 ][INFO    ][27194] The service maas-regiond is already running
2019-05-20 21:19:44,212 [salt.state       :1951][INFO    ][27194] Completed state [maas-regiond] at time 21:19:44.212384 duration_in_ms=64.316
2019-05-20 21:19:44,213 [salt.state       :1780][INFO    ][27194] Running state [bind9] at time 21:19:44.213433
2019-05-20 21:19:44,213 [salt.state       :1813][INFO    ][27194] Executing state service.running for [bind9]
2019-05-20 21:19:44,214 [salt.loaded.int.module.cmdmod:395 ][INFO    ][27194] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-05-20 21:19:44,229 [salt.loaded.int.module.cmdmod:395 ][INFO    ][27194] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-05-20 21:19:44,246 [salt.loaded.int.module.cmdmod:395 ][INFO    ][27194] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-05-20 21:19:44,262 [salt.state       :300 ][INFO    ][27194] The service bind9 is already running
2019-05-20 21:19:44,262 [salt.state       :1951][INFO    ][27194] Completed state [bind9] at time 21:19:44.262476 duration_in_ms=49.043
2019-05-20 21:19:44,263 [salt.state       :1780][INFO    ][27194] Running state [apache2] at time 21:19:44.263566
2019-05-20 21:19:44,263 [salt.state       :1813][INFO    ][27194] Executing state service.running for [apache2]
2019-05-20 21:19:44,264 [salt.loaded.int.module.cmdmod:395 ][INFO    ][27194] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-05-20 21:19:44,278 [salt.loaded.int.module.cmdmod:395 ][INFO    ][27194] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-05-20 21:19:44,292 [salt.loaded.int.module.cmdmod:395 ][INFO    ][27194] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-05-20 21:19:44,308 [salt.state       :300 ][INFO    ][27194] The service apache2 is already running
2019-05-20 21:19:44,309 [salt.state       :1951][INFO    ][27194] Completed state [apache2] at time 21:19:44.309199 duration_in_ms=45.632
2019-05-20 21:19:44,310 [salt.state       :1780][INFO    ][27194] Running state [maasng.wait_for_http_code] at time 21:19:44.310144
2019-05-20 21:19:44,310 [salt.state       :1813][INFO    ][27194] Executing state module.run for [maasng.wait_for_http_code]
2019-05-20 21:19:44,310 [salt.utils.decorators:613 ][WARNING ][27194] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-20 21:19:44,426 [salt.state       :300 ][INFO    ][27194] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-05-20 21:19:44,426 [salt.state       :1951][INFO    ][27194] Completed state [maasng.wait_for_http_code] at time 21:19:44.426614 duration_in_ms=116.469
2019-05-20 21:19:44,427 [salt.state       :1780][INFO    ][27194] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 21:19:44.427439
2019-05-20 21:19:44,427 [salt.state       :1813][INFO    ][27194] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-05-20 21:19:44,428 [salt.state       :300 ][INFO    ][27194] /var/lib/maas/.setup_admin exists
2019-05-20 21:19:44,428 [salt.state       :1951][INFO    ][27194] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 21:19:44.428470 duration_in_ms=1.031
2019-05-20 21:19:44,429 [salt.state       :1780][INFO    ][27194] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:19:44.429233
2019-05-20 21:19:44,429 [salt.state       :1813][INFO    ][27194] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-20 21:19:44,430 [salt.loaded.int.module.cmdmod:395 ][INFO    ][27194] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-20 21:19:45,897 [salt.state       :300 ][INFO    ][27194] {'pid': 27248, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-20 21:19:45,898 [salt.state       :1951][INFO    ][27194] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:19:45.897987 duration_in_ms=1468.754
2019-05-20 21:19:45,901 [salt.state       :1780][INFO    ][27194] Running state [maas_region_boot_source_resources_mirror] at time 21:19:45.901554
2019-05-20 21:19:45,901 [salt.state       :1813][INFO    ][27194] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-05-20 21:19:46,004 [salt.state       :300 ][INFO    ][27194] {'changes': {}}
2019-05-20 21:19:46,005 [salt.state       :1951][INFO    ][27194] Completed state [maas_region_boot_source_resources_mirror] at time 21:19:46.005078 duration_in_ms=103.523
2019-05-20 21:19:46,006 [salt.state       :1780][INFO    ][27194] Running state [maasng.boot_resources_import] at time 21:19:46.006167
2019-05-20 21:19:46,006 [salt.state       :1813][INFO    ][27194] Executing state module.run for [maasng.boot_resources_import]
2019-05-20 21:19:46,007 [salt.utils.decorators:613 ][WARNING ][27194] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-20 21:19:46,113 [salt.loaded.ext.module.maasng:1600][INFO    ][27194] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-05-20 21:19:51,181 [salt.loaded.ext.module.maasng:1600][INFO    ][27194] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-05-20 21:19:52,098 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520211952086110
2019-05-20 21:19:52,122 [salt.minion      :1432][INFO    ][27271] Starting a new job with PID 27271
2019-05-20 21:19:52,144 [salt.minion      :1711][INFO    ][27271] Returning information for job: 20190520211952086110
2019-05-20 21:19:56,247 [salt.loaded.ext.module.maasng:1600][INFO    ][27194] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-05-20 21:20:01,355 [salt.state       :300 ][INFO    ][27194] {'ret': True}
2019-05-20 21:20:01,355 [salt.state       :1951][INFO    ][27194] Completed state [maasng.boot_resources_import] at time 21:20:01.355830 duration_in_ms=15349.662
2019-05-20 21:20:01,357 [salt.state       :1780][INFO    ][27194] Running state [maas_region_boot_sources_selection_xenial] at time 21:20:01.357192
2019-05-20 21:20:01,357 [salt.state       :1813][INFO    ][27194] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-05-20 21:20:01,558 [salt.state       :300 ][INFO    ][27194] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-05-20 21:20:01,559 [salt.state       :1951][INFO    ][27194] Completed state [maas_region_boot_sources_selection_xenial] at time 21:20:01.559133 duration_in_ms=201.94
2019-05-20 21:20:01,560 [salt.state       :1780][INFO    ][27194] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 21:20:01.560454
2019-05-20 21:20:01,560 [salt.state       :1813][INFO    ][27194] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-05-20 21:20:01,561 [salt.utils.decorators:613 ][WARNING ][27194] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-20 21:20:01,562 [salt.loaded.ext.module.maasng:1771][INFO    ][27194] boot-sources sync initiated for ALL Rack's
2019-05-20 21:20:03,061 [salt.state       :300 ][INFO    ][27194] {'ret': True}
2019-05-20 21:20:03,062 [salt.state       :1951][INFO    ][27194] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 21:20:03.062313 duration_in_ms=1501.859
2019-05-20 21:20:03,064 [salt.state       :1780][INFO    ][27194] Running state [maas.process_maas_config] at time 21:20:03.064395
2019-05-20 21:20:03,064 [salt.state       :1813][INFO    ][27194] Executing state module.run for [maas.process_maas_config]
2019-05-20 21:20:03,065 [salt.utils.decorators:613 ][WARNING ][27194] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-20 21:20:03,066 [salt.loaded.ext.module.maas:92  ][INFO    ][27194] maasconfig name=enable_http_proxy value=True
2019-05-20 21:20:03,144 [salt.loaded.ext.module.maas:92  ][INFO    ][27194] maasconfig name=upstream_dns value=8.8.8.8
2019-05-20 21:20:03,217 [salt.loaded.ext.module.maas:92  ][INFO    ][27194] maasconfig name=commissioning_distro_series value=xenial
2019-05-20 21:20:03,288 [salt.loaded.ext.module.maas:92  ][INFO    ][27194] maasconfig name=default_osystem value=ubuntu
2019-05-20 21:20:03,355 [salt.loaded.ext.module.maas:92  ][INFO    ][27194] maasconfig name=active_discovery_interval value=600
2019-05-20 21:20:03,450 [salt.loaded.ext.module.maas:92  ][INFO    ][27194] maasconfig name=dnssec_validation value=no
2019-05-20 21:20:03,517 [salt.loaded.ext.module.maas:92  ][INFO    ][27194] maasconfig name=maas_name value=mas01
2019-05-20 21:20:06,121 [salt.loaded.ext.module.maas:92  ][INFO    ][27194] maasconfig name=network_discovery value=enabled
2019-05-20 21:20:06,284 [salt.loaded.ext.module.maas:92  ][INFO    ][27194] maasconfig name=enable_third_party_drivers value=True
2019-05-20 21:20:06,356 [salt.loaded.ext.module.maas:92  ][INFO    ][27194] maasconfig name=default_storage_layout value=lvm
2019-05-20 21:20:06,402 [salt.loaded.ext.module.maas:92  ][INFO    ][27194] maasconfig name=ntp_external_only value=True
2019-05-20 21:20:06,468 [salt.loaded.ext.module.maas:92  ][INFO    ][27194] maasconfig name=disk_erase_with_secure_erase value=False
2019-05-20 21:20:06,542 [salt.loaded.ext.module.maas:92  ][INFO    ][27194] maasconfig name=default_distro_series value=xenial
2019-05-20 21:20:06,615 [salt.loaded.ext.module.maas:92  ][INFO    ][27194] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-05-20 21:20:06,765 [salt.state       :300 ][INFO    ][27194] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-05-20 21:20:06,766 [salt.state       :1951][INFO    ][27194] Completed state [maas.process_maas_config] at time 21:20:06.766066 duration_in_ms=3701.671
2019-05-20 21:20:06,766 [salt.state       :1780][INFO    ][27194] Running state [pxe_admin] at time 21:20:06.766738
2019-05-20 21:20:06,767 [salt.state       :1813][INFO    ][27194] Executing state maasng.fabric_present for [pxe_admin]
2019-05-20 21:20:06,815 [salt.loaded.ext.module.maasng:945 ][INFO    ][27194] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'fabric_id': 1, u'dhcp_on': False, 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'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'bwhqxw', 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-05-20 21:20:06,863 [salt.loaded.ext.module.maasng:1008][WARNING ][27194] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-05-20 21:20:06,864 [salt.loaded.ext.module.maasng:1011][WARNING ][27194] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-05-20 21:20:06,905 [salt.state       :300 ][INFO    ][27194] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-05-20 21:20:06,905 [salt.state       :1951][INFO    ][27194] Completed state [pxe_admin] at time 21:20:06.905899 duration_in_ms=139.16
2019-05-20 21:20:06,906 [salt.state       :1780][INFO    ][27194] Running state [vlan 0] at time 21:20:06.906197
2019-05-20 21:20:06,906 [salt.state       :1813][INFO    ][27194] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-20 21:20:06,953 [salt.loaded.ext.module.maasng:945 ][INFO    ][27194] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'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'id': 1, u'name': u'fabric-1', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'bwhqxw', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'class_type': u''}]
2019-05-20 21:20:07,055 [salt.loaded.ext.module.maasng:945 ][INFO    ][27194] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'fabric_id': 1, u'dhcp_on': False, 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'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'bwhqxw', 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-05-20 21:20:07,295 [salt.loaded.ext.module.maasng:945 ][INFO    ][27194] [{u'id': 0, u'class_type': None, u'vlans': [{u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', 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'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'vid': 0, u'fabric': u'fabric-1', u'name': u'untagged', 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'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'bwhqxw', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-20 21:20:07,361 [salt.state       :300 ][INFO    ][27194] {'new': 'Vlan untagged was updated'}
2019-05-20 21:20:07,362 [salt.state       :1951][INFO    ][27194] Completed state [vlan 0] at time 21:20:07.361926 duration_in_ms=455.727
2019-05-20 21:20:07,363 [salt.state       :1780][INFO    ][27194] Running state [192.168.11.0/24] at time 21:20:07.363344
2019-05-20 21:20:07,363 [salt.state       :1813][INFO    ][27194] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-05-20 21:20:07,516 [salt.loaded.ext.module.maasng:945 ][INFO    ][27194] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, 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'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, 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'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'bwhqxw', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-20 21:20:07,517 [salt.loaded.ext.module.maasng:1235][WARNING ][27194] Ignoring parameter vlan:0
2019-05-20 21:20:07,571 [salt.state       :300 ][INFO    ][27194] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-05-20 21:20:07,571 [salt.state       :1951][INFO    ][27194] Completed state [192.168.11.0/24] at time 21:20:07.571479 duration_in_ms=208.133
2019-05-20 21:20:07,573 [salt.state       :1780][INFO    ][27194] Running state [maas_create_iprange_1] at time 21:20:07.573250
2019-05-20 21:20:07,573 [salt.state       :1813][INFO    ][27194] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-05-20 21:20:07,636 [salt.state       :300 ][INFO    ][27194] Iprange maas_create_iprange_1 already exist.
2019-05-20 21:20:07,637 [salt.state       :1951][INFO    ][27194] Completed state [maas_create_iprange_1] at time 21:20:07.637174 duration_in_ms=63.926
2019-05-20 21:20:07,637 [salt.state       :1780][INFO    ][27194] Running state [vlan 0] at time 21:20:07.637402
2019-05-20 21:20:07,637 [salt.state       :1813][INFO    ][27194] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-20 21:20:07,684 [salt.loaded.ext.module.maasng:945 ][INFO    ][27194] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, 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'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, 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'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'bwhqxw', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-20 21:20:07,775 [salt.loaded.ext.module.maasng:945 ][INFO    ][27194] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'fabric_id': 1, u'dhcp_on': False, 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'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'bwhqxw', 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-05-20 21:20:07,966 [salt.loaded.ext.module.maasng:945 ][INFO    ][27194] [{u'id': 0, u'class_type': None, u'vlans': [{u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', 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'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'vid': 0, u'fabric': u'fabric-1', u'name': u'untagged', 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'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'bwhqxw', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-20 21:20:08,026 [salt.state       :300 ][INFO    ][27194] {'new': 'Vlan untagged was updated'}
2019-05-20 21:20:08,026 [salt.state       :1951][INFO    ][27194] Completed state [vlan 0] at time 21:20:08.026500 duration_in_ms=389.097
2019-05-20 21:20:08,027 [salt.state       :1780][INFO    ][27194] Running state [opnfv] at time 21:20:08.027067
2019-05-20 21:20:08,027 [salt.state       :1813][INFO    ][27194] Executing state maasng.sshkey_present for [opnfv]
2019-05-20 21:20:08,067 [salt.loaded.ext.module.maasng:1903][INFO    ][27194] [{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-05-20 21:20:08,068 [salt.state       :300 ][INFO    ][27194] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-05-20 21:20:08,068 [salt.state       :1951][INFO    ][27194] Completed state [opnfv] at time 21:20:08.068310 duration_in_ms=41.243
2019-05-20 21:20:08,101 [salt.minion      :1711][INFO    ][27194] Returning information for job: 20190520211936976620
2019-05-20 21:20:08,547 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command state.apply with jid 20190520212008535580
2019-05-20 21:20:08,560 [salt.minion      :1432][INFO    ][27698] Starting a new job with PID 27698
2019-05-20 21:20:12,086 [salt.state       :915 ][INFO    ][27698] Loading fresh modules for state activity
2019-05-20 21:20:12,144 [salt.fileclient  :1219][INFO    ][27698] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-05-20 21:20:12,184 [salt.state       :1780][INFO    ][27698] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:20:12.183930
2019-05-20 21:20:12,184 [salt.state       :1813][INFO    ][27698] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-20 21:20:12,186 [salt.loaded.int.module.cmdmod:395 ][INFO    ][27698] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-20 21:20:13,653 [salt.state       :300 ][INFO    ][27698] {'pid': 27727, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-20 21:20:13,653 [salt.state       :1951][INFO    ][27698] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:20:13.653507 duration_in_ms=1469.577
2019-05-20 21:20:13,655 [salt.state       :1780][INFO    ][27698] Running state [maas.process_machines] at time 21:20:13.655082
2019-05-20 21:20:13,655 [salt.state       :1813][INFO    ][27698] Executing state module.run for [maas.process_machines]
2019-05-20 21:20:13,655 [salt.utils.decorators:613 ][WARNING ][27698] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-20 21:20:13,722 [salt.loaded.ext.module.maas:412 ][WARNING ][27698] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-20 21:20:13,722 [salt.loaded.ext.module.maas:92  ][INFO    ][27698] 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-05-20 21:20:15,196 [salt.loaded.ext.module.maas:412 ][WARNING ][27698] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-20 21:20:15,196 [salt.loaded.ext.module.maas:92  ][INFO    ][27698] 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-05-20 21:20:16,681 [salt.loaded.ext.module.maas:412 ][WARNING ][27698] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-20 21:20:16,681 [salt.loaded.ext.module.maas:92  ][INFO    ][27698] 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 architecture=amd64/generic power_parameters_power_user=admin
2019-05-20 21:20:18,083 [salt.loaded.ext.module.maas:412 ][WARNING ][27698] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-20 21:20:18,083 [salt.loaded.ext.module.maas:92  ][INFO    ][27698] 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-05-20 21:20:19,580 [salt.loaded.ext.module.maas:412 ][WARNING ][27698] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-20 21:20:19,581 [salt.loaded.ext.module.maas:92  ][INFO    ][27698] 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 architecture=amd64/generic power_parameters_power_user=admin
2019-05-20 21:20:21,078 [salt.state       :300 ][INFO    ][27698] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-05-20 21:20:21,078 [salt.state       :1951][INFO    ][27698] Completed state [maas.process_machines] at time 21:20:21.078789 duration_in_ms=7423.704
2019-05-20 21:20:21,082 [salt.minion      :1711][INFO    ][27698] Returning information for job: 20190520212008535580
2019-05-20 21:20:52,217 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command state.apply with jid 20190520212052203678
2019-05-20 21:20:52,238 [salt.minion      :1432][INFO    ][28019] Starting a new job with PID 28019
2019-05-20 21:20:55,901 [salt.state       :915 ][INFO    ][28019] Loading fresh modules for state activity
2019-05-20 21:20:55,954 [salt.fileclient  :1219][INFO    ][28019] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-05-20 21:20:56,000 [salt.state       :1780][INFO    ][28019] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:20:56.000091
2019-05-20 21:20:56,000 [salt.state       :1813][INFO    ][28019] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-20 21:20:56,002 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28019] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-20 21:20:57,419 [salt.state       :300 ][INFO    ][28019] {'pid': 28026, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-20 21:20:57,420 [salt.state       :1951][INFO    ][28019] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:20:57.420201 duration_in_ms=1420.111
2019-05-20 21:20:57,421 [salt.state       :1780][INFO    ][28019] Running state [maas.wait_for_machine_status] at time 21:20:57.421637
2019-05-20 21:20:57,421 [salt.state       :1813][INFO    ][28019] Executing state module.run for [maas.wait_for_machine_status]
2019-05-20 21:20:57,422 [salt.utils.decorators:613 ][WARNING ][28019] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-20 21:20:58,379 [salt.loaded.ext.module.maas:1023][INFO    ][28019] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.0469532s left)
2019-05-20 21:21:07,295 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520212107281846
2019-05-20 21:21:07,318 [salt.minion      :1432][INFO    ][28079] Starting a new job with PID 28079
2019-05-20 21:21:07,342 [salt.minion      :1711][INFO    ][28079] Returning information for job: 20190520212107281846
2019-05-20 21:21:29,364 [salt.loaded.ext.module.maas:1023][INFO    ][28019] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.06212807s left)
2019-05-20 21:21:37,336 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520212137323003
2019-05-20 21:21:37,358 [salt.minion      :1432][INFO    ][28098] Starting a new job with PID 28098
2019-05-20 21:21:37,382 [salt.minion      :1711][INFO    ][28098] Returning information for job: 20190520212137323003
2019-05-20 21:22:00,588 [salt.loaded.ext.module.maas:1023][INFO    ][28019] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1436.83777118s left)
2019-05-20 21:22:07,397 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520212207389263
2019-05-20 21:22:07,415 [salt.minion      :1432][INFO    ][28258] Starting a new job with PID 28258
2019-05-20 21:22:07,427 [salt.minion      :1711][INFO    ][28258] Returning information for job: 20190520212207389263
2019-05-20 21:22:31,824 [salt.loaded.ext.module.maas:1023][INFO    ][28019] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1405.60172606s left)
2019-05-20 21:22:37,426 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520212237414120
2019-05-20 21:22:37,449 [salt.minion      :1432][INFO    ][28369] Starting a new job with PID 28369
2019-05-20 21:22:37,471 [salt.minion      :1711][INFO    ][28369] Returning information for job: 20190520212237414120
2019-05-20 21:23:03,214 [salt.loaded.ext.module.maas:1023][INFO    ][28019] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1374.21242404s left)
2019-05-20 21:23:07,493 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520212307481128
2019-05-20 21:23:07,515 [salt.minion      :1432][INFO    ][29093] Starting a new job with PID 29093
2019-05-20 21:23:07,538 [salt.minion      :1711][INFO    ][29093] Returning information for job: 20190520212307481128
2019-05-20 21:23:35,140 [salt.loaded.ext.module.maas:1023][INFO    ][28019] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1342.28558421s left)
2019-05-20 21:23:37,548 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520212337532440
2019-05-20 21:23:37,571 [salt.minion      :1432][INFO    ][29153] Starting a new job with PID 29153
2019-05-20 21:23:37,596 [salt.minion      :1711][INFO    ][29153] Returning information for job: 20190520212337532440
2019-05-20 21:24:07,614 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520212407598895
2019-05-20 21:24:07,637 [salt.minion      :1432][INFO    ][29346] Starting a new job with PID 29346
2019-05-20 21:24:07,660 [salt.minion      :1711][INFO    ][29346] Returning information for job: 20190520212407598895
2019-05-20 21:24:08,351 [salt.state       :300 ][INFO    ][28019] {'ret': True}
2019-05-20 21:24:08,351 [salt.state       :1951][INFO    ][28019] Completed state [maas.wait_for_machine_status] at time 21:24:08.351751 duration_in_ms=190930.111
2019-05-20 21:24:08,355 [salt.minion      :1711][INFO    ][28019] Returning information for job: 20190520212052203678
2019-05-20 21:24:09,031 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command state.apply with jid 20190520212409014654
2019-05-20 21:24:09,053 [salt.minion      :1432][INFO    ][29354] Starting a new job with PID 29354
2019-05-20 21:24:12,744 [salt.state       :915 ][INFO    ][29354] Loading fresh modules for state activity
2019-05-20 21:24:12,796 [salt.fileclient  :1219][INFO    ][29354] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-05-20 21:24:12,878 [salt.state       :1780][INFO    ][29354] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:24:12.878668
2019-05-20 21:24:12,878 [salt.state       :1813][INFO    ][29354] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-20 21:24:12,880 [salt.loaded.int.module.cmdmod:395 ][INFO    ][29354] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-20 21:24:14,197 [salt.state       :300 ][INFO    ][29354] {'pid': 29362, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-20 21:24:14,198 [salt.state       :1951][INFO    ][29354] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:24:14.198455 duration_in_ms=1319.786
2019-05-20 21:24:14,201 [salt.state       :1780][INFO    ][29354] Running state [maas_machines_storage_cmp002_lvm] at time 21:24:14.201537
2019-05-20 21:24:14,202 [salt.state       :1813][INFO    ][29354] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-20 21:24:15,632 [salt.loaded.ext.module.maasng:610 ][INFO    ][29354] 8efycx
2019-05-20 21:24:15,633 [salt.loaded.ext.module.maasng:626 ][INFO    ][29354] sda
2019-05-20 21:24:16,358 [salt.loaded.ext.module.maasng:361 ][INFO    ][29354] 8efycx
2019-05-20 21:24:16,490 [salt.loaded.ext.module.maasng:367 ][INFO    ][29354] [{u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'uuid': None, u'resource_uri': u'/MAAS/api/2.0/nodes/8efycx/blockdevices/4/', u'used_for': u'GPT partitioned with 1 partition', u'used_size': 2397998940160, u'tags': [u'rotary'], u'name': u'sda', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'8efycx', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'uuid': u'7d333a99-ba9a-418b-8673-2b609f24e993', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'8efycx', u'device_id': 4, u'filesystem': {u'label': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'8961618e-69a5-4201-a921-cf7f5a26f602', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/8efycx/blockdevices/4/partition/5'}], u'type': u'physical', u'id': 4, u'size': 2397998940160}, {u'model': None, u'block_size': 4096, u'uuid': u'5569f636-9a59-49a7-afab-15b6736c7887', u'resource_uri': u'/MAAS/api/2.0/nodes/8efycx/blockdevices/10/', u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 2397988454400, u'tags': [], u'name': u'vgroot-lvroot', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'8efycx', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'27bcbf40-bee0-4134-ac8e-c000ce618d05', u'mount_options': None}, u'id_path': None, u'available_size': 0, u'serial': None, u'partitions': [], u'type': u'virtual', u'id': 10, u'size': 2397988454400}]
2019-05-20 21:24:16,491 [salt.loaded.ext.module.maasng:632 ][INFO    ][29354] vgroot
2019-05-20 21:24:16,491 [salt.loaded.ext.module.maasng:635 ][INFO    ][29354] lvroot
2019-05-20 21:24:16,492 [salt.loaded.ext.module.maasng:639 ][INFO    ][29354] 107374182400
2019-05-20 21:24:17,253 [salt.loaded.ext.module.maasng:645 ][INFO    ][29354] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'disable_ipv4': False, 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'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'bwhqxw', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'mode': u'dhcp', u'id': 32}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'bwhqxw', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'bwhqxw', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'system_id': u'8efycx', u'mac_address': u'00:25:b5:a0:00:6a', u'id': 4, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/8efycx/interfaces/4/'}, u'fqdn': u'cmp002.maas', u'status_action': u'modules-final', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 2397998940160, u'resource_uri': u'/MAAS/api/2.0/nodes/8efycx/blockdevices/4/', u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'id': 4, u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'8efycx', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'type': u'physical', u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'uuid': u'14b6d7e7-67b5-4118-8511-3db30c3d988a', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'8efycx', u'device_id': 4, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'c8c61fab-029d-491c-a626-b6536e831f94', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/8efycx/blockdevices/4/partition/8', u'type': u'partition', u'id': 8, u'size': 2397992648704}]}, u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/8efycx/', u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'8efycx', 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"'cloudinit' running modules for final", u'virtualblockdevice_set': [{u'size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/8efycx/blockdevices/13/', u'uuid': u'2357bc30-39b3-47c2-a8df-ece59e64546f', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'id': 13, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'ed76ccd7-c48a-488d-a4c6-4a1c655b3b77', u'fstype': u'ext4', u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'8efycx', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'serial': None, u'partitions': []}], u'blockdevice_set': [{u'size': 2397998940160, u'resource_uri': u'/MAAS/api/2.0/nodes/8efycx/blockdevices/4/', u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'id': 4, u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'8efycx', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'type': u'physical', u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'uuid': u'14b6d7e7-67b5-4118-8511-3db30c3d988a', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'8efycx', u'device_id': 4, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'c8c61fab-029d-491c-a626-b6536e831f94', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/8efycx/blockdevices/4/partition/8', u'type': u'partition', u'id': 8, u'size': 2397992648704}]}, {u'size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/8efycx/blockdevices/13/', u'uuid': u'2357bc30-39b3-47c2-a8df-ece59e64546f', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'id': 13, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'ed76ccd7-c48a-488d-a4c6-4a1c655b3b77', u'fstype': u'ext4', u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'8efycx', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'serial': None, u'partitions': []}], u'status': 4, u'bcaches': [], u'cpu_count': 16, u'power_state': u'on', u'physicalblockdevice_set': [{u'size': 2397998940160, u'resource_uri': u'/MAAS/api/2.0/nodes/8efycx/blockdevices/4/', u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'id': 4, u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'8efycx', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'type': u'physical', u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'uuid': u'14b6d7e7-67b5-4118-8511-3db30c3d988a', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'8efycx', u'device_id': 4, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'c8c61fab-029d-491c-a626-b6536e831f94', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/8efycx/blockdevices/4/partition/8', u'type': u'partition', u'id': 8, u'size': 2397992648704}]}], u'ip_addresses': [u'192.168.11.42'], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'8efycx', u'id': 8}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', 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'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'bwhqxw', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'mode': u'dhcp', u'id': 32}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'bwhqxw', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'bwhqxw', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'system_id': u'8efycx', u'mac_address': u'00:25:b5:a0:00:6a', u'id': 4, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/8efycx/interfaces/4/'}, {u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 36}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'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'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'system_id': u'8efycx', u'mac_address': u'00:25:b5:a0:00:6d', u'id': 22, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/8efycx/interfaces/22/'}, {u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 38}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'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'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'system_id': u'8efycx', u'mac_address': u'00:25:b5:a0:00:6c', u'id': 23, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/8efycx/interfaces/23/'}, {u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 34}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'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'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'system_id': u'8efycx', u'mac_address': u'00:25:b5:a0:00:6b', u'id': 21, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/8efycx/interfaces/21/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'storage_test_status': 2, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'node_type': 0, u'memory_test_status_name': u'Unknown', u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'current_commissioning_result_id': 2, u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-05-20 21:24:17,255 [salt.state       :300 ][INFO    ][29354] {'new': {'storage_layout': 'lvm'}}
2019-05-20 21:24:17,255 [salt.state       :1951][INFO    ][29354] Completed state [maas_machines_storage_cmp002_lvm] at time 21:24:17.255531 duration_in_ms=3053.993
2019-05-20 21:24:17,256 [salt.state       :1780][INFO    ][29354] Running state [maas_machines_storage_cmp001_lvm] at time 21:24:17.256065
2019-05-20 21:24:17,256 [salt.state       :1813][INFO    ][29354] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-20 21:24:18,369 [salt.loaded.ext.module.maasng:610 ][INFO    ][29354] fdbthf
2019-05-20 21:24:18,370 [salt.loaded.ext.module.maasng:626 ][INFO    ][29354] sda
2019-05-20 21:24:18,776 [salt.loaded.ext.module.maasng:361 ][INFO    ][29354] fdbthf
2019-05-20 21:24:18,903 [salt.loaded.ext.module.maasng:367 ][INFO    ][29354] [{u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'uuid': None, u'resource_uri': u'/MAAS/api/2.0/nodes/fdbthf/blockdevices/3/', u'used_for': u'GPT partitioned with 1 partition', u'used_size': 2397998940160, u'tags': [u'rotary'], u'name': u'sda', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'fdbthf', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'partitions': [{u'uuid': u'ce86fb87-98b6-4a0b-82c3-c795e6ac5ef3', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'fdbthf', u'device_id': 3, u'filesystem': {u'label': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'01b2d29b-467d-46a9-822f-4c16b3f01b84', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/fdbthf/blockdevices/3/partition/6'}], u'type': u'physical', u'id': 3, u'size': 2397998940160}, {u'model': None, u'block_size': 4096, u'uuid': u'60f952a0-c00d-4ff5-9b61-d8e69fae33f3', u'resource_uri': u'/MAAS/api/2.0/nodes/fdbthf/blockdevices/11/', u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 2397988454400, u'tags': [], u'name': u'vgroot-lvroot', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'fdbthf', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'cfd0bac1-b261-437d-ab7e-fdbfb9dbe605', u'mount_options': None}, u'id_path': None, u'available_size': 0, u'serial': None, u'partitions': [], u'type': u'virtual', u'id': 11, u'size': 2397988454400}]
2019-05-20 21:24:18,904 [salt.loaded.ext.module.maasng:632 ][INFO    ][29354] vgroot
2019-05-20 21:24:18,904 [salt.loaded.ext.module.maasng:635 ][INFO    ][29354] lvroot
2019-05-20 21:24:18,904 [salt.loaded.ext.module.maasng:639 ][INFO    ][29354] 107374182400
2019-05-20 21:24:19,654 [salt.loaded.ext.module.maasng:645 ][INFO    ][29354] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'testing_status_name': u'Passed', u'disable_ipv4': False, u'cpu_count': 16, u'power_type': u'ipmi', u'hwe_kernel': u'', u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'bwhqxw', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 39, u'mode': u'dhcp'}], u'tags': [], u'enabled': True, u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'bwhqxw', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'bwhqxw', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'system_id': u'fdbthf', u'params': u'', u'mac_address': u'00:25:b5:a0:00:5a', u'parents': [], u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/fdbthf/interfaces/5/'}, u'min_hwe_kernel': u'hwe-16.04', u'status_action': u'modules-final', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/fdbthf/blockdevices/3/', u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'171a9723-2808-43f4-a074-79c4e90ef6f5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'fdbthf', u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'1c60b1d5-e406-4a73-b7ea-164d6d8196b7', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 3, u'type': u'partition', u'id': 9, u'resource_uri': u'/MAAS/api/2.0/nodes/fdbthf/blockdevices/3/partition/9'}], u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'fdbthf', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'used_size': 2397998940160, u'id': 3, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}, u'zone': {u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default', u'description': u''}, u'resource_uri': u'/MAAS/api/2.0/machines/fdbthf/', u'current_commissioning_result_id': 4, u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'node_type': 0, u'testing_status': 2, u'system_id': u'fdbthf', u'power_state': u'on', 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"'cloudinit' running modules for final", u'owner_data': {}, u'blockdevice_set': [{u'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/fdbthf/blockdevices/3/', u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'171a9723-2808-43f4-a074-79c4e90ef6f5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'fdbthf', u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'1c60b1d5-e406-4a73-b7ea-164d6d8196b7', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 3, u'type': u'partition', u'id': 9, u'resource_uri': u'/MAAS/api/2.0/nodes/fdbthf/blockdevices/3/partition/9'}], u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'fdbthf', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'used_size': 2397998940160, u'id': 3, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}, {u'uuid': u'3b2ce9b7-d84f-468e-84a1-1375f3ccb15a', u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/fdbthf/blockdevices/14/', u'type': u'virtual', u'partitions': [], u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'fdbthf', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'uuid': u'd98e4750-3cf6-46fd-87d6-3763ab0dffe7', u'fstype': u'ext4', u'mount_options': None}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 14, u'serial': None, u'size': 107374182400}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'physicalblockdevice_set': [{u'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/fdbthf/blockdevices/3/', u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'171a9723-2808-43f4-a074-79c4e90ef6f5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'fdbthf', u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'1c60b1d5-e406-4a73-b7ea-164d6d8196b7', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 3, u'type': u'partition', u'id': 9, u'resource_uri': u'/MAAS/api/2.0/nodes/fdbthf/blockdevices/3/partition/9'}], u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'fdbthf', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'used_size': 2397998940160, u'id': 3, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}], u'ip_addresses': [u'192.168.11.38'], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'fdbthf', u'id': 9}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'node_type_name': u'Machine', u'current_testing_result_id': 5, u'cpu_test_status': -1, u'storage_test_status': 2, 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'uuid': u'3b2ce9b7-d84f-468e-84a1-1375f3ccb15a', u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/fdbthf/blockdevices/14/', u'type': u'virtual', u'partitions': [], u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'fdbthf', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'uuid': u'd98e4750-3cf6-46fd-87d6-3763ab0dffe7', u'fstype': u'ext4', u'mount_options': None}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 14, u'serial': None, u'size': 107374182400}], u'commissioning_status': 2, u'architecture': u'amd64/generic', 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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'bwhqxw', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 39, u'mode': u'dhcp'}], u'tags': [], u'enabled': True, u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'bwhqxw', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'bwhqxw', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'system_id': u'fdbthf', u'params': u'', u'mac_address': u'00:25:b5:a0:00:5a', u'parents': [], u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/fdbthf/interfaces/5/'}, {u'name': u'enp8s0', u'links': [{u'id': 40, u'mode': u'link_up'}], u'tags': [], u'enabled': True, u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, 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'effective_mtu': 1500, u'children': [], u'discovered': None, u'system_id': u'fdbthf', u'params': u'', u'mac_address': u'00:25:b5:a0:00:5c', u'parents': [], u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/fdbthf/interfaces/14/'}, {u'name': u'enp7s0', u'links': [{u'id': 41, u'mode': u'link_up'}], u'tags': [], u'enabled': True, u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, 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'effective_mtu': 1500, u'children': [], u'discovered': None, u'system_id': u'fdbthf', u'params': u'', u'mac_address': u'00:25:b5:a0:00:5b', u'parents': [], u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/fdbthf/interfaces/15/'}, {u'name': u'enp9s0', u'links': [{u'id': 42, u'mode': u'link_up'}], u'tags': [], u'enabled': True, u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, 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'effective_mtu': 1500, u'children': [], u'discovered': None, u'system_id': u'fdbthf', u'params': u'', u'mac_address': u'00:25:b5:a0:00:5d', u'parents': [], u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/fdbthf/interfaces/16/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-05-20 21:24:19,656 [salt.state       :300 ][INFO    ][29354] {'new': {'storage_layout': 'lvm'}}
2019-05-20 21:24:19,656 [salt.state       :1951][INFO    ][29354] Completed state [maas_machines_storage_cmp001_lvm] at time 21:24:19.656671 duration_in_ms=2400.606
2019-05-20 21:24:19,660 [salt.minion      :1711][INFO    ][29354] Returning information for job: 20190520212409014654
2019-05-20 21:24:20,303 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command state.apply with jid 20190520212420289386
2019-05-20 21:24:20,325 [salt.minion      :1432][INFO    ][29380] Starting a new job with PID 29380
2019-05-20 21:24:21,069 [salt.state       :915 ][INFO    ][29380] Loading fresh modules for state activity
2019-05-20 21:24:21,119 [salt.fileclient  :1219][INFO    ][29380] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-05-20 21:24:21,160 [salt.state       :1780][INFO    ][29380] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:24:21.160066
2019-05-20 21:24:21,160 [salt.state       :1813][INFO    ][29380] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-20 21:24:21,162 [salt.loaded.int.module.cmdmod:395 ][INFO    ][29380] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-20 21:24:22,474 [salt.state       :300 ][INFO    ][29380] {'pid': 29387, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-20 21:24:22,475 [salt.state       :1951][INFO    ][29380] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:24:22.475286 duration_in_ms=1315.221
2019-05-20 21:24:22,477 [salt.state       :1780][INFO    ][29380] Running state [maas.deploy_machines] at time 21:24:22.477061
2019-05-20 21:24:22,477 [salt.state       :1813][INFO    ][29380] Executing state module.run for [maas.deploy_machines]
2019-05-20 21:24:22,478 [salt.utils.decorators:613 ][WARNING ][29380] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-20 21:24:23,017 [salt.loaded.ext.module.maas:684 ][INFO    ][29380] deploymachines hwe_kernel=hwe-16.04 system_id=8efycx distro_series=xenial
2019-05-20 21:24:26,416 [salt.loaded.ext.module.maas:684 ][INFO    ][29380] deploymachines hwe_kernel=hwe-16.04 system_id=fdbthf distro_series=xenial
2019-05-20 21:24:29,020 [salt.loaded.ext.module.maas:684 ][INFO    ][29380] deploymachines hwe_kernel=hwe-16.04 system_id=yaddtf distro_series=xenial
2019-05-20 21:24:31,755 [salt.loaded.ext.module.maas:684 ][INFO    ][29380] deploymachines hwe_kernel=hwe-16.04 system_id=nq6br4 distro_series=xenial
2019-05-20 21:24:34,259 [salt.loaded.ext.module.maas:684 ][INFO    ][29380] deploymachines hwe_kernel=hwe-16.04 system_id=as6dpy distro_series=xenial
2019-05-20 21:24:35,344 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520212435331180
2019-05-20 21:24:35,367 [salt.minion      :1432][INFO    ][29719] Starting a new job with PID 29719
2019-05-20 21:24:35,391 [salt.minion      :1711][INFO    ][29719] Returning information for job: 20190520212435331180
2019-05-20 21:24:36,841 [salt.state       :300 ][INFO    ][29380] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-05-20 21:24:36,841 [salt.state       :1951][INFO    ][29380] Completed state [maas.deploy_machines] at time 21:24:36.841660 duration_in_ms=14364.598
2019-05-20 21:24:36,845 [salt.minion      :1711][INFO    ][29380] Returning information for job: 20190520212420289386
2019-05-20 21:24:37,476 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command state.apply with jid 20190520212437463926
2019-05-20 21:24:37,498 [salt.minion      :1432][INFO    ][29745] Starting a new job with PID 29745
2019-05-20 21:24:41,209 [salt.state       :915 ][INFO    ][29745] Loading fresh modules for state activity
2019-05-20 21:24:41,237 [salt.fileclient  :1219][INFO    ][29745] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-05-20 21:24:41,264 [salt.state       :1780][INFO    ][29745] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:24:41.264643
2019-05-20 21:24:41,264 [salt.state       :1813][INFO    ][29745] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-20 21:24:41,266 [salt.loaded.int.module.cmdmod:395 ][INFO    ][29745] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-20 21:24:42,687 [salt.state       :300 ][INFO    ][29745] {'pid': 29759, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-20 21:24:42,688 [salt.state       :1951][INFO    ][29745] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:24:42.687942 duration_in_ms=1423.299
2019-05-20 21:24:42,690 [salt.state       :1780][INFO    ][29745] Running state [maas.wait_for_machine_status] at time 21:24:42.690919
2019-05-20 21:24:42,691 [salt.state       :1813][INFO    ][29745] Executing state module.run for [maas.wait_for_machine_status]
2019-05-20 21:24:42,692 [salt.utils.decorators:613 ][WARNING ][29745] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-20 21:24:46,229 [salt.loaded.ext.module.maas:1023][INFO    ][29745] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.47192788s left)
2019-05-20 21:24:52,504 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520212452491346
2019-05-20 21:24:52,527 [salt.minion      :1432][INFO    ][29772] Starting a new job with PID 29772
2019-05-20 21:24:52,551 [salt.minion      :1711][INFO    ][29772] Returning information for job: 20190520212452491346
2019-05-20 21:25:19,849 [salt.loaded.ext.module.maas:1023][INFO    ][29745] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2212.85147786s left)
2019-05-20 21:25:22,561 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520212522546712
2019-05-20 21:25:22,584 [salt.minion      :1432][INFO    ][29830] Starting a new job with PID 29830
2019-05-20 21:25:22,608 [salt.minion      :1711][INFO    ][29830] Returning information for job: 20190520212522546712
2019-05-20 21:25:52,657 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520212552644850
2019-05-20 21:25:52,680 [salt.minion      :1432][INFO    ][29848] Starting a new job with PID 29848
2019-05-20 21:25:52,702 [salt.minion      :1711][INFO    ][29848] Returning information for job: 20190520212552644850
2019-05-20 21:25:53,260 [salt.loaded.ext.module.maas:1023][INFO    ][29745] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2179.44100285s left)
2019-05-20 21:26:22,696 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520212622687971
2019-05-20 21:26:22,715 [salt.minion      :1432][INFO    ][29987] Starting a new job with PID 29987
2019-05-20 21:26:22,735 [salt.minion      :1711][INFO    ][29987] Returning information for job: 20190520212622687971
2019-05-20 21:26:26,138 [salt.loaded.ext.module.maas:1023][INFO    ][29745] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2146.56318903s left)
2019-05-20 21:26:52,744 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520212652731210
2019-05-20 21:26:52,766 [salt.minion      :1432][INFO    ][30162] Starting a new job with PID 30162
2019-05-20 21:26:52,789 [salt.minion      :1711][INFO    ][30162] Returning information for job: 20190520212652731210
2019-05-20 21:26:59,590 [salt.loaded.ext.module.maas:1023][INFO    ][29745] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2113.11059499s left)
2019-05-20 21:27:22,803 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520212722792400
2019-05-20 21:27:22,824 [salt.minion      :1432][INFO    ][30770] Starting a new job with PID 30770
2019-05-20 21:27:22,849 [salt.minion      :1711][INFO    ][30770] Returning information for job: 20190520212722792400
2019-05-20 21:27:33,064 [salt.loaded.ext.module.maas:1023][INFO    ][29745] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2079.63693404s left)
2019-05-20 21:27:52,862 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520212752848865
2019-05-20 21:27:52,884 [salt.minion      :1432][INFO    ][30791] Starting a new job with PID 30791
2019-05-20 21:27:52,906 [salt.minion      :1711][INFO    ][30791] Returning information for job: 20190520212752848865
2019-05-20 21:28:05,782 [salt.loaded.ext.module.maas:1023][INFO    ][29745] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2046.91835785s left)
2019-05-20 21:28:22,930 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520212822914664
2019-05-20 21:28:22,954 [salt.minion      :1432][INFO    ][31111] Starting a new job with PID 31111
2019-05-20 21:28:22,979 [salt.minion      :1711][INFO    ][31111] Returning information for job: 20190520212822914664
2019-05-20 21:28:39,217 [salt.loaded.ext.module.maas:1023][INFO    ][29745] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2013.48394704s left)
2019-05-20 21:28:52,994 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520212852981807
2019-05-20 21:28:53,017 [salt.minion      :1432][INFO    ][31142] Starting a new job with PID 31142
2019-05-20 21:28:53,041 [salt.minion      :1711][INFO    ][31142] Returning information for job: 20190520212852981807
2019-05-20 21:29:12,235 [salt.loaded.ext.module.maas:1023][INFO    ][29745] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1980.46577597s left)
2019-05-20 21:29:23,071 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520212923057607
2019-05-20 21:29:23,094 [salt.minion      :1432][INFO    ][31582] Starting a new job with PID 31582
2019-05-20 21:29:23,117 [salt.minion      :1711][INFO    ][31582] Returning information for job: 20190520212923057607
2019-05-20 21:29:45,525 [salt.loaded.ext.module.maas:1023][INFO    ][29745] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1947.17568088s left)
2019-05-20 21:29:53,144 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520212953129437
2019-05-20 21:29:53,166 [salt.minion      :1432][INFO    ][31610] Starting a new job with PID 31610
2019-05-20 21:29:53,189 [salt.minion      :1711][INFO    ][31610] Returning information for job: 20190520212953129437
2019-05-20 21:30:19,007 [salt.loaded.ext.module.maas:1023][INFO    ][29745] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1913.69325185s left)
2019-05-20 21:30:23,226 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520213023208312
2019-05-20 21:30:23,249 [salt.minion      :1432][INFO    ][32067] Starting a new job with PID 32067
2019-05-20 21:30:23,273 [salt.minion      :1711][INFO    ][32067] Returning information for job: 20190520213023208312
2019-05-20 21:30:52,123 [salt.loaded.ext.module.maas:1023][INFO    ][29745] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1880.577425s left)
2019-05-20 21:30:53,308 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520213053295416
2019-05-20 21:30:53,328 [salt.minion      :1432][INFO    ][32088] Starting a new job with PID 32088
2019-05-20 21:30:53,352 [salt.minion      :1711][INFO    ][32088] Returning information for job: 20190520213053295416
2019-05-20 21:31:23,391 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520213123378507
2019-05-20 21:31:23,414 [salt.minion      :1432][INFO    ][32279] Starting a new job with PID 32279
2019-05-20 21:31:23,437 [salt.minion      :1711][INFO    ][32279] Returning information for job: 20190520213123378507
2019-05-20 21:31:25,852 [salt.loaded.ext.module.maas:1023][INFO    ][29745] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1846.84901094s left)
2019-05-20 21:31:53,486 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520213153473794
2019-05-20 21:31:53,508 [salt.minion      :1432][INFO    ][32366] Starting a new job with PID 32366
2019-05-20 21:31:53,530 [salt.minion      :1711][INFO    ][32366] Returning information for job: 20190520213153473794
2019-05-20 21:31:59,847 [salt.loaded.ext.module.maas:1023][INFO    ][29745] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1812.85366488s left)
2019-05-20 21:32:23,702 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520213223689799
2019-05-20 21:32:23,724 [salt.minion      :1432][INFO    ][32586] Starting a new job with PID 32586
2019-05-20 21:32:23,747 [salt.minion      :1711][INFO    ][32586] Returning information for job: 20190520213223689799
2019-05-20 21:32:32,881 [salt.loaded.ext.module.maas:1023][INFO    ][29745] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1779.81993794s left)
2019-05-20 21:32:53,811 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520213253798531
2019-05-20 21:32:53,835 [salt.minion      :1432][INFO    ][32645] Starting a new job with PID 32645
2019-05-20 21:32:53,860 [salt.minion      :1711][INFO    ][32645] Returning information for job: 20190520213253798531
2019-05-20 21:33:05,148 [salt.loaded.ext.module.maas:1023][INFO    ][29745] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1747.55243087s left)
2019-05-20 21:33:23,919 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520213323906403
2019-05-20 21:33:23,941 [salt.minion      :1432][INFO    ][596] Starting a new job with PID 596
2019-05-20 21:33:23,965 [salt.minion      :1711][INFO    ][596] Returning information for job: 20190520213323906403
2019-05-20 21:33:38,497 [salt.loaded.ext.module.maas:1023][INFO    ][29745] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1714.20416284s left)
2019-05-20 21:33:54,037 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520213354024024
2019-05-20 21:33:54,060 [salt.minion      :1432][INFO    ][619] Starting a new job with PID 619
2019-05-20 21:33:54,084 [salt.minion      :1711][INFO    ][619] Returning information for job: 20190520213354024024
2019-05-20 21:34:11,957 [salt.loaded.ext.module.maas:1023][INFO    ][29745] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1680.74376202s left)
2019-05-20 21:34:24,163 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520213424149688
2019-05-20 21:34:24,186 [salt.minion      :1432][INFO    ][680] Starting a new job with PID 680
2019-05-20 21:34:24,211 [salt.minion      :1711][INFO    ][680] Returning information for job: 20190520213424149688
2019-05-20 21:34:45,490 [salt.loaded.ext.module.maas:1023][INFO    ][29745] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1647.21029282s left)
2019-05-20 21:34:54,298 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520213454286223
2019-05-20 21:34:54,321 [salt.minion      :1432][INFO    ][700] Starting a new job with PID 700
2019-05-20 21:34:54,346 [salt.minion      :1711][INFO    ][700] Returning information for job: 20190520213454286223
2019-05-20 21:35:19,102 [salt.loaded.ext.module.maas:1023][INFO    ][29745] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1613.59911203s left)
2019-05-20 21:35:24,442 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520213524429933
2019-05-20 21:35:24,466 [salt.minion      :1432][INFO    ][754] Starting a new job with PID 754
2019-05-20 21:35:24,489 [salt.minion      :1711][INFO    ][754] Returning information for job: 20190520213524429933
2019-05-20 21:35:52,506 [salt.loaded.ext.module.maas:1023][INFO    ][29745] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1580.19431186s left)
2019-05-20 21:35:54,593 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520213554580919
2019-05-20 21:35:54,615 [salt.minion      :1432][INFO    ][777] Starting a new job with PID 777
2019-05-20 21:35:54,639 [salt.minion      :1711][INFO    ][777] Returning information for job: 20190520213554580919
2019-05-20 21:36:24,753 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520213624740048
2019-05-20 21:36:24,775 [salt.minion      :1432][INFO    ][833] Starting a new job with PID 833
2019-05-20 21:36:24,799 [salt.minion      :1711][INFO    ][833] Returning information for job: 20190520213624740048
2019-05-20 21:36:26,048 [salt.loaded.ext.module.maas:1023][INFO    ][29745] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1546.65236282s left)
2019-05-20 21:36:54,917 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520213654905691
2019-05-20 21:36:54,940 [salt.minion      :1432][INFO    ][860] Starting a new job with PID 860
2019-05-20 21:36:54,964 [salt.minion      :1711][INFO    ][860] Returning information for job: 20190520213654905691
2019-05-20 21:36:59,422 [salt.loaded.ext.module.maas:1023][INFO    ][29745] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1513.27957702s left)
2019-05-20 21:37:25,098 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520213725085861
2019-05-20 21:37:25,121 [salt.minion      :1432][INFO    ][918] Starting a new job with PID 918
2019-05-20 21:37:25,143 [salt.minion      :1711][INFO    ][918] Returning information for job: 20190520213725085861
2019-05-20 21:37:32,856 [salt.loaded.ext.module.maas:1023][INFO    ][29745] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1479.84501886s left)
2019-05-20 21:37:55,284 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520213755272643
2019-05-20 21:37:55,307 [salt.minion      :1432][INFO    ][938] Starting a new job with PID 938
2019-05-20 21:37:55,331 [salt.minion      :1711][INFO    ][938] Returning information for job: 20190520213755272643
2019-05-20 21:38:06,281 [salt.loaded.ext.module.maas:1023][INFO    ][29745] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1446.41991591s left)
2019-05-20 21:38:25,483 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520213825471261
2019-05-20 21:38:25,506 [salt.minion      :1432][INFO    ][1120] Starting a new job with PID 1120
2019-05-20 21:38:25,530 [salt.minion      :1711][INFO    ][1120] Returning information for job: 20190520213825471261
2019-05-20 21:38:39,370 [salt.loaded.ext.module.maas:1023][INFO    ][29745] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1413.33058095s left)
2019-05-20 21:38:55,697 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520213855685039
2019-05-20 21:38:55,720 [salt.minion      :1432][INFO    ][1140] Starting a new job with PID 1140
2019-05-20 21:38:55,742 [salt.minion      :1711][INFO    ][1140] Returning information for job: 20190520213855685039
2019-05-20 21:39:13,046 [salt.loaded.ext.module.maas:1023][INFO    ][29745] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1379.65458298s left)
2019-05-20 21:39:25,914 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520213925902057
2019-05-20 21:39:25,938 [salt.minion      :1432][INFO    ][1194] Starting a new job with PID 1194
2019-05-20 21:39:25,963 [salt.minion      :1711][INFO    ][1194] Returning information for job: 20190520213925902057
2019-05-20 21:39:45,939 [salt.loaded.ext.module.maas:1023][INFO    ][29745] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1346.76197791s left)
2019-05-20 21:39:55,939 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520213955927716
2019-05-20 21:39:55,962 [salt.minion      :1432][INFO    ][1215] Starting a new job with PID 1215
2019-05-20 21:39:55,985 [salt.minion      :1711][INFO    ][1215] Returning information for job: 20190520213955927716
2019-05-20 21:40:19,554 [salt.loaded.ext.module.maas:993 ][INFO    ][29745] Machine as6dpy mark broken
2019-05-20 21:40:20,141 [salt.loaded.ext.module.maas:996 ][INFO    ][29745] Machine as6dpy mark fixed
2019-05-20 21:40:21,324 [salt.loaded.ext.module.maas:684 ][INFO    ][29745] deploymachines hwe_kernel=hwe-16.04 system_id=as6dpy distro_series=xenial
2019-05-20 21:40:24,039 [salt.loaded.ext.module.maas:160 ][ERROR   ][29745] 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-05-20 21:40:24,041 [salt.state       :302 ][ERROR   ][29745] 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-05-20 21:40:24,041 [salt.state       :1951][INFO    ][29745] Completed state [maas.wait_for_machine_status] at time 21:40:24.041795 duration_in_ms=941350.854
2019-05-20 21:40:24,051 [salt.minion      :1711][INFO    ][29745] Returning information for job: 20190520212437463926
2019-05-20 21:40:34,800 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command pillar.get with jid 20190520214034787405
2019-05-20 21:40:34,820 [salt.minion      :1432][INFO    ][1360] Starting a new job with PID 1360
2019-05-20 21:40:34,825 [salt.minion      :1711][INFO    ][1360] Returning information for job: 20190520214034787405
2019-05-20 21:40:35,334 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command service.status with jid 20190520214035321887
2019-05-20 21:40:35,352 [salt.minion      :1432][INFO    ][1365] Starting a new job with PID 1365
2019-05-20 21:40:35,802 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][1365] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-05-20 21:40:35,837 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][1365] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-05-20 21:40:35,854 [salt.minion      :1711][INFO    ][1365] Returning information for job: 20190520214035321887
2019-05-20 21:40:36,342 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command state.apply with jid 20190520214036329783
2019-05-20 21:40:36,362 [salt.minion      :1432][INFO    ][1376] Starting a new job with PID 1376
2019-05-20 21:40:40,126 [salt.state       :915 ][INFO    ][1376] Loading fresh modules for state activity
2019-05-20 21:40:40,564 [salt.loaded.int.module.cmdmod:395 ][INFO    ][1376] Executing command 'salt-minion --version' in directory '/root'
2019-05-20 21:40:40,862 [salt.loaded.int.module.cmdmod:395 ][INFO    ][1376] Executing command 'salt-minion --version' in directory '/root'
2019-05-20 21:40:41,779 [salt.loaded.int.module.cmdmod:395 ][INFO    ][1376] Executing command 'salt-minion --version' in directory '/root'
2019-05-20 21:40:42,010 [salt.loaded.int.module.cmdmod:395 ][INFO    ][1376] Executing command 'salt-minion --version' in directory '/root'
2019-05-20 21:40:43,496 [salt.state       :1780][INFO    ][1376] Running state [salt-minion] at time 21:40:43.495976
2019-05-20 21:40:43,496 [salt.state       :1813][INFO    ][1376] Executing state pkg.installed for [salt-minion]
2019-05-20 21:40:43,496 [salt.loaded.int.module.cmdmod:395 ][INFO    ][1376] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-20 21:40:43,561 [salt.state       :300 ][INFO    ][1376] All specified packages are already installed
2019-05-20 21:40:43,561 [salt.state       :1951][INFO    ][1376] Completed state [salt-minion] at time 21:40:43.561290 duration_in_ms=65.314
2019-05-20 21:40:43,561 [salt.state       :1780][INFO    ][1376] Running state [salt_minion_dependency_packages] at time 21:40:43.561546
2019-05-20 21:40:43,561 [salt.state       :1813][INFO    ][1376] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-05-20 21:40:43,566 [salt.state       :300 ][INFO    ][1376] All specified packages are already installed
2019-05-20 21:40:43,566 [salt.state       :1951][INFO    ][1376] Completed state [salt_minion_dependency_packages] at time 21:40:43.566402 duration_in_ms=4.856
2019-05-20 21:40:43,568 [salt.state       :1780][INFO    ][1376] Running state [/etc/salt/minion.d/minion.conf] at time 21:40:43.568766
2019-05-20 21:40:43,568 [salt.state       :1813][INFO    ][1376] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-05-20 21:40:43,753 [salt.state       :300 ][INFO    ][1376] File /etc/salt/minion.d/minion.conf is in the correct state
2019-05-20 21:40:43,754 [salt.state       :1951][INFO    ][1376] Completed state [/etc/salt/minion.d/minion.conf] at time 21:40:43.754200 duration_in_ms=185.434
2019-05-20 21:40:43,754 [salt.state       :1780][INFO    ][1376] Running state [python-netaddr] at time 21:40:43.754452
2019-05-20 21:40:43,754 [salt.state       :1813][INFO    ][1376] Executing state pkg.installed for [python-netaddr]
2019-05-20 21:40:43,761 [salt.state       :300 ][INFO    ][1376] All specified packages are already installed
2019-05-20 21:40:43,761 [salt.state       :1951][INFO    ][1376] Completed state [python-netaddr] at time 21:40:43.761324 duration_in_ms=6.872
2019-05-20 21:40:43,764 [salt.state       :1780][INFO    ][1376] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 21:40:43.764266
2019-05-20 21:40:43,764 [salt.state       :1813][INFO    ][1376] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-05-20 21:40:43,775 [salt.state       :300 ][INFO    ][1376] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-05-20 21:40:43,775 [salt.state       :1951][INFO    ][1376] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 21:40:43.775523 duration_in_ms=11.257
2019-05-20 21:40:43,776 [salt.state       :1780][INFO    ][1376] Running state [salt-minion] at time 21:40:43.776461
2019-05-20 21:40:43,776 [salt.state       :1813][INFO    ][1376] Executing state service.running for [salt-minion]
2019-05-20 21:40:43,777 [salt.loaded.int.module.cmdmod:395 ][INFO    ][1376] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-20 21:40:43,813 [salt.loaded.int.module.cmdmod:395 ][INFO    ][1376] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-05-20 21:40:43,830 [salt.loaded.int.module.cmdmod:395 ][INFO    ][1376] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-05-20 21:40:43,846 [salt.state       :300 ][INFO    ][1376] The service salt-minion is already running
2019-05-20 21:40:43,846 [salt.state       :1951][INFO    ][1376] Completed state [salt-minion] at time 21:40:43.846808 duration_in_ms=70.346
2019-05-20 21:40:43,848 [salt.state       :1780][INFO    ][1376] Running state [/etc/salt/grains.d] at time 21:40:43.848585
2019-05-20 21:40:43,849 [salt.state       :1813][INFO    ][1376] Executing state file.directory for [/etc/salt/grains.d]
2019-05-20 21:40:43,850 [salt.state       :300 ][INFO    ][1376] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-05-20 21:40:43,850 [salt.state       :1951][INFO    ][1376] Completed state [/etc/salt/grains.d] at time 21:40:43.850330 duration_in_ms=1.745
2019-05-20 21:40:43,851 [salt.state       :1780][INFO    ][1376] Running state [/etc/salt/grains] at time 21:40:43.851117
2019-05-20 21:40:43,851 [salt.state       :1813][INFO    ][1376] Executing state file.managed for [/etc/salt/grains]
2019-05-20 21:40:43,852 [salt.state       :300 ][INFO    ][1376] File /etc/salt/grains exists with proper permissions. No changes made.
2019-05-20 21:40:43,852 [salt.state       :1951][INFO    ][1376] Completed state [/etc/salt/grains] at time 21:40:43.852354 duration_in_ms=1.237
2019-05-20 21:40:43,852 [salt.state       :1780][INFO    ][1376] Running state [/etc/salt/grains.d/placeholder] at time 21:40:43.852906
2019-05-20 21:40:43,853 [salt.state       :1813][INFO    ][1376] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-05-20 21:40:43,853 [salt.state       :300 ][INFO    ][1376] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-05-20 21:40:43,854 [salt.state       :1951][INFO    ][1376] Completed state [/etc/salt/grains.d/placeholder] at time 21:40:43.854098 duration_in_ms=1.192
2019-05-20 21:40:43,854 [salt.state       :1780][INFO    ][1376] Running state [/etc/salt/grains.d/sphinx] at time 21:40:43.854650
2019-05-20 21:40:43,854 [salt.state       :1813][INFO    ][1376] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-05-20 21:40:43,867 [salt.state       :300 ][INFO    ][1376] File /etc/salt/grains.d/sphinx is in the correct state
2019-05-20 21:40:43,868 [salt.state       :1951][INFO    ][1376] Completed state [/etc/salt/grains.d/sphinx] at time 21:40:43.868046 duration_in_ms=13.396
2019-05-20 21:40:43,870 [salt.state       :1780][INFO    ][1376] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 21:40:43.870691
2019-05-20 21:40:43,871 [salt.state       :1813][INFO    ][1376] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-05-20 21:40:43,871 [salt.state       :300 ][INFO    ][1376] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-05-20 21:40:43,871 [salt.state       :1951][INFO    ][1376] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 21:40:43.871732 duration_in_ms=1.04
2019-05-20 21:40:43,872 [salt.state       :1780][INFO    ][1376] Running state [/etc/salt/grains.d/dns_records] at time 21:40:43.872285
2019-05-20 21:40:43,872 [salt.state       :1813][INFO    ][1376] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-05-20 21:40:43,885 [salt.state       :300 ][INFO    ][1376] File /etc/salt/grains.d/dns_records is in the correct state
2019-05-20 21:40:43,885 [salt.state       :1951][INFO    ][1376] Completed state [/etc/salt/grains.d/dns_records] at time 21:40:43.885829 duration_in_ms=13.544
2019-05-20 21:40:43,886 [salt.state       :1780][INFO    ][1376] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 21:40:43.886883
2019-05-20 21:40:43,887 [salt.state       :1813][INFO    ][1376] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-05-20 21:40:43,887 [salt.state       :300 ][INFO    ][1376] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-05-20 21:40:43,887 [salt.state       :1951][INFO    ][1376] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 21:40:43.887868 duration_in_ms=0.986
2019-05-20 21:40:43,888 [salt.state       :1780][INFO    ][1376] Running state [/etc/salt/grains.d/salt] at time 21:40:43.888408
2019-05-20 21:40:43,888 [salt.state       :1813][INFO    ][1376] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-05-20 21:40:43,897 [salt.state       :300 ][INFO    ][1376] File /etc/salt/grains.d/salt is in the correct state
2019-05-20 21:40:43,898 [salt.state       :1951][INFO    ][1376] Completed state [/etc/salt/grains.d/salt] at time 21:40:43.897938 duration_in_ms=9.53
2019-05-20 21:40:43,899 [salt.state       :1780][INFO    ][1376] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 21:40:43.898940
2019-05-20 21:40:43,899 [salt.state       :1813][INFO    ][1376] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-05-20 21:40:43,899 [salt.state       :300 ][INFO    ][1376] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-05-20 21:40:43,899 [salt.state       :1951][INFO    ][1376] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 21:40:43.899920 duration_in_ms=0.979
2019-05-20 21:40:43,902 [salt.state       :1780][INFO    ][1376] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 21:40:43.902052
2019-05-20 21:40:43,902 [salt.state       :1813][INFO    ][1376] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-05-20 21:40:43,902 [salt.state       :300 ][INFO    ][1376] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-05-20 21:40:43,903 [salt.state       :1951][INFO    ][1376] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 21:40:43.903052 duration_in_ms=1.0
2019-05-20 21:40:43,903 [salt.state       :1780][INFO    ][1376] Running state [mine.update] at time 21:40:43.903834
2019-05-20 21:40:43,904 [salt.state       :1813][INFO    ][1376] Executing state module.wait for [mine.update]
2019-05-20 21:40:43,904 [salt.state       :300 ][INFO    ][1376] No changes made for mine.update
2019-05-20 21:40:43,904 [salt.state       :1951][INFO    ][1376] Completed state [mine.update] at time 21:40:43.904758 duration_in_ms=0.924
2019-05-20 21:40:43,905 [salt.state       :1780][INFO    ][1376] Running state [ca-certificates] at time 21:40:43.905044
2019-05-20 21:40:43,905 [salt.state       :1813][INFO    ][1376] Executing state pkg.installed for [ca-certificates]
2019-05-20 21:40:43,913 [salt.state       :300 ][INFO    ][1376] All specified packages are already installed
2019-05-20 21:40:43,914 [salt.state       :1951][INFO    ][1376] Completed state [ca-certificates] at time 21:40:43.913964 duration_in_ms=8.92
2019-05-20 21:40:43,914 [salt.state       :1780][INFO    ][1376] Running state [update-ca-certificates] at time 21:40:43.914752
2019-05-20 21:40:43,915 [salt.state       :1813][INFO    ][1376] Executing state cmd.wait for [update-ca-certificates]
2019-05-20 21:40:43,915 [salt.state       :300 ][INFO    ][1376] No changes made for update-ca-certificates
2019-05-20 21:40:43,915 [salt.state       :1951][INFO    ][1376] Completed state [update-ca-certificates] at time 21:40:43.915671 duration_in_ms=0.919
2019-05-20 21:40:43,915 [salt.state       :1780][INFO    ][1376] Running state [iptables] at time 21:40:43.915942
2019-05-20 21:40:43,916 [salt.state       :1813][INFO    ][1376] Executing state pkg.installed for [iptables]
2019-05-20 21:40:43,923 [salt.state       :300 ][INFO    ][1376] All specified packages are already installed
2019-05-20 21:40:43,924 [salt.state       :1951][INFO    ][1376] Completed state [iptables] at time 21:40:43.924005 duration_in_ms=8.063
2019-05-20 21:40:43,924 [salt.state       :1780][INFO    ][1376] Running state [iptables-persistent] at time 21:40:43.924276
2019-05-20 21:40:43,924 [salt.state       :1813][INFO    ][1376] Executing state pkg.installed for [iptables-persistent]
2019-05-20 21:40:43,931 [salt.state       :300 ][INFO    ][1376] All specified packages are already installed
2019-05-20 21:40:43,932 [salt.state       :1951][INFO    ][1376] Completed state [iptables-persistent] at time 21:40:43.932053 duration_in_ms=7.776
2019-05-20 21:40:43,933 [salt.state       :1780][INFO    ][1376] Running state [iptables_modules_v4_load] at time 21:40:43.933095
2019-05-20 21:40:43,933 [salt.state       :1813][INFO    ][1376] Executing state kmod.present for [iptables_modules_v4_load]
2019-05-20 21:40:43,934 [salt.loaded.int.module.cmdmod:395 ][INFO    ][1376] Executing command 'lsmod' in directory '/root'
2019-05-20 21:40:43,957 [salt.state       :300 ][INFO    ][1376] Kernel modules iptable_filter, ip_tables are already present
2019-05-20 21:40:43,958 [salt.state       :1951][INFO    ][1376] Completed state [iptables_modules_v4_load] at time 21:40:43.958238 duration_in_ms=25.143
2019-05-20 21:40:43,958 [salt.state       :1780][INFO    ][1376] Running state [/etc/iptables/rules.v4] at time 21:40:43.958920
2019-05-20 21:40:43,959 [salt.state       :1813][INFO    ][1376] Executing state file.managed for [/etc/iptables/rules.v4]
2019-05-20 21:40:44,047 [salt.state       :300 ][INFO    ][1376] File /etc/iptables/rules.v4 is in the correct state
2019-05-20 21:40:44,047 [salt.state       :1951][INFO    ][1376] Completed state [/etc/iptables/rules.v4] at time 21:40:44.047620 duration_in_ms=88.7
2019-05-20 21:40:44,048 [salt.state       :1780][INFO    ][1376] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 21:40:44.048558
2019-05-20 21:40:44,048 [salt.state       :1813][INFO    ][1376] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-05-20 21:40:44,049 [salt.loaded.int.module.cmdmod:395 ][INFO    ][1376] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-05-20 21:40:44,068 [salt.state       :300 ][INFO    ][1376] onlyif execution failed
2019-05-20 21:40:44,069 [salt.state       :1951][INFO    ][1376] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 21:40:44.069023 duration_in_ms=20.464
2019-05-20 21:40:44,070 [salt.state       :1780][INFO    ][1376] Running state [netfilter-persistent] at time 21:40:44.070801
2019-05-20 21:40:44,071 [salt.state       :1813][INFO    ][1376] Executing state service.running for [netfilter-persistent]
2019-05-20 21:40:44,072 [salt.loaded.int.module.cmdmod:395 ][INFO    ][1376] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-05-20 21:40:44,094 [salt.loaded.int.module.cmdmod:395 ][INFO    ][1376] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-05-20 21:40:44,112 [salt.loaded.int.module.cmdmod:395 ][INFO    ][1376] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-05-20 21:40:44,130 [salt.state       :300 ][INFO    ][1376] The service netfilter-persistent is already running
2019-05-20 21:40:44,130 [salt.state       :1951][INFO    ][1376] Completed state [netfilter-persistent] at time 21:40:44.130519 duration_in_ms=59.717
2019-05-20 21:40:44,131 [salt.state       :1780][INFO    ][1376] Running state [iptables_extra.remove_stale_tables] at time 21:40:44.131709
2019-05-20 21:40:44,132 [salt.state       :1813][INFO    ][1376] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-05-20 21:40:44,132 [salt.state       :300 ][INFO    ][1376] No changes made for iptables_extra.remove_stale_tables
2019-05-20 21:40:44,132 [salt.state       :1951][INFO    ][1376] Completed state [iptables_extra.remove_stale_tables] at time 21:40:44.132924 duration_in_ms=1.216
2019-05-20 21:40:44,133 [salt.state       :1780][INFO    ][1376] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 21:40:44.133251
2019-05-20 21:40:44,133 [salt.state       :1813][INFO    ][1376] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-05-20 21:40:44,134 [salt.loaded.int.module.cmdmod:395 ][INFO    ][1376] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-05-20 21:40:44,149 [salt.state       :300 ][INFO    ][1376] onlyif execution failed
2019-05-20 21:40:44,149 [salt.state       :1951][INFO    ][1376] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 21:40:44.149882 duration_in_ms=16.63
2019-05-20 21:40:44,151 [salt.state       :1780][INFO    ][1376] Running state [/etc/iptables/rules.v6] at time 21:40:44.151204
2019-05-20 21:40:44,151 [salt.state       :1813][INFO    ][1376] Executing state file.absent for [/etc/iptables/rules.v6]
2019-05-20 21:40:44,152 [salt.state       :300 ][INFO    ][1376] File /etc/iptables/rules.v6 is not present
2019-05-20 21:40:44,152 [salt.state       :1951][INFO    ][1376] Completed state [/etc/iptables/rules.v6] at time 21:40:44.152598 duration_in_ms=1.393
2019-05-20 21:40:44,153 [salt.state       :1780][INFO    ][1376] Running state [iptables_extra.flush_all] at time 21:40:44.153528
2019-05-20 21:40:44,153 [salt.state       :1813][INFO    ][1376] Executing state module.wait for [iptables_extra.flush_all]
2019-05-20 21:40:44,154 [salt.state       :300 ][INFO    ][1376] No changes made for iptables_extra.flush_all
2019-05-20 21:40:44,154 [salt.state       :1951][INFO    ][1376] Completed state [iptables_extra.flush_all] at time 21:40:44.154612 duration_in_ms=1.084
2019-05-20 21:40:44,158 [salt.minion      :1711][INFO    ][1376] Returning information for job: 20190520214036329783
2019-05-20 21:40:44,800 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command state.apply with jid 20190520214044787076
2019-05-20 21:40:44,823 [salt.minion      :1432][INFO    ][1454] Starting a new job with PID 1454
2019-05-20 21:40:45,614 [salt.state       :915 ][INFO    ][1454] Loading fresh modules for state activity
2019-05-20 21:40:46,233 [salt.state       :1780][INFO    ][1454] Running state [maas-rack-controller] at time 21:40:46.233167
2019-05-20 21:40:46,233 [salt.state       :1813][INFO    ][1454] Executing state pkg.installed for [maas-rack-controller]
2019-05-20 21:40:46,234 [salt.loaded.int.module.cmdmod:395 ][INFO    ][1454] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-20 21:40:46,333 [salt.state       :300 ][INFO    ][1454] All specified packages are already installed
2019-05-20 21:40:46,333 [salt.state       :1951][INFO    ][1454] Completed state [maas-rack-controller] at time 21:40:46.333688 duration_in_ms=100.52
2019-05-20 21:40:46,334 [salt.state       :1780][INFO    ][1454] Running state [ipmitool] at time 21:40:46.334089
2019-05-20 21:40:46,334 [salt.state       :1813][INFO    ][1454] Executing state pkg.installed for [ipmitool]
2019-05-20 21:40:46,342 [salt.state       :300 ][INFO    ][1454] All specified packages are already installed
2019-05-20 21:40:46,342 [salt.state       :1951][INFO    ][1454] Completed state [ipmitool] at time 21:40:46.342776 duration_in_ms=8.687
2019-05-20 21:40:46,346 [salt.state       :1780][INFO    ][1454] Running state [/etc/maas/rackd.conf] at time 21:40:46.346422
2019-05-20 21:40:46,346 [salt.state       :1813][INFO    ][1454] Executing state file.line for [/etc/maas/rackd.conf]
2019-05-20 21:40:46,348 [salt.state       :300 ][INFO    ][1454] No changes needed to be made
2019-05-20 21:40:46,348 [salt.state       :1951][INFO    ][1454] Completed state [/etc/maas/rackd.conf] at time 21:40:46.348304 duration_in_ms=1.883
2019-05-20 21:40:46,348 [salt.state       :1780][INFO    ][1454] Running state [/etc/maas/rackd.conf] at time 21:40:46.348606
2019-05-20 21:40:46,348 [salt.state       :1813][INFO    ][1454] Executing state file.managed for [/etc/maas/rackd.conf]
2019-05-20 21:40:46,349 [salt.loaded.int.states.file:2298][WARNING ][1454] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-05-20 21:40:46,349 [salt.state       :300 ][INFO    ][1454] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-05-20 21:40:46,350 [salt.state       :1951][INFO    ][1454] Completed state [/etc/maas/rackd.conf] at time 21:40:46.350141 duration_in_ms=1.535
2019-05-20 21:40:46,351 [salt.state       :1780][INFO    ][1454] Running state [maas-rackd] at time 21:40:46.351315
2019-05-20 21:40:46,351 [salt.state       :1813][INFO    ][1454] Executing state service.running for [maas-rackd]
2019-05-20 21:40:46,352 [salt.loaded.int.module.cmdmod:395 ][INFO    ][1454] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-05-20 21:40:46,388 [salt.loaded.int.module.cmdmod:395 ][INFO    ][1454] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-05-20 21:40:46,406 [salt.loaded.int.module.cmdmod:395 ][INFO    ][1454] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-05-20 21:40:46,423 [salt.state       :300 ][INFO    ][1454] The service maas-rackd is already running
2019-05-20 21:40:46,424 [salt.state       :1951][INFO    ][1454] Completed state [maas-rackd] at time 21:40:46.424084 duration_in_ms=72.768
2019-05-20 21:40:46,426 [salt.minion      :1711][INFO    ][1454] Returning information for job: 20190520214044787076
2019-05-20 21:40:47,001 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command state.apply with jid 20190520214046989470
2019-05-20 21:40:47,023 [salt.minion      :1432][INFO    ][1477] Starting a new job with PID 1477
2019-05-20 21:40:47,773 [salt.state       :915 ][INFO    ][1477] Loading fresh modules for state activity
2019-05-20 21:40:48,372 [salt.state       :1780][INFO    ][1477] Running state [maas-region-controller] at time 21:40:48.372455
2019-05-20 21:40:48,372 [salt.state       :1813][INFO    ][1477] Executing state pkg.installed for [maas-region-controller]
2019-05-20 21:40:48,373 [salt.loaded.int.module.cmdmod:395 ][INFO    ][1477] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-20 21:40:48,449 [salt.state       :300 ][INFO    ][1477] All specified packages are already installed
2019-05-20 21:40:48,449 [salt.state       :1951][INFO    ][1477] Completed state [maas-region-controller] at time 21:40:48.449295 duration_in_ms=76.839
2019-05-20 21:40:48,449 [salt.state       :1780][INFO    ][1477] Running state [python-oauth] at time 21:40:48.449555
2019-05-20 21:40:48,449 [salt.state       :1813][INFO    ][1477] Executing state pkg.installed for [python-oauth]
2019-05-20 21:40:48,454 [salt.state       :300 ][INFO    ][1477] All specified packages are already installed
2019-05-20 21:40:48,454 [salt.state       :1951][INFO    ][1477] Completed state [python-oauth] at time 21:40:48.454675 duration_in_ms=5.12
2019-05-20 21:40:48,457 [salt.state       :1780][INFO    ][1477] Running state [/etc/maas/regiond.conf] at time 21:40:48.456977
2019-05-20 21:40:48,457 [salt.state       :1813][INFO    ][1477] Executing state file.replace for [/etc/maas/regiond.conf]
2019-05-20 21:40:48,501 [salt.state       :300 ][INFO    ][1477] No changes needed to be made
2019-05-20 21:40:48,501 [salt.state       :1951][INFO    ][1477] Completed state [/etc/maas/regiond.conf] at time 21:40:48.501473 duration_in_ms=44.494
2019-05-20 21:40:48,502 [salt.state       :1780][INFO    ][1477] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 21:40:48.502022
2019-05-20 21:40:48,502 [salt.state       :1813][INFO    ][1477] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-05-20 21:40:48,572 [salt.state       :300 ][INFO    ][1477] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-05-20 21:40:48,572 [salt.state       :1951][INFO    ][1477] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 21:40:48.572718 duration_in_ms=70.696
2019-05-20 21:40:48,573 [salt.state       :1780][INFO    ][1477] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 21:40:48.573266
2019-05-20 21:40:48,573 [salt.state       :1813][INFO    ][1477] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-20 21:40:48,592 [salt.state       :300 ][INFO    ][1477] No changes needed to be made
2019-05-20 21:40:48,592 [salt.state       :1951][INFO    ][1477] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 21:40:48.592254 duration_in_ms=18.988
2019-05-20 21:40:48,592 [salt.state       :1780][INFO    ][1477] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 21:40:48.592802
2019-05-20 21:40:48,593 [salt.state       :1813][INFO    ][1477] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-20 21:40:48,621 [salt.state       :300 ][INFO    ][1477] No changes needed to be made
2019-05-20 21:40:48,622 [salt.state       :1951][INFO    ][1477] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 21:40:48.622154 duration_in_ms=29.352
2019-05-20 21:40:48,622 [salt.state       :1780][INFO    ][1477] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 21:40:48.622708
2019-05-20 21:40:48,623 [salt.state       :1813][INFO    ][1477] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-05-20 21:40:48,648 [salt.state       :300 ][INFO    ][1477] No changes needed to be made
2019-05-20 21:40:48,649 [salt.state       :1951][INFO    ][1477] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 21:40:48.649079 duration_in_ms=26.371
2019-05-20 21:40:48,649 [salt.state       :1780][INFO    ][1477] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 21:40:48.649594
2019-05-20 21:40:48,649 [salt.state       :1813][INFO    ][1477] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-05-20 21:40:48,661 [salt.state       :300 ][INFO    ][1477] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-05-20 21:40:48,662 [salt.state       :1951][INFO    ][1477] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 21:40:48.662126 duration_in_ms=12.533
2019-05-20 21:40:48,663 [salt.state       :1780][INFO    ][1477] Running state [a2enmod headers] at time 21:40:48.663450
2019-05-20 21:40:48,663 [salt.state       :1813][INFO    ][1477] Executing state cmd.run for [a2enmod headers]
2019-05-20 21:40:48,664 [salt.loaded.int.module.cmdmod:395 ][INFO    ][1477] Executing command 'a2enmod headers' in directory '/root'
2019-05-20 21:40:48,735 [salt.state       :300 ][INFO    ][1477] {'pid': 1497, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-05-20 21:40:48,735 [salt.state       :1951][INFO    ][1477] Completed state [a2enmod headers] at time 21:40:48.735800 duration_in_ms=72.349
2019-05-20 21:40:48,736 [salt.state       :1780][INFO    ][1477] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 21:40:48.736385
2019-05-20 21:40:48,737 [salt.state       :1813][INFO    ][1477] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-05-20 21:40:48,755 [salt.state       :300 ][INFO    ][1477] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-05-20 21:40:48,756 [salt.state       :1951][INFO    ][1477] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 21:40:48.756130 duration_in_ms=19.746
2019-05-20 21:40:48,756 [salt.state       :1780][INFO    ][1477] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 21:40:48.756908
2019-05-20 21:40:48,757 [salt.state       :1813][INFO    ][1477] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-05-20 21:40:48,830 [salt.state       :300 ][INFO    ][1477] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-05-20 21:40:48,830 [salt.state       :1951][INFO    ][1477] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 21:40:48.830815 duration_in_ms=73.907
2019-05-20 21:40:48,831 [salt.state       :1780][INFO    ][1477] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 21:40:48.831589
2019-05-20 21:40:48,832 [salt.state       :1813][INFO    ][1477] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-05-20 21:40:48,902 [salt.state       :300 ][INFO    ][1477] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-05-20 21:40:48,902 [salt.state       :1951][INFO    ][1477] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 21:40:48.902355 duration_in_ms=70.767
2019-05-20 21:40:48,903 [salt.state       :1780][INFO    ][1477] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 21:40:48.902985
2019-05-20 21:40:48,903 [salt.state       :1813][INFO    ][1477] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-05-20 21:40:48,979 [salt.state       :300 ][INFO    ][1477] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-05-20 21:40:48,980 [salt.state       :1951][INFO    ][1477] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 21:40:48.980321 duration_in_ms=77.336
2019-05-20 21:40:48,980 [salt.state       :1780][INFO    ][1477] Running state [/root/.pgpass] at time 21:40:48.980725
2019-05-20 21:40:48,981 [salt.state       :1813][INFO    ][1477] Executing state file.managed for [/root/.pgpass]
2019-05-20 21:40:49,034 [salt.state       :300 ][INFO    ][1477] File /root/.pgpass is in the correct state
2019-05-20 21:40:49,034 [salt.state       :1951][INFO    ][1477] Completed state [/root/.pgpass] at time 21:40:49.034884 duration_in_ms=54.158
2019-05-20 21:40:49,040 [salt.state       :1780][INFO    ][1477] Running state [maas-region syncdb --noinput] at time 21:40:49.040444
2019-05-20 21:40:49,040 [salt.state       :1813][INFO    ][1477] Executing state cmd.run for [maas-region syncdb --noinput]
2019-05-20 21:40:49,041 [salt.loaded.int.module.cmdmod:395 ][INFO    ][1477] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-05-20 21:40:50,963 [salt.state       :300 ][INFO    ][1477] {'pid': 1510, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: piston3, auth, maasserver, metadataserver, sessions, contenttypes, sites\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-05-20 21:40:50,963 [salt.state       :1951][INFO    ][1477] Completed state [maas-region syncdb --noinput] at time 21:40:50.963452 duration_in_ms=1923.007
2019-05-20 21:40:50,963 [salt.state       :2022][WARNING ][1477] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-20 21:40:50,964 [salt.state       :1780][INFO    ][1477] Running state [maas-regiond] at time 21:40:50.964659
2019-05-20 21:40:50,964 [salt.state       :1813][INFO    ][1477] Executing state service.running for [maas-regiond]
2019-05-20 21:40:50,965 [salt.loaded.int.module.cmdmod:395 ][INFO    ][1477] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-05-20 21:40:50,997 [salt.loaded.int.module.cmdmod:395 ][INFO    ][1477] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-05-20 21:40:51,011 [salt.loaded.int.module.cmdmod:395 ][INFO    ][1477] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-05-20 21:40:51,025 [salt.state       :300 ][INFO    ][1477] The service maas-regiond is already running
2019-05-20 21:40:51,025 [salt.state       :1951][INFO    ][1477] Completed state [maas-regiond] at time 21:40:51.025818 duration_in_ms=61.159
2019-05-20 21:40:51,026 [salt.state       :1780][INFO    ][1477] Running state [bind9] at time 21:40:51.026814
2019-05-20 21:40:51,027 [salt.state       :1813][INFO    ][1477] Executing state service.running for [bind9]
2019-05-20 21:40:51,027 [salt.loaded.int.module.cmdmod:395 ][INFO    ][1477] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-05-20 21:40:51,042 [salt.loaded.int.module.cmdmod:395 ][INFO    ][1477] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-05-20 21:40:51,056 [salt.loaded.int.module.cmdmod:395 ][INFO    ][1477] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-05-20 21:40:51,070 [salt.state       :300 ][INFO    ][1477] The service bind9 is already running
2019-05-20 21:40:51,070 [salt.state       :1951][INFO    ][1477] Completed state [bind9] at time 21:40:51.070379 duration_in_ms=43.564
2019-05-20 21:40:51,071 [salt.state       :1780][INFO    ][1477] Running state [apache2] at time 21:40:51.071372
2019-05-20 21:40:51,071 [salt.state       :1813][INFO    ][1477] Executing state service.running for [apache2]
2019-05-20 21:40:51,072 [salt.loaded.int.module.cmdmod:395 ][INFO    ][1477] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-05-20 21:40:51,087 [salt.loaded.int.module.cmdmod:395 ][INFO    ][1477] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-05-20 21:40:51,100 [salt.loaded.int.module.cmdmod:395 ][INFO    ][1477] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-05-20 21:40:51,118 [salt.state       :300 ][INFO    ][1477] The service apache2 is already running
2019-05-20 21:40:51,118 [salt.state       :1951][INFO    ][1477] Completed state [apache2] at time 21:40:51.118501 duration_in_ms=47.129
2019-05-20 21:40:51,119 [salt.state       :1780][INFO    ][1477] Running state [maasng.wait_for_http_code] at time 21:40:51.119347
2019-05-20 21:40:51,119 [salt.state       :1813][INFO    ][1477] Executing state module.run for [maasng.wait_for_http_code]
2019-05-20 21:40:51,119 [salt.utils.decorators:613 ][WARNING ][1477] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-20 21:40:51,224 [salt.state       :300 ][INFO    ][1477] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-05-20 21:40:51,225 [salt.state       :1951][INFO    ][1477] Completed state [maasng.wait_for_http_code] at time 21:40:51.225130 duration_in_ms=105.782
2019-05-20 21:40:51,225 [salt.state       :1780][INFO    ][1477] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 21:40:51.225710
2019-05-20 21:40:51,226 [salt.state       :1813][INFO    ][1477] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-05-20 21:40:51,226 [salt.state       :300 ][INFO    ][1477] /var/lib/maas/.setup_admin exists
2019-05-20 21:40:51,226 [salt.state       :1951][INFO    ][1477] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 21:40:51.226784 duration_in_ms=1.074
2019-05-20 21:40:51,227 [salt.state       :1780][INFO    ][1477] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:40:51.227539
2019-05-20 21:40:51,227 [salt.state       :1813][INFO    ][1477] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-20 21:40:51,228 [salt.loaded.int.module.cmdmod:395 ][INFO    ][1477] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-20 21:40:52,673 [salt.state       :300 ][INFO    ][1477] {'pid': 1531, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-20 21:40:52,674 [salt.state       :1951][INFO    ][1477] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:40:52.674289 duration_in_ms=1446.749
2019-05-20 21:40:52,677 [salt.state       :1780][INFO    ][1477] Running state [maas_region_boot_source_resources_mirror] at time 21:40:52.677802
2019-05-20 21:40:52,678 [salt.state       :1813][INFO    ][1477] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-05-20 21:40:52,781 [salt.state       :300 ][INFO    ][1477] {'changes': {}}
2019-05-20 21:40:52,782 [salt.state       :1951][INFO    ][1477] Completed state [maas_region_boot_source_resources_mirror] at time 21:40:52.782080 duration_in_ms=104.276
2019-05-20 21:40:52,783 [salt.state       :1780][INFO    ][1477] Running state [maasng.boot_resources_import] at time 21:40:52.783207
2019-05-20 21:40:52,783 [salt.state       :1813][INFO    ][1477] Executing state module.run for [maasng.boot_resources_import]
2019-05-20 21:40:52,784 [salt.utils.decorators:613 ][WARNING ][1477] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-20 21:40:52,889 [salt.loaded.ext.module.maasng:1600][INFO    ][1477] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-05-20 21:40:57,951 [salt.loaded.ext.module.maasng:1600][INFO    ][1477] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-05-20 21:41:02,048 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520214102036430
2019-05-20 21:41:02,070 [salt.minion      :1432][INFO    ][1576] Starting a new job with PID 1576
2019-05-20 21:41:02,093 [salt.minion      :1711][INFO    ][1576] Returning information for job: 20190520214102036430
2019-05-20 21:41:03,018 [salt.loaded.ext.module.maasng:1600][INFO    ][1477] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-05-20 21:41:08,132 [salt.state       :300 ][INFO    ][1477] {'ret': True}
2019-05-20 21:41:08,133 [salt.state       :1951][INFO    ][1477] Completed state [maasng.boot_resources_import] at time 21:41:08.133180 duration_in_ms=15349.972
2019-05-20 21:41:08,134 [salt.state       :1780][INFO    ][1477] Running state [maas_region_boot_sources_selection_xenial] at time 21:41:08.134372
2019-05-20 21:41:08,134 [salt.state       :1813][INFO    ][1477] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-05-20 21:41:08,354 [salt.state       :300 ][INFO    ][1477] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-05-20 21:41:08,354 [salt.state       :1951][INFO    ][1477] Completed state [maas_region_boot_sources_selection_xenial] at time 21:41:08.354557 duration_in_ms=220.184
2019-05-20 21:41:08,356 [salt.state       :1780][INFO    ][1477] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 21:41:08.355997
2019-05-20 21:41:08,356 [salt.state       :1813][INFO    ][1477] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-05-20 21:41:08,357 [salt.utils.decorators:613 ][WARNING ][1477] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-20 21:41:08,357 [salt.loaded.ext.module.maasng:1771][INFO    ][1477] boot-sources sync initiated for ALL Rack's
2019-05-20 21:41:09,442 [salt.state       :300 ][INFO    ][1477] {'ret': True}
2019-05-20 21:41:09,443 [salt.state       :1951][INFO    ][1477] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 21:41:09.443165 duration_in_ms=1087.168
2019-05-20 21:41:09,444 [salt.state       :1780][INFO    ][1477] Running state [maas.process_maas_config] at time 21:41:09.444803
2019-05-20 21:41:09,445 [salt.state       :1813][INFO    ][1477] Executing state module.run for [maas.process_maas_config]
2019-05-20 21:41:09,445 [salt.utils.decorators:613 ][WARNING ][1477] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-20 21:41:09,446 [salt.loaded.ext.module.maas:92  ][INFO    ][1477] maasconfig name=enable_http_proxy value=True
2019-05-20 21:41:09,510 [salt.loaded.ext.module.maas:92  ][INFO    ][1477] maasconfig name=upstream_dns value=8.8.8.8
2019-05-20 21:41:09,575 [salt.loaded.ext.module.maas:92  ][INFO    ][1477] maasconfig name=commissioning_distro_series value=xenial
2019-05-20 21:41:12,585 [salt.loaded.ext.module.maas:92  ][INFO    ][1477] maasconfig name=default_osystem value=ubuntu
2019-05-20 21:41:12,653 [salt.loaded.ext.module.maas:92  ][INFO    ][1477] maasconfig name=active_discovery_interval value=600
2019-05-20 21:41:12,707 [salt.loaded.ext.module.maas:92  ][INFO    ][1477] maasconfig name=dnssec_validation value=no
2019-05-20 21:41:12,761 [salt.loaded.ext.module.maas:92  ][INFO    ][1477] maasconfig name=maas_name value=mas01
2019-05-20 21:41:12,821 [salt.loaded.ext.module.maas:92  ][INFO    ][1477] maasconfig name=network_discovery value=enabled
2019-05-20 21:41:12,923 [salt.loaded.ext.module.maas:92  ][INFO    ][1477] maasconfig name=enable_third_party_drivers value=True
2019-05-20 21:41:12,981 [salt.loaded.ext.module.maas:92  ][INFO    ][1477] maasconfig name=default_storage_layout value=lvm
2019-05-20 21:41:13,030 [salt.loaded.ext.module.maas:92  ][INFO    ][1477] maasconfig name=ntp_external_only value=True
2019-05-20 21:41:13,090 [salt.loaded.ext.module.maas:92  ][INFO    ][1477] maasconfig name=disk_erase_with_secure_erase value=False
2019-05-20 21:41:13,151 [salt.loaded.ext.module.maas:92  ][INFO    ][1477] maasconfig name=default_distro_series value=xenial
2019-05-20 21:41:13,261 [salt.loaded.ext.module.maas:92  ][INFO    ][1477] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-05-20 21:41:13,395 [salt.state       :300 ][INFO    ][1477] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-05-20 21:41:13,396 [salt.state       :1951][INFO    ][1477] Completed state [maas.process_maas_config] at time 21:41:13.396156 duration_in_ms=3951.352
2019-05-20 21:41:13,397 [salt.state       :1780][INFO    ][1477] Running state [pxe_admin] at time 21:41:13.397002
2019-05-20 21:41:13,397 [salt.state       :1813][INFO    ][1477] Executing state maasng.fabric_present for [pxe_admin]
2019-05-20 21:41:13,450 [salt.loaded.ext.module.maasng:945 ][INFO    ][1477] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'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'id': 1, u'name': u'fabric-1', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'bwhqxw', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'class_type': u''}]
2019-05-20 21:41:13,521 [salt.loaded.ext.module.maasng:1008][WARNING ][1477] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-05-20 21:41:13,521 [salt.loaded.ext.module.maasng:1011][WARNING ][1477] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-05-20 21:41:13,587 [salt.state       :300 ][INFO    ][1477] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-05-20 21:41:13,587 [salt.state       :1951][INFO    ][1477] Completed state [pxe_admin] at time 21:41:13.587366 duration_in_ms=190.364
2019-05-20 21:41:13,587 [salt.state       :1780][INFO    ][1477] Running state [vlan 0] at time 21:41:13.587740
2019-05-20 21:41:13,588 [salt.state       :1813][INFO    ][1477] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-20 21:41:13,640 [salt.loaded.ext.module.maasng:945 ][INFO    ][1477] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, 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'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, 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'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'bwhqxw', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-20 21:41:13,749 [salt.loaded.ext.module.maasng:945 ][INFO    ][1477] [{u'id': 0, u'class_type': None, u'vlans': [{u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', 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'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'vid': 0, u'fabric': u'fabric-1', u'name': u'untagged', 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'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'bwhqxw', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-20 21:41:14,029 [salt.loaded.ext.module.maasng:945 ][INFO    ][1477] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, 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'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, 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'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'bwhqxw', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-20 21:41:14,140 [salt.state       :300 ][INFO    ][1477] {'new': 'Vlan untagged was updated'}
2019-05-20 21:41:14,140 [salt.state       :1951][INFO    ][1477] Completed state [vlan 0] at time 21:41:14.140850 duration_in_ms=553.109
2019-05-20 21:41:14,142 [salt.state       :1780][INFO    ][1477] Running state [192.168.11.0/24] at time 21:41:14.141979
2019-05-20 21:41:14,142 [salt.state       :1813][INFO    ][1477] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-05-20 21:41:14,341 [salt.loaded.ext.module.maasng:945 ][INFO    ][1477] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, 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'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, 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'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'bwhqxw', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-20 21:41:14,341 [salt.loaded.ext.module.maasng:1235][WARNING ][1477] Ignoring parameter vlan:0
2019-05-20 21:41:14,401 [salt.state       :300 ][INFO    ][1477] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-05-20 21:41:14,402 [salt.state       :1951][INFO    ][1477] Completed state [192.168.11.0/24] at time 21:41:14.402020 duration_in_ms=260.041
2019-05-20 21:41:14,402 [salt.state       :1780][INFO    ][1477] Running state [maas_create_iprange_1] at time 21:41:14.402888
2019-05-20 21:41:14,403 [salt.state       :1813][INFO    ][1477] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-05-20 21:41:14,443 [salt.state       :300 ][INFO    ][1477] Iprange maas_create_iprange_1 already exist.
2019-05-20 21:41:14,443 [salt.state       :1951][INFO    ][1477] Completed state [maas_create_iprange_1] at time 21:41:14.443350 duration_in_ms=40.462
2019-05-20 21:41:14,443 [salt.state       :1780][INFO    ][1477] Running state [vlan 0] at time 21:41:14.443636
2019-05-20 21:41:14,443 [salt.state       :1813][INFO    ][1477] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-20 21:41:14,479 [salt.loaded.ext.module.maasng:945 ][INFO    ][1477] [{u'id': 0, u'class_type': None, u'vlans': [{u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', 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'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'vid': 0, u'fabric': u'fabric-1', u'name': u'untagged', 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'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'bwhqxw', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-20 21:41:14,551 [salt.loaded.ext.module.maasng:945 ][INFO    ][1477] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, 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'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, 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'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'bwhqxw', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-20 21:41:14,744 [salt.loaded.ext.module.maasng:945 ][INFO    ][1477] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'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'id': 1, u'name': u'fabric-1', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'bwhqxw', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'class_type': u''}]
2019-05-20 21:41:14,796 [salt.state       :300 ][INFO    ][1477] {'new': 'Vlan untagged was updated'}
2019-05-20 21:41:14,797 [salt.state       :1951][INFO    ][1477] Completed state [vlan 0] at time 21:41:14.797114 duration_in_ms=353.476
2019-05-20 21:41:14,798 [salt.state       :1780][INFO    ][1477] Running state [opnfv] at time 21:41:14.797989
2019-05-20 21:41:14,798 [salt.state       :1813][INFO    ][1477] Executing state maasng.sshkey_present for [opnfv]
2019-05-20 21:41:14,838 [salt.loaded.ext.module.maasng:1903][INFO    ][1477] [{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-05-20 21:41:14,838 [salt.state       :300 ][INFO    ][1477] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-05-20 21:41:14,838 [salt.state       :1951][INFO    ][1477] Completed state [opnfv] at time 21:41:14.838866 duration_in_ms=40.876
2019-05-20 21:41:14,841 [salt.minion      :1711][INFO    ][1477] Returning information for job: 20190520214046989470
2019-05-20 21:41:15,311 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command state.apply with jid 20190520214115299315
2019-05-20 21:41:15,329 [salt.minion      :1432][INFO    ][1957] Starting a new job with PID 1957
2019-05-20 21:41:18,977 [salt.state       :915 ][INFO    ][1957] Loading fresh modules for state activity
2019-05-20 21:41:19,068 [salt.state       :1780][INFO    ][1957] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:41:19.068745
2019-05-20 21:41:19,069 [salt.state       :1813][INFO    ][1957] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-20 21:41:19,071 [salt.loaded.int.module.cmdmod:395 ][INFO    ][1957] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-20 21:41:20,455 [salt.state       :300 ][INFO    ][1957] {'pid': 1982, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-20 21:41:20,456 [salt.state       :1951][INFO    ][1957] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:41:20.456090 duration_in_ms=1387.346
2019-05-20 21:41:20,457 [salt.state       :1780][INFO    ][1957] Running state [maas.process_machines] at time 21:41:20.457292
2019-05-20 21:41:20,457 [salt.state       :1813][INFO    ][1957] Executing state module.run for [maas.process_machines]
2019-05-20 21:41:20,458 [salt.utils.decorators:613 ][WARNING ][1957] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-20 21:41:21,191 [salt.loaded.ext.module.maas:412 ][WARNING ][1957] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-20 21:41:21,192 [salt.loaded.ext.module.maas:92  ][INFO    ][1957] 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=8efycx architecture=amd64/generic power_parameters_power_user=admin
2019-05-20 21:41:22,504 [salt.loaded.ext.module.maas:412 ][WARNING ][1957] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-20 21:41:22,505 [salt.loaded.ext.module.maas:92  ][INFO    ][1957] 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=fdbthf architecture=amd64/generic power_parameters_power_user=admin
2019-05-20 21:41:23,803 [salt.loaded.ext.module.maas:412 ][WARNING ][1957] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-20 21:41:23,804 [salt.loaded.ext.module.maas:92  ][INFO    ][1957] 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=yaddtf architecture=amd64/generic power_parameters_power_user=admin
2019-05-20 21:41:25,144 [salt.loaded.ext.module.maas:412 ][WARNING ][1957] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-20 21:41:25,145 [salt.loaded.ext.module.maas:92  ][INFO    ][1957] 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=nq6br4 architecture=amd64/generic power_parameters_power_user=admin
2019-05-20 21:41:26,444 [salt.loaded.ext.module.maas:412 ][WARNING ][1957] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-20 21:41:26,444 [salt.loaded.ext.module.maas:92  ][INFO    ][1957] 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=as6dpy architecture=amd64/generic power_parameters_power_user=admin
2019-05-20 21:41:27,702 [salt.state       :300 ][INFO    ][1957] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-05-20 21:41:27,703 [salt.state       :1951][INFO    ][1957] Completed state [maas.process_machines] at time 21:41:27.703009 duration_in_ms=7245.714
2019-05-20 21:41:27,749 [salt.minion      :1711][INFO    ][1957] Returning information for job: 20190520214115299315
2019-05-20 21:42:01,039 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command state.apply with jid 20190520214201026546
2019-05-20 21:42:01,062 [salt.minion      :1432][INFO    ][2226] Starting a new job with PID 2226
2019-05-20 21:42:04,687 [salt.state       :915 ][INFO    ][2226] Loading fresh modules for state activity
2019-05-20 21:42:04,780 [salt.state       :1780][INFO    ][2226] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:42:04.780162
2019-05-20 21:42:04,780 [salt.state       :1813][INFO    ][2226] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-20 21:42:04,782 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2226] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-20 21:42:06,328 [salt.state       :300 ][INFO    ][2226] {'pid': 2262, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-20 21:42:06,329 [salt.state       :1951][INFO    ][2226] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:42:06.329038 duration_in_ms=1548.875
2019-05-20 21:42:06,332 [salt.state       :1780][INFO    ][2226] Running state [maas.wait_for_machine_status] at time 21:42:06.332419
2019-05-20 21:42:06,333 [salt.state       :1813][INFO    ][2226] Executing state module.run for [maas.wait_for_machine_status]
2019-05-20 21:42:06,333 [salt.utils.decorators:613 ][WARNING ][2226] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-20 21:42:09,479 [salt.loaded.ext.module.maas:993 ][INFO    ][2226] Machine as6dpy mark broken
2019-05-20 21:42:10,097 [salt.loaded.ext.module.maas:996 ][INFO    ][2226] Machine as6dpy mark fixed
2019-05-20 21:42:11,377 [salt.loaded.ext.module.maas:684 ][INFO    ][2226] deploymachines hwe_kernel=hwe-16.04 system_id=as6dpy distro_series=xenial
2019-05-20 21:42:13,308 [salt.loaded.ext.module.maas:1023][INFO    ][2226] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1493.03625202s left)
2019-05-20 21:42:16,089 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520214216073300
2019-05-20 21:42:16,114 [salt.minion      :1432][INFO    ][2328] Starting a new job with PID 2328
2019-05-20 21:42:16,137 [salt.minion      :1711][INFO    ][2328] Returning information for job: 20190520214216073300
2019-05-20 21:42:46,173 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520214246126398
2019-05-20 21:42:46,191 [salt.minion      :1432][INFO    ][2359] Starting a new job with PID 2359
2019-05-20 21:42:46,215 [salt.minion      :1711][INFO    ][2359] Returning information for job: 20190520214246126398
2019-05-20 21:42:46,426 [salt.loaded.ext.module.maas:1023][INFO    ][2226] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1459.9179492s left)
2019-05-20 21:43:16,264 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520214316251768
2019-05-20 21:43:16,286 [salt.minion      :1432][INFO    ][2424] Starting a new job with PID 2424
2019-05-20 21:43:16,312 [salt.minion      :1711][INFO    ][2424] Returning information for job: 20190520214316251768
2019-05-20 21:43:20,127 [salt.loaded.ext.module.maas:1023][INFO    ][2226] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1426.2166431s left)
2019-05-20 21:43:46,312 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520214346299380
2019-05-20 21:43:46,334 [salt.minion      :1432][INFO    ][2449] Starting a new job with PID 2449
2019-05-20 21:43:46,362 [salt.minion      :1711][INFO    ][2449] Returning information for job: 20190520214346299380
2019-05-20 21:43:52,725 [salt.loaded.ext.module.maas:1023][INFO    ][2226] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1393.61915517s left)
2019-05-20 21:44:16,368 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520214416355349
2019-05-20 21:44:16,390 [salt.minion      :1432][INFO    ][2552] Starting a new job with PID 2552
2019-05-20 21:44:16,416 [salt.minion      :1711][INFO    ][2552] Returning information for job: 20190520214416355349
2019-05-20 21:44:26,355 [salt.loaded.ext.module.maas:1023][INFO    ][2226] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1359.98910713s left)
2019-05-20 21:44:46,421 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520214446409207
2019-05-20 21:44:46,443 [salt.minion      :1432][INFO    ][2578] Starting a new job with PID 2578
2019-05-20 21:44:46,469 [salt.minion      :1711][INFO    ][2578] Returning information for job: 20190520214446409207
2019-05-20 21:44:59,273 [salt.loaded.ext.module.maas:1023][INFO    ][2226] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1327.0715971s left)
2019-05-20 21:45:16,490 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520214516477702
2019-05-20 21:45:16,513 [salt.minion      :1432][INFO    ][2789] Starting a new job with PID 2789
2019-05-20 21:45:16,539 [salt.minion      :1711][INFO    ][2789] Returning information for job: 20190520214516477702
2019-05-20 21:45:32,750 [salt.loaded.ext.module.maas:1023][INFO    ][2226] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1293.59460402s left)
2019-05-20 21:45:46,550 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520214546537821
2019-05-20 21:45:46,569 [salt.minion      :1432][INFO    ][2813] Starting a new job with PID 2813
2019-05-20 21:45:46,594 [salt.minion      :1711][INFO    ][2813] Returning information for job: 20190520214546537821
2019-05-20 21:46:06,171 [salt.loaded.ext.module.maas:1023][INFO    ][2226] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1260.17286015s left)
2019-05-20 21:46:16,608 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520214616595307
2019-05-20 21:46:16,630 [salt.minion      :1432][INFO    ][2883] Starting a new job with PID 2883
2019-05-20 21:46:16,654 [salt.minion      :1711][INFO    ][2883] Returning information for job: 20190520214616595307
2019-05-20 21:46:38,929 [salt.loaded.ext.module.maas:1023][INFO    ][2226] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1227.41549802s left)
2019-05-20 21:46:46,682 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520214646669343
2019-05-20 21:46:46,705 [salt.minion      :1432][INFO    ][2906] Starting a new job with PID 2906
2019-05-20 21:46:46,731 [salt.minion      :1711][INFO    ][2906] Returning information for job: 20190520214646669343
2019-05-20 21:47:12,397 [salt.loaded.ext.module.maas:1023][INFO    ][2226] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1193.94758701s left)
2019-05-20 21:47:16,762 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520214716748656
2019-05-20 21:47:16,785 [salt.minion      :1432][INFO    ][3047] Starting a new job with PID 3047
2019-05-20 21:47:16,811 [salt.minion      :1711][INFO    ][3047] Returning information for job: 20190520214716748656
2019-05-20 21:47:45,859 [salt.loaded.ext.module.maas:1023][INFO    ][2226] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1160.48501515s left)
2019-05-20 21:47:46,844 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520214746831228
2019-05-20 21:47:46,866 [salt.minion      :1432][INFO    ][3074] Starting a new job with PID 3074
2019-05-20 21:47:46,893 [salt.minion      :1711][INFO    ][3074] Returning information for job: 20190520214746831228
2019-05-20 21:48:16,928 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520214816915454
2019-05-20 21:48:16,949 [salt.minion      :1432][INFO    ][3200] Starting a new job with PID 3200
2019-05-20 21:48:16,973 [salt.minion      :1711][INFO    ][3200] Returning information for job: 20190520214816915454
2019-05-20 21:48:19,247 [salt.loaded.ext.module.maas:1023][INFO    ][2226] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1127.09735608s left)
2019-05-20 21:48:47,013 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520214847000325
2019-05-20 21:48:47,044 [salt.minion      :1432][INFO    ][3218] Starting a new job with PID 3218
2019-05-20 21:48:47,083 [salt.minion      :1711][INFO    ][3218] Returning information for job: 20190520214847000325
2019-05-20 21:48:52,902 [salt.loaded.ext.module.maas:1023][INFO    ][2226] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1093.44244313s left)
2019-05-20 21:49:17,130 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520214917117960
2019-05-20 21:49:17,151 [salt.minion      :1432][INFO    ][3276] Starting a new job with PID 3276
2019-05-20 21:49:17,180 [salt.minion      :1711][INFO    ][3276] Returning information for job: 20190520214917117960
2019-05-20 21:49:26,554 [salt.loaded.ext.module.maas:1023][INFO    ][2226] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1059.7902751s left)
2019-05-20 21:49:47,241 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520214947228390
2019-05-20 21:49:47,265 [salt.minion      :1432][INFO    ][3336] Starting a new job with PID 3336
2019-05-20 21:49:47,294 [salt.minion      :1711][INFO    ][3336] Returning information for job: 20190520214947228390
2019-05-20 21:50:00,003 [salt.state       :300 ][INFO    ][2226] {'ret': True}
2019-05-20 21:50:00,003 [salt.state       :1951][INFO    ][2226] Completed state [maas.wait_for_machine_status] at time 21:50:00.003498 duration_in_ms=473671.077
2019-05-20 21:50:00,007 [salt.minion      :1711][INFO    ][2226] Returning information for job: 20190520214201026546
2019-05-20 21:50:00,675 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command state.apply with jid 20190520215000662582
2019-05-20 21:50:00,697 [salt.minion      :1432][INFO    ][3399] Starting a new job with PID 3399
2019-05-20 21:50:04,085 [salt.state       :915 ][INFO    ][3399] Loading fresh modules for state activity
2019-05-20 21:50:04,194 [salt.state       :1780][INFO    ][3399] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:50:04.194843
2019-05-20 21:50:04,195 [salt.state       :1813][INFO    ][3399] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-20 21:50:04,196 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3399] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-20 21:50:05,417 [salt.state       :300 ][INFO    ][3399] {'pid': 3639, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-20 21:50:05,417 [salt.state       :1951][INFO    ][3399] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:50:05.417488 duration_in_ms=1222.637
2019-05-20 21:50:05,418 [salt.state       :1780][INFO    ][3399] Running state [maas_machines_storage_cmp002_lvm] at time 21:50:05.418795
2019-05-20 21:50:05,419 [salt.state       :1813][INFO    ][3399] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-20 21:50:06,113 [salt.state       :300 ][INFO    ][3399] Machine cmp002 is not in Ready state.
2019-05-20 21:50:06,113 [salt.state       :1951][INFO    ][3399] Completed state [maas_machines_storage_cmp002_lvm] at time 21:50:06.113846 duration_in_ms=695.048
2019-05-20 21:50:06,114 [salt.state       :1780][INFO    ][3399] Running state [maas_machines_storage_cmp001_lvm] at time 21:50:06.114639
2019-05-20 21:50:06,115 [salt.state       :1813][INFO    ][3399] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-20 21:50:06,811 [salt.state       :300 ][INFO    ][3399] Machine cmp001 is not in Ready state.
2019-05-20 21:50:06,811 [salt.state       :1951][INFO    ][3399] Completed state [maas_machines_storage_cmp001_lvm] at time 21:50:06.811788 duration_in_ms=697.148
2019-05-20 21:50:06,816 [salt.minion      :1711][INFO    ][3399] Returning information for job: 20190520215000662582
2019-05-20 21:50:07,433 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command state.apply with jid 20190520215007424983
2019-05-20 21:50:07,453 [salt.minion      :1432][INFO    ][3649] Starting a new job with PID 3649
2019-05-20 21:50:08,193 [salt.state       :915 ][INFO    ][3649] Loading fresh modules for state activity
2019-05-20 21:50:08,284 [salt.state       :1780][INFO    ][3649] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:50:08.284665
2019-05-20 21:50:08,285 [salt.state       :1813][INFO    ][3649] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-20 21:50:08,287 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3649] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-20 21:50:09,752 [salt.state       :300 ][INFO    ][3649] {'pid': 3659, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-20 21:50:09,753 [salt.state       :1951][INFO    ][3649] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:50:09.753315 duration_in_ms=1468.651
2019-05-20 21:50:09,754 [salt.state       :1780][INFO    ][3649] Running state [maas.deploy_machines] at time 21:50:09.754497
2019-05-20 21:50:09,754 [salt.state       :1813][INFO    ][3649] Executing state module.run for [maas.deploy_machines]
2019-05-20 21:50:09,755 [salt.utils.decorators:613 ][WARNING ][3649] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-20 21:50:10,511 [salt.state       :300 ][INFO    ][3649] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-05-20 21:50:10,511 [salt.state       :1951][INFO    ][3649] Completed state [maas.deploy_machines] at time 21:50:10.511779 duration_in_ms=757.28
2019-05-20 21:50:10,514 [salt.minion      :1711][INFO    ][3649] Returning information for job: 20190520215007424983
2019-05-20 21:50:11,113 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command state.apply with jid 20190520215011100317
2019-05-20 21:50:11,136 [salt.minion      :1432][INFO    ][3670] Starting a new job with PID 3670
2019-05-20 21:50:11,842 [salt.state       :915 ][INFO    ][3670] Loading fresh modules for state activity
2019-05-20 21:50:11,931 [salt.state       :1780][INFO    ][3670] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:50:11.931664
2019-05-20 21:50:11,932 [salt.state       :1813][INFO    ][3670] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-20 21:50:11,934 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3670] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-20 21:50:13,274 [salt.state       :300 ][INFO    ][3670] {'pid': 3678, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-20 21:50:13,275 [salt.state       :1951][INFO    ][3670] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:50:13.275068 duration_in_ms=1343.404
2019-05-20 21:50:13,276 [salt.state       :1780][INFO    ][3670] Running state [maas.wait_for_machine_status] at time 21:50:13.276464
2019-05-20 21:50:13,276 [salt.state       :1813][INFO    ][3670] Executing state module.run for [maas.wait_for_machine_status]
2019-05-20 21:50:13,276 [salt.utils.decorators:613 ][WARNING ][3670] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-20 21:50:16,703 [salt.state       :300 ][INFO    ][3670] {'ret': True}
2019-05-20 21:50:16,704 [salt.state       :1951][INFO    ][3670] Completed state [maas.wait_for_machine_status] at time 21:50:16.704183 duration_in_ms=3427.716
2019-05-20 21:50:16,707 [salt.minion      :1711][INFO    ][3670] Returning information for job: 20190520215011100317
2019-05-20 22:19:33,872 [salt.utils.schedule:1377][INFO    ][27096] Running scheduled job: __mine_interval
2019-05-20 23:13:09,959 [salt.minion      :1308][INFO    ][27096] User sudo_ubuntu Executing command cp.push_dir with jid 20190520231309947703
2019-05-20 23:13:09,981 [salt.minion      :1432][INFO    ][9604] Starting a new job with PID 9604
