2019-10-30 05:08:34,515 [salt.utils.decorators:613 ][WARNING ][2114] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-10-30 05:08:34,986 [salt.utils.decorators:613 ][WARNING ][2114] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-10-30 05:08:36,958 [salt.loaded.int.states.file:2298][WARNING ][2404] 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-10-30 05:08:57,998 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3022] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-10-30 05:08:58,027 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3022] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-10-30 05:08:58,049 [salt.utils.parsers:1051][WARNING ][317] Minion received a SIGTERM. Exiting.
2019-10-30 05:08:59,069 [salt.cli.daemons :293 ][INFO    ][3140] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-10-30 05:08:59,167 [salt.cli.daemons :82  ][INFO    ][3140] Starting up the Salt Minion
2019-10-30 05:08:59,168 [salt.utils.event :1017][INFO    ][3140] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-10-30 05:08:59,910 [salt.minion      :976 ][INFO    ][3140] Creating minion process manager
2019-10-30 05:09:01,360 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][3140] Executing command ['date', '+%z'] in directory '/root'
2019-10-30 05:09:01,390 [salt.utils.schedule:568 ][INFO    ][3140] Updating job settings for scheduled job: __mine_interval
2019-10-30 05:09:01,393 [salt.minion      :1108][INFO    ][3140] Added mine.update to scheduler
2019-10-30 05:09:01,400 [salt.minion      :1975][INFO    ][3140] Minion is starting as user 'root'
2019-10-30 05:09:01,414 [salt.minion      :2336][INFO    ][3140] Minion is ready to receive requests!
2019-10-30 05:09:03,715 [salt.state       :2022][WARNING ][3025] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-10-30 05:09:07,450 [salt.utils.decorators:613 ][WARNING ][3025] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-10-30 05:09:11,630 [salt.minion      :1308][INFO    ][3140] User sudo_ubuntu Executing command saltutil.find_job with jid 20191030050911612470
2019-10-30 05:09:11,655 [salt.minion      :1432][INFO    ][3637] Starting a new job with PID 3637
2019-10-30 05:09:11,678 [salt.minion      :1711][INFO    ][3637] Returning information for job: 20191030050911612470
2019-10-30 05:09:27,519 [salt.utils.decorators:613 ][WARNING ][3025] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-10-30 05:09:41,705 [salt.minion      :1308][INFO    ][3140] User sudo_ubuntu Executing command saltutil.find_job with jid 20191030050941688717
2019-10-30 05:09:41,731 [salt.minion      :1432][INFO    ][3800] Starting a new job with PID 3800
2019-10-30 05:09:41,752 [salt.minion      :1711][INFO    ][3800] Returning information for job: 20191030050941688717
2019-10-30 05:10:11,817 [salt.minion      :1308][INFO    ][3140] User sudo_ubuntu Executing command saltutil.find_job with jid 20191030051011805661
2019-10-30 05:10:11,843 [salt.minion      :1432][INFO    ][3996] Starting a new job with PID 3996
2019-10-30 05:10:11,867 [salt.minion      :1711][INFO    ][3996] Returning information for job: 20191030051011805661
2019-10-30 05:10:41,889 [salt.minion      :1308][INFO    ][3140] User sudo_ubuntu Executing command saltutil.find_job with jid 20191030051041872674
2019-10-30 05:10:41,915 [salt.minion      :1432][INFO    ][4201] Starting a new job with PID 4201
2019-10-30 05:10:44,522 [salt.minion      :1711][INFO    ][4201] Returning information for job: 20191030051041872674
2019-10-30 05:10:49,597 [salt.utils.decorators:613 ][WARNING ][3025] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-10-30 05:10:50,393 [salt.utils.decorators:613 ][WARNING ][3025] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-10-30 05:10:53,485 [salt.loaded.ext.module.maas:160 ][ERROR   ][3025] Failed for object commissioning_distro_series reason {"commissioning_distro_series": ["'bionic' is not a valid commissioning_distro_series.  It should be one of: '---'."]}
2019-10-30 05:10:53,554 [salt.loaded.ext.module.maas:160 ][ERROR   ][3025] Failed for object default_osystem reason {"default_osystem": ["'ubuntu' is not a valid osystem.  It should be one of: '---'."]}
2019-10-30 05:10:54,278 [salt.loaded.ext.module.maas:160 ][ERROR   ][3025] Failed for object default_min_hwe_kernel reason {"default_min_hwe_kernel": ["'ga-18.04' is not a valid default_min_hwe_kernel.  It should be one of: ''."]}
2019-10-30 05:10:54,278 [salt.state       :302 ][ERROR   ][3025] Module function maas.process_maas_config threw an exception. Exception: {'updated': [], 'errors': {'commissioning_distro_series': '{"commissioning_distro_series": ["\'bionic\' is not a valid commissioning_distro_series.  It should be one of: \'---\'."]}', 'default_osystem': '{"default_osystem": ["\'ubuntu\' is not a valid osystem.  It should be one of: \'---\'."]}', 'default_min_hwe_kernel': '{"default_min_hwe_kernel": ["\'ga-18.04\' is not a valid default_min_hwe_kernel.  It should be one of: \'\'."]}'}, 'success': ['enable_http_proxy', 'upstream_dns', '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']}
2019-10-30 05:10:54,410 [salt.loaded.ext.module.maasng:1008][WARNING ][3025] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-10-30 05:10:54,411 [salt.loaded.ext.module.maasng:1011][WARNING ][3025] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-10-30 05:10:55,206 [salt.loaded.ext.module.maasng:1235][WARNING ][3025] Ignoring parameter vlan:0
2019-10-30 05:10:56,269 [salt.utils.decorators:613 ][WARNING ][3025] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-10-30 05:11:07,023 [salt.minion      :1308][INFO    ][3140] User sudo_ubuntu Executing command state.apply with jid 20191030051107013181
2019-10-30 05:11:07,046 [salt.minion      :1432][INFO    ][4522] Starting a new job with PID 4522
2019-10-30 05:11:11,003 [salt.state       :915 ][INFO    ][4522] Loading fresh modules for state activity
2019-10-30 05:11:11,670 [salt.state       :1780][INFO    ][4522] Running state [maas-region-controller] at time 05:11:11.670776
2019-10-30 05:11:11,671 [salt.state       :1813][INFO    ][4522] Executing state pkg.installed for [maas-region-controller]
2019-10-30 05:11:11,671 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4522] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-10-30 05:11:11,742 [salt.state       :300 ][INFO    ][4522] All specified packages are already installed
2019-10-30 05:11:11,743 [salt.state       :1951][INFO    ][4522] Completed state [maas-region-controller] at time 05:11:11.743231 duration_in_ms=72.455
2019-10-30 05:11:11,743 [salt.state       :1780][INFO    ][4522] Running state [python-oauth] at time 05:11:11.743553
2019-10-30 05:11:11,743 [salt.state       :1813][INFO    ][4522] Executing state pkg.installed for [python-oauth]
2019-10-30 05:11:11,748 [salt.state       :300 ][INFO    ][4522] All specified packages are already installed
2019-10-30 05:11:11,748 [salt.state       :1951][INFO    ][4522] Completed state [python-oauth] at time 05:11:11.748906 duration_in_ms=5.353
2019-10-30 05:11:11,751 [salt.state       :1780][INFO    ][4522] Running state [/etc/maas/regiond.conf] at time 05:11:11.751264
2019-10-30 05:11:11,751 [salt.state       :1813][INFO    ][4522] Executing state file.replace for [/etc/maas/regiond.conf]
2019-10-30 05:11:11,811 [salt.state       :300 ][INFO    ][4522] No changes needed to be made
2019-10-30 05:11:11,811 [salt.state       :1951][INFO    ][4522] Completed state [/etc/maas/regiond.conf] at time 05:11:11.811626 duration_in_ms=60.361
2019-10-30 05:11:11,812 [salt.state       :1780][INFO    ][4522] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:11:11.812089
2019-10-30 05:11:11,812 [salt.state       :1813][INFO    ][4522] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-10-30 05:11:11,907 [salt.state       :300 ][INFO    ][4522] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-10-30 05:11:11,908 [salt.state       :1951][INFO    ][4522] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:11:11.908020 duration_in_ms=95.929
2019-10-30 05:11:11,909 [salt.state       :1780][INFO    ][4522] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:11:11.909056
2019-10-30 05:11:11,909 [salt.state       :1813][INFO    ][4522] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-10-30 05:11:11,969 [salt.state       :300 ][INFO    ][4522] No changes needed to be made
2019-10-30 05:11:11,970 [salt.state       :1951][INFO    ][4522] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:11:11.970264 duration_in_ms=61.209
2019-10-30 05:11:11,971 [salt.state       :1780][INFO    ][4522] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:11:11.971124
2019-10-30 05:11:11,971 [salt.state       :1813][INFO    ][4522] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-10-30 05:11:12,030 [salt.state       :300 ][INFO    ][4522] No changes needed to be made
2019-10-30 05:11:12,030 [salt.state       :1951][INFO    ][4522] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:11:12.030885 duration_in_ms=59.761
2019-10-30 05:11:12,031 [salt.state       :1780][INFO    ][4522] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:11:12.031775
2019-10-30 05:11:12,032 [salt.state       :1813][INFO    ][4522] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-10-30 05:11:12,067 [salt.state       :300 ][INFO    ][4522] No changes needed to be made
2019-10-30 05:11:12,067 [salt.state       :1951][INFO    ][4522] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:11:12.067902 duration_in_ms=36.127
2019-10-30 05:11:12,068 [salt.state       :1780][INFO    ][4522] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:11:12.068568
2019-10-30 05:11:12,068 [salt.state       :1813][INFO    ][4522] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-10-30 05:11:12,081 [salt.state       :300 ][INFO    ][4522] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-10-30 05:11:12,082 [salt.state       :1951][INFO    ][4522] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:11:12.082073 duration_in_ms=13.505
2019-10-30 05:11:12,083 [salt.state       :1780][INFO    ][4522] Running state [a2enmod headers] at time 05:11:12.083712
2019-10-30 05:11:12,084 [salt.state       :1813][INFO    ][4522] Executing state cmd.run for [a2enmod headers]
2019-10-30 05:11:12,084 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4522] Executing command 'a2enmod headers' in directory '/root'
2019-10-30 05:11:12,143 [salt.state       :300 ][INFO    ][4522] {'pid': 4558, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-10-30 05:11:12,144 [salt.state       :1951][INFO    ][4522] Completed state [a2enmod headers] at time 05:11:12.144163 duration_in_ms=60.45
2019-10-30 05:11:12,144 [salt.state       :1780][INFO    ][4522] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:11:12.144740
2019-10-30 05:11:12,145 [salt.state       :1813][INFO    ][4522] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-10-30 05:11:12,163 [salt.state       :300 ][INFO    ][4522] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-10-30 05:11:12,163 [salt.state       :1951][INFO    ][4522] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:11:12.163475 duration_in_ms=18.734
2019-10-30 05:11:12,164 [salt.state       :1780][INFO    ][4522] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:11:12.164336
2019-10-30 05:11:12,164 [salt.state       :1813][INFO    ][4522] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-10-30 05:11:12,286 [salt.state       :300 ][INFO    ][4522] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-10-30 05:11:12,286 [salt.state       :1951][INFO    ][4522] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:11:12.286739 duration_in_ms=122.4
2019-10-30 05:11:12,288 [salt.state       :1780][INFO    ][4522] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:11:12.287926
2019-10-30 05:11:12,288 [salt.state       :1813][INFO    ][4522] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-10-30 05:11:12,399 [salt.state       :300 ][INFO    ][4522] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-10-30 05:11:12,399 [salt.state       :1951][INFO    ][4522] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:11:12.399837 duration_in_ms=111.911
2019-10-30 05:11:12,400 [salt.state       :1780][INFO    ][4522] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:11:12.400607
2019-10-30 05:11:12,401 [salt.state       :1813][INFO    ][4522] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-10-30 05:11:12,513 [salt.state       :300 ][INFO    ][4522] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-10-30 05:11:12,513 [salt.state       :1951][INFO    ][4522] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:11:12.513475 duration_in_ms=112.869
2019-10-30 05:11:12,514 [salt.state       :1780][INFO    ][4522] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_bionic] at time 05:11:12.514151
2019-10-30 05:11:12,514 [salt.state       :1813][INFO    ][4522] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_bionic]
2019-10-30 05:11:12,615 [salt.state       :300 ][INFO    ][4522] File /etc/maas/preseeds/curtin_userdata_amd64_generic_bionic is in the correct state
2019-10-30 05:11:12,615 [salt.state       :1951][INFO    ][4522] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_bionic] at time 05:11:12.615855 duration_in_ms=101.703
2019-10-30 05:11:12,616 [salt.state       :1780][INFO    ][4522] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_bionic] at time 05:11:12.616744
2019-10-30 05:11:12,617 [salt.state       :1813][INFO    ][4522] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_bionic]
2019-10-30 05:11:12,735 [salt.state       :300 ][INFO    ][4522] File /etc/maas/preseeds/curtin_userdata_arm64_generic_bionic is in the correct state
2019-10-30 05:11:12,735 [salt.state       :1951][INFO    ][4522] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_bionic] at time 05:11:12.735532 duration_in_ms=118.788
2019-10-30 05:11:12,736 [salt.state       :1780][INFO    ][4522] Running state [/root/.pgpass] at time 05:11:12.735978
2019-10-30 05:11:12,736 [salt.state       :1813][INFO    ][4522] Executing state file.managed for [/root/.pgpass]
2019-10-30 05:11:12,842 [salt.state       :300 ][INFO    ][4522] File /root/.pgpass is in the correct state
2019-10-30 05:11:12,843 [salt.state       :1951][INFO    ][4522] Completed state [/root/.pgpass] at time 05:11:12.843253 duration_in_ms=107.275
2019-10-30 05:11:12,852 [salt.state       :1780][INFO    ][4522] Running state [maas-region syncdb --noinput] at time 05:11:12.852357
2019-10-30 05:11:12,852 [salt.state       :1813][INFO    ][4522] Executing state cmd.run for [maas-region syncdb --noinput]
2019-10-30 05:11:12,853 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4522] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-10-30 05:11:14,695 [salt.state       :300 ][INFO    ][4522] {'pid': 4575, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: maasserver, metadataserver, contenttypes, sites, piston3, sessions, auth\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-10-30 05:11:14,695 [salt.state       :1951][INFO    ][4522] Completed state [maas-region syncdb --noinput] at time 05:11:14.695811 duration_in_ms=1843.454
2019-10-30 05:11:14,696 [salt.state       :2022][WARNING ][4522] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-10-30 05:11:14,698 [salt.state       :1780][INFO    ][4522] Running state [maas-regiond] at time 05:11:14.698182
2019-10-30 05:11:14,698 [salt.state       :1813][INFO    ][4522] Executing state service.running for [maas-regiond]
2019-10-30 05:11:14,699 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4522] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-10-30 05:11:14,740 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4522] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-10-30 05:11:14,759 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4522] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-10-30 05:11:14,777 [salt.state       :300 ][INFO    ][4522] The service maas-regiond is already running
2019-10-30 05:11:14,778 [salt.state       :1951][INFO    ][4522] Completed state [maas-regiond] at time 05:11:14.777957 duration_in_ms=79.774
2019-10-30 05:11:14,780 [salt.state       :1780][INFO    ][4522] Running state [bind9] at time 05:11:14.780343
2019-10-30 05:11:14,780 [salt.state       :1813][INFO    ][4522] Executing state service.running for [bind9]
2019-10-30 05:11:14,781 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4522] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-10-30 05:11:14,800 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4522] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-10-30 05:11:14,817 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4522] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-10-30 05:11:14,834 [salt.state       :300 ][INFO    ][4522] The service bind9 is already running
2019-10-30 05:11:14,835 [salt.state       :1951][INFO    ][4522] Completed state [bind9] at time 05:11:14.835239 duration_in_ms=54.895
2019-10-30 05:11:14,837 [salt.state       :1780][INFO    ][4522] Running state [apache2] at time 05:11:14.837463
2019-10-30 05:11:14,837 [salt.state       :1813][INFO    ][4522] Executing state service.running for [apache2]
2019-10-30 05:11:14,838 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4522] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-10-30 05:11:14,855 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4522] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-10-30 05:11:14,871 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4522] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-10-30 05:11:14,890 [salt.state       :300 ][INFO    ][4522] The service apache2 is already running
2019-10-30 05:11:14,890 [salt.state       :1951][INFO    ][4522] Completed state [apache2] at time 05:11:14.890884 duration_in_ms=53.42
2019-10-30 05:11:14,893 [salt.state       :1780][INFO    ][4522] Running state [maasng.wait_for_http_code] at time 05:11:14.893269
2019-10-30 05:11:14,893 [salt.state       :1813][INFO    ][4522] Executing state module.run for [maasng.wait_for_http_code]
2019-10-30 05:11:14,894 [salt.utils.decorators:613 ][WARNING ][4522] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-10-30 05:11:14,997 [salt.state       :300 ][INFO    ][4522] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-10-30 05:11:14,998 [salt.state       :1951][INFO    ][4522] Completed state [maasng.wait_for_http_code] at time 05:11:14.998280 duration_in_ms=105.009
2019-10-30 05:11:15,000 [salt.state       :1780][INFO    ][4522] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:11:15.000292
2019-10-30 05:11:15,000 [salt.state       :1813][INFO    ][4522] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-10-30 05:11:15,001 [salt.state       :300 ][INFO    ][4522] /var/lib/maas/.setup_admin exists
2019-10-30 05:11:15,001 [salt.state       :1951][INFO    ][4522] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:11:15.001878 duration_in_ms=1.586
2019-10-30 05:11:15,003 [salt.state       :1780][INFO    ][4522] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:11:15.003011
2019-10-30 05:11:15,003 [salt.state       :1813][INFO    ][4522] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-10-30 05:11:15,004 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4522] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-10-30 05:11:16,413 [salt.state       :300 ][INFO    ][4522] {'pid': 4596, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-10-30 05:11:16,414 [salt.state       :1951][INFO    ][4522] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:11:16.414194 duration_in_ms=1411.184
2019-10-30 05:11:16,417 [salt.state       :1780][INFO    ][4522] Running state [maas_region_boot_source_resources_mirror] at time 05:11:16.417901
2019-10-30 05:11:16,418 [salt.state       :1813][INFO    ][4522] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-10-30 05:11:16,525 [salt.state       :300 ][INFO    ][4522] {'changes': {}}
2019-10-30 05:11:16,525 [salt.state       :1951][INFO    ][4522] Completed state [maas_region_boot_source_resources_mirror] at time 05:11:16.525525 duration_in_ms=107.622
2019-10-30 05:11:16,526 [salt.state       :1780][INFO    ][4522] Running state [maasng.boot_resources_import] at time 05:11:16.526652
2019-10-30 05:11:16,527 [salt.state       :1813][INFO    ][4522] Executing state module.run for [maasng.boot_resources_import]
2019-10-30 05:11:16,527 [salt.utils.decorators:613 ][WARNING ][4522] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-10-30 05:11:16,657 [salt.loaded.ext.module.maasng:1600][INFO    ][4522] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-10-30 05:11:21,712 [salt.loaded.ext.module.maasng:1600][INFO    ][4522] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-10-30 05:11:22,068 [salt.minion      :1308][INFO    ][3140] User sudo_ubuntu Executing command saltutil.find_job with jid 20191030051122052893
2019-10-30 05:11:22,093 [salt.minion      :1432][INFO    ][4616] Starting a new job with PID 4616
2019-10-30 05:11:22,112 [salt.minion      :1711][INFO    ][4616] Returning information for job: 20191030051122052893
2019-10-30 05:11:26,773 [salt.loaded.ext.module.maasng:1600][INFO    ][4522] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-10-30 05:11:31,892 [salt.state       :300 ][INFO    ][4522] {'ret': True}
2019-10-30 05:11:31,892 [salt.state       :1951][INFO    ][4522] Completed state [maasng.boot_resources_import] at time 05:11:31.892642 duration_in_ms=15365.989
2019-10-30 05:11:31,893 [salt.state       :1780][INFO    ][4522] Running state [maas_region_boot_sources_selection_bionic] at time 05:11:31.893910
2019-10-30 05:11:31,894 [salt.state       :1813][INFO    ][4522] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_bionic]
2019-10-30 05:11:32,095 [salt.state       :300 ][INFO    ][4522] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-10-30 05:11:32,095 [salt.state       :1951][INFO    ][4522] Completed state [maas_region_boot_sources_selection_bionic] at time 05:11:32.095687 duration_in_ms=201.777
2019-10-30 05:11:32,097 [salt.state       :1780][INFO    ][4522] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 05:11:32.097240
2019-10-30 05:11:32,097 [salt.state       :1813][INFO    ][4522] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-10-30 05:11:32,098 [salt.utils.decorators:613 ][WARNING ][4522] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-10-30 05:11:32,098 [salt.loaded.ext.module.maasng:1771][INFO    ][4522] boot-sources sync initiated for ALL Rack's
2019-10-30 05:11:33,017 [salt.state       :300 ][INFO    ][4522] {'ret': True}
2019-10-30 05:11:33,017 [salt.state       :1951][INFO    ][4522] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 05:11:33.017729 duration_in_ms=920.489
2019-10-30 05:11:33,019 [salt.state       :1780][INFO    ][4522] Running state [maas.process_maas_config] at time 05:11:33.019611
2019-10-30 05:11:33,020 [salt.state       :1813][INFO    ][4522] Executing state module.run for [maas.process_maas_config]
2019-10-30 05:11:33,020 [salt.utils.decorators:613 ][WARNING ][4522] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-10-30 05:11:33,021 [salt.loaded.ext.module.maas:92  ][INFO    ][4522] maasconfig name=enable_http_proxy value=True
2019-10-30 05:11:33,085 [salt.loaded.ext.module.maas:92  ][INFO    ][4522] maasconfig name=upstream_dns value=8.8.8.8
2019-10-30 05:11:33,149 [salt.loaded.ext.module.maas:92  ][INFO    ][4522] maasconfig name=commissioning_distro_series value=bionic
2019-10-30 05:11:33,215 [salt.loaded.ext.module.maas:92  ][INFO    ][4522] maasconfig name=default_osystem value=ubuntu
2019-10-30 05:11:33,274 [salt.loaded.ext.module.maas:92  ][INFO    ][4522] maasconfig name=active_discovery_interval value=600
2019-10-30 05:11:36,165 [salt.loaded.ext.module.maas:92  ][INFO    ][4522] maasconfig name=dnssec_validation value=no
2019-10-30 05:11:36,242 [salt.loaded.ext.module.maas:92  ][INFO    ][4522] maasconfig name=maas_name value=mas01
2019-10-30 05:11:36,300 [salt.loaded.ext.module.maas:92  ][INFO    ][4522] maasconfig name=network_discovery value=enabled
2019-10-30 05:11:36,420 [salt.loaded.ext.module.maas:92  ][INFO    ][4522] maasconfig name=enable_third_party_drivers value=True
2019-10-30 05:11:36,474 [salt.loaded.ext.module.maas:92  ][INFO    ][4522] maasconfig name=default_storage_layout value=lvm
2019-10-30 05:11:36,528 [salt.loaded.ext.module.maas:92  ][INFO    ][4522] maasconfig name=ntp_external_only value=True
2019-10-30 05:11:36,580 [salt.loaded.ext.module.maas:92  ][INFO    ][4522] maasconfig name=disk_erase_with_secure_erase value=False
2019-10-30 05:11:36,628 [salt.loaded.ext.module.maas:92  ][INFO    ][4522] maasconfig name=default_distro_series value=bionic
2019-10-30 05:11:36,688 [salt.loaded.ext.module.maas:92  ][INFO    ][4522] maasconfig name=default_min_hwe_kernel value=ga-18.04
2019-10-30 05:11:36,784 [salt.state       :300 ][INFO    ][4522] {'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-10-30 05:11:36,784 [salt.state       :1951][INFO    ][4522] Completed state [maas.process_maas_config] at time 05:11:36.784869 duration_in_ms=3765.256
2019-10-30 05:11:36,785 [salt.state       :1780][INFO    ][4522] Running state [pxe_admin] at time 05:11:36.785828
2019-10-30 05:11:36,786 [salt.state       :1813][INFO    ][4522] Executing state maasng.fabric_present for [pxe_admin]
2019-10-30 05:11:36,838 [salt.loaded.ext.module.maasng:945 ][INFO    ][4522] [{u'id': 0, u'class_type': None, u'vlans': [{u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'vid': 0, u'fabric': u'fabric-1', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'pxrgk4', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-10-30 05:11:36,910 [salt.loaded.ext.module.maasng:1008][WARNING ][4522] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-10-30 05:11:36,910 [salt.loaded.ext.module.maasng:1011][WARNING ][4522] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-10-30 05:11:36,982 [salt.state       :300 ][INFO    ][4522] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-10-30 05:11:36,982 [salt.state       :1951][INFO    ][4522] Completed state [pxe_admin] at time 05:11:36.982648 duration_in_ms=196.818
2019-10-30 05:11:36,983 [salt.state       :1780][INFO    ][4522] Running state [vlan 0] at time 05:11:36.983350
2019-10-30 05:11:36,984 [salt.state       :1813][INFO    ][4522] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-10-30 05:11:37,065 [salt.loaded.ext.module.maasng:945 ][INFO    ][4522] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'pxrgk4', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-10-30 05:11:37,185 [salt.loaded.ext.module.maasng:945 ][INFO    ][4522] [{u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0'}, {u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1'}, {u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'pxrgk4', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin'}]
2019-10-30 05:11:37,401 [salt.loaded.ext.module.maasng:945 ][INFO    ][4522] [{u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0'}, {u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1'}, {u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'pxrgk4', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin'}]
2019-10-30 05:11:37,485 [salt.state       :300 ][INFO    ][4522] {'new': 'Vlan untagged was updated'}
2019-10-30 05:11:37,486 [salt.state       :1951][INFO    ][4522] Completed state [vlan 0] at time 05:11:37.486046 duration_in_ms=502.697
2019-10-30 05:11:37,487 [salt.state       :1780][INFO    ][4522] Running state [192.168.11.0/24] at time 05:11:37.487101
2019-10-30 05:11:37,487 [salt.state       :1813][INFO    ][4522] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-10-30 05:11:37,658 [salt.loaded.ext.module.maasng:945 ][INFO    ][4522] [{u'id': 0, u'class_type': None, 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'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, 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'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', 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'pxrgk4', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-10-30 05:11:37,658 [salt.loaded.ext.module.maasng:1235][WARNING ][4522] Ignoring parameter vlan:0
2019-10-30 05:11:37,742 [salt.state       :300 ][INFO    ][4522] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-10-30 05:11:37,742 [salt.state       :1951][INFO    ][4522] Completed state [192.168.11.0/24] at time 05:11:37.742700 duration_in_ms=255.598
2019-10-30 05:11:37,743 [salt.state       :1780][INFO    ][4522] Running state [maas_create_iprange_1] at time 05:11:37.743528
2019-10-30 05:11:37,743 [salt.state       :1813][INFO    ][4522] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-10-30 05:11:37,802 [salt.state       :300 ][INFO    ][4522] Iprange maas_create_iprange_1 already exist.
2019-10-30 05:11:37,802 [salt.state       :1951][INFO    ][4522] Completed state [maas_create_iprange_1] at time 05:11:37.802670 duration_in_ms=59.141
2019-10-30 05:11:37,803 [salt.state       :1780][INFO    ][4522] Running state [vlan 0] at time 05:11:37.802991
2019-10-30 05:11:37,803 [salt.state       :1813][INFO    ][4522] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-10-30 05:11:37,839 [salt.loaded.ext.module.maasng:945 ][INFO    ][4522] [{u'id': 0, u'class_type': None, 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'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, 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'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', 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'pxrgk4', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-10-30 05:11:37,952 [salt.loaded.ext.module.maasng:945 ][INFO    ][4522] [{u'id': 0, u'class_type': None, 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'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, 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'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', 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'pxrgk4', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-10-30 05:11:38,144 [salt.loaded.ext.module.maasng:945 ][INFO    ][4522] [{u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0'}, {u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1'}, {u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'pxrgk4', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin'}]
2019-10-30 05:11:38,204 [salt.state       :300 ][INFO    ][4522] {'new': 'Vlan untagged was updated'}
2019-10-30 05:11:38,204 [salt.state       :1951][INFO    ][4522] Completed state [vlan 0] at time 05:11:38.204727 duration_in_ms=401.735
2019-10-30 05:11:38,205 [salt.state       :1780][INFO    ][4522] Running state [opnfv] at time 05:11:38.205435
2019-10-30 05:11:38,205 [salt.state       :1813][INFO    ][4522] Executing state maasng.sshkey_present for [opnfv]
2019-10-30 05:11:38,233 [salt.loaded.ext.module.maasng:1903][INFO    ][4522] [{u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP', u'keysource': u''}]
2019-10-30 05:11:38,234 [salt.state       :300 ][INFO    ][4522] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-10-30 05:11:38,234 [salt.state       :1951][INFO    ][4522] Completed state [opnfv] at time 05:11:38.234369 duration_in_ms=28.935
2019-10-30 05:11:38,235 [salt.state       :1780][INFO    ][4522] Running state [maas.process_tags] at time 05:11:38.234983
2019-10-30 05:11:38,235 [salt.state       :1813][INFO    ][4522] Executing state module.run for [maas.process_tags]
2019-10-30 05:11:38,235 [salt.utils.decorators:613 ][WARNING ][4522] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-10-30 05:11:38,263 [salt.loaded.ext.module.maas:92  ][INFO    ][4522] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-10-30 05:11:38,325 [salt.state       :300 ][INFO    ][4522] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-10-30 05:11:38,325 [salt.state       :1951][INFO    ][4522] Completed state [maas.process_tags] at time 05:11:38.325546 duration_in_ms=90.561
2019-10-30 05:11:38,330 [salt.minion      :1711][INFO    ][4522] Returning information for job: 20191030051107013181
2019-10-30 05:11:38,864 [salt.minion      :1308][INFO    ][3140] User sudo_ubuntu Executing command state.apply with jid 20191030051138854626
2019-10-30 05:11:38,881 [salt.minion      :1432][INFO    ][4911] Starting a new job with PID 4911
2019-10-30 05:11:43,986 [salt.state       :915 ][INFO    ][4911] Loading fresh modules for state activity
2019-10-30 05:11:44,042 [salt.fileclient  :1219][INFO    ][4911] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-10-30 05:11:44,082 [salt.state       :1780][INFO    ][4911] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:11:44.082094
2019-10-30 05:11:44,082 [salt.state       :1813][INFO    ][4911] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-10-30 05:11:44,084 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4911] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-10-30 05:11:45,464 [salt.state       :300 ][INFO    ][4911] {'pid': 4935, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-10-30 05:11:45,465 [salt.state       :1951][INFO    ][4911] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:11:45.465089 duration_in_ms=1382.996
2019-10-30 05:11:45,466 [salt.state       :1780][INFO    ][4911] Running state [maas.process_machines] at time 05:11:45.466277
2019-10-30 05:11:45,466 [salt.state       :1813][INFO    ][4911] Executing state module.run for [maas.process_machines]
2019-10-30 05:11:45,466 [salt.utils.decorators:613 ][WARNING ][4911] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-10-30 05:11:45,540 [salt.loaded.ext.module.maas:412 ][WARNING ][4911] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-10-30 05:11:45,540 [salt.loaded.ext.module.maas:92  ][INFO    ][4911] 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-10-30 05:11:46,990 [salt.loaded.ext.module.maas:412 ][WARNING ][4911] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-10-30 05:11:46,991 [salt.loaded.ext.module.maas:92  ][INFO    ][4911] 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-10-30 05:11:48,504 [salt.loaded.ext.module.maas:412 ][WARNING ][4911] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-10-30 05:11:48,505 [salt.loaded.ext.module.maas:92  ][INFO    ][4911] 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-10-30 05:11:49,871 [salt.loaded.ext.module.maas:412 ][WARNING ][4911] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-10-30 05:11:49,872 [salt.loaded.ext.module.maas:92  ][INFO    ][4911] 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-10-30 05:11:51,284 [salt.loaded.ext.module.maas:412 ][WARNING ][4911] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-10-30 05:11:51,285 [salt.loaded.ext.module.maas:92  ][INFO    ][4911] 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-10-30 05:11:52,650 [salt.state       :300 ][INFO    ][4911] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-10-30 05:11:52,650 [salt.state       :1951][INFO    ][4911] Completed state [maas.process_machines] at time 05:11:52.650789 duration_in_ms=7184.51
2019-10-30 05:11:52,654 [salt.minion      :1711][INFO    ][4911] Returning information for job: 20191030051138854626
2019-10-30 05:12:23,695 [salt.minion      :1308][INFO    ][3140] User sudo_ubuntu Executing command state.apply with jid 20191030051223682359
2019-10-30 05:12:23,721 [salt.minion      :1432][INFO    ][5256] Starting a new job with PID 5256
2019-10-30 05:12:27,435 [salt.state       :915 ][INFO    ][5256] Loading fresh modules for state activity
2019-10-30 05:12:27,465 [salt.fileclient  :1219][INFO    ][5256] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-10-30 05:12:27,491 [salt.state       :1780][INFO    ][5256] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:12:27.491196
2019-10-30 05:12:27,491 [salt.state       :1813][INFO    ][5256] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-10-30 05:12:27,492 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5256] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-10-30 05:12:29,084 [salt.state       :300 ][INFO    ][5256] {'pid': 5266, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-10-30 05:12:29,085 [salt.state       :1951][INFO    ][5256] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:12:29.085456 duration_in_ms=1594.259
2019-10-30 05:12:29,088 [salt.state       :1780][INFO    ][5256] Running state [maas.wait_for_machine_status] at time 05:12:29.088000
2019-10-30 05:12:29,088 [salt.state       :1813][INFO    ][5256] Executing state module.run for [maas.wait_for_machine_status]
2019-10-30 05:12:29,089 [salt.utils.decorators:613 ][WARNING ][5256] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-10-30 05:12:30,082 [salt.loaded.ext.module.maas:1023][INFO    ][5256] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.01680493s left)
2019-10-30 05:12:38,732 [salt.minion      :1308][INFO    ][3140] User sudo_ubuntu Executing command saltutil.find_job with jid 20191030051238716507
2019-10-30 05:12:38,757 [salt.minion      :1432][INFO    ][5295] Starting a new job with PID 5295
2019-10-30 05:12:38,780 [salt.minion      :1711][INFO    ][5295] Returning information for job: 20191030051238716507
2019-10-30 05:13:01,066 [salt.loaded.ext.module.maas:1023][INFO    ][5256] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.03255415s left)
2019-10-30 05:13:08,781 [salt.minion      :1308][INFO    ][3140] User sudo_ubuntu Executing command saltutil.find_job with jid 20191030051308768378
2019-10-30 05:13:08,807 [salt.minion      :1432][INFO    ][5334] Starting a new job with PID 5334
2019-10-30 05:13:08,829 [salt.minion      :1711][INFO    ][5334] Returning information for job: 20191030051308768378
2019-10-30 05:13:32,416 [salt.loaded.ext.module.maas:1023][INFO    ][5256] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1436.68315411s left)
2019-10-30 05:13:38,887 [salt.minion      :1308][INFO    ][3140] User sudo_ubuntu Executing command saltutil.find_job with jid 20191030051338874225
2019-10-30 05:13:38,911 [salt.minion      :1432][INFO    ][5481] Starting a new job with PID 5481
2019-10-30 05:13:38,921 [salt.minion      :1711][INFO    ][5481] Returning information for job: 20191030051338874225
2019-10-30 05:14:03,681 [salt.loaded.ext.module.maas:1023][INFO    ][5256] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1405.41777706s left)
2019-10-30 05:14:08,925 [salt.minion      :1308][INFO    ][3140] User sudo_ubuntu Executing command saltutil.find_job with jid 20191030051408916010
2019-10-30 05:14:08,943 [salt.minion      :1432][INFO    ][5742] Starting a new job with PID 5742
2019-10-30 05:14:08,956 [salt.minion      :1711][INFO    ][5742] Returning information for job: 20191030051408916010
2019-10-30 05:14:35,305 [salt.loaded.ext.module.maas:1023][INFO    ][5256] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1373.79400301s left)
2019-10-30 05:14:38,968 [salt.minion      :1308][INFO    ][3140] User sudo_ubuntu Executing command saltutil.find_job with jid 20191030051438956097
2019-10-30 05:14:38,991 [salt.minion      :1432][INFO    ][6332] Starting a new job with PID 6332
2019-10-30 05:14:39,013 [salt.minion      :1711][INFO    ][6332] Returning information for job: 20191030051438956097
2019-10-30 05:15:07,195 [salt.loaded.ext.module.maas:1023][INFO    ][5256] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1341.90445805s left)
2019-10-30 05:15:09,028 [salt.minion      :1308][INFO    ][3140] User sudo_ubuntu Executing command saltutil.find_job with jid 20191030051509011889
2019-10-30 05:15:09,054 [salt.minion      :1432][INFO    ][6402] Starting a new job with PID 6402
2019-10-30 05:15:09,075 [salt.minion      :1711][INFO    ][6402] Returning information for job: 20191030051509011889
2019-10-30 05:15:39,086 [salt.minion      :1308][INFO    ][3140] User sudo_ubuntu Executing command saltutil.find_job with jid 20191030051539073675
2019-10-30 05:15:39,111 [salt.minion      :1432][INFO    ][6538] Starting a new job with PID 6538
2019-10-30 05:15:39,133 [salt.minion      :1711][INFO    ][6538] Returning information for job: 20191030051539073675
2019-10-30 05:15:40,742 [salt.loaded.ext.module.maas:1023][INFO    ][5256] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1308.35676193s left)
2019-10-30 05:16:09,165 [salt.minion      :1308][INFO    ][3140] User sudo_ubuntu Executing command saltutil.find_job with jid 20191030051609145827
2019-10-30 05:16:09,191 [salt.minion      :1432][INFO    ][6664] Starting a new job with PID 6664
2019-10-30 05:16:09,212 [salt.minion      :1711][INFO    ][6664] Returning information for job: 20191030051609145827
2019-10-30 05:16:14,484 [salt.state       :300 ][INFO    ][5256] {'ret': True}
2019-10-30 05:16:14,484 [salt.state       :1951][INFO    ][5256] Completed state [maas.wait_for_machine_status] at time 05:16:14.484462 duration_in_ms=225396.459
2019-10-30 05:16:14,488 [salt.minion      :1711][INFO    ][5256] Returning information for job: 20191030051223682359
2019-10-30 05:16:15,141 [salt.minion      :1308][INFO    ][3140] User sudo_ubuntu Executing command state.apply with jid 20191030051615128676
2019-10-30 05:16:15,168 [salt.minion      :1432][INFO    ][6678] Starting a new job with PID 6678
2019-10-30 05:16:18,880 [salt.state       :915 ][INFO    ][6678] Loading fresh modules for state activity
2019-10-30 05:16:18,927 [salt.fileclient  :1219][INFO    ][6678] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-10-30 05:16:19,003 [salt.state       :1780][INFO    ][6678] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:16:19.003349
2019-10-30 05:16:19,003 [salt.state       :1813][INFO    ][6678] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-10-30 05:16:19,005 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6678] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-10-30 05:16:20,376 [salt.state       :300 ][INFO    ][6678] {'pid': 6732, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-10-30 05:16:20,376 [salt.state       :1951][INFO    ][6678] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:16:20.376619 duration_in_ms=1373.27
2019-10-30 05:16:20,378 [salt.state       :1780][INFO    ][6678] Running state [maas_machines_storage_cmp002_lvm] at time 05:16:20.378167
2019-10-30 05:16:20,378 [salt.state       :1813][INFO    ][6678] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-10-30 05:16:21,796 [salt.loaded.ext.module.maasng:610 ][INFO    ][6678] af86da
2019-10-30 05:16:21,797 [salt.loaded.ext.module.maasng:626 ][INFO    ][6678] sda
2019-10-30 05:16:22,538 [salt.loaded.ext.module.maasng:361 ][INFO    ][6678] af86da
2019-10-30 05:16:22,677 [salt.loaded.ext.module.maasng:367 ][INFO    ][6678] [{u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'125e57e2-3b18-4b8c-9293-6914fe2554cf', u'resource_uri': u'/MAAS/api/2.0/nodes/af86da/blockdevices/5/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'af86da', u'filesystem': {u'label': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'b49d0a3c-be28-457a-85f8-5f76b705d278', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 5, u'type': u'partition', u'id': 7, u'size': 2397992648704}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'af86da', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'size': 2397998940160, u'used_size': 2397998940160, u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/af86da/blockdevices/5/'}, {u'model': None, u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'53c80894-02ea-4fee-a5b3-f6b15611118f', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'af86da', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'ae5d3f6c-eed4-490b-81ee-49ad9be6e8e3', u'mount_options': None}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'size': 2397988454400, u'used_size': 2397988454400, u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/af86da/blockdevices/12/'}]
2019-10-30 05:16:22,677 [salt.loaded.ext.module.maasng:632 ][INFO    ][6678] vgroot
2019-10-30 05:16:22,678 [salt.loaded.ext.module.maasng:635 ][INFO    ][6678] lvroot
2019-10-30 05:16:22,678 [salt.loaded.ext.module.maasng:639 ][INFO    ][6678] 107374182400
2019-10-30 05:16:23,440 [salt.loaded.ext.module.maasng:645 ][INFO    ][6678] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'ip_addresses': [u'192.168.11.42'], 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'memory_test_status_name': u'Unknown', u'fqdn': u'cmp002.maas', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'commissioning_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default'}, u'resource_uri': u'/MAAS/api/2.0/machines/af86da/', u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'af86da', u'power_state': u'off', u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'uuid': u'39173ffd-d596-4b37-b2e1-63aaa92e57ce', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'uuid': u'b9ac55e0-7fe1-44af-a00c-19b579ebf284', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'af86da', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 13, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/af86da/blockdevices/13/'}], u'blockdevice_set': [{u'size': 2397998940160, u'uuid': None, u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'uuid': u'e7c41684-a4cc-4fa6-bcb4-9c689325f27c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'af86da', u'device_id': 5, u'filesystem': {u'uuid': u'f93c6ee2-493e-4be1-98fb-9847a510c5f1', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 8, u'resource_uri': u'/MAAS/api/2.0/nodes/af86da/blockdevices/5/partition/8'}], u'filesystem': None, u'name': u'sda', u'system_id': u'af86da', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'used_size': 2397998940160, u'id': 5, u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/af86da/blockdevices/5/'}, {u'size': 107374182400, u'uuid': u'39173ffd-d596-4b37-b2e1-63aaa92e57ce', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'partitions': [], u'filesystem': {u'uuid': u'b9ac55e0-7fe1-44af-a00c-19b579ebf284', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'name': u'vgroot-lvroot', u'system_id': u'af86da', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 13, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/af86da/blockdevices/13/'}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'af86da', u'id': 8}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'size': 2397998940160, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'e7c41684-a4cc-4fa6-bcb4-9c689325f27c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'af86da', u'device_id': 5, u'filesystem': {u'uuid': u'f93c6ee2-493e-4be1-98fb-9847a510c5f1', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 8, u'resource_uri': u'/MAAS/api/2.0/nodes/af86da/blockdevices/5/partition/8'}], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'af86da', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'used_size': 2397998940160, u'id': 5, u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/af86da/blockdevices/5/'}, u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'pxrgk4', 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': 43, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'pxrgk4', 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'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'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'pxrgk4', 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'params': u'', u'mac_address': u'00:25:b5:a0:00:6a', u'parents': [], u'system_id': u'af86da', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/af86da/interfaces/4/'}, {u'name': u'enp8s0', u'links': [{u'id': 44, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'params': u'', u'mac_address': u'00:25:b5:a0:00:6c', u'parents': [], u'system_id': u'af86da', u'type': u'physical', u'id': 21, u'resource_uri': u'/MAAS/api/2.0/nodes/af86da/interfaces/21/'}, {u'name': u'enp7s0', u'links': [{u'id': 45, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'params': u'', u'mac_address': u'00:25:b5:a0:00:6b', u'parents': [], u'system_id': u'af86da', u'type': u'physical', u'id': 22, u'resource_uri': u'/MAAS/api/2.0/nodes/af86da/interfaces/22/'}, {u'name': u'enp9s0', u'links': [{u'id': 46, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'params': u'', u'mac_address': u'00:25:b5:a0:00:6d', u'parents': [], u'system_id': u'af86da', u'type': u'physical', u'id': 23, u'resource_uri': u'/MAAS/api/2.0/nodes/af86da/interfaces/23/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'storage_test_status': 2, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 2397998940160, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'e7c41684-a4cc-4fa6-bcb4-9c689325f27c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'af86da', u'device_id': 5, u'filesystem': {u'uuid': u'f93c6ee2-493e-4be1-98fb-9847a510c5f1', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 8, u'resource_uri': u'/MAAS/api/2.0/nodes/af86da/blockdevices/5/partition/8'}], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'af86da', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'used_size': 2397998940160, u'id': 5, u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/af86da/blockdevices/5/'}], u'netboot': True, u'osystem': u'', u'node_type': 0, u'disable_ipv4': False, u'commissioning_status': 2, u'min_hwe_kernel': u'ga-18.04', u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'pxrgk4', 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': 43, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'pxrgk4', 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'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'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'pxrgk4', 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'params': u'', u'mac_address': u'00:25:b5:a0:00:6a', u'parents': [], u'system_id': u'af86da', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/af86da/interfaces/4/'}, u'current_commissioning_result_id': 2, u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-10-30 05:16:23,443 [salt.state       :300 ][INFO    ][6678] {'new': {'storage_layout': 'lvm'}}
2019-10-30 05:16:23,443 [salt.state       :1951][INFO    ][6678] Completed state [maas_machines_storage_cmp002_lvm] at time 05:16:23.443336 duration_in_ms=3065.167
2019-10-30 05:16:23,444 [salt.state       :1780][INFO    ][6678] Running state [maas_machines_storage_cmp001_lvm] at time 05:16:23.443945
2019-10-30 05:16:23,444 [salt.state       :1813][INFO    ][6678] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-10-30 05:16:24,771 [salt.loaded.ext.module.maasng:610 ][INFO    ][6678] 46mknt
2019-10-30 05:16:24,772 [salt.loaded.ext.module.maasng:626 ][INFO    ][6678] sda
2019-10-30 05:16:25,472 [salt.loaded.ext.module.maasng:361 ][INFO    ][6678] 46mknt
2019-10-30 05:16:25,599 [salt.loaded.ext.module.maasng:367 ][INFO    ][6678] [{u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'226bb0d9-7d33-414d-92b4-cfd3c24350e3', u'resource_uri': u'/MAAS/api/2.0/nodes/46mknt/blockdevices/3/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'46mknt', u'filesystem': {u'mount_options': None, u'uuid': u'f5ea7f20-d3de-4842-aa8e-73733eca9ff2', u'fstype': u'lvm-pv', u'mount_point': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 3, u'type': u'partition', u'id': 6, u'size': 2397992648704}], u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'46mknt', 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'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/46mknt/blockdevices/3/', u'type': u'physical', u'id': 3, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}, {u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 2397988454400, u'partitions': [], u'filesystem': {u'mount_options': None, u'uuid': u'259479ee-a2e4-4c91-b328-7c8d30f3d82c', u'fstype': u'ext4', u'mount_point': u'/', u'label': u'root'}, u'uuid': u'92530d82-12ec-4d68-9829-24cf691caf5b', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'46mknt', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/46mknt/blockdevices/11/', u'type': u'virtual', u'id': 11, u'serial': None, u'size': 2397988454400}]
2019-10-30 05:16:25,600 [salt.loaded.ext.module.maasng:632 ][INFO    ][6678] vgroot
2019-10-30 05:16:25,600 [salt.loaded.ext.module.maasng:635 ][INFO    ][6678] lvroot
2019-10-30 05:16:25,600 [salt.loaded.ext.module.maasng:639 ][INFO    ][6678] 107374182400
2019-10-30 05:16:26,314 [salt.loaded.ext.module.maasng:645 ][INFO    ][6678] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'memory_test_status': -1, u'boot_interface': {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'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'pxrgk4', 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'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'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'pxrgk4', 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'mode': u'dhcp', u'id': 36}], u'tags': [], u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'pxrgk4', 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'enabled': True, u'id': 5, u'effective_mtu': 1500, u'mac_address': u'00:25:b5:a0:00:5a', u'parents': [], u'params': u'', u'system_id': u'46mknt', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/46mknt/interfaces/5/'}, u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'min_hwe_kernel': u'ga-18.04', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'description': u''}, 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'46mknt', 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'ip_addresses': [u'192.168.11.38'], u'blockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'f3d22de5-27e2-4932-a6fb-95fd6283e563', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'46mknt', u'device_id': 3, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'3efe4b79-65be-4843-8e9e-f9bcc88d6c22', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/46mknt/blockdevices/3/partition/9', u'type': u'partition', u'id': 9, u'size': 2397992648704}], u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'46mknt', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'type': u'physical', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/nodes/46mknt/blockdevices/3/'}, {u'size': 107374182400, u'model': None, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'97e9d65d-1769-4d9c-abb6-8d2a29af00bf', u'fstype': u'ext4'}, u'uuid': u'7e24cc00-99b1-4942-8167-6f4a18a16156', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'46mknt', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/46mknt/blockdevices/14/'}], u'status': 4, u'bcaches': [], u'cpu_count': 16, u'power_state': u'off', u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'46mknt', u'id': 9}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'f3d22de5-27e2-4932-a6fb-95fd6283e563', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'46mknt', u'device_id': 3, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'3efe4b79-65be-4843-8e9e-f9bcc88d6c22', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/46mknt/blockdevices/3/partition/9', u'type': u'partition', u'id': 9, u'size': 2397992648704}], u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'46mknt', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'type': u'physical', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/nodes/46mknt/blockdevices/3/'}, u'current_testing_result_id': 5, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'f3d22de5-27e2-4932-a6fb-95fd6283e563', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'46mknt', u'device_id': 3, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'3efe4b79-65be-4843-8e9e-f9bcc88d6c22', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/46mknt/blockdevices/3/partition/9', u'type': u'partition', u'id': 9, u'size': 2397992648704}], u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'46mknt', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'type': u'physical', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/nodes/46mknt/blockdevices/3/'}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'node_type': 0, u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'97e9d65d-1769-4d9c-abb6-8d2a29af00bf', u'fstype': u'ext4'}, u'uuid': u'7e24cc00-99b1-4942-8167-6f4a18a16156', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'46mknt', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/46mknt/blockdevices/14/'}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'interface_set': [{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'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'pxrgk4', 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'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'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'pxrgk4', 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'mode': u'dhcp', u'id': 36}], u'tags': [], u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'pxrgk4', 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'enabled': True, u'id': 5, u'effective_mtu': 1500, u'mac_address': u'00:25:b5:a0:00:5a', u'parents': [], u'params': u'', u'system_id': u'46mknt', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/46mknt/interfaces/5/'}, {u'discovered': None, u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 37}], u'tags': [], u'vlan': {u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'id': 10, u'effective_mtu': 1500, u'mac_address': u'00:25:b5:a0:00:5c', u'parents': [], u'params': u'', u'system_id': u'46mknt', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/46mknt/interfaces/10/'}, {u'discovered': None, u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 39}], u'tags': [], u'vlan': {u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'id': 11, u'effective_mtu': 1500, u'mac_address': u'00:25:b5:a0:00:5b', u'parents': [], u'params': u'', u'system_id': u'46mknt', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/46mknt/interfaces/11/'}, {u'discovered': None, u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 41}], u'tags': [], u'vlan': {u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'id': 15, u'effective_mtu': 1500, u'mac_address': u'00:25:b5:a0:00:5d', u'parents': [], u'params': u'', u'system_id': u'46mknt', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/46mknt/interfaces/15/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/46mknt/'}
2019-10-30 05:16:26,316 [salt.state       :300 ][INFO    ][6678] {'new': {'storage_layout': 'lvm'}}
2019-10-30 05:16:26,316 [salt.state       :1951][INFO    ][6678] Completed state [maas_machines_storage_cmp001_lvm] at time 05:16:26.316274 duration_in_ms=2872.329
2019-10-30 05:16:26,319 [salt.minion      :1711][INFO    ][6678] Returning information for job: 20191030051615128676
2019-10-30 05:16:26,939 [salt.minion      :1308][INFO    ][3140] User sudo_ubuntu Executing command state.apply with jid 20191030051626926338
2019-10-30 05:16:26,963 [salt.minion      :1432][INFO    ][6750] Starting a new job with PID 6750
2019-10-30 05:16:27,735 [salt.state       :915 ][INFO    ][6750] Loading fresh modules for state activity
2019-10-30 05:16:27,787 [salt.fileclient  :1219][INFO    ][6750] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-10-30 05:16:27,833 [salt.state       :1780][INFO    ][6750] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:16:27.833872
2019-10-30 05:16:27,834 [salt.state       :1813][INFO    ][6750] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-10-30 05:16:27,836 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6750] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-10-30 05:16:29,266 [salt.state       :300 ][INFO    ][6750] {'pid': 6761, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-10-30 05:16:29,266 [salt.state       :1951][INFO    ][6750] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:16:29.266871 duration_in_ms=1432.999
2019-10-30 05:16:29,269 [salt.state       :1780][INFO    ][6750] Running state [maas.deploy_machines] at time 05:16:29.269106
2019-10-30 05:16:29,269 [salt.state       :1813][INFO    ][6750] Executing state module.run for [maas.deploy_machines]
2019-10-30 05:16:29,270 [salt.utils.decorators:613 ][WARNING ][6750] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-10-30 05:16:30,009 [salt.loaded.ext.module.maas:684 ][INFO    ][6750] deploymachines hwe_kernel=ga-18.04 system_id=af86da distro_series=bionic
2019-10-30 05:16:32,519 [salt.loaded.ext.module.maas:684 ][INFO    ][6750] deploymachines hwe_kernel=ga-18.04 system_id=46mknt distro_series=bionic
2019-10-30 05:16:34,722 [salt.loaded.ext.module.maas:684 ][INFO    ][6750] deploymachines hwe_kernel=ga-18.04 system_id=p6cnbk distro_series=bionic
2019-10-30 05:16:37,041 [salt.loaded.ext.module.maas:684 ][INFO    ][6750] deploymachines hwe_kernel=ga-18.04 system_id=psnhkp distro_series=bionic
2019-10-30 05:16:39,581 [salt.loaded.ext.module.maas:684 ][INFO    ][6750] deploymachines hwe_kernel=ga-18.04 system_id=pqnhss distro_series=bionic
2019-10-30 05:16:42,008 [salt.minion      :1308][INFO    ][3140] User sudo_ubuntu Executing command saltutil.find_job with jid 20191030051641999156
2019-10-30 05:16:42,019 [salt.state       :300 ][INFO    ][6750] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-10-30 05:16:42,020 [salt.state       :1951][INFO    ][6750] Completed state [maas.deploy_machines] at time 05:16:42.019961 duration_in_ms=12750.855
2019-10-30 05:16:42,023 [salt.minion      :1711][INFO    ][6750] Returning information for job: 20191030051626926338
2019-10-30 05:16:42,034 [salt.minion      :1432][INFO    ][7058] Starting a new job with PID 7058
2019-10-30 05:16:42,050 [salt.minion      :1711][INFO    ][7058] Returning information for job: 20191030051641999156
2019-10-30 05:16:42,538 [salt.minion      :1308][INFO    ][3140] User sudo_ubuntu Executing command state.apply with jid 20191030051642524644
2019-10-30 05:16:42,563 [salt.minion      :1432][INFO    ][7064] Starting a new job with PID 7064
2019-10-30 05:16:46,652 [salt.state       :915 ][INFO    ][7064] Loading fresh modules for state activity
2019-10-30 05:16:46,705 [salt.fileclient  :1219][INFO    ][7064] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-10-30 05:16:46,749 [salt.state       :1780][INFO    ][7064] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:16:46.749568
2019-10-30 05:16:46,749 [salt.state       :1813][INFO    ][7064] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-10-30 05:16:46,751 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7064] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-10-30 05:16:48,146 [salt.state       :300 ][INFO    ][7064] {'pid': 7077, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-10-30 05:16:48,147 [salt.state       :1951][INFO    ][7064] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:16:48.146921 duration_in_ms=1397.353
2019-10-30 05:16:48,150 [salt.state       :1780][INFO    ][7064] Running state [maas.wait_for_machine_status] at time 05:16:48.150031
2019-10-30 05:16:48,150 [salt.state       :1813][INFO    ][7064] Executing state module.run for [maas.wait_for_machine_status]
2019-10-30 05:16:48,151 [salt.utils.decorators:613 ][WARNING ][7064] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-10-30 05:16:51,385 [salt.loaded.ext.module.maas:1023][INFO    ][7064] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.77535295s left)
2019-10-30 05:16:57,609 [salt.minion      :1308][INFO    ][3140] User sudo_ubuntu Executing command saltutil.find_job with jid 20191030051657596214
2019-10-30 05:16:57,635 [salt.minion      :1432][INFO    ][7102] Starting a new job with PID 7102
2019-10-30 05:16:57,657 [salt.minion      :1711][INFO    ][7102] Returning information for job: 20191030051657596214
2019-10-30 05:17:25,010 [salt.loaded.ext.module.maas:1023][INFO    ][7064] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.15051007s left)
2019-10-30 05:17:27,657 [salt.minion      :1308][INFO    ][3140] User sudo_ubuntu Executing command saltutil.find_job with jid 20191030051727643157
2019-10-30 05:17:27,683 [salt.minion      :1432][INFO    ][7136] Starting a new job with PID 7136
2019-10-30 05:17:27,705 [salt.minion      :1711][INFO    ][7136] Returning information for job: 20191030051727643157
2019-10-30 05:17:57,707 [salt.minion      :1308][INFO    ][3140] User sudo_ubuntu Executing command saltutil.find_job with jid 20191030051757696029
2019-10-30 05:17:57,732 [salt.minion      :1432][INFO    ][7180] Starting a new job with PID 7180
2019-10-30 05:17:57,755 [salt.minion      :1711][INFO    ][7180] Returning information for job: 20191030051757696029
2019-10-30 05:17:58,520 [salt.loaded.ext.module.maas:1023][INFO    ][7064] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2179.64019799s left)
2019-10-30 05:18:27,757 [salt.minion      :1308][INFO    ][3140] User sudo_ubuntu Executing command saltutil.find_job with jid 20191030051827745396
2019-10-30 05:18:27,783 [salt.minion      :1432][INFO    ][7297] Starting a new job with PID 7297
2019-10-30 05:18:27,802 [salt.minion      :1711][INFO    ][7297] Returning information for job: 20191030051827745396
2019-10-30 05:18:31,377 [salt.loaded.ext.module.maas:1023][INFO    ][7064] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2146.78347802s left)
2019-10-30 05:18:57,817 [salt.minion      :1308][INFO    ][3140] User sudo_ubuntu Executing command saltutil.find_job with jid 20191030051857803876
2019-10-30 05:18:57,841 [salt.minion      :1432][INFO    ][7531] Starting a new job with PID 7531
2019-10-30 05:18:57,863 [salt.minion      :1711][INFO    ][7531] Returning information for job: 20191030051857803876
2019-10-30 05:19:05,070 [salt.loaded.ext.module.maas:1023][INFO    ][7064] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2113.09082007s left)
2019-10-30 05:19:27,876 [salt.minion      :1308][INFO    ][3140] User sudo_ubuntu Executing command saltutil.find_job with jid 20191030051927862424
2019-10-30 05:19:27,902 [salt.minion      :1432][INFO    ][8042] Starting a new job with PID 8042
2019-10-30 05:19:27,923 [salt.minion      :1711][INFO    ][8042] Returning information for job: 20191030051927862424
2019-10-30 05:19:38,498 [salt.loaded.ext.module.maas:1023][INFO    ][7064] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2079.66286206s left)
2019-10-30 05:19:57,931 [salt.minion      :1308][INFO    ][3140] User sudo_ubuntu Executing command saltutil.find_job with jid 20191030051957917618
2019-10-30 05:19:57,956 [salt.minion      :1432][INFO    ][8313] Starting a new job with PID 8313
2019-10-30 05:19:57,979 [salt.minion      :1711][INFO    ][8313] Returning information for job: 20191030051957917618
2019-10-30 05:20:11,740 [salt.loaded.ext.module.maas:1023][INFO    ][7064] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2046.42049909s left)
2019-10-30 05:20:27,993 [salt.minion      :1308][INFO    ][3140] User sudo_ubuntu Executing command saltutil.find_job with jid 20191030052027980048
2019-10-30 05:20:28,020 [salt.minion      :1432][INFO    ][8472] Starting a new job with PID 8472
2019-10-30 05:20:28,041 [salt.minion      :1711][INFO    ][8472] Returning information for job: 20191030052027980048
2019-10-30 05:20:45,433 [salt.loaded.ext.module.maas:1023][INFO    ][7064] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2012.72749615s left)
2019-10-30 05:20:58,059 [salt.minion      :1308][INFO    ][3140] User sudo_ubuntu Executing command saltutil.find_job with jid 20191030052058045878
2019-10-30 05:20:58,085 [salt.minion      :1432][INFO    ][8525] Starting a new job with PID 8525
2019-10-30 05:20:58,108 [salt.minion      :1711][INFO    ][8525] Returning information for job: 20191030052058045878
2019-10-30 05:21:18,876 [salt.loaded.ext.module.maas:1023][INFO    ][7064] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1979.2847681s left)
2019-10-30 05:21:28,137 [salt.minion      :1308][INFO    ][3140] User sudo_ubuntu Executing command saltutil.find_job with jid 20191030052128118927
2019-10-30 05:21:28,162 [salt.minion      :1432][INFO    ][8678] Starting a new job with PID 8678
2019-10-30 05:21:28,185 [salt.minion      :1711][INFO    ][8678] Returning information for job: 20191030052128118927
2019-10-30 05:21:52,345 [salt.loaded.ext.module.maas:1023][INFO    ][7064] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1945.81545806s left)
2019-10-30 05:21:58,214 [salt.minion      :1308][INFO    ][3140] User sudo_ubuntu Executing command saltutil.find_job with jid 20191030052158200416
2019-10-30 05:21:58,240 [salt.minion      :1432][INFO    ][8777] Starting a new job with PID 8777
2019-10-30 05:21:58,262 [salt.minion      :1711][INFO    ][8777] Returning information for job: 20191030052158200416
2019-10-30 05:22:25,913 [salt.loaded.ext.module.maas:1023][INFO    ][7064] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1912.2473011s left)
2019-10-30 05:22:28,298 [salt.minion      :1308][INFO    ][3140] User sudo_ubuntu Executing command saltutil.find_job with jid 20191030052228285245
2019-10-30 05:22:28,325 [salt.minion      :1432][INFO    ][9122] Starting a new job with PID 9122
2019-10-30 05:22:28,347 [salt.minion      :1711][INFO    ][9122] Returning information for job: 20191030052228285245
2019-10-30 05:22:58,463 [salt.minion      :1308][INFO    ][3140] User sudo_ubuntu Executing command saltutil.find_job with jid 20191030052258450594
2019-10-30 05:22:58,485 [salt.minion      :1432][INFO    ][9298] Starting a new job with PID 9298
2019-10-30 05:22:58,499 [salt.minion      :1711][INFO    ][9298] Returning information for job: 20191030052258450594
2019-10-30 05:22:59,229 [salt.loaded.ext.module.maas:1023][INFO    ][7064] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1878.93168497s left)
2019-10-30 05:23:28,533 [salt.minion      :1308][INFO    ][3140] User sudo_ubuntu Executing command saltutil.find_job with jid 20191030052328520806
2019-10-30 05:23:28,558 [salt.minion      :1432][INFO    ][9451] Starting a new job with PID 9451
2019-10-30 05:23:28,580 [salt.minion      :1711][INFO    ][9451] Returning information for job: 20191030052328520806
2019-10-30 05:23:32,599 [salt.loaded.ext.module.maas:1023][INFO    ][7064] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1845.56114316s left)
2019-10-30 05:23:58,627 [salt.minion      :1308][INFO    ][3140] User sudo_ubuntu Executing command saltutil.find_job with jid 20191030052358614471
2019-10-30 05:23:58,652 [salt.minion      :1432][INFO    ][9522] Starting a new job with PID 9522
2019-10-30 05:23:58,674 [salt.minion      :1711][INFO    ][9522] Returning information for job: 20191030052358614471
2019-10-30 05:24:05,817 [salt.loaded.ext.module.maas:1023][INFO    ][7064] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1812.34313917s left)
2019-10-30 05:24:28,802 [salt.minion      :1308][INFO    ][3140] User sudo_ubuntu Executing command saltutil.find_job with jid 20191030052428794791
2019-10-30 05:24:28,826 [salt.minion      :1432][INFO    ][9711] Starting a new job with PID 9711
2019-10-30 05:24:28,846 [salt.minion      :1711][INFO    ][9711] Returning information for job: 20191030052428794791
2019-10-30 05:24:39,345 [salt.state       :300 ][INFO    ][7064] {'ret': True}
2019-10-30 05:24:39,346 [salt.state       :1951][INFO    ][7064] Completed state [maas.wait_for_machine_status] at time 05:24:39.346040 duration_in_ms=471196.008
2019-10-30 05:24:39,349 [salt.minion      :1711][INFO    ][7064] Returning information for job: 20191030051642524644
2019-10-30 06:09:02,417 [salt.utils.schedule:1377][INFO    ][3140] Running scheduled job: __mine_interval
2019-10-30 07:03:02,982 [salt.minion      :1308][INFO    ][3140] User sudo_ubuntu Executing command cp.push_dir with jid 20191030070302971149
2019-10-30 07:03:03,005 [salt.minion      :1432][INFO    ][16854] Starting a new job with PID 16854
