2019-04-24 06:19:51,688 [salt.minion      :870 ][ERROR   ][363] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-24 06:20:41,736 [salt.minion      :870 ][ERROR   ][363] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-24 06:21:31,779 [salt.minion      :870 ][ERROR   ][363] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-24 06:22:21,821 [salt.minion      :870 ][ERROR   ][363] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-24 06:23:11,868 [salt.minion      :870 ][ERROR   ][363] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-24 06:25:20,206 [salt.utils.decorators:613 ][WARNING ][2785] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 06:25:20,702 [salt.utils.decorators:613 ][WARNING ][2785] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 06:25:22,824 [salt.loaded.int.states.file:2298][WARNING ][2927] 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-04-24 06:25:28,293 [salt.state       :2022][WARNING ][3037] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-04-24 06:25:30,842 [salt.utils.decorators:613 ][WARNING ][3037] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 06:40:38,385 [salt.utils.decorators:613 ][WARNING ][3037] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 07:10:48,982 [salt.utils.decorators:613 ][WARNING ][3037] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 07:30:59,760 [salt.state       :302 ][ERROR   ][3037] Module function maasng.sync_and_wait_bs_to_all_racks threw an exception. Exception: HTTP Error 401: OK
2019-04-24 07:31:01,523 [salt.loaded.ext.module.maasng:1008][WARNING ][3037] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-04-24 07:31:01,523 [salt.loaded.ext.module.maasng:1011][WARNING ][3037] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-04-24 07:31:04,399 [salt.loaded.ext.module.maasng:1235][WARNING ][3037] Ignoring parameter vlan:0
2019-04-24 07:31:09,504 [salt.utils.decorators:613 ][WARNING ][3037] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 07:31:12,258 [salt.loaded.int.module.cmdmod:395 ][INFO    ][24033] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-04-24 07:31:12,293 [salt.loaded.int.module.cmdmod:395 ][INFO    ][24033] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-04-24 07:31:12,317 [salt.utils.parsers:1051][WARNING ][363] Minion received a SIGTERM. Exiting.
2019-04-24 07:31:13,397 [salt.cli.daemons :293 ][INFO    ][24078] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-04-24 07:31:13,497 [salt.cli.daemons :82  ][INFO    ][24078] Starting up the Salt Minion
2019-04-24 07:31:13,497 [salt.utils.event :1017][INFO    ][24078] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-04-24 07:31:14,406 [salt.minion      :976 ][INFO    ][24078] Creating minion process manager
2019-04-24 07:31:15,983 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][24078] Executing command ['date', '+%z'] in directory '/root'
2019-04-24 07:31:16,003 [salt.utils.schedule:568 ][INFO    ][24078] Updating job settings for scheduled job: __mine_interval
2019-04-24 07:31:16,005 [salt.minion      :1108][INFO    ][24078] Added mine.update to scheduler
2019-04-24 07:31:16,008 [salt.minion      :1975][INFO    ][24078] Minion is starting as user 'root'
2019-04-24 07:31:16,019 [salt.minion      :2336][INFO    ][24078] Minion is ready to receive requests!
2019-04-24 07:31:21,182 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command state.apply with jid 20190424073121169347
2019-04-24 07:31:21,210 [salt.minion      :1432][INFO    ][24159] Starting a new job with PID 24159
2019-04-24 07:31:24,864 [salt.state       :915 ][INFO    ][24159] Loading fresh modules for state activity
2019-04-24 07:31:25,638 [salt.state       :1780][INFO    ][24159] Running state [maas-region-controller] at time 07:31:25.638516
2019-04-24 07:31:25,638 [salt.state       :1813][INFO    ][24159] Executing state pkg.installed for [maas-region-controller]
2019-04-24 07:31:25,639 [salt.loaded.int.module.cmdmod:395 ][INFO    ][24159] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-24 07:31:25,721 [salt.state       :300 ][INFO    ][24159] All specified packages are already installed
2019-04-24 07:31:25,722 [salt.state       :1951][INFO    ][24159] Completed state [maas-region-controller] at time 07:31:25.722190 duration_in_ms=83.674
2019-04-24 07:31:25,722 [salt.state       :1780][INFO    ][24159] Running state [python-oauth] at time 07:31:25.722433
2019-04-24 07:31:25,722 [salt.state       :1813][INFO    ][24159] Executing state pkg.installed for [python-oauth]
2019-04-24 07:31:25,727 [salt.state       :300 ][INFO    ][24159] All specified packages are already installed
2019-04-24 07:31:25,727 [salt.state       :1951][INFO    ][24159] Completed state [python-oauth] at time 07:31:25.727228 duration_in_ms=4.794
2019-04-24 07:31:25,729 [salt.state       :1780][INFO    ][24159] Running state [/etc/maas/regiond.conf] at time 07:31:25.729568
2019-04-24 07:31:25,729 [salt.state       :1813][INFO    ][24159] Executing state file.replace for [/etc/maas/regiond.conf]
2019-04-24 07:31:25,759 [salt.state       :300 ][INFO    ][24159] No changes needed to be made
2019-04-24 07:31:25,759 [salt.state       :1951][INFO    ][24159] Completed state [/etc/maas/regiond.conf] at time 07:31:25.759553 duration_in_ms=29.985
2019-04-24 07:31:25,760 [salt.state       :1780][INFO    ][24159] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 07:31:25.760179
2019-04-24 07:31:25,760 [salt.state       :1813][INFO    ][24159] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-04-24 07:31:25,824 [salt.state       :300 ][INFO    ][24159] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-04-24 07:31:25,824 [salt.state       :1951][INFO    ][24159] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 07:31:25.824635 duration_in_ms=64.456
2019-04-24 07:31:25,825 [salt.state       :1780][INFO    ][24159] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 07:31:25.825173
2019-04-24 07:31:25,825 [salt.state       :1813][INFO    ][24159] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-04-24 07:31:25,837 [salt.state       :300 ][INFO    ][24159] No changes needed to be made
2019-04-24 07:31:25,838 [salt.state       :1951][INFO    ][24159] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 07:31:25.838188 duration_in_ms=13.015
2019-04-24 07:31:25,838 [salt.state       :1780][INFO    ][24159] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 07:31:25.838743
2019-04-24 07:31:25,839 [salt.state       :1813][INFO    ][24159] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-04-24 07:31:25,867 [salt.state       :300 ][INFO    ][24159] No changes needed to be made
2019-04-24 07:31:25,868 [salt.state       :1951][INFO    ][24159] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 07:31:25.868130 duration_in_ms=29.388
2019-04-24 07:31:25,868 [salt.state       :1780][INFO    ][24159] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 07:31:25.868775
2019-04-24 07:31:25,869 [salt.state       :1813][INFO    ][24159] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-04-24 07:31:25,911 [salt.state       :300 ][INFO    ][24159] No changes needed to be made
2019-04-24 07:31:25,913 [salt.state       :1951][INFO    ][24159] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 07:31:25.913011 duration_in_ms=44.233
2019-04-24 07:31:25,914 [salt.state       :1780][INFO    ][24159] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 07:31:25.914362
2019-04-24 07:31:25,915 [salt.state       :1813][INFO    ][24159] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-04-24 07:31:25,930 [salt.state       :300 ][INFO    ][24159] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-04-24 07:31:25,931 [salt.state       :1951][INFO    ][24159] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 07:31:25.931122 duration_in_ms=16.761
2019-04-24 07:31:25,934 [salt.state       :1780][INFO    ][24159] Running state [a2enmod headers] at time 07:31:25.934209
2019-04-24 07:31:25,934 [salt.state       :1813][INFO    ][24159] Executing state cmd.run for [a2enmod headers]
2019-04-24 07:31:25,935 [salt.loaded.int.module.cmdmod:395 ][INFO    ][24159] Executing command 'a2enmod headers' in directory '/root'
2019-04-24 07:31:26,012 [salt.state       :300 ][INFO    ][24159] {'pid': 24216, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-04-24 07:31:26,013 [salt.state       :1951][INFO    ][24159] Completed state [a2enmod headers] at time 07:31:26.013472 duration_in_ms=79.263
2019-04-24 07:31:26,014 [salt.state       :1780][INFO    ][24159] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 07:31:26.014036
2019-04-24 07:31:26,014 [salt.state       :1813][INFO    ][24159] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-04-24 07:31:26,032 [salt.state       :300 ][INFO    ][24159] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-04-24 07:31:26,032 [salt.state       :1951][INFO    ][24159] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 07:31:26.032709 duration_in_ms=18.674
2019-04-24 07:31:26,033 [salt.state       :1780][INFO    ][24159] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 07:31:26.033530
2019-04-24 07:31:26,033 [salt.state       :1813][INFO    ][24159] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-04-24 07:31:26,117 [salt.state       :300 ][INFO    ][24159] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-04-24 07:31:26,118 [salt.state       :1951][INFO    ][24159] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 07:31:26.118302 duration_in_ms=84.772
2019-04-24 07:31:26,119 [salt.state       :1780][INFO    ][24159] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 07:31:26.119163
2019-04-24 07:31:26,119 [salt.state       :1813][INFO    ][24159] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-04-24 07:31:26,195 [salt.state       :300 ][INFO    ][24159] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-04-24 07:31:26,195 [salt.state       :1951][INFO    ][24159] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 07:31:26.195898 duration_in_ms=76.736
2019-04-24 07:31:26,196 [salt.state       :1780][INFO    ][24159] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 07:31:26.196537
2019-04-24 07:31:26,196 [salt.state       :1813][INFO    ][24159] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-04-24 07:31:26,267 [salt.state       :300 ][INFO    ][24159] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-04-24 07:31:26,267 [salt.state       :1951][INFO    ][24159] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 07:31:26.267715 duration_in_ms=71.177
2019-04-24 07:31:26,268 [salt.state       :1780][INFO    ][24159] Running state [/root/.pgpass] at time 07:31:26.268005
2019-04-24 07:31:26,268 [salt.state       :1813][INFO    ][24159] Executing state file.managed for [/root/.pgpass]
2019-04-24 07:31:26,315 [salt.state       :300 ][INFO    ][24159] File /root/.pgpass is in the correct state
2019-04-24 07:31:26,315 [salt.state       :1951][INFO    ][24159] Completed state [/root/.pgpass] at time 07:31:26.315341 duration_in_ms=47.335
2019-04-24 07:31:26,320 [salt.state       :1780][INFO    ][24159] Running state [maas-region syncdb --noinput] at time 07:31:26.320299
2019-04-24 07:31:26,320 [salt.state       :1813][INFO    ][24159] Executing state cmd.run for [maas-region syncdb --noinput]
2019-04-24 07:31:26,321 [salt.loaded.int.module.cmdmod:395 ][INFO    ][24159] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-04-24 07:31:28,310 [salt.state       :300 ][INFO    ][24159] {'pid': 24229, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: maasserver, piston3, auth, sessions, contenttypes, metadataserver, sites\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-04-24 07:31:28,310 [salt.state       :1951][INFO    ][24159] Completed state [maas-region syncdb --noinput] at time 07:31:28.310775 duration_in_ms=1990.474
2019-04-24 07:31:28,311 [salt.state       :2022][WARNING ][24159] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-04-24 07:31:28,314 [salt.state       :1780][INFO    ][24159] Running state [maas-regiond] at time 07:31:28.313982
2019-04-24 07:31:28,314 [salt.state       :1813][INFO    ][24159] Executing state service.running for [maas-regiond]
2019-04-24 07:31:28,315 [salt.loaded.int.module.cmdmod:395 ][INFO    ][24159] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-04-24 07:31:28,357 [salt.loaded.int.module.cmdmod:395 ][INFO    ][24159] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-04-24 07:31:28,376 [salt.loaded.int.module.cmdmod:395 ][INFO    ][24159] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-04-24 07:31:28,394 [salt.state       :300 ][INFO    ][24159] The service maas-regiond is already running
2019-04-24 07:31:28,394 [salt.state       :1951][INFO    ][24159] Completed state [maas-regiond] at time 07:31:28.394550 duration_in_ms=80.568
2019-04-24 07:31:28,396 [salt.state       :1780][INFO    ][24159] Running state [bind9] at time 07:31:28.396910
2019-04-24 07:31:28,397 [salt.state       :1813][INFO    ][24159] Executing state service.running for [bind9]
2019-04-24 07:31:28,398 [salt.loaded.int.module.cmdmod:395 ][INFO    ][24159] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-04-24 07:31:28,419 [salt.loaded.int.module.cmdmod:395 ][INFO    ][24159] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-04-24 07:31:28,438 [salt.loaded.int.module.cmdmod:395 ][INFO    ][24159] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-04-24 07:31:28,455 [salt.state       :300 ][INFO    ][24159] The service bind9 is already running
2019-04-24 07:31:28,456 [salt.state       :1951][INFO    ][24159] Completed state [bind9] at time 07:31:28.456172 duration_in_ms=59.262
2019-04-24 07:31:28,458 [salt.state       :1780][INFO    ][24159] Running state [apache2] at time 07:31:28.458319
2019-04-24 07:31:28,458 [salt.state       :1813][INFO    ][24159] Executing state service.running for [apache2]
2019-04-24 07:31:28,459 [salt.loaded.int.module.cmdmod:395 ][INFO    ][24159] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-04-24 07:31:28,478 [salt.loaded.int.module.cmdmod:395 ][INFO    ][24159] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-04-24 07:31:28,495 [salt.loaded.int.module.cmdmod:395 ][INFO    ][24159] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-04-24 07:31:28,515 [salt.state       :300 ][INFO    ][24159] The service apache2 is already running
2019-04-24 07:31:28,516 [salt.state       :1951][INFO    ][24159] Completed state [apache2] at time 07:31:28.516099 duration_in_ms=57.779
2019-04-24 07:31:28,517 [salt.state       :1780][INFO    ][24159] Running state [maasng.wait_for_http_code] at time 07:31:28.517720
2019-04-24 07:31:28,518 [salt.state       :1813][INFO    ][24159] Executing state module.run for [maasng.wait_for_http_code]
2019-04-24 07:31:28,518 [salt.utils.decorators:613 ][WARNING ][24159] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 07:31:28,526 [salt.state       :300 ][INFO    ][24159] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-04-24 07:31:28,527 [salt.state       :1951][INFO    ][24159] Completed state [maasng.wait_for_http_code] at time 07:31:28.527272 duration_in_ms=9.551
2019-04-24 07:31:28,528 [salt.state       :1780][INFO    ][24159] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 07:31:28.528618
2019-04-24 07:31:28,529 [salt.state       :1813][INFO    ][24159] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-04-24 07:31:28,529 [salt.state       :300 ][INFO    ][24159] /var/lib/maas/.setup_admin exists
2019-04-24 07:31:28,530 [salt.state       :1951][INFO    ][24159] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 07:31:28.530149 duration_in_ms=1.531
2019-04-24 07:31:28,531 [salt.state       :1780][INFO    ][24159] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:31:28.531223
2019-04-24 07:31:28,531 [salt.state       :1813][INFO    ][24159] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-24 07:31:28,532 [salt.loaded.int.module.cmdmod:395 ][INFO    ][24159] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-24 07:31:30,055 [salt.state       :300 ][INFO    ][24159] {'pid': 24248, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-24 07:31:30,056 [salt.state       :1951][INFO    ][24159] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:31:30.056051 duration_in_ms=1524.828
2019-04-24 07:31:30,060 [salt.state       :1780][INFO    ][24159] Running state [maas_region_boot_source_resources_mirror] at time 07:31:30.059973
2019-04-24 07:31:30,060 [salt.state       :1813][INFO    ][24159] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-04-24 07:31:30,155 [salt.state       :300 ][INFO    ][24159] {'changes': {}}
2019-04-24 07:31:30,155 [salt.state       :1951][INFO    ][24159] Completed state [maas_region_boot_source_resources_mirror] at time 07:31:30.155626 duration_in_ms=95.651
2019-04-24 07:31:30,156 [salt.state       :1780][INFO    ][24159] Running state [maasng.boot_resources_import] at time 07:31:30.156773
2019-04-24 07:31:30,157 [salt.state       :1813][INFO    ][24159] Executing state module.run for [maasng.boot_resources_import]
2019-04-24 07:31:30,157 [salt.utils.decorators:613 ][WARNING ][24159] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 07:31:30,258 [salt.loaded.ext.module.maasng:1600][INFO    ][24159] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-04-24 07:31:35,325 [salt.loaded.ext.module.maasng:1600][INFO    ][24159] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-04-24 07:31:36,259 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424073136246747
2019-04-24 07:31:36,285 [salt.minion      :1432][INFO    ][24303] Starting a new job with PID 24303
2019-04-24 07:31:36,307 [salt.minion      :1711][INFO    ][24303] Returning information for job: 20190424073136246747
2019-04-24 07:31:40,385 [salt.loaded.ext.module.maasng:1600][INFO    ][24159] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-04-24 07:31:45,500 [salt.state       :300 ][INFO    ][24159] {'ret': True}
2019-04-24 07:31:45,500 [salt.state       :1951][INFO    ][24159] Completed state [maasng.boot_resources_import] at time 07:31:45.500803 duration_in_ms=15344.029
2019-04-24 07:31:45,502 [salt.state       :1780][INFO    ][24159] Running state [maas_region_boot_sources_selection_xenial] at time 07:31:45.501948
2019-04-24 07:31:45,502 [salt.state       :1813][INFO    ][24159] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-04-24 07:31:45,709 [salt.state       :300 ][INFO    ][24159] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-04-24 07:31:45,710 [salt.state       :1951][INFO    ][24159] Completed state [maas_region_boot_sources_selection_xenial] at time 07:31:45.709951 duration_in_ms=208.002
2019-04-24 07:31:45,711 [salt.state       :1780][INFO    ][24159] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 07:31:45.711137
2019-04-24 07:31:45,711 [salt.state       :1813][INFO    ][24159] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-04-24 07:31:45,712 [salt.utils.decorators:613 ][WARNING ][24159] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 07:31:45,712 [salt.loaded.ext.module.maasng:1771][INFO    ][24159] boot-sources sync initiated for ALL Rack's
2019-04-24 07:31:46,800 [salt.state       :300 ][INFO    ][24159] {'ret': True}
2019-04-24 07:31:46,801 [salt.state       :1951][INFO    ][24159] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 07:31:46.801099 duration_in_ms=1089.96
2019-04-24 07:31:46,803 [salt.state       :1780][INFO    ][24159] Running state [maas.process_maas_config] at time 07:31:46.803279
2019-04-24 07:31:46,803 [salt.state       :1813][INFO    ][24159] Executing state module.run for [maas.process_maas_config]
2019-04-24 07:31:46,804 [salt.utils.decorators:613 ][WARNING ][24159] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 07:31:46,805 [salt.loaded.ext.module.maas:92  ][INFO    ][24159] maasconfig name=enable_http_proxy value=True
2019-04-24 07:31:46,873 [salt.loaded.ext.module.maas:92  ][INFO    ][24159] maasconfig name=upstream_dns value=8.8.8.8
2019-04-24 07:31:46,931 [salt.loaded.ext.module.maas:92  ][INFO    ][24159] maasconfig name=commissioning_distro_series value=xenial
2019-04-24 07:31:47,003 [salt.loaded.ext.module.maas:92  ][INFO    ][24159] maasconfig name=default_osystem value=ubuntu
2019-04-24 07:31:47,075 [salt.loaded.ext.module.maas:92  ][INFO    ][24159] maasconfig name=active_discovery_interval value=600
2019-04-24 07:31:47,129 [salt.loaded.ext.module.maas:92  ][INFO    ][24159] maasconfig name=dnssec_validation value=no
2019-04-24 07:31:49,795 [salt.loaded.ext.module.maas:92  ][INFO    ][24159] maasconfig name=maas_name value=mas01
2019-04-24 07:31:49,848 [salt.loaded.ext.module.maas:92  ][INFO    ][24159] maasconfig name=network_discovery value=enabled
2019-04-24 07:31:49,967 [salt.loaded.ext.module.maas:92  ][INFO    ][24159] maasconfig name=enable_third_party_drivers value=True
2019-04-24 07:31:50,022 [salt.loaded.ext.module.maas:92  ][INFO    ][24159] maasconfig name=default_storage_layout value=lvm
2019-04-24 07:31:50,077 [salt.loaded.ext.module.maas:92  ][INFO    ][24159] maasconfig name=ntp_external_only value=True
2019-04-24 07:31:50,131 [salt.loaded.ext.module.maas:92  ][INFO    ][24159] maasconfig name=disk_erase_with_secure_erase value=False
2019-04-24 07:31:50,185 [salt.loaded.ext.module.maas:92  ][INFO    ][24159] maasconfig name=default_distro_series value=xenial
2019-04-24 07:31:50,271 [salt.loaded.ext.module.maas:92  ][INFO    ][24159] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-04-24 07:31:50,407 [salt.state       :300 ][INFO    ][24159] {'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-04-24 07:31:50,408 [salt.state       :1951][INFO    ][24159] Completed state [maas.process_maas_config] at time 07:31:50.408516 duration_in_ms=3605.237
2019-04-24 07:31:50,409 [salt.state       :1780][INFO    ][24159] Running state [pxe_admin] at time 07:31:50.409537
2019-04-24 07:31:50,410 [salt.state       :1813][INFO    ][24159] Executing state maasng.fabric_present for [pxe_admin]
2019-04-24 07:31:50,482 [salt.loaded.ext.module.maasng:945 ][INFO    ][24159] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None, u'name': u'fabric-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'primary_rack': u'a38yr8', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u'', u'name': u'pxe_admin'}]
2019-04-24 07:31:50,536 [salt.loaded.ext.module.maasng:1008][WARNING ][24159] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-04-24 07:31:50,536 [salt.loaded.ext.module.maasng:1011][WARNING ][24159] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-04-24 07:31:50,590 [salt.state       :300 ][INFO    ][24159] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-04-24 07:31:50,590 [salt.state       :1951][INFO    ][24159] Completed state [pxe_admin] at time 07:31:50.590877 duration_in_ms=181.341
2019-04-24 07:31:50,591 [salt.state       :1780][INFO    ][24159] Running state [vlan 0] at time 07:31:50.591310
2019-04-24 07:31:50,591 [salt.state       :1813][INFO    ][24159] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-04-24 07:31:50,638 [salt.loaded.ext.module.maasng:945 ][INFO    ][24159] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'a38yr8', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-04-24 07:31:50,746 [salt.loaded.ext.module.maasng:945 ][INFO    ][24159] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None, u'name': u'fabric-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'primary_rack': u'a38yr8', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u'', u'name': u'pxe_admin'}]
2019-04-24 07:31:50,975 [salt.loaded.ext.module.maasng:945 ][INFO    ][24159] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None, u'name': u'fabric-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'primary_rack': u'a38yr8', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u'', u'name': u'pxe_admin'}]
2019-04-24 07:31:51,048 [salt.state       :300 ][INFO    ][24159] {'new': 'Vlan untagged was updated'}
2019-04-24 07:31:51,048 [salt.state       :1951][INFO    ][24159] Completed state [vlan 0] at time 07:31:51.048603 duration_in_ms=457.292
2019-04-24 07:31:51,050 [salt.state       :1780][INFO    ][24159] Running state [192.168.11.0/24] at time 07:31:51.050031
2019-04-24 07:31:51,050 [salt.state       :1813][INFO    ][24159] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-04-24 07:31:51,231 [salt.loaded.ext.module.maasng:945 ][INFO    ][24159] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None, u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'a38yr8', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u'', u'name': u'pxe_admin'}]
2019-04-24 07:31:51,232 [salt.loaded.ext.module.maasng:1235][WARNING ][24159] Ignoring parameter vlan:0
2019-04-24 07:31:51,303 [salt.state       :300 ][INFO    ][24159] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-04-24 07:31:51,303 [salt.state       :1951][INFO    ][24159] Completed state [192.168.11.0/24] at time 07:31:51.303893 duration_in_ms=253.862
2019-04-24 07:31:51,304 [salt.state       :1780][INFO    ][24159] Running state [maas_create_iprange_1] at time 07:31:51.304806
2019-04-24 07:31:51,305 [salt.state       :1813][INFO    ][24159] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-04-24 07:31:51,375 [salt.state       :300 ][INFO    ][24159] Iprange maas_create_iprange_1 already exist.
2019-04-24 07:31:51,375 [salt.state       :1951][INFO    ][24159] Completed state [maas_create_iprange_1] at time 07:31:51.375648 duration_in_ms=70.841
2019-04-24 07:31:51,376 [salt.state       :1780][INFO    ][24159] Running state [vlan 0] at time 07:31:51.376058
2019-04-24 07:31:51,376 [salt.state       :1813][INFO    ][24159] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-04-24 07:31:51,435 [salt.loaded.ext.module.maasng:945 ][INFO    ][24159] [{u'id': 0, 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'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'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 1, 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'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'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'id': 2, 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'a38yr8', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-04-24 07:31:51,575 [salt.loaded.ext.module.maasng:945 ][INFO    ][24159] [{u'id': 0, 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'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'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 1, 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'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'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'id': 2, 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'a38yr8', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-04-24 07:31:51,856 [salt.loaded.ext.module.maasng:945 ][INFO    ][24159] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'a38yr8', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-04-24 07:31:51,940 [salt.state       :300 ][INFO    ][24159] {'new': 'Vlan untagged was updated'}
2019-04-24 07:31:51,940 [salt.state       :1951][INFO    ][24159] Completed state [vlan 0] at time 07:31:51.940515 duration_in_ms=564.457
2019-04-24 07:31:51,941 [salt.state       :1780][INFO    ][24159] Running state [opnfv] at time 07:31:51.941121
2019-04-24 07:31:51,941 [salt.state       :1813][INFO    ][24159] Executing state maasng.sshkey_present for [opnfv]
2019-04-24 07:31:51,987 [salt.loaded.ext.module.maasng:1903][INFO    ][24159] [{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-04-24 07:31:51,988 [salt.state       :300 ][INFO    ][24159] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-04-24 07:31:51,988 [salt.state       :1951][INFO    ][24159] Completed state [opnfv] at time 07:31:51.988540 duration_in_ms=47.419
2019-04-24 07:31:51,989 [salt.state       :1780][INFO    ][24159] Running state [maas.process_tags] at time 07:31:51.989244
2019-04-24 07:31:51,989 [salt.state       :1813][INFO    ][24159] Executing state module.run for [maas.process_tags]
2019-04-24 07:31:51,990 [salt.utils.decorators:613 ][WARNING ][24159] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 07:31:52,041 [salt.loaded.ext.module.maas:92  ][INFO    ][24159] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-04-24 07:31:52,108 [salt.state       :300 ][INFO    ][24159] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-04-24 07:31:52,108 [salt.state       :1951][INFO    ][24159] Completed state [maas.process_tags] at time 07:31:52.108615 duration_in_ms=119.37
2019-04-24 07:31:52,113 [salt.minion      :1711][INFO    ][24159] Returning information for job: 20190424073121169347
2019-04-24 07:31:52,694 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command state.apply with jid 20190424073152681985
2019-04-24 07:31:52,716 [salt.minion      :1432][INFO    ][24698] Starting a new job with PID 24698
2019-04-24 07:31:56,436 [salt.state       :915 ][INFO    ][24698] Loading fresh modules for state activity
2019-04-24 07:31:56,496 [salt.fileclient  :1219][INFO    ][24698] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-04-24 07:31:56,540 [salt.state       :1780][INFO    ][24698] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:31:56.540781
2019-04-24 07:31:56,541 [salt.state       :1813][INFO    ][24698] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-24 07:31:56,543 [salt.loaded.int.module.cmdmod:395 ][INFO    ][24698] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-24 07:31:57,947 [salt.state       :300 ][INFO    ][24698] {'pid': 24730, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-24 07:31:57,947 [salt.state       :1951][INFO    ][24698] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:31:57.947681 duration_in_ms=1406.901
2019-04-24 07:31:57,949 [salt.state       :1780][INFO    ][24698] Running state [maas.process_machines] at time 07:31:57.949026
2019-04-24 07:31:57,949 [salt.state       :1813][INFO    ][24698] Executing state module.run for [maas.process_machines]
2019-04-24 07:31:57,949 [salt.utils.decorators:613 ][WARNING ][24698] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 07:31:58,026 [salt.loaded.ext.module.maas:412 ][WARNING ][24698] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-24 07:31:58,026 [salt.loaded.ext.module.maas:92  ][INFO    ][24698] 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-04-24 07:31:59,521 [salt.loaded.ext.module.maas:412 ][WARNING ][24698] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-24 07:31:59,521 [salt.loaded.ext.module.maas:92  ][INFO    ][24698] 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-04-24 07:32:00,940 [salt.loaded.ext.module.maas:412 ][WARNING ][24698] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-24 07:32:00,940 [salt.loaded.ext.module.maas:92  ][INFO    ][24698] 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-04-24 07:32:02,402 [salt.loaded.ext.module.maas:412 ][WARNING ][24698] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-24 07:32:02,403 [salt.loaded.ext.module.maas:92  ][INFO    ][24698] 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-04-24 07:32:03,834 [salt.loaded.ext.module.maas:412 ][WARNING ][24698] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-24 07:32:03,835 [salt.loaded.ext.module.maas:92  ][INFO    ][24698] 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-04-24 07:32:05,235 [salt.state       :300 ][INFO    ][24698] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-04-24 07:32:05,235 [salt.state       :1951][INFO    ][24698] Completed state [maas.process_machines] at time 07:32:05.235750 duration_in_ms=7286.72
2019-04-24 07:32:05,239 [salt.minion      :1711][INFO    ][24698] Returning information for job: 20190424073152681985
2019-04-24 07:32:36,022 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command state.apply with jid 20190424073236014242
2019-04-24 07:32:36,041 [salt.minion      :1432][INFO    ][25064] Starting a new job with PID 25064
2019-04-24 07:32:39,682 [salt.state       :915 ][INFO    ][25064] Loading fresh modules for state activity
2019-04-24 07:32:39,737 [salt.fileclient  :1219][INFO    ][25064] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-04-24 07:32:39,780 [salt.state       :1780][INFO    ][25064] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:32:39.780017
2019-04-24 07:32:39,780 [salt.state       :1813][INFO    ][25064] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-24 07:32:39,782 [salt.loaded.int.module.cmdmod:395 ][INFO    ][25064] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-24 07:32:41,242 [salt.state       :300 ][INFO    ][25064] {'pid': 25072, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-24 07:32:41,243 [salt.state       :1951][INFO    ][25064] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:32:41.243362 duration_in_ms=1463.346
2019-04-24 07:32:41,244 [salt.state       :1780][INFO    ][25064] Running state [maas.wait_for_machine_status] at time 07:32:41.244846
2019-04-24 07:32:41,245 [salt.state       :1813][INFO    ][25064] Executing state module.run for [maas.wait_for_machine_status]
2019-04-24 07:32:41,245 [salt.utils.decorators:613 ][WARNING ][25064] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 07:32:42,117 [salt.loaded.ext.module.maas:1023][INFO    ][25064] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.13212514s left)
2019-04-24 07:32:51,074 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424073251061913
2019-04-24 07:32:51,101 [salt.minion      :1432][INFO    ][25083] Starting a new job with PID 25083
2019-04-24 07:32:51,122 [salt.minion      :1711][INFO    ][25083] Returning information for job: 20190424073251061913
2019-04-24 07:33:13,088 [salt.loaded.ext.module.maas:1023][INFO    ][25064] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.16097403s left)
2019-04-24 07:33:21,117 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424073321104243
2019-04-24 07:33:21,144 [salt.minion      :1432][INFO    ][25103] Starting a new job with PID 25103
2019-04-24 07:33:21,165 [salt.minion      :1711][INFO    ][25103] Returning information for job: 20190424073321104243
2019-04-24 07:33:44,002 [salt.loaded.ext.module.maas:1023][INFO    ][25064] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.24720621s left)
2019-04-24 07:33:51,234 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424073351221625
2019-04-24 07:33:51,258 [salt.minion      :1432][INFO    ][25273] Starting a new job with PID 25273
2019-04-24 07:33:51,277 [salt.minion      :1711][INFO    ][25273] Returning information for job: 20190424073351221625
2019-04-24 07:34:15,406 [salt.loaded.ext.module.maas:1023][INFO    ][25064] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1405.84316301s left)
2019-04-24 07:34:21,280 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424073421268354
2019-04-24 07:34:21,307 [salt.minion      :1432][INFO    ][25395] Starting a new job with PID 25395
2019-04-24 07:34:21,327 [salt.minion      :1711][INFO    ][25395] Returning information for job: 20190424073421268354
2019-04-24 07:34:46,922 [salt.loaded.ext.module.maas:1023][INFO    ][25064] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1374.32716918s left)
2019-04-24 07:34:51,340 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424073451327937
2019-04-24 07:34:51,367 [salt.minion      :1432][INFO    ][26021] Starting a new job with PID 26021
2019-04-24 07:34:51,388 [salt.minion      :1711][INFO    ][26021] Returning information for job: 20190424073451327937
2019-04-24 07:35:18,757 [salt.loaded.ext.module.maas:1023][INFO    ][25064] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1342.49232721s left)
2019-04-24 07:35:21,403 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424073521390402
2019-04-24 07:35:21,429 [salt.minion      :1432][INFO    ][26054] Starting a new job with PID 26054
2019-04-24 07:35:21,450 [salt.minion      :1711][INFO    ][26054] Returning information for job: 20190424073521390402
2019-04-24 07:35:51,469 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424073551456033
2019-04-24 07:35:51,490 [salt.state       :300 ][INFO    ][25064] {'ret': True}
2019-04-24 07:35:51,490 [salt.state       :1951][INFO    ][25064] Completed state [maas.wait_for_machine_status] at time 07:35:51.490635 duration_in_ms=190245.786
2019-04-24 07:35:51,492 [salt.minion      :1432][INFO    ][26425] Starting a new job with PID 26425
2019-04-24 07:35:51,494 [salt.minion      :1711][INFO    ][25064] Returning information for job: 20190424073236014242
2019-04-24 07:35:51,514 [salt.minion      :1711][INFO    ][26425] Returning information for job: 20190424073551456033
2019-04-24 07:35:51,896 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command state.apply with jid 20190424073551884364
2019-04-24 07:35:51,918 [salt.minion      :1432][INFO    ][26440] Starting a new job with PID 26440
2019-04-24 07:35:55,643 [salt.state       :915 ][INFO    ][26440] Loading fresh modules for state activity
2019-04-24 07:35:55,696 [salt.fileclient  :1219][INFO    ][26440] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-04-24 07:35:55,787 [salt.state       :1780][INFO    ][26440] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:35:55.787236
2019-04-24 07:35:55,787 [salt.state       :1813][INFO    ][26440] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-24 07:35:55,789 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26440] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-24 07:35:57,148 [salt.state       :300 ][INFO    ][26440] {'pid': 26465, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-24 07:35:57,149 [salt.state       :1951][INFO    ][26440] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:35:57.149672 duration_in_ms=1362.434
2019-04-24 07:35:57,152 [salt.state       :1780][INFO    ][26440] Running state [maas_machines_storage_cmp002_lvm] at time 07:35:57.152546
2019-04-24 07:35:57,153 [salt.state       :1813][INFO    ][26440] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-04-24 07:35:58,583 [salt.loaded.ext.module.maasng:610 ][INFO    ][26440] pxbhtq
2019-04-24 07:35:58,584 [salt.loaded.ext.module.maasng:626 ][INFO    ][26440] sda
2019-04-24 07:35:59,317 [salt.loaded.ext.module.maasng:361 ][INFO    ][26440] pxbhtq
2019-04-24 07:35:59,447 [salt.loaded.ext.module.maasng:367 ][INFO    ][26440] [{u'size': 2397998940160, u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'system_id': u'pxbhtq', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id': 4, u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'size': 2397992648704, u'uuid': u'7d5c879b-0d49-4656-8507-6ff007dc2c03', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'pxbhtq', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'c100418f-c494-47c7-a675-0e3cd17a23aa', 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/pxbhtq/blockdevices/4/partition/4', u'type': u'partition', u'id': 4, u'device_id': 4}], u'type': u'physical', u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/pxbhtq/blockdevices/4/'}, {u'size': 2397988454400, u'block_size': 4096, u'uuid': u'f827ed1f-f467-41f3-a090-24faa8d07c1e', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 2397988454400, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'cc8e165c-f0e8-4009-b17a-2e0ed0d29e1b', u'fstype': u'ext4', u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'id_path': None, u'system_id': u'pxbhtq', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id': 9, u'available_size': 0, u'serial': None, u'partitions': [], u'type': u'virtual', u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/pxbhtq/blockdevices/9/'}]
2019-04-24 07:35:59,448 [salt.loaded.ext.module.maasng:632 ][INFO    ][26440] vgroot
2019-04-24 07:35:59,448 [salt.loaded.ext.module.maasng:635 ][INFO    ][26440] lvroot
2019-04-24 07:35:59,448 [salt.loaded.ext.module.maasng:639 ][INFO    ][26440] 107374182400
2019-04-24 07:36:00,089 [salt.loaded.ext.module.maasng:645 ][INFO    ][26440] {u'hwe_kernel': u'', u'swap_size': None, u'disable_ipv4': False, u'cpu_count': 16, 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'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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'a38yr8', 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'id': 31, u'mode': u'dhcp'}], u'tags': [], u'vlan': {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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'a38yr8', 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'id': 4, 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'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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'a38yr8', 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'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:6a', u'system_id': u'pxbhtq', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/pxbhtq/interfaces/4/'}, u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'testing_status': 2, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'status_action': u'modules-final', 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/pxbhtq/', u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'owner_data': {}, u'system_id': u'pxbhtq', 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'virtualblockdevice_set': [{u'size': 107374182400, u'block_size': 4096, u'uuid': u'665b9243-5133-422b-bdb6-0e86f29eceb5', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'3b317c8c-39dd-46ce-8937-2dc2e63912bf', u'fstype': u'ext4', u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'id_path': None, u'system_id': u'pxbhtq', u'partition_table_type': None, u'available_size': 0, u'id': 11, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'partitions': [], u'type': u'virtual', u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/pxbhtq/blockdevices/11/'}], u'blockdevice_set': [{u'size': 2397998940160, u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'system_id': u'pxbhtq', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id': 4, u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'size': 2397992648704, u'uuid': u'c35a958c-4c43-48dd-8d7a-cd2a526f2dd7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'pxbhtq', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'abda0d68-1402-463a-8514-ec0ea7977b82', 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/pxbhtq/blockdevices/4/partition/6', u'type': u'partition', u'id': 6, u'device_id': 4}], u'type': u'physical', u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/pxbhtq/blockdevices/4/'}, {u'size': 107374182400, u'block_size': 4096, u'uuid': u'665b9243-5133-422b-bdb6-0e86f29eceb5', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'3b317c8c-39dd-46ce-8937-2dc2e63912bf', u'fstype': u'ext4', u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'id_path': None, u'system_id': u'pxbhtq', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id': 11, u'available_size': 0, u'serial': None, u'partitions': [], u'type': u'virtual', u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/pxbhtq/blockdevices/11/'}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'physicalblockdevice_set': [{u'size': 2397998940160, u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'system_id': u'pxbhtq', u'partition_table_type': u'GPT', u'available_size': 0, u'id': 4, u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'size': 2397992648704, u'uuid': u'c35a958c-4c43-48dd-8d7a-cd2a526f2dd7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'pxbhtq', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'abda0d68-1402-463a-8514-ec0ea7977b82', 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/pxbhtq/blockdevices/4/partition/6', u'type': u'partition', u'id': 6, u'device_id': 4}], u'type': u'physical', u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/pxbhtq/blockdevices/4/'}], u'ip_addresses': [u'192.168.11.42'], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'pxbhtq', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'node_type_name': u'Machine', u'current_testing_result_id': 3, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'memory_test_status_name': u'Unknown', u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', 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'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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'a38yr8', 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'id': 31, u'mode': u'dhcp'}], u'tags': [], u'vlan': {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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'a38yr8', 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'id': 4, 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'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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'a38yr8', 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'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:6a', u'system_id': u'pxbhtq', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/pxbhtq/interfaces/4/'}, {u'name': u'enp7s0', u'links': [{u'id': 32, u'mode': u'link_up'}], u'tags': [], u'vlan': {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'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'enabled': True, u'effective_mtu': 1500, u'id': 18, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:6b', u'system_id': u'pxbhtq', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/pxbhtq/interfaces/18/'}, {u'name': u'enp9s0', u'links': [{u'id': 33, u'mode': u'link_up'}], u'tags': [], u'vlan': {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'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'enabled': True, u'effective_mtu': 1500, u'id': 19, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:6d', u'system_id': u'pxbhtq', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/pxbhtq/interfaces/19/'}, {u'name': u'enp8s0', u'links': [{u'id': 34, u'mode': u'link_up'}], u'tags': [], u'vlan': {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'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'enabled': True, u'effective_mtu': 1500, u'id': 20, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:6c', u'system_id': u'pxbhtq', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/pxbhtq/interfaces/20/'}], u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'boot_disk': {u'size': 2397998940160, u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'system_id': u'pxbhtq', u'partition_table_type': u'GPT', u'available_size': 0, u'id': 4, u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'size': 2397992648704, u'uuid': u'c35a958c-4c43-48dd-8d7a-cd2a526f2dd7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'pxbhtq', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'abda0d68-1402-463a-8514-ec0ea7977b82', 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/pxbhtq/blockdevices/4/partition/6', u'type': u'partition', u'id': 6, u'device_id': 4}], u'type': u'physical', u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/pxbhtq/blockdevices/4/'}}
2019-04-24 07:36:00,092 [salt.state       :300 ][INFO    ][26440] {'new': {'storage_layout': 'lvm'}}
2019-04-24 07:36:00,092 [salt.state       :1951][INFO    ][26440] Completed state [maas_machines_storage_cmp002_lvm] at time 07:36:00.092345 duration_in_ms=2939.798
2019-04-24 07:36:00,092 [salt.state       :1780][INFO    ][26440] Running state [maas_machines_storage_cmp001_lvm] at time 07:36:00.092878
2019-04-24 07:36:00,093 [salt.state       :1813][INFO    ][26440] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-04-24 07:36:01,410 [salt.loaded.ext.module.maasng:610 ][INFO    ][26440] d8h4qq
2019-04-24 07:36:01,411 [salt.loaded.ext.module.maasng:626 ][INFO    ][26440] sda
2019-04-24 07:36:02,123 [salt.loaded.ext.module.maasng:361 ][INFO    ][26440] d8h4qq
2019-04-24 07:36:02,250 [salt.loaded.ext.module.maasng:367 ][INFO    ][26440] [{u'size': 2397998940160, u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'system_id': u'd8h4qq', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id': 1, u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'partitions': [{u'size': 2397992648704, u'uuid': u'eafaf392-c81f-4a8a-8ded-fb6cb5ef3952', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'd8h4qq', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'061b034d-2cee-4acf-bbca-481f4661765b', 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/d8h4qq/blockdevices/1/partition/1', u'type': u'partition', u'id': 1, u'device_id': 1}], u'type': u'physical', u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/d8h4qq/blockdevices/1/'}, {u'size': 2397988454400, u'block_size': 4096, u'uuid': u'86506ea2-4f16-4854-b888-a9e127a3ea98', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 2397988454400, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'd48da04e-6c1a-460c-b91e-6ce07d012481', u'fstype': u'ext4', u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'id_path': None, u'system_id': u'd8h4qq', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id': 6, u'available_size': 0, u'serial': None, u'partitions': [], u'type': u'virtual', u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/d8h4qq/blockdevices/6/'}]
2019-04-24 07:36:02,250 [salt.loaded.ext.module.maasng:632 ][INFO    ][26440] vgroot
2019-04-24 07:36:02,251 [salt.loaded.ext.module.maasng:635 ][INFO    ][26440] lvroot
2019-04-24 07:36:02,251 [salt.loaded.ext.module.maasng:639 ][INFO    ][26440] 107374182400
2019-04-24 07:36:02,964 [salt.loaded.ext.module.maasng:645 ][INFO    ][26440] {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'status_action': u'', u'swap_size': None, u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.38'], u'storage_test_status_name': u'Passed', 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'a38yr8', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 15, u'mode': u'dhcp'}], u'tags': [], u'enabled': True, 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'primary_rack': u'a38yr8', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'a38yr8', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:5a', u'system_id': u'd8h4qq', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/d8h4qq/interfaces/5/'}, u'min_hwe_kernel': u'hwe-16.04', u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'59ee2ca0-50d0-4a65-85df-4c175af5613f', u'resource_uri': u'/MAAS/api/2.0/nodes/d8h4qq/blockdevices/1/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'd8h4qq', u'filesystem': {u'mount_options': None, u'uuid': u'184a9871-86f1-44aa-a7d9-cfcbad68c2ee', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 7, u'device_id': 1}], u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'd8h4qq', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160, u'type': u'physical', u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/d8h4qq/blockdevices/1/'}, u'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'id': 1}, u'current_commissioning_result_id': 4, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'd8h4qq', u'raids': [], u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'model': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'mount_options': None, u'uuid': u'760e4455-4634-415f-a8c5-c692511036e6', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, u'uuid': u'6738d1bd-f91a-4ff5-b40b-4a3affa571b9', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'd8h4qq', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'size': 107374182400, u'type': u'virtual', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/d8h4qq/blockdevices/12/'}], u'blockdevice_set': [{u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'59ee2ca0-50d0-4a65-85df-4c175af5613f', u'resource_uri': u'/MAAS/api/2.0/nodes/d8h4qq/blockdevices/1/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'd8h4qq', u'filesystem': {u'mount_options': None, u'uuid': u'184a9871-86f1-44aa-a7d9-cfcbad68c2ee', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 7, u'device_id': 1}], u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'd8h4qq', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160, u'used_size': 2397998940160, u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/d8h4qq/blockdevices/1/'}, {u'model': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'mount_options': None, u'uuid': u'760e4455-4634-415f-a8c5-c692511036e6', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, u'uuid': u'6738d1bd-f91a-4ff5-b40b-4a3affa571b9', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'd8h4qq', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'size': 107374182400, u'used_size': 107374182400, u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/d8h4qq/blockdevices/12/'}], u'status': 4, u'storage_test_status': 2, u'cpu_count': 16, u'power_state': u'off', u'owner_data': {}, u'memory_test_status_name': u'Unknown', u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'd8h4qq', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'commissioning_status_name': u'Passed', u'current_testing_result_id': 5, u'cpu_test_status': -1, u'bcaches': [], u'status_name': u'Ready', u'physicalblockdevice_set': [{u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'59ee2ca0-50d0-4a65-85df-4c175af5613f', u'resource_uri': u'/MAAS/api/2.0/nodes/d8h4qq/blockdevices/1/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'd8h4qq', u'filesystem': {u'mount_options': None, u'uuid': u'184a9871-86f1-44aa-a7d9-cfcbad68c2ee', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 7, u'device_id': 1}], u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'd8h4qq', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160, u'type': u'physical', u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/d8h4qq/blockdevices/1/'}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'disable_ipv4': False, u'commissioning_status': 2, u'architecture': u'amd64/generic', 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'primary_rack': u'a38yr8', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 15, u'mode': u'dhcp'}], u'tags': [], u'enabled': True, 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'primary_rack': u'a38yr8', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'a38yr8', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:5a', u'system_id': u'd8h4qq', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/d8h4qq/interfaces/5/'}, {u'name': u'enp7s0', u'links': [{u'id': 16, u'mode': u'link_up'}], u'tags': [], u'enabled': True, 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'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:5b', u'system_id': u'd8h4qq', u'type': u'physical', u'id': 9, u'resource_uri': u'/MAAS/api/2.0/nodes/d8h4qq/interfaces/9/'}, {u'name': u'enp9s0', u'links': [{u'id': 17, u'mode': u'link_up'}], u'tags': [], u'enabled': True, 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'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:5d', u'system_id': u'd8h4qq', u'type': u'physical', u'id': 10, u'resource_uri': u'/MAAS/api/2.0/nodes/d8h4qq/interfaces/10/'}, {u'name': u'enp8s0', u'links': [{u'id': 18, u'mode': u'link_up'}], u'tags': [], u'enabled': True, 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'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:5c', u'system_id': u'd8h4qq', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/d8h4qq/interfaces/11/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/d8h4qq/'}
2019-04-24 07:36:02,966 [salt.state       :300 ][INFO    ][26440] {'new': {'storage_layout': 'lvm'}}
2019-04-24 07:36:02,966 [salt.state       :1951][INFO    ][26440] Completed state [maas_machines_storage_cmp001_lvm] at time 07:36:02.966683 duration_in_ms=2873.804
2019-04-24 07:36:02,970 [salt.minion      :1711][INFO    ][26440] Returning information for job: 20190424073551884364
2019-04-24 07:36:03,590 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command state.apply with jid 20190424073603577067
2019-04-24 07:36:03,616 [salt.minion      :1432][INFO    ][26485] Starting a new job with PID 26485
2019-04-24 07:36:04,351 [salt.state       :915 ][INFO    ][26485] Loading fresh modules for state activity
2019-04-24 07:36:04,402 [salt.fileclient  :1219][INFO    ][26485] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-04-24 07:36:04,442 [salt.state       :1780][INFO    ][26485] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:36:04.442598
2019-04-24 07:36:04,443 [salt.state       :1813][INFO    ][26485] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-24 07:36:04,445 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26485] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-24 07:36:05,940 [salt.state       :300 ][INFO    ][26485] {'pid': 26492, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-24 07:36:05,941 [salt.state       :1951][INFO    ][26485] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:36:05.941575 duration_in_ms=1498.976
2019-04-24 07:36:05,944 [salt.state       :1780][INFO    ][26485] Running state [maas.deploy_machines] at time 07:36:05.944187
2019-04-24 07:36:05,944 [salt.state       :1813][INFO    ][26485] Executing state module.run for [maas.deploy_machines]
2019-04-24 07:36:05,946 [salt.utils.decorators:613 ][WARNING ][26485] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 07:36:06,657 [salt.loaded.ext.module.maas:684 ][INFO    ][26485] deploymachines hwe_kernel=hwe-16.04 system_id=pxbhtq distro_series=xenial
2019-04-24 07:36:10,317 [salt.loaded.ext.module.maas:684 ][INFO    ][26485] deploymachines hwe_kernel=hwe-16.04 system_id=d8h4qq distro_series=xenial
2019-04-24 07:36:13,071 [salt.loaded.ext.module.maas:684 ][INFO    ][26485] deploymachines hwe_kernel=hwe-16.04 system_id=try6q3 distro_series=xenial
2019-04-24 07:36:15,496 [salt.loaded.ext.module.maas:684 ][INFO    ][26485] deploymachines hwe_kernel=hwe-16.04 system_id=efhw3h distro_series=xenial
2019-04-24 07:36:18,083 [salt.loaded.ext.module.maas:684 ][INFO    ][26485] deploymachines hwe_kernel=hwe-16.04 system_id=qnnnt3 distro_series=xenial
2019-04-24 07:36:18,626 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424073618614339
2019-04-24 07:36:18,654 [salt.minion      :1432][INFO    ][26772] Starting a new job with PID 26772
2019-04-24 07:36:18,675 [salt.minion      :1711][INFO    ][26772] Returning information for job: 20190424073618614339
2019-04-24 07:36:20,511 [salt.state       :300 ][INFO    ][26485] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-04-24 07:36:20,511 [salt.state       :1951][INFO    ][26485] Completed state [maas.deploy_machines] at time 07:36:20.511745 duration_in_ms=14567.555
2019-04-24 07:36:20,516 [salt.minion      :1711][INFO    ][26485] Returning information for job: 20190424073603577067
2019-04-24 07:36:21,134 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command state.apply with jid 20190424073621120884
2019-04-24 07:36:21,160 [salt.minion      :1432][INFO    ][26831] Starting a new job with PID 26831
2019-04-24 07:36:24,642 [salt.state       :915 ][INFO    ][26831] Loading fresh modules for state activity
2019-04-24 07:36:24,693 [salt.fileclient  :1219][INFO    ][26831] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-04-24 07:36:24,735 [salt.state       :1780][INFO    ][26831] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:36:24.734959
2019-04-24 07:36:24,735 [salt.state       :1813][INFO    ][26831] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-24 07:36:24,737 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26831] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-24 07:36:26,067 [salt.state       :300 ][INFO    ][26831] {'pid': 26858, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-24 07:36:26,067 [salt.state       :1951][INFO    ][26831] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:36:26.067457 duration_in_ms=1332.499
2019-04-24 07:36:26,068 [salt.state       :1780][INFO    ][26831] Running state [maas.wait_for_machine_status] at time 07:36:26.068809
2019-04-24 07:36:26,069 [salt.state       :1813][INFO    ][26831] Executing state module.run for [maas.wait_for_machine_status]
2019-04-24 07:36:26,069 [salt.utils.decorators:613 ][WARNING ][26831] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 07:36:29,125 [salt.loaded.ext.module.maas:1023][INFO    ][26831] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.94774485s left)
2019-04-24 07:36:36,151 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424073636138821
2019-04-24 07:36:36,177 [salt.minion      :1432][INFO    ][26902] Starting a new job with PID 26902
2019-04-24 07:36:36,201 [salt.minion      :1711][INFO    ][26902] Returning information for job: 20190424073636138821
2019-04-24 07:37:02,386 [salt.loaded.ext.module.maas:1023][INFO    ][26831] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.68651485s left)
2019-04-24 07:37:06,208 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424073706196077
2019-04-24 07:37:06,235 [salt.minion      :1432][INFO    ][26923] Starting a new job with PID 26923
2019-04-24 07:37:06,258 [salt.minion      :1711][INFO    ][26923] Returning information for job: 20190424073706196077
2019-04-24 07:37:36,216 [salt.loaded.ext.module.maas:1023][INFO    ][26831] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2179.85672092s left)
2019-04-24 07:37:36,304 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424073736293813
2019-04-24 07:37:36,330 [salt.minion      :1432][INFO    ][26977] Starting a new job with PID 26977
2019-04-24 07:37:36,352 [salt.minion      :1711][INFO    ][26977] Returning information for job: 20190424073736293813
2019-04-24 07:38:06,345 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424073806333953
2019-04-24 07:38:06,368 [salt.minion      :1432][INFO    ][27076] Starting a new job with PID 27076
2019-04-24 07:38:06,386 [salt.minion      :1711][INFO    ][27076] Returning information for job: 20190424073806333953
2019-04-24 07:38:09,712 [salt.loaded.ext.module.maas:1023][INFO    ][26831] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2146.36088204s left)
2019-04-24 07:38:36,393 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424073836380266
2019-04-24 07:38:36,419 [salt.minion      :1432][INFO    ][27606] Starting a new job with PID 27606
2019-04-24 07:38:36,439 [salt.minion      :1711][INFO    ][27606] Returning information for job: 20190424073836380266
2019-04-24 07:38:42,978 [salt.loaded.ext.module.maas:1023][INFO    ][26831] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2113.09461498s left)
2019-04-24 07:39:06,441 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424073906432744
2019-04-24 07:39:06,466 [salt.minion      :1432][INFO    ][27650] Starting a new job with PID 27650
2019-04-24 07:39:06,483 [salt.minion      :1711][INFO    ][27650] Returning information for job: 20190424073906432744
2019-04-24 07:39:16,301 [salt.loaded.ext.module.maas:1023][INFO    ][26831] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2079.7716229s left)
2019-04-24 07:39:36,488 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424073936475558
2019-04-24 07:39:36,514 [salt.minion      :1432][INFO    ][28174] Starting a new job with PID 28174
2019-04-24 07:39:36,537 [salt.minion      :1711][INFO    ][28174] Returning information for job: 20190424073936475558
2019-04-24 07:39:49,908 [salt.loaded.ext.module.maas:1023][INFO    ][26831] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2046.16477704s left)
2019-04-24 07:40:06,556 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424074006543152
2019-04-24 07:40:06,580 [salt.minion      :1432][INFO    ][28198] Starting a new job with PID 28198
2019-04-24 07:40:06,601 [salt.minion      :1711][INFO    ][28198] Returning information for job: 20190424074006543152
2019-04-24 07:40:23,455 [salt.loaded.ext.module.maas:1023][INFO    ][26831] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2012.61733198s left)
2019-04-24 07:40:36,622 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424074036609997
2019-04-24 07:40:36,648 [salt.minion      :1432][INFO    ][28519] Starting a new job with PID 28519
2019-04-24 07:40:36,669 [salt.minion      :1711][INFO    ][28519] Returning information for job: 20190424074036609997
2019-04-24 07:40:56,533 [salt.loaded.ext.module.maas:1023][INFO    ][26831] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1979.53935885s left)
2019-04-24 07:41:06,693 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424074106679903
2019-04-24 07:41:06,719 [salt.minion      :1432][INFO    ][28544] Starting a new job with PID 28544
2019-04-24 07:41:06,739 [salt.minion      :1711][INFO    ][28544] Returning information for job: 20190424074106679903
2019-04-24 07:41:30,250 [salt.loaded.ext.module.maas:1023][INFO    ][26831] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1945.82257605s left)
2019-04-24 07:41:36,768 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424074136755220
2019-04-24 07:41:36,794 [salt.minion      :1432][INFO    ][29065] Starting a new job with PID 29065
2019-04-24 07:41:36,815 [salt.minion      :1711][INFO    ][29065] Returning information for job: 20190424074136755220
2019-04-24 07:42:03,695 [salt.loaded.ext.module.maas:1023][INFO    ][26831] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1912.37788296s left)
2019-04-24 07:42:06,843 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424074206831307
2019-04-24 07:42:06,869 [salt.minion      :1432][INFO    ][29103] Starting a new job with PID 29103
2019-04-24 07:42:06,890 [salt.minion      :1711][INFO    ][29103] Returning information for job: 20190424074206831307
2019-04-24 07:42:36,922 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424074236909137
2019-04-24 07:42:36,949 [salt.minion      :1432][INFO    ][29349] Starting a new job with PID 29349
2019-04-24 07:42:36,975 [salt.minion      :1711][INFO    ][29349] Returning information for job: 20190424074236909137
2019-04-24 07:42:37,470 [salt.loaded.ext.module.maas:1023][INFO    ][26831] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1878.60228801s left)
2019-04-24 07:43:07,015 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424074307003088
2019-04-24 07:43:07,041 [salt.minion      :1432][INFO    ][29364] Starting a new job with PID 29364
2019-04-24 07:43:07,062 [salt.minion      :1711][INFO    ][29364] Returning information for job: 20190424074307003088
2019-04-24 07:43:10,896 [salt.loaded.ext.module.maas:1023][INFO    ][26831] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1845.17627406s left)
2019-04-24 07:43:37,115 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424074337101699
2019-04-24 07:43:37,141 [salt.minion      :1432][INFO    ][29570] Starting a new job with PID 29570
2019-04-24 07:43:37,163 [salt.minion      :1711][INFO    ][29570] Returning information for job: 20190424074337101699
2019-04-24 07:43:43,670 [salt.loaded.ext.module.maas:1023][INFO    ][26831] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1812.40260386s left)
2019-04-24 07:44:07,309 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424074407297301
2019-04-24 07:44:07,335 [salt.minion      :1432][INFO    ][29675] Starting a new job with PID 29675
2019-04-24 07:44:07,358 [salt.minion      :1711][INFO    ][29675] Returning information for job: 20190424074407297301
2019-04-24 07:44:16,935 [salt.loaded.ext.module.maas:1023][INFO    ][26831] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1779.13730502s left)
2019-04-24 07:44:37,419 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424074437406055
2019-04-24 07:44:37,444 [salt.minion      :1432][INFO    ][30135] Starting a new job with PID 30135
2019-04-24 07:44:37,466 [salt.minion      :1711][INFO    ][30135] Returning information for job: 20190424074437406055
2019-04-24 07:44:50,476 [salt.loaded.ext.module.maas:1023][INFO    ][26831] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1745.59692907s left)
2019-04-24 07:45:07,538 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424074507525286
2019-04-24 07:45:07,564 [salt.minion      :1432][INFO    ][30154] Starting a new job with PID 30154
2019-04-24 07:45:07,587 [salt.minion      :1711][INFO    ][30154] Returning information for job: 20190424074507525286
2019-04-24 07:45:23,909 [salt.loaded.ext.module.maas:1023][INFO    ][26831] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1712.16351604s left)
2019-04-24 07:45:37,663 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424074537647443
2019-04-24 07:45:37,690 [salt.minion      :1432][INFO    ][30223] Starting a new job with PID 30223
2019-04-24 07:45:37,712 [salt.minion      :1711][INFO    ][30223] Returning information for job: 20190424074537647443
2019-04-24 07:45:57,547 [salt.loaded.ext.module.maas:1023][INFO    ][26831] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1678.52567792s left)
2019-04-24 07:46:07,794 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424074607781612
2019-04-24 07:46:07,819 [salt.minion      :1432][INFO    ][30251] Starting a new job with PID 30251
2019-04-24 07:46:07,842 [salt.minion      :1711][INFO    ][30251] Returning information for job: 20190424074607781612
2019-04-24 07:46:30,874 [salt.loaded.ext.module.maas:1023][INFO    ][26831] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1645.19882607s left)
2019-04-24 07:46:37,931 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424074637918754
2019-04-24 07:46:37,957 [salt.minion      :1432][INFO    ][30310] Starting a new job with PID 30310
2019-04-24 07:46:37,978 [salt.minion      :1711][INFO    ][30310] Returning information for job: 20190424074637918754
2019-04-24 07:47:04,226 [salt.loaded.ext.module.maas:1023][INFO    ][26831] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1611.84746385s left)
2019-04-24 07:47:08,071 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424074708059526
2019-04-24 07:47:08,095 [salt.minion      :1432][INFO    ][30329] Starting a new job with PID 30329
2019-04-24 07:47:08,117 [salt.minion      :1711][INFO    ][30329] Returning information for job: 20190424074708059526
2019-04-24 07:47:37,343 [salt.loaded.ext.module.maas:1023][INFO    ][26831] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1578.72943401s left)
2019-04-24 07:47:38,222 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424074738209444
2019-04-24 07:47:38,249 [salt.minion      :1432][INFO    ][30387] Starting a new job with PID 30387
2019-04-24 07:47:38,269 [salt.minion      :1711][INFO    ][30387] Returning information for job: 20190424074738209444
2019-04-24 07:48:08,379 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424074808366649
2019-04-24 07:48:08,406 [salt.minion      :1432][INFO    ][30408] Starting a new job with PID 30408
2019-04-24 07:48:08,429 [salt.minion      :1711][INFO    ][30408] Returning information for job: 20190424074808366649
2019-04-24 07:48:10,537 [salt.loaded.ext.module.maas:1023][INFO    ][26831] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1545.53517985s left)
2019-04-24 07:48:38,550 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424074838538173
2019-04-24 07:48:38,567 [salt.minion      :1432][INFO    ][30471] Starting a new job with PID 30471
2019-04-24 07:48:38,589 [salt.minion      :1711][INFO    ][30471] Returning information for job: 20190424074838538173
2019-04-24 07:48:43,726 [salt.loaded.ext.module.maas:1023][INFO    ][26831] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1512.34623694s left)
2019-04-24 07:49:08,719 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424074908706751
2019-04-24 07:49:08,745 [salt.minion      :1432][INFO    ][30491] Starting a new job with PID 30491
2019-04-24 07:49:08,768 [salt.minion      :1711][INFO    ][30491] Returning information for job: 20190424074908706751
2019-04-24 07:49:17,295 [salt.loaded.ext.module.maas:1023][INFO    ][26831] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1478.77791286s left)
2019-04-24 07:49:38,914 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424074938901436
2019-04-24 07:49:38,939 [salt.minion      :1432][INFO    ][30550] Starting a new job with PID 30550
2019-04-24 07:49:38,961 [salt.minion      :1711][INFO    ][30550] Returning information for job: 20190424074938901436
2019-04-24 07:49:50,463 [salt.loaded.ext.module.maas:1023][INFO    ][26831] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1445.60983586s left)
2019-04-24 07:50:09,112 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424075009099300
2019-04-24 07:50:09,137 [salt.minion      :1432][INFO    ][30570] Starting a new job with PID 30570
2019-04-24 07:50:09,161 [salt.minion      :1711][INFO    ][30570] Returning information for job: 20190424075009099300
2019-04-24 07:50:23,905 [salt.loaded.ext.module.maas:1023][INFO    ][26831] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1412.16789889s left)
2019-04-24 07:50:39,325 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424075039312985
2019-04-24 07:50:39,351 [salt.minion      :1432][INFO    ][30755] Starting a new job with PID 30755
2019-04-24 07:50:39,376 [salt.minion      :1711][INFO    ][30755] Returning information for job: 20190424075039312985
2019-04-24 07:50:57,363 [salt.loaded.ext.module.maas:1023][INFO    ][26831] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1378.7098999s left)
2019-04-24 07:51:09,345 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424075109332110
2019-04-24 07:51:09,369 [salt.minion      :1432][INFO    ][30774] Starting a new job with PID 30774
2019-04-24 07:51:09,393 [salt.minion      :1711][INFO    ][30774] Returning information for job: 20190424075109332110
2019-04-24 07:51:30,785 [salt.loaded.ext.module.maas:1023][INFO    ][26831] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1345.28769588s left)
2019-04-24 07:51:39,368 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424075139355436
2019-04-24 07:51:39,393 [salt.minion      :1432][INFO    ][30831] Starting a new job with PID 30831
2019-04-24 07:51:39,415 [salt.minion      :1711][INFO    ][30831] Returning information for job: 20190424075139355436
2019-04-24 07:52:04,005 [salt.loaded.ext.module.maas:993 ][INFO    ][26831] Machine qnnnt3 mark broken
2019-04-24 07:52:04,760 [salt.loaded.ext.module.maas:996 ][INFO    ][26831] Machine qnnnt3 mark fixed
2019-04-24 07:52:05,848 [salt.loaded.ext.module.maas:684 ][INFO    ][26831] deploymachines hwe_kernel=hwe-16.04 system_id=qnnnt3 distro_series=xenial
2019-04-24 07:52:08,572 [salt.loaded.ext.module.maas:160 ][ERROR   ][26831] 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-04-24 07:52:08,574 [salt.state       :302 ][ERROR   ][26831] 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-04-24 07:52:08,575 [salt.state       :1951][INFO    ][26831] Completed state [maas.wait_for_machine_status] at time 07:52:08.575268 duration_in_ms=942506.437
2019-04-24 07:52:08,584 [salt.minion      :1711][INFO    ][26831] Returning information for job: 20190424073621120884
2019-04-24 07:52:19,388 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command pillar.get with jid 20190424075219379577
2019-04-24 07:52:19,414 [salt.minion      :1432][INFO    ][30928] Starting a new job with PID 30928
2019-04-24 07:52:19,424 [salt.minion      :1711][INFO    ][30928] Returning information for job: 20190424075219379577
2019-04-24 07:52:19,901 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command service.status with jid 20190424075219889192
2019-04-24 07:52:19,925 [salt.minion      :1432][INFO    ][30933] Starting a new job with PID 30933
2019-04-24 07:52:20,320 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][30933] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-04-24 07:52:20,355 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][30933] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-04-24 07:52:20,372 [salt.minion      :1711][INFO    ][30933] Returning information for job: 20190424075219889192
2019-04-24 07:52:20,875 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command state.apply with jid 20190424075220862660
2019-04-24 07:52:20,899 [salt.minion      :1432][INFO    ][30944] Starting a new job with PID 30944
2019-04-24 07:52:24,518 [salt.state       :915 ][INFO    ][30944] Loading fresh modules for state activity
2019-04-24 07:52:24,945 [salt.loaded.int.module.cmdmod:395 ][INFO    ][30944] Executing command 'salt-minion --version' in directory '/root'
2019-04-24 07:52:25,261 [salt.loaded.int.module.cmdmod:395 ][INFO    ][30944] Executing command 'salt-minion --version' in directory '/root'
2019-04-24 07:52:26,168 [salt.loaded.int.module.cmdmod:395 ][INFO    ][30944] Executing command 'salt-minion --version' in directory '/root'
2019-04-24 07:52:26,523 [salt.loaded.int.module.cmdmod:395 ][INFO    ][30944] Executing command 'salt-minion --version' in directory '/root'
2019-04-24 07:52:27,898 [salt.state       :1780][INFO    ][30944] Running state [salt-minion] at time 07:52:27.898307
2019-04-24 07:52:27,898 [salt.state       :1813][INFO    ][30944] Executing state pkg.installed for [salt-minion]
2019-04-24 07:52:27,899 [salt.loaded.int.module.cmdmod:395 ][INFO    ][30944] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-24 07:52:27,980 [salt.state       :300 ][INFO    ][30944] All specified packages are already installed
2019-04-24 07:52:27,980 [salt.state       :1951][INFO    ][30944] Completed state [salt-minion] at time 07:52:27.980609 duration_in_ms=82.303
2019-04-24 07:52:27,980 [salt.state       :1780][INFO    ][30944] Running state [salt_minion_dependency_packages] at time 07:52:27.980911
2019-04-24 07:52:27,981 [salt.state       :1813][INFO    ][30944] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-04-24 07:52:27,987 [salt.state       :300 ][INFO    ][30944] All specified packages are already installed
2019-04-24 07:52:27,987 [salt.state       :1951][INFO    ][30944] Completed state [salt_minion_dependency_packages] at time 07:52:27.987163 duration_in_ms=6.251
2019-04-24 07:52:27,989 [salt.state       :1780][INFO    ][30944] Running state [/etc/salt/minion.d/minion.conf] at time 07:52:27.989844
2019-04-24 07:52:27,990 [salt.state       :1813][INFO    ][30944] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-04-24 07:52:28,182 [salt.state       :300 ][INFO    ][30944] File /etc/salt/minion.d/minion.conf is in the correct state
2019-04-24 07:52:28,182 [salt.state       :1951][INFO    ][30944] Completed state [/etc/salt/minion.d/minion.conf] at time 07:52:28.182852 duration_in_ms=193.008
2019-04-24 07:52:28,183 [salt.state       :1780][INFO    ][30944] Running state [python-netaddr] at time 07:52:28.183104
2019-04-24 07:52:28,183 [salt.state       :1813][INFO    ][30944] Executing state pkg.installed for [python-netaddr]
2019-04-24 07:52:28,190 [salt.state       :300 ][INFO    ][30944] All specified packages are already installed
2019-04-24 07:52:28,190 [salt.state       :1951][INFO    ][30944] Completed state [python-netaddr] at time 07:52:28.190203 duration_in_ms=7.099
2019-04-24 07:52:28,193 [salt.state       :1780][INFO    ][30944] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 07:52:28.193197
2019-04-24 07:52:28,193 [salt.state       :1813][INFO    ][30944] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-04-24 07:52:28,204 [salt.state       :300 ][INFO    ][30944] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-04-24 07:52:28,204 [salt.state       :1951][INFO    ][30944] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 07:52:28.204326 duration_in_ms=11.129
2019-04-24 07:52:28,205 [salt.state       :1780][INFO    ][30944] Running state [salt-minion] at time 07:52:28.205258
2019-04-24 07:52:28,205 [salt.state       :1813][INFO    ][30944] Executing state service.running for [salt-minion]
2019-04-24 07:52:28,206 [salt.loaded.int.module.cmdmod:395 ][INFO    ][30944] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-04-24 07:52:28,242 [salt.loaded.int.module.cmdmod:395 ][INFO    ][30944] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-04-24 07:52:28,259 [salt.loaded.int.module.cmdmod:395 ][INFO    ][30944] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-04-24 07:52:28,277 [salt.state       :300 ][INFO    ][30944] The service salt-minion is already running
2019-04-24 07:52:28,277 [salt.state       :1951][INFO    ][30944] Completed state [salt-minion] at time 07:52:28.277797 duration_in_ms=72.537
2019-04-24 07:52:28,280 [salt.state       :1780][INFO    ][30944] Running state [/etc/salt/grains.d] at time 07:52:28.280057
2019-04-24 07:52:28,280 [salt.state       :1813][INFO    ][30944] Executing state file.directory for [/etc/salt/grains.d]
2019-04-24 07:52:28,281 [salt.state       :300 ][INFO    ][30944] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-04-24 07:52:28,282 [salt.state       :1951][INFO    ][30944] Completed state [/etc/salt/grains.d] at time 07:52:28.282244 duration_in_ms=2.186
2019-04-24 07:52:28,283 [salt.state       :1780][INFO    ][30944] Running state [/etc/salt/grains] at time 07:52:28.283260
2019-04-24 07:52:28,283 [salt.state       :1813][INFO    ][30944] Executing state file.managed for [/etc/salt/grains]
2019-04-24 07:52:28,284 [salt.state       :300 ][INFO    ][30944] File /etc/salt/grains exists with proper permissions. No changes made.
2019-04-24 07:52:28,284 [salt.state       :1951][INFO    ][30944] Completed state [/etc/salt/grains] at time 07:52:28.284828 duration_in_ms=1.567
2019-04-24 07:52:28,285 [salt.state       :1780][INFO    ][30944] Running state [/etc/salt/grains.d/placeholder] at time 07:52:28.285526
2019-04-24 07:52:28,285 [salt.state       :1813][INFO    ][30944] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-04-24 07:52:28,286 [salt.state       :300 ][INFO    ][30944] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-04-24 07:52:28,287 [salt.state       :1951][INFO    ][30944] Completed state [/etc/salt/grains.d/placeholder] at time 07:52:28.287005 duration_in_ms=1.478
2019-04-24 07:52:28,287 [salt.state       :1780][INFO    ][30944] Running state [/etc/salt/grains.d/sphinx] at time 07:52:28.287663
2019-04-24 07:52:28,288 [salt.state       :1813][INFO    ][30944] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-04-24 07:52:28,302 [salt.state       :300 ][INFO    ][30944] File /etc/salt/grains.d/sphinx is in the correct state
2019-04-24 07:52:28,302 [salt.state       :1951][INFO    ][30944] Completed state [/etc/salt/grains.d/sphinx] at time 07:52:28.302601 duration_in_ms=14.938
2019-04-24 07:52:28,305 [salt.state       :1780][INFO    ][30944] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 07:52:28.305600
2019-04-24 07:52:28,306 [salt.state       :1813][INFO    ][30944] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-04-24 07:52:28,306 [salt.state       :300 ][INFO    ][30944] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-04-24 07:52:28,306 [salt.state       :1951][INFO    ][30944] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 07:52:28.306776 duration_in_ms=1.177
2019-04-24 07:52:28,307 [salt.state       :1780][INFO    ][30944] Running state [/etc/salt/grains.d/dns_records] at time 07:52:28.307420
2019-04-24 07:52:28,307 [salt.state       :1813][INFO    ][30944] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-04-24 07:52:28,320 [salt.state       :300 ][INFO    ][30944] File /etc/salt/grains.d/dns_records is in the correct state
2019-04-24 07:52:28,320 [salt.state       :1951][INFO    ][30944] Completed state [/etc/salt/grains.d/dns_records] at time 07:52:28.320558 duration_in_ms=13.139
2019-04-24 07:52:28,321 [salt.state       :1780][INFO    ][30944] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 07:52:28.321849
2019-04-24 07:52:28,322 [salt.state       :1813][INFO    ][30944] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-04-24 07:52:28,322 [salt.state       :300 ][INFO    ][30944] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-04-24 07:52:28,323 [salt.state       :1951][INFO    ][30944] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 07:52:28.323010 duration_in_ms=1.161
2019-04-24 07:52:28,323 [salt.state       :1780][INFO    ][30944] Running state [/etc/salt/grains.d/salt] at time 07:52:28.323675
2019-04-24 07:52:28,324 [salt.state       :1813][INFO    ][30944] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-04-24 07:52:28,332 [salt.state       :300 ][INFO    ][30944] File /etc/salt/grains.d/salt is in the correct state
2019-04-24 07:52:28,332 [salt.state       :1951][INFO    ][30944] Completed state [/etc/salt/grains.d/salt] at time 07:52:28.332544 duration_in_ms=8.869
2019-04-24 07:52:28,333 [salt.state       :1780][INFO    ][30944] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 07:52:28.333769
2019-04-24 07:52:28,334 [salt.state       :1813][INFO    ][30944] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-04-24 07:52:28,334 [salt.state       :300 ][INFO    ][30944] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-04-24 07:52:28,335 [salt.state       :1951][INFO    ][30944] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 07:52:28.334941 duration_in_ms=1.171
2019-04-24 07:52:28,337 [salt.state       :1780][INFO    ][30944] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 07:52:28.337597
2019-04-24 07:52:28,338 [salt.state       :1813][INFO    ][30944] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-04-24 07:52:28,338 [salt.state       :300 ][INFO    ][30944] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-04-24 07:52:28,338 [salt.state       :1951][INFO    ][30944] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 07:52:28.338780 duration_in_ms=1.182
2019-04-24 07:52:28,339 [salt.state       :1780][INFO    ][30944] Running state [mine.update] at time 07:52:28.339723
2019-04-24 07:52:28,340 [salt.state       :1813][INFO    ][30944] Executing state module.wait for [mine.update]
2019-04-24 07:52:28,340 [salt.state       :300 ][INFO    ][30944] No changes made for mine.update
2019-04-24 07:52:28,340 [salt.state       :1951][INFO    ][30944] Completed state [mine.update] at time 07:52:28.340800 duration_in_ms=1.077
2019-04-24 07:52:28,341 [salt.state       :1780][INFO    ][30944] Running state [ca-certificates] at time 07:52:28.341125
2019-04-24 07:52:28,341 [salt.state       :1813][INFO    ][30944] Executing state pkg.installed for [ca-certificates]
2019-04-24 07:52:28,351 [salt.state       :300 ][INFO    ][30944] All specified packages are already installed
2019-04-24 07:52:28,352 [salt.state       :1951][INFO    ][30944] Completed state [ca-certificates] at time 07:52:28.352046 duration_in_ms=10.921
2019-04-24 07:52:28,353 [salt.state       :1780][INFO    ][30944] Running state [update-ca-certificates] at time 07:52:28.353014
2019-04-24 07:52:28,353 [salt.state       :1813][INFO    ][30944] Executing state cmd.wait for [update-ca-certificates]
2019-04-24 07:52:28,353 [salt.state       :300 ][INFO    ][30944] No changes made for update-ca-certificates
2019-04-24 07:52:28,354 [salt.state       :1951][INFO    ][30944] Completed state [update-ca-certificates] at time 07:52:28.354120 duration_in_ms=1.106
2019-04-24 07:52:28,354 [salt.state       :1780][INFO    ][30944] Running state [iptables] at time 07:52:28.354437
2019-04-24 07:52:28,354 [salt.state       :1813][INFO    ][30944] Executing state pkg.installed for [iptables]
2019-04-24 07:52:28,364 [salt.state       :300 ][INFO    ][30944] All specified packages are already installed
2019-04-24 07:52:28,364 [salt.state       :1951][INFO    ][30944] Completed state [iptables] at time 07:52:28.364424 duration_in_ms=9.987
2019-04-24 07:52:28,364 [salt.state       :1780][INFO    ][30944] Running state [iptables-persistent] at time 07:52:28.364778
2019-04-24 07:52:28,365 [salt.state       :1813][INFO    ][30944] Executing state pkg.installed for [iptables-persistent]
2019-04-24 07:52:28,374 [salt.state       :300 ][INFO    ][30944] All specified packages are already installed
2019-04-24 07:52:28,374 [salt.state       :1951][INFO    ][30944] Completed state [iptables-persistent] at time 07:52:28.374284 duration_in_ms=9.507
2019-04-24 07:52:28,375 [salt.state       :1780][INFO    ][30944] Running state [iptables_modules_v4_load] at time 07:52:28.375593
2019-04-24 07:52:28,375 [salt.state       :1813][INFO    ][30944] Executing state kmod.present for [iptables_modules_v4_load]
2019-04-24 07:52:28,376 [salt.loaded.int.module.cmdmod:395 ][INFO    ][30944] Executing command 'lsmod' in directory '/root'
2019-04-24 07:52:28,399 [salt.state       :300 ][INFO    ][30944] Kernel modules iptable_filter, ip_tables are already present
2019-04-24 07:52:28,400 [salt.state       :1951][INFO    ][30944] Completed state [iptables_modules_v4_load] at time 07:52:28.400300 duration_in_ms=24.706
2019-04-24 07:52:28,401 [salt.state       :1780][INFO    ][30944] Running state [/etc/iptables/rules.v4] at time 07:52:28.401411
2019-04-24 07:52:28,402 [salt.state       :1813][INFO    ][30944] Executing state file.managed for [/etc/iptables/rules.v4]
2019-04-24 07:52:28,524 [salt.state       :300 ][INFO    ][30944] File /etc/iptables/rules.v4 is in the correct state
2019-04-24 07:52:28,524 [salt.state       :1951][INFO    ][30944] Completed state [/etc/iptables/rules.v4] at time 07:52:28.524438 duration_in_ms=123.027
2019-04-24 07:52:28,525 [salt.state       :1780][INFO    ][30944] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 07:52:28.525701
2019-04-24 07:52:28,526 [salt.state       :1813][INFO    ][30944] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-04-24 07:52:28,526 [salt.loaded.int.module.cmdmod:395 ][INFO    ][30944] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-04-24 07:52:28,546 [salt.state       :300 ][INFO    ][30944] onlyif execution failed
2019-04-24 07:52:28,547 [salt.state       :1951][INFO    ][30944] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 07:52:28.547146 duration_in_ms=21.444
2019-04-24 07:52:28,549 [salt.state       :1780][INFO    ][30944] Running state [netfilter-persistent] at time 07:52:28.548919
2019-04-24 07:52:28,549 [salt.state       :1813][INFO    ][30944] Executing state service.running for [netfilter-persistent]
2019-04-24 07:52:28,550 [salt.loaded.int.module.cmdmod:395 ][INFO    ][30944] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-04-24 07:52:28,571 [salt.loaded.int.module.cmdmod:395 ][INFO    ][30944] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-04-24 07:52:28,589 [salt.loaded.int.module.cmdmod:395 ][INFO    ][30944] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-04-24 07:52:28,607 [salt.state       :300 ][INFO    ][30944] The service netfilter-persistent is already running
2019-04-24 07:52:28,608 [salt.state       :1951][INFO    ][30944] Completed state [netfilter-persistent] at time 07:52:28.608396 duration_in_ms=59.477
2019-04-24 07:52:28,609 [salt.state       :1780][INFO    ][30944] Running state [iptables_extra.remove_stale_tables] at time 07:52:28.609783
2019-04-24 07:52:28,610 [salt.state       :1813][INFO    ][30944] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-04-24 07:52:28,610 [salt.state       :300 ][INFO    ][30944] No changes made for iptables_extra.remove_stale_tables
2019-04-24 07:52:28,611 [salt.state       :1951][INFO    ][30944] Completed state [iptables_extra.remove_stale_tables] at time 07:52:28.611198 duration_in_ms=1.415
2019-04-24 07:52:28,611 [salt.state       :1780][INFO    ][30944] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 07:52:28.611595
2019-04-24 07:52:28,612 [salt.state       :1813][INFO    ][30944] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-04-24 07:52:28,613 [salt.loaded.int.module.cmdmod:395 ][INFO    ][30944] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-04-24 07:52:28,628 [salt.state       :300 ][INFO    ][30944] onlyif execution failed
2019-04-24 07:52:28,628 [salt.state       :1951][INFO    ][30944] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 07:52:28.628732 duration_in_ms=17.137
2019-04-24 07:52:28,630 [salt.state       :1780][INFO    ][30944] Running state [/etc/iptables/rules.v6] at time 07:52:28.630180
2019-04-24 07:52:28,630 [salt.state       :1813][INFO    ][30944] Executing state file.absent for [/etc/iptables/rules.v6]
2019-04-24 07:52:28,631 [salt.state       :300 ][INFO    ][30944] File /etc/iptables/rules.v6 is not present
2019-04-24 07:52:28,631 [salt.state       :1951][INFO    ][30944] Completed state [/etc/iptables/rules.v6] at time 07:52:28.631698 duration_in_ms=1.518
2019-04-24 07:52:28,632 [salt.state       :1780][INFO    ][30944] Running state [iptables_extra.flush_all] at time 07:52:28.632739
2019-04-24 07:52:28,633 [salt.state       :1813][INFO    ][30944] Executing state module.wait for [iptables_extra.flush_all]
2019-04-24 07:52:28,633 [salt.state       :300 ][INFO    ][30944] No changes made for iptables_extra.flush_all
2019-04-24 07:52:28,634 [salt.state       :1951][INFO    ][30944] Completed state [iptables_extra.flush_all] at time 07:52:28.633934 duration_in_ms=1.195
2019-04-24 07:52:28,638 [salt.minion      :1711][INFO    ][30944] Returning information for job: 20190424075220862660
2019-04-24 07:52:29,231 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command state.apply with jid 20190424075229218647
2019-04-24 07:52:29,256 [salt.minion      :1432][INFO    ][31036] Starting a new job with PID 31036
2019-04-24 07:52:30,026 [salt.state       :915 ][INFO    ][31036] Loading fresh modules for state activity
2019-04-24 07:52:30,689 [salt.state       :1780][INFO    ][31036] Running state [maas-rack-controller] at time 07:52:30.689800
2019-04-24 07:52:30,690 [salt.state       :1813][INFO    ][31036] Executing state pkg.installed for [maas-rack-controller]
2019-04-24 07:52:30,690 [salt.loaded.int.module.cmdmod:395 ][INFO    ][31036] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-24 07:52:30,784 [salt.state       :300 ][INFO    ][31036] All specified packages are already installed
2019-04-24 07:52:30,784 [salt.state       :1951][INFO    ][31036] Completed state [maas-rack-controller] at time 07:52:30.784461 duration_in_ms=94.661
2019-04-24 07:52:30,784 [salt.state       :1780][INFO    ][31036] Running state [ipmitool] at time 07:52:30.784855
2019-04-24 07:52:30,785 [salt.state       :1813][INFO    ][31036] Executing state pkg.installed for [ipmitool]
2019-04-24 07:52:30,792 [salt.state       :300 ][INFO    ][31036] All specified packages are already installed
2019-04-24 07:52:30,792 [salt.state       :1951][INFO    ][31036] Completed state [ipmitool] at time 07:52:30.792360 duration_in_ms=7.505
2019-04-24 07:52:30,795 [salt.state       :1780][INFO    ][31036] Running state [/etc/maas/rackd.conf] at time 07:52:30.795431
2019-04-24 07:52:30,795 [salt.state       :1813][INFO    ][31036] Executing state file.line for [/etc/maas/rackd.conf]
2019-04-24 07:52:30,796 [salt.state       :300 ][INFO    ][31036] No changes needed to be made
2019-04-24 07:52:30,797 [salt.state       :1951][INFO    ][31036] Completed state [/etc/maas/rackd.conf] at time 07:52:30.797023 duration_in_ms=1.592
2019-04-24 07:52:30,797 [salt.state       :1780][INFO    ][31036] Running state [/etc/maas/rackd.conf] at time 07:52:30.797259
2019-04-24 07:52:30,797 [salt.state       :1813][INFO    ][31036] Executing state file.managed for [/etc/maas/rackd.conf]
2019-04-24 07:52:30,797 [salt.loaded.int.states.file:2298][WARNING ][31036] 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-04-24 07:52:30,798 [salt.state       :300 ][INFO    ][31036] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-04-24 07:52:30,798 [salt.state       :1951][INFO    ][31036] Completed state [/etc/maas/rackd.conf] at time 07:52:30.798557 duration_in_ms=1.298
2019-04-24 07:52:30,799 [salt.state       :1780][INFO    ][31036] Running state [maas-rackd] at time 07:52:30.799537
2019-04-24 07:52:30,799 [salt.state       :1813][INFO    ][31036] Executing state service.running for [maas-rackd]
2019-04-24 07:52:30,800 [salt.loaded.int.module.cmdmod:395 ][INFO    ][31036] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-04-24 07:52:30,825 [salt.loaded.int.module.cmdmod:395 ][INFO    ][31036] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-04-24 07:52:30,838 [salt.loaded.int.module.cmdmod:395 ][INFO    ][31036] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-04-24 07:52:30,851 [salt.state       :300 ][INFO    ][31036] The service maas-rackd is already running
2019-04-24 07:52:30,852 [salt.state       :1951][INFO    ][31036] Completed state [maas-rackd] at time 07:52:30.852176 duration_in_ms=52.639
2019-04-24 07:52:30,853 [salt.minion      :1711][INFO    ][31036] Returning information for job: 20190424075229218647
2019-04-24 07:52:31,405 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command state.apply with jid 20190424075231393266
2019-04-24 07:52:31,431 [salt.minion      :1432][INFO    ][31089] Starting a new job with PID 31089
2019-04-24 07:52:32,174 [salt.state       :915 ][INFO    ][31089] Loading fresh modules for state activity
2019-04-24 07:52:32,910 [salt.state       :1780][INFO    ][31089] Running state [maas-region-controller] at time 07:52:32.910928
2019-04-24 07:52:32,911 [salt.state       :1813][INFO    ][31089] Executing state pkg.installed for [maas-region-controller]
2019-04-24 07:52:32,911 [salt.loaded.int.module.cmdmod:395 ][INFO    ][31089] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-24 07:52:32,978 [salt.state       :300 ][INFO    ][31089] All specified packages are already installed
2019-04-24 07:52:32,979 [salt.state       :1951][INFO    ][31089] Completed state [maas-region-controller] at time 07:52:32.979227 duration_in_ms=68.299
2019-04-24 07:52:32,979 [salt.state       :1780][INFO    ][31089] Running state [python-oauth] at time 07:52:32.979472
2019-04-24 07:52:32,979 [salt.state       :1813][INFO    ][31089] Executing state pkg.installed for [python-oauth]
2019-04-24 07:52:32,984 [salt.state       :300 ][INFO    ][31089] All specified packages are already installed
2019-04-24 07:52:32,984 [salt.state       :1951][INFO    ][31089] Completed state [python-oauth] at time 07:52:32.984564 duration_in_ms=5.092
2019-04-24 07:52:32,986 [salt.state       :1780][INFO    ][31089] Running state [/etc/maas/regiond.conf] at time 07:52:32.986887
2019-04-24 07:52:32,987 [salt.state       :1813][INFO    ][31089] Executing state file.replace for [/etc/maas/regiond.conf]
2019-04-24 07:52:33,027 [salt.state       :300 ][INFO    ][31089] No changes needed to be made
2019-04-24 07:52:33,027 [salt.state       :1951][INFO    ][31089] Completed state [/etc/maas/regiond.conf] at time 07:52:33.027615 duration_in_ms=40.728
2019-04-24 07:52:33,028 [salt.state       :1780][INFO    ][31089] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 07:52:33.027978
2019-04-24 07:52:33,028 [salt.state       :1813][INFO    ][31089] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-04-24 07:52:33,080 [salt.state       :300 ][INFO    ][31089] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-04-24 07:52:33,080 [salt.state       :1951][INFO    ][31089] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 07:52:33.080882 duration_in_ms=52.904
2019-04-24 07:52:33,081 [salt.state       :1780][INFO    ][31089] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 07:52:33.081241
2019-04-24 07:52:33,081 [salt.state       :1813][INFO    ][31089] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-04-24 07:52:33,093 [salt.state       :300 ][INFO    ][31089] No changes needed to be made
2019-04-24 07:52:33,094 [salt.state       :1951][INFO    ][31089] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 07:52:33.093962 duration_in_ms=12.721
2019-04-24 07:52:33,094 [salt.state       :1780][INFO    ][31089] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 07:52:33.094315
2019-04-24 07:52:33,094 [salt.state       :1813][INFO    ][31089] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-04-24 07:52:33,117 [salt.state       :300 ][INFO    ][31089] No changes needed to be made
2019-04-24 07:52:33,118 [salt.state       :1951][INFO    ][31089] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 07:52:33.118093 duration_in_ms=23.778
2019-04-24 07:52:33,118 [salt.state       :1780][INFO    ][31089] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 07:52:33.118476
2019-04-24 07:52:33,118 [salt.state       :1813][INFO    ][31089] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-04-24 07:52:33,137 [salt.state       :300 ][INFO    ][31089] No changes needed to be made
2019-04-24 07:52:33,137 [salt.state       :1951][INFO    ][31089] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 07:52:33.137536 duration_in_ms=19.06
2019-04-24 07:52:33,137 [salt.state       :1780][INFO    ][31089] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 07:52:33.137900
2019-04-24 07:52:33,138 [salt.state       :1813][INFO    ][31089] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-04-24 07:52:33,148 [salt.state       :300 ][INFO    ][31089] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-04-24 07:52:33,148 [salt.state       :1951][INFO    ][31089] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 07:52:33.148598 duration_in_ms=10.699
2019-04-24 07:52:33,149 [salt.state       :1780][INFO    ][31089] Running state [a2enmod headers] at time 07:52:33.149622
2019-04-24 07:52:33,149 [salt.state       :1813][INFO    ][31089] Executing state cmd.run for [a2enmod headers]
2019-04-24 07:52:33,150 [salt.loaded.int.module.cmdmod:395 ][INFO    ][31089] Executing command 'a2enmod headers' in directory '/root'
2019-04-24 07:52:33,214 [salt.state       :300 ][INFO    ][31089] {'pid': 31108, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-04-24 07:52:33,214 [salt.state       :1951][INFO    ][31089] Completed state [a2enmod headers] at time 07:52:33.214724 duration_in_ms=65.101
2019-04-24 07:52:33,215 [salt.state       :1780][INFO    ][31089] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 07:52:33.215305
2019-04-24 07:52:33,215 [salt.state       :1813][INFO    ][31089] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-04-24 07:52:33,242 [salt.state       :300 ][INFO    ][31089] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-04-24 07:52:33,243 [salt.state       :1951][INFO    ][31089] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 07:52:33.243089 duration_in_ms=27.77
2019-04-24 07:52:33,244 [salt.state       :1780][INFO    ][31089] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 07:52:33.244356
2019-04-24 07:52:33,244 [salt.state       :1813][INFO    ][31089] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-04-24 07:52:33,326 [salt.state       :300 ][INFO    ][31089] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-04-24 07:52:33,327 [salt.state       :1951][INFO    ][31089] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 07:52:33.327162 duration_in_ms=82.806
2019-04-24 07:52:33,327 [salt.state       :1780][INFO    ][31089] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 07:52:33.327767
2019-04-24 07:52:33,328 [salt.state       :1813][INFO    ][31089] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-04-24 07:52:33,392 [salt.state       :300 ][INFO    ][31089] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-04-24 07:52:33,392 [salt.state       :1951][INFO    ][31089] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 07:52:33.392865 duration_in_ms=65.098
2019-04-24 07:52:33,393 [salt.state       :1780][INFO    ][31089] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 07:52:33.393451
2019-04-24 07:52:33,393 [salt.state       :1813][INFO    ][31089] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-04-24 07:52:33,458 [salt.state       :300 ][INFO    ][31089] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-04-24 07:52:33,458 [salt.state       :1951][INFO    ][31089] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 07:52:33.458682 duration_in_ms=65.231
2019-04-24 07:52:33,459 [salt.state       :1780][INFO    ][31089] Running state [/root/.pgpass] at time 07:52:33.458990
2019-04-24 07:52:33,459 [salt.state       :1813][INFO    ][31089] Executing state file.managed for [/root/.pgpass]
2019-04-24 07:52:33,511 [salt.state       :300 ][INFO    ][31089] File /root/.pgpass is in the correct state
2019-04-24 07:52:33,512 [salt.state       :1951][INFO    ][31089] Completed state [/root/.pgpass] at time 07:52:33.512177 duration_in_ms=53.187
2019-04-24 07:52:33,517 [salt.state       :1780][INFO    ][31089] Running state [maas-region syncdb --noinput] at time 07:52:33.517779
2019-04-24 07:52:33,518 [salt.state       :1813][INFO    ][31089] Executing state cmd.run for [maas-region syncdb --noinput]
2019-04-24 07:52:33,518 [salt.loaded.int.module.cmdmod:395 ][INFO    ][31089] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-04-24 07:52:35,537 [salt.state       :300 ][INFO    ][31089] {'pid': 31121, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: piston3, maasserver, metadataserver, sessions, auth, 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-04-24 07:52:35,538 [salt.state       :1951][INFO    ][31089] Completed state [maas-region syncdb --noinput] at time 07:52:35.538077 duration_in_ms=2020.298
2019-04-24 07:52:35,538 [salt.state       :2022][WARNING ][31089] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-04-24 07:52:35,541 [salt.state       :1780][INFO    ][31089] Running state [maas-regiond] at time 07:52:35.541097
2019-04-24 07:52:35,541 [salt.state       :1813][INFO    ][31089] Executing state service.running for [maas-regiond]
2019-04-24 07:52:35,542 [salt.loaded.int.module.cmdmod:395 ][INFO    ][31089] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-04-24 07:52:35,583 [salt.loaded.int.module.cmdmod:395 ][INFO    ][31089] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-04-24 07:52:35,603 [salt.loaded.int.module.cmdmod:395 ][INFO    ][31089] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-04-24 07:52:35,623 [salt.state       :300 ][INFO    ][31089] The service maas-regiond is already running
2019-04-24 07:52:35,624 [salt.state       :1951][INFO    ][31089] Completed state [maas-regiond] at time 07:52:35.624106 duration_in_ms=83.009
2019-04-24 07:52:35,626 [salt.state       :1780][INFO    ][31089] Running state [bind9] at time 07:52:35.626829
2019-04-24 07:52:35,627 [salt.state       :1813][INFO    ][31089] Executing state service.running for [bind9]
2019-04-24 07:52:35,628 [salt.loaded.int.module.cmdmod:395 ][INFO    ][31089] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-04-24 07:52:35,651 [salt.loaded.int.module.cmdmod:395 ][INFO    ][31089] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-04-24 07:52:35,669 [salt.loaded.int.module.cmdmod:395 ][INFO    ][31089] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-04-24 07:52:35,687 [salt.state       :300 ][INFO    ][31089] The service bind9 is already running
2019-04-24 07:52:35,687 [salt.state       :1951][INFO    ][31089] Completed state [bind9] at time 07:52:35.687453 duration_in_ms=60.624
2019-04-24 07:52:35,689 [salt.state       :1780][INFO    ][31089] Running state [apache2] at time 07:52:35.689786
2019-04-24 07:52:35,690 [salt.state       :1813][INFO    ][31089] Executing state service.running for [apache2]
2019-04-24 07:52:35,691 [salt.loaded.int.module.cmdmod:395 ][INFO    ][31089] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-04-24 07:52:35,710 [salt.loaded.int.module.cmdmod:395 ][INFO    ][31089] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-04-24 07:52:35,727 [salt.loaded.int.module.cmdmod:395 ][INFO    ][31089] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-04-24 07:52:35,748 [salt.state       :300 ][INFO    ][31089] The service apache2 is already running
2019-04-24 07:52:35,749 [salt.state       :1951][INFO    ][31089] Completed state [apache2] at time 07:52:35.749205 duration_in_ms=59.419
2019-04-24 07:52:35,751 [salt.state       :1780][INFO    ][31089] Running state [maasng.wait_for_http_code] at time 07:52:35.750936
2019-04-24 07:52:35,751 [salt.state       :1813][INFO    ][31089] Executing state module.run for [maasng.wait_for_http_code]
2019-04-24 07:52:35,751 [salt.utils.decorators:613 ][WARNING ][31089] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 07:52:35,874 [salt.state       :300 ][INFO    ][31089] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-04-24 07:52:35,875 [salt.state       :1951][INFO    ][31089] Completed state [maasng.wait_for_http_code] at time 07:52:35.874913 duration_in_ms=123.977
2019-04-24 07:52:35,876 [salt.state       :1780][INFO    ][31089] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 07:52:35.876311
2019-04-24 07:52:35,876 [salt.state       :1813][INFO    ][31089] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-04-24 07:52:35,877 [salt.state       :300 ][INFO    ][31089] /var/lib/maas/.setup_admin exists
2019-04-24 07:52:35,878 [salt.state       :1951][INFO    ][31089] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 07:52:35.878002 duration_in_ms=1.691
2019-04-24 07:52:35,879 [salt.state       :1780][INFO    ][31089] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:52:35.879148
2019-04-24 07:52:35,879 [salt.state       :1813][INFO    ][31089] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-24 07:52:35,880 [salt.loaded.int.module.cmdmod:395 ][INFO    ][31089] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-24 07:52:37,337 [salt.state       :300 ][INFO    ][31089] {'pid': 31142, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-24 07:52:37,337 [salt.state       :1951][INFO    ][31089] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:52:37.337858 duration_in_ms=1458.709
2019-04-24 07:52:37,348 [salt.state       :1780][INFO    ][31089] Running state [maas_region_boot_source_resources_mirror] at time 07:52:37.348301
2019-04-24 07:52:37,348 [salt.state       :1813][INFO    ][31089] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-04-24 07:52:37,457 [salt.state       :300 ][INFO    ][31089] {'changes': {}}
2019-04-24 07:52:37,458 [salt.state       :1951][INFO    ][31089] Completed state [maas_region_boot_source_resources_mirror] at time 07:52:37.458032 duration_in_ms=109.73
2019-04-24 07:52:37,459 [salt.state       :1780][INFO    ][31089] Running state [maasng.boot_resources_import] at time 07:52:37.459231
2019-04-24 07:52:37,459 [salt.state       :1813][INFO    ][31089] Executing state module.run for [maasng.boot_resources_import]
2019-04-24 07:52:37,460 [salt.utils.decorators:613 ][WARNING ][31089] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 07:52:37,566 [salt.loaded.ext.module.maasng:1600][INFO    ][31089] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-04-24 07:52:42,627 [salt.loaded.ext.module.maasng:1600][INFO    ][31089] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-04-24 07:52:46,446 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424075246434117
2019-04-24 07:52:46,474 [salt.minion      :1432][INFO    ][31171] Starting a new job with PID 31171
2019-04-24 07:52:46,496 [salt.minion      :1711][INFO    ][31171] Returning information for job: 20190424075246434117
2019-04-24 07:52:47,687 [salt.loaded.ext.module.maasng:1600][INFO    ][31089] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-04-24 07:52:52,804 [salt.state       :300 ][INFO    ][31089] {'ret': True}
2019-04-24 07:52:52,804 [salt.state       :1951][INFO    ][31089] Completed state [maasng.boot_resources_import] at time 07:52:52.804585 duration_in_ms=15345.354
2019-04-24 07:52:52,805 [salt.state       :1780][INFO    ][31089] Running state [maas_region_boot_sources_selection_xenial] at time 07:52:52.805712
2019-04-24 07:52:52,806 [salt.state       :1813][INFO    ][31089] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-04-24 07:52:53,013 [salt.state       :300 ][INFO    ][31089] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-04-24 07:52:53,014 [salt.state       :1951][INFO    ][31089] Completed state [maas_region_boot_sources_selection_xenial] at time 07:52:53.013893 duration_in_ms=208.181
2019-04-24 07:52:53,015 [salt.state       :1780][INFO    ][31089] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 07:52:53.015350
2019-04-24 07:52:53,015 [salt.state       :1813][INFO    ][31089] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-04-24 07:52:53,016 [salt.utils.decorators:613 ][WARNING ][31089] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 07:52:53,017 [salt.loaded.ext.module.maasng:1771][INFO    ][31089] boot-sources sync initiated for ALL Rack's
2019-04-24 07:52:53,797 [salt.state       :300 ][INFO    ][31089] {'ret': True}
2019-04-24 07:52:53,798 [salt.state       :1951][INFO    ][31089] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 07:52:53.797955 duration_in_ms=782.605
2019-04-24 07:52:53,800 [salt.state       :1780][INFO    ][31089] Running state [maas.process_maas_config] at time 07:52:53.800129
2019-04-24 07:52:53,800 [salt.state       :1813][INFO    ][31089] Executing state module.run for [maas.process_maas_config]
2019-04-24 07:52:53,801 [salt.utils.decorators:613 ][WARNING ][31089] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 07:52:53,802 [salt.loaded.ext.module.maas:92  ][INFO    ][31089] maasconfig name=enable_http_proxy value=True
2019-04-24 07:52:53,858 [salt.loaded.ext.module.maas:92  ][INFO    ][31089] maasconfig name=upstream_dns value=8.8.8.8
2019-04-24 07:52:53,929 [salt.loaded.ext.module.maas:92  ][INFO    ][31089] maasconfig name=commissioning_distro_series value=xenial
2019-04-24 07:52:56,861 [salt.loaded.ext.module.maas:92  ][INFO    ][31089] maasconfig name=default_osystem value=ubuntu
2019-04-24 07:52:56,946 [salt.loaded.ext.module.maas:92  ][INFO    ][31089] maasconfig name=active_discovery_interval value=600
2019-04-24 07:52:57,001 [salt.loaded.ext.module.maas:92  ][INFO    ][31089] maasconfig name=dnssec_validation value=no
2019-04-24 07:52:57,034 [salt.loaded.ext.module.maas:92  ][INFO    ][31089] maasconfig name=maas_name value=mas01
2019-04-24 07:52:57,070 [salt.loaded.ext.module.maas:92  ][INFO    ][31089] maasconfig name=network_discovery value=enabled
2019-04-24 07:52:57,172 [salt.loaded.ext.module.maas:92  ][INFO    ][31089] maasconfig name=enable_third_party_drivers value=True
2019-04-24 07:52:57,220 [salt.loaded.ext.module.maas:92  ][INFO    ][31089] maasconfig name=default_storage_layout value=lvm
2019-04-24 07:52:57,258 [salt.loaded.ext.module.maas:92  ][INFO    ][31089] maasconfig name=ntp_external_only value=True
2019-04-24 07:52:57,301 [salt.loaded.ext.module.maas:92  ][INFO    ][31089] maasconfig name=disk_erase_with_secure_erase value=False
2019-04-24 07:52:57,337 [salt.loaded.ext.module.maas:92  ][INFO    ][31089] maasconfig name=default_distro_series value=xenial
2019-04-24 07:52:57,384 [salt.loaded.ext.module.maas:92  ][INFO    ][31089] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-04-24 07:52:57,476 [salt.state       :300 ][INFO    ][31089] {'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-04-24 07:52:57,476 [salt.state       :1951][INFO    ][31089] Completed state [maas.process_maas_config] at time 07:52:57.476764 duration_in_ms=3676.634
2019-04-24 07:52:57,477 [salt.state       :1780][INFO    ][31089] Running state [pxe_admin] at time 07:52:57.477699
2019-04-24 07:52:57,478 [salt.state       :1813][INFO    ][31089] Executing state maasng.fabric_present for [pxe_admin]
2019-04-24 07:52:57,553 [salt.loaded.ext.module.maasng:945 ][INFO    ][31089] [{u'id': 0, 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'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'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 1, 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'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'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'id': 2, 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'a38yr8', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-04-24 07:52:57,627 [salt.loaded.ext.module.maasng:1008][WARNING ][31089] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-04-24 07:52:57,628 [salt.loaded.ext.module.maasng:1011][WARNING ][31089] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-04-24 07:52:57,712 [salt.state       :300 ][INFO    ][31089] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-04-24 07:52:57,712 [salt.state       :1951][INFO    ][31089] Completed state [pxe_admin] at time 07:52:57.712752 duration_in_ms=235.052
2019-04-24 07:52:57,713 [salt.state       :1780][INFO    ][31089] Running state [vlan 0] at time 07:52:57.713216
2019-04-24 07:52:57,713 [salt.state       :1813][INFO    ][31089] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-04-24 07:52:57,795 [salt.loaded.ext.module.maasng:945 ][INFO    ][31089] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None, u'name': u'fabric-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'primary_rack': u'a38yr8', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u'', u'name': u'pxe_admin'}]
2019-04-24 07:52:57,921 [salt.loaded.ext.module.maasng:945 ][INFO    ][31089] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None, u'name': u'fabric-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'primary_rack': u'a38yr8', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u'', u'name': u'pxe_admin'}]
2019-04-24 07:52:58,211 [salt.loaded.ext.module.maasng:945 ][INFO    ][31089] [{u'id': 0, 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'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'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 1, 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'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'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'id': 2, 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'a38yr8', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-04-24 07:52:58,301 [salt.state       :300 ][INFO    ][31089] {'new': 'Vlan untagged was updated'}
2019-04-24 07:52:58,302 [salt.state       :1951][INFO    ][31089] Completed state [vlan 0] at time 07:52:58.301997 duration_in_ms=588.781
2019-04-24 07:52:58,303 [salt.state       :1780][INFO    ][31089] Running state [192.168.11.0/24] at time 07:52:58.303395
2019-04-24 07:52:58,303 [salt.state       :1813][INFO    ][31089] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-04-24 07:52:58,517 [salt.loaded.ext.module.maasng:945 ][INFO    ][31089] [{u'id': 0, 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'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'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 1, 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'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'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'id': 2, 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'a38yr8', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-04-24 07:52:58,518 [salt.loaded.ext.module.maasng:1235][WARNING ][31089] Ignoring parameter vlan:0
2019-04-24 07:52:58,621 [salt.state       :300 ][INFO    ][31089] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-04-24 07:52:58,622 [salt.state       :1951][INFO    ][31089] Completed state [192.168.11.0/24] at time 07:52:58.621951 duration_in_ms=318.555
2019-04-24 07:52:58,623 [salt.state       :1780][INFO    ][31089] Running state [maas_create_iprange_1] at time 07:52:58.623185
2019-04-24 07:52:58,623 [salt.state       :1813][INFO    ][31089] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-04-24 07:52:58,682 [salt.state       :300 ][INFO    ][31089] Iprange maas_create_iprange_1 already exist.
2019-04-24 07:52:58,682 [salt.state       :1951][INFO    ][31089] Completed state [maas_create_iprange_1] at time 07:52:58.682629 duration_in_ms=59.442
2019-04-24 07:52:58,683 [salt.state       :1780][INFO    ][31089] Running state [vlan 0] at time 07:52:58.683117
2019-04-24 07:52:58,683 [salt.state       :1813][INFO    ][31089] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-04-24 07:52:58,741 [salt.loaded.ext.module.maasng:945 ][INFO    ][31089] [{u'id': 0, 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'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'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 1, 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'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'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'id': 2, 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'a38yr8', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-04-24 07:52:58,868 [salt.loaded.ext.module.maasng:945 ][INFO    ][31089] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None, u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'a38yr8', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u'', u'name': u'pxe_admin'}]
2019-04-24 07:52:59,058 [salt.loaded.ext.module.maasng:945 ][INFO    ][31089] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'a38yr8', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-04-24 07:52:59,118 [salt.state       :300 ][INFO    ][31089] {'new': 'Vlan untagged was updated'}
2019-04-24 07:52:59,118 [salt.state       :1951][INFO    ][31089] Completed state [vlan 0] at time 07:52:59.118941 duration_in_ms=435.824
2019-04-24 07:52:59,119 [salt.state       :1780][INFO    ][31089] Running state [opnfv] at time 07:52:59.119456
2019-04-24 07:52:59,119 [salt.state       :1813][INFO    ][31089] Executing state maasng.sshkey_present for [opnfv]
2019-04-24 07:52:59,172 [salt.loaded.ext.module.maasng:1903][INFO    ][31089] [{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-04-24 07:52:59,173 [salt.state       :300 ][INFO    ][31089] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-04-24 07:52:59,173 [salt.state       :1951][INFO    ][31089] Completed state [opnfv] at time 07:52:59.173418 duration_in_ms=53.961
2019-04-24 07:52:59,174 [salt.state       :1780][INFO    ][31089] Running state [maas.process_tags] at time 07:52:59.174281
2019-04-24 07:52:59,174 [salt.state       :1813][INFO    ][31089] Executing state module.run for [maas.process_tags]
2019-04-24 07:52:59,175 [salt.utils.decorators:613 ][WARNING ][31089] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 07:52:59,226 [salt.loaded.ext.module.maas:92  ][INFO    ][31089] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-04-24 07:52:59,300 [salt.state       :300 ][INFO    ][31089] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-04-24 07:52:59,301 [salt.state       :1951][INFO    ][31089] Completed state [maas.process_tags] at time 07:52:59.301215 duration_in_ms=126.934
2019-04-24 07:52:59,304 [salt.minion      :1711][INFO    ][31089] Returning information for job: 20190424075231393266
2019-04-24 07:52:59,872 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command state.apply with jid 20190424075259861175
2019-04-24 07:52:59,896 [salt.minion      :1432][INFO    ][31531] Starting a new job with PID 31531
2019-04-24 07:53:03,802 [salt.state       :915 ][INFO    ][31531] Loading fresh modules for state activity
2019-04-24 07:53:03,893 [salt.state       :1780][INFO    ][31531] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:53:03.893180
2019-04-24 07:53:03,893 [salt.state       :1813][INFO    ][31531] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-24 07:53:03,895 [salt.loaded.int.module.cmdmod:395 ][INFO    ][31531] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-24 07:53:05,283 [salt.state       :300 ][INFO    ][31531] {'pid': 31555, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-24 07:53:05,284 [salt.state       :1951][INFO    ][31531] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:53:05.284061 duration_in_ms=1390.881
2019-04-24 07:53:05,285 [salt.state       :1780][INFO    ][31531] Running state [maas.process_machines] at time 07:53:05.285217
2019-04-24 07:53:05,285 [salt.state       :1813][INFO    ][31531] Executing state module.run for [maas.process_machines]
2019-04-24 07:53:05,286 [salt.utils.decorators:613 ][WARNING ][31531] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 07:53:06,022 [salt.loaded.ext.module.maas:412 ][WARNING ][31531] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-24 07:53:06,023 [salt.loaded.ext.module.maas:92  ][INFO    ][31531] 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=pxbhtq architecture=amd64/generic power_parameters_power_user=admin
2019-04-24 07:53:07,209 [salt.loaded.ext.module.maas:412 ][WARNING ][31531] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-24 07:53:07,210 [salt.loaded.ext.module.maas:92  ][INFO    ][31531] 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=d8h4qq architecture=amd64/generic power_parameters_power_user=admin
2019-04-24 07:53:08,269 [salt.loaded.ext.module.maas:412 ][WARNING ][31531] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-24 07:53:08,269 [salt.loaded.ext.module.maas:92  ][INFO    ][31531] 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=try6q3 architecture=amd64/generic power_parameters_power_user=admin
2019-04-24 07:53:09,449 [salt.loaded.ext.module.maas:412 ][WARNING ][31531] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-24 07:53:09,450 [salt.loaded.ext.module.maas:92  ][INFO    ][31531] 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=efhw3h architecture=amd64/generic power_parameters_power_user=admin
2019-04-24 07:53:10,556 [salt.loaded.ext.module.maas:412 ][WARNING ][31531] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-24 07:53:10,557 [salt.loaded.ext.module.maas:92  ][INFO    ][31531] 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=qnnnt3 architecture=amd64/generic power_parameters_power_user=admin
2019-04-24 07:53:11,648 [salt.state       :300 ][INFO    ][31531] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-04-24 07:53:11,648 [salt.state       :1951][INFO    ][31531] Completed state [maas.process_machines] at time 07:53:11.648797 duration_in_ms=6363.576
2019-04-24 07:53:11,652 [salt.minion      :1711][INFO    ][31531] Returning information for job: 20190424075259861175
2019-04-24 07:53:44,875 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command state.apply with jid 20190424075344863196
2019-04-24 07:53:44,901 [salt.minion      :1432][INFO    ][31826] Starting a new job with PID 31826
2019-04-24 07:53:48,604 [salt.state       :915 ][INFO    ][31826] Loading fresh modules for state activity
2019-04-24 07:53:48,694 [salt.state       :1780][INFO    ][31826] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:53:48.694882
2019-04-24 07:53:48,695 [salt.state       :1813][INFO    ][31826] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-24 07:53:48,697 [salt.loaded.int.module.cmdmod:395 ][INFO    ][31826] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-24 07:53:50,100 [salt.state       :300 ][INFO    ][31826] {'pid': 31833, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-24 07:53:50,100 [salt.state       :1951][INFO    ][31826] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:53:50.100498 duration_in_ms=1405.616
2019-04-24 07:53:50,102 [salt.state       :1780][INFO    ][31826] Running state [maas.wait_for_machine_status] at time 07:53:50.101988
2019-04-24 07:53:50,102 [salt.state       :1813][INFO    ][31826] Executing state module.run for [maas.wait_for_machine_status]
2019-04-24 07:53:50,102 [salt.utils.decorators:613 ][WARNING ][31826] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 07:53:53,639 [salt.loaded.ext.module.maas:993 ][INFO    ][31826] Machine qnnnt3 mark broken
2019-04-24 07:53:54,268 [salt.loaded.ext.module.maas:996 ][INFO    ][31826] Machine qnnnt3 mark fixed
2019-04-24 07:53:55,363 [salt.loaded.ext.module.maas:684 ][INFO    ][31826] deploymachines hwe_kernel=hwe-16.04 system_id=qnnnt3 distro_series=xenial
2019-04-24 07:53:57,891 [salt.loaded.ext.module.maas:1023][INFO    ][31826] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1492.21473384s left)
2019-04-24 07:54:00,000 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424075359987296
2019-04-24 07:54:00,025 [salt.minion      :1432][INFO    ][31905] Starting a new job with PID 31905
2019-04-24 07:54:00,042 [salt.minion      :1711][INFO    ][31905] Returning information for job: 20190424075359987296
2019-04-24 07:54:30,232 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424075430176375
2019-04-24 07:54:30,255 [salt.minion      :1432][INFO    ][31944] Starting a new job with PID 31944
2019-04-24 07:54:30,274 [salt.minion      :1711][INFO    ][31944] Returning information for job: 20190424075430176375
2019-04-24 07:54:31,359 [salt.loaded.ext.module.maas:1023][INFO    ][31826] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1458.74707294s left)
2019-04-24 07:55:00,298 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424075500285127
2019-04-24 07:55:00,325 [salt.minion      :1432][INFO    ][31985] Starting a new job with PID 31985
2019-04-24 07:55:00,350 [salt.minion      :1711][INFO    ][31985] Returning information for job: 20190424075500285127
2019-04-24 07:55:04,508 [salt.loaded.ext.module.maas:1023][INFO    ][31826] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1425.59860802s left)
2019-04-24 07:55:30,353 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424075530339176
2019-04-24 07:55:30,384 [salt.minion      :1432][INFO    ][32030] Starting a new job with PID 32030
2019-04-24 07:55:30,410 [salt.minion      :1711][INFO    ][32030] Returning information for job: 20190424075530339176
2019-04-24 07:55:37,003 [salt.loaded.ext.module.maas:1023][INFO    ][31826] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1393.10323596s left)
2019-04-24 07:56:00,427 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424075600415121
2019-04-24 07:56:00,453 [salt.minion      :1432][INFO    ][32134] Starting a new job with PID 32134
2019-04-24 07:56:00,477 [salt.minion      :1711][INFO    ][32134] Returning information for job: 20190424075600415121
2019-04-24 07:56:09,868 [salt.loaded.ext.module.maas:1023][INFO    ][31826] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1360.23806095s left)
2019-04-24 07:56:30,477 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424075630469420
2019-04-24 07:56:30,500 [salt.minion      :1432][INFO    ][32208] Starting a new job with PID 32208
2019-04-24 07:56:30,516 [salt.minion      :1711][INFO    ][32208] Returning information for job: 20190424075630469420
2019-04-24 07:56:43,115 [salt.loaded.ext.module.maas:1023][INFO    ][31826] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1326.99116087s left)
2019-04-24 07:57:00,523 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424075700510610
2019-04-24 07:57:00,550 [salt.minion      :1432][INFO    ][32348] Starting a new job with PID 32348
2019-04-24 07:57:00,573 [salt.minion      :1711][INFO    ][32348] Returning information for job: 20190424075700510610
2019-04-24 07:57:16,022 [salt.loaded.ext.module.maas:1023][INFO    ][31826] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1294.08410883s left)
2019-04-24 07:57:30,578 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424075730570536
2019-04-24 07:57:30,600 [salt.minion      :1432][INFO    ][32421] Starting a new job with PID 32421
2019-04-24 07:57:30,620 [salt.minion      :1711][INFO    ][32421] Returning information for job: 20190424075730570536
2019-04-24 07:57:49,645 [salt.loaded.ext.module.maas:1023][INFO    ][31826] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1260.46102881s left)
2019-04-24 07:58:00,634 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424075800621779
2019-04-24 07:58:00,660 [salt.minion      :1432][INFO    ][32467] Starting a new job with PID 32467
2019-04-24 07:58:00,683 [salt.minion      :1711][INFO    ][32467] Returning information for job: 20190424075800621779
2019-04-24 07:58:23,315 [salt.loaded.ext.module.maas:1023][INFO    ][31826] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1226.7913878s left)
2019-04-24 07:58:30,695 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424075830686619
2019-04-24 07:58:30,714 [salt.minion      :1432][INFO    ][32566] Starting a new job with PID 32566
2019-04-24 07:58:30,730 [salt.minion      :1711][INFO    ][32566] Returning information for job: 20190424075830686619
2019-04-24 07:58:57,025 [salt.loaded.ext.module.maas:1023][INFO    ][31826] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1193.08135486s left)
2019-04-24 07:59:00,748 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424075900736749
2019-04-24 07:59:00,776 [salt.minion      :1432][INFO    ][32664] Starting a new job with PID 32664
2019-04-24 07:59:00,802 [salt.minion      :1711][INFO    ][32664] Returning information for job: 20190424075900736749
2019-04-24 07:59:30,689 [salt.loaded.ext.module.maas:1023][INFO    ][31826] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1159.41676879s left)
2019-04-24 07:59:30,821 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424075930814472
2019-04-24 07:59:30,838 [salt.minion      :1432][INFO    ][305] Starting a new job with PID 305
2019-04-24 07:59:30,852 [salt.minion      :1711][INFO    ][305] Returning information for job: 20190424075930814472
2019-04-24 08:00:00,881 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424080000869222
2019-04-24 08:00:00,907 [salt.minion      :1432][INFO    ][342] Starting a new job with PID 342
2019-04-24 08:00:00,929 [salt.minion      :1711][INFO    ][342] Returning information for job: 20190424080000869222
2019-04-24 08:00:04,005 [salt.loaded.ext.module.maas:1023][INFO    ][31826] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1126.10086989s left)
2019-04-24 08:00:30,973 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424080030959168
2019-04-24 08:00:31,003 [salt.minion      :1432][INFO    ][397] Starting a new job with PID 397
2019-04-24 08:00:31,031 [salt.minion      :1711][INFO    ][397] Returning information for job: 20190424080030959168
2019-04-24 08:00:37,754 [salt.loaded.ext.module.maas:1023][INFO    ][31826] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1092.3518548s left)
2019-04-24 08:01:01,079 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424080101067610
2019-04-24 08:01:01,107 [salt.minion      :1432][INFO    ][442] Starting a new job with PID 442
2019-04-24 08:01:01,135 [salt.minion      :1711][INFO    ][442] Returning information for job: 20190424080101067610
2019-04-24 08:01:11,199 [salt.loaded.ext.module.maas:1023][INFO    ][31826] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1058.90705395s left)
2019-04-24 08:01:31,219 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424080131207755
2019-04-24 08:01:31,244 [salt.minion      :1432][INFO    ][585] Starting a new job with PID 585
2019-04-24 08:01:31,279 [salt.minion      :1711][INFO    ][585] Returning information for job: 20190424080131207755
2019-04-24 08:01:44,363 [salt.state       :300 ][INFO    ][31826] {'ret': True}
2019-04-24 08:01:44,363 [salt.state       :1951][INFO    ][31826] Completed state [maas.wait_for_machine_status] at time 08:01:44.363530 duration_in_ms=474261.538
2019-04-24 08:01:44,367 [salt.minion      :1711][INFO    ][31826] Returning information for job: 20190424075344863196
2019-04-24 08:01:45,000 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command state.apply with jid 20190424080144991967
2019-04-24 08:01:45,025 [salt.minion      :1432][INFO    ][621] Starting a new job with PID 621
2019-04-24 08:01:48,723 [salt.state       :915 ][INFO    ][621] Loading fresh modules for state activity
2019-04-24 08:01:48,803 [salt.state       :1780][INFO    ][621] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:01:48.803267
2019-04-24 08:01:48,803 [salt.state       :1813][INFO    ][621] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-24 08:01:48,804 [salt.loaded.int.module.cmdmod:395 ][INFO    ][621] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-24 08:01:50,250 [salt.state       :300 ][INFO    ][621] {'pid': 629, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-24 08:01:50,251 [salt.state       :1951][INFO    ][621] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:01:50.251161 duration_in_ms=1447.895
2019-04-24 08:01:50,252 [salt.state       :1780][INFO    ][621] Running state [maas_machines_storage_cmp002_lvm] at time 08:01:50.252529
2019-04-24 08:01:50,252 [salt.state       :1813][INFO    ][621] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-04-24 08:01:50,869 [salt.state       :300 ][INFO    ][621] Machine cmp002 is not in Ready state.
2019-04-24 08:01:50,869 [salt.state       :1951][INFO    ][621] Completed state [maas_machines_storage_cmp002_lvm] at time 08:01:50.869726 duration_in_ms=617.195
2019-04-24 08:01:50,870 [salt.state       :1780][INFO    ][621] Running state [maas_machines_storage_cmp001_lvm] at time 08:01:50.870282
2019-04-24 08:01:50,870 [salt.state       :1813][INFO    ][621] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-04-24 08:01:51,668 [salt.state       :300 ][INFO    ][621] Machine cmp001 is not in Ready state.
2019-04-24 08:01:51,669 [salt.state       :1951][INFO    ][621] Completed state [maas_machines_storage_cmp001_lvm] at time 08:01:51.668957 duration_in_ms=798.675
2019-04-24 08:01:51,673 [salt.minion      :1711][INFO    ][621] Returning information for job: 20190424080144991967
2019-04-24 08:01:52,298 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command state.apply with jid 20190424080152285443
2019-04-24 08:01:52,326 [salt.minion      :1432][INFO    ][645] Starting a new job with PID 645
2019-04-24 08:01:53,073 [salt.state       :915 ][INFO    ][645] Loading fresh modules for state activity
2019-04-24 08:01:53,163 [salt.state       :1780][INFO    ][645] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:01:53.163374
2019-04-24 08:01:53,163 [salt.state       :1813][INFO    ][645] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-24 08:01:53,166 [salt.loaded.int.module.cmdmod:395 ][INFO    ][645] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-24 08:01:54,578 [salt.state       :300 ][INFO    ][645] {'pid': 652, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-24 08:01:54,579 [salt.state       :1951][INFO    ][645] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:01:54.579083 duration_in_ms=1415.71
2019-04-24 08:01:54,580 [salt.state       :1780][INFO    ][645] Running state [maas.deploy_machines] at time 08:01:54.580255
2019-04-24 08:01:54,580 [salt.state       :1813][INFO    ][645] Executing state module.run for [maas.deploy_machines]
2019-04-24 08:01:54,581 [salt.utils.decorators:613 ][WARNING ][645] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 08:01:55,335 [salt.state       :300 ][INFO    ][645] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-04-24 08:01:55,335 [salt.state       :1951][INFO    ][645] Completed state [maas.deploy_machines] at time 08:01:55.335692 duration_in_ms=755.435
2019-04-24 08:01:55,339 [salt.minion      :1711][INFO    ][645] Returning information for job: 20190424080152285443
2019-04-24 08:01:55,973 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command state.apply with jid 20190424080155961385
2019-04-24 08:01:55,999 [salt.minion      :1432][INFO    ][670] Starting a new job with PID 670
2019-04-24 08:01:56,739 [salt.state       :915 ][INFO    ][670] Loading fresh modules for state activity
2019-04-24 08:01:56,828 [salt.state       :1780][INFO    ][670] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:01:56.828887
2019-04-24 08:01:56,829 [salt.state       :1813][INFO    ][670] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-24 08:01:56,831 [salt.loaded.int.module.cmdmod:395 ][INFO    ][670] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-24 08:01:58,215 [salt.state       :300 ][INFO    ][670] {'pid': 678, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-24 08:01:58,215 [salt.state       :1951][INFO    ][670] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:01:58.215598 duration_in_ms=1386.711
2019-04-24 08:01:58,217 [salt.state       :1780][INFO    ][670] Running state [maas.wait_for_machine_status] at time 08:01:58.217160
2019-04-24 08:01:58,217 [salt.state       :1813][INFO    ][670] Executing state module.run for [maas.wait_for_machine_status]
2019-04-24 08:01:58,217 [salt.utils.decorators:613 ][WARNING ][670] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 08:02:01,735 [salt.state       :300 ][INFO    ][670] {'ret': True}
2019-04-24 08:02:01,736 [salt.state       :1951][INFO    ][670] Completed state [maas.wait_for_machine_status] at time 08:02:01.736396 duration_in_ms=3519.233
2019-04-24 08:02:01,740 [salt.minion      :1711][INFO    ][670] Returning information for job: 20190424080155961385
2019-04-24 08:31:17,021 [salt.utils.schedule:1377][INFO    ][24078] Running scheduled job: __mine_interval
2019-04-24 09:31:17,021 [salt.utils.schedule:1377][INFO    ][24078] Running scheduled job: __mine_interval
2019-04-24 09:37:34,765 [salt.minion      :1308][INFO    ][24078] User sudo_ubuntu Executing command cp.push_dir with jid 20190424093734754007
2019-04-24 09:37:34,792 [salt.minion      :1432][INFO    ][7630] Starting a new job with PID 7630
