2020-01-09 01:41:48,154 [salt.utils.decorators:613 ][WARNING ][2120] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-09 01:41:48,984 [salt.utils.decorators:613 ][WARNING ][2120] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-09 01:41:52,504 [salt.loaded.int.states.file:2298][WARNING ][2466] 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.
2020-01-09 01:42:18,068 [salt.state       :2022][WARNING ][2813] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2020-01-09 01:42:21,290 [salt.utils.decorators:613 ][WARNING ][2813] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-09 01:57:29,701 [salt.utils.decorators:613 ][WARNING ][2813] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-09 02:05:28,356 [salt.utils.decorators:613 ][WARNING ][2813] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-09 02:05:29,601 [salt.utils.decorators:613 ][WARNING ][2813] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-09 02:05:31,187 [salt.loaded.ext.module.maas:160 ][ERROR   ][2813] Failed for object commissioning_distro_series reason {"commissioning_distro_series": ["'bionic' is not a valid commissioning_distro_series.  It should be one of: '---'."]}
2020-01-09 02:05:32,351 [salt.loaded.ext.module.maas:160 ][ERROR   ][2813] 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: ''."]}
2020-01-09 02:05:32,352 [salt.state       :302 ][ERROR   ][2813] 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_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', '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']}
2020-01-09 02:05:32,534 [salt.loaded.ext.module.maasng:1008][WARNING ][2813] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2020-01-09 02:05:32,536 [salt.loaded.ext.module.maasng:1011][WARNING ][2813] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2020-01-09 02:05:33,784 [salt.loaded.ext.module.maasng:1235][WARNING ][2813] Ignoring parameter vlan:0
2020-01-09 02:05:34,918 [salt.utils.decorators:613 ][WARNING ][2813] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-09 02:05:39,656 [salt.loaded.int.module.cmdmod:395 ][INFO    ][35860] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2020-01-09 02:05:39,692 [salt.loaded.int.module.cmdmod:395 ][INFO    ][35860] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2020-01-09 02:05:39,757 [salt.utils.parsers:1051][WARNING ][336] Minion received a SIGTERM. Exiting.
2020-01-09 02:05:41,331 [salt.cli.daemons :293 ][INFO    ][35926] Setting up the Salt Minion "mas01.mcp-fdio-noha.local"
2020-01-09 02:05:41,451 [salt.cli.daemons :82  ][INFO    ][35926] Starting up the Salt Minion
2020-01-09 02:05:41,451 [salt.utils.event :1017][INFO    ][35926] Starting pull socket on /var/run/salt/minion/minion_event_38d774b16c_pull.ipc
2020-01-09 02:05:42,577 [salt.minion      :976 ][INFO    ][35926] Creating minion process manager
2020-01-09 02:05:44,521 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][35926] Executing command ['date', '+%z'] in directory '/root'
2020-01-09 02:05:44,550 [salt.utils.schedule:568 ][INFO    ][35926] Updating job settings for scheduled job: __mine_interval
2020-01-09 02:05:44,552 [salt.minion      :1108][INFO    ][35926] Added mine.update to scheduler
2020-01-09 02:05:44,557 [salt.minion      :1975][INFO    ][35926] Minion is starting as user 'root'
2020-01-09 02:05:44,577 [salt.minion      :2336][INFO    ][35926] Minion is ready to receive requests!
2020-01-09 02:05:46,284 [salt.minion      :1308][INFO    ][35926] User sudo_ubuntu Executing command state.apply with jid 20200109020546274007
2020-01-09 02:05:46,305 [salt.minion      :1432][INFO    ][36007] Starting a new job with PID 36007
2020-01-09 02:05:54,360 [salt.state       :915 ][INFO    ][36007] Loading fresh modules for state activity
2020-01-09 02:05:55,536 [salt.state       :1780][INFO    ][36007] Running state [maas-region-controller] at time 02:05:55.536051
2020-01-09 02:05:55,536 [salt.state       :1813][INFO    ][36007] Executing state pkg.installed for [maas-region-controller]
2020-01-09 02:05:55,537 [salt.loaded.int.module.cmdmod:395 ][INFO    ][36007] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2020-01-09 02:05:55,652 [salt.state       :300 ][INFO    ][36007] All specified packages are already installed
2020-01-09 02:05:55,653 [salt.state       :1951][INFO    ][36007] Completed state [maas-region-controller] at time 02:05:55.653225 duration_in_ms=117.175
2020-01-09 02:05:55,653 [salt.state       :1780][INFO    ][36007] Running state [python-oauth] at time 02:05:55.653837
2020-01-09 02:05:55,654 [salt.state       :1813][INFO    ][36007] Executing state pkg.installed for [python-oauth]
2020-01-09 02:05:55,663 [salt.state       :300 ][INFO    ][36007] All specified packages are already installed
2020-01-09 02:05:55,664 [salt.state       :1951][INFO    ][36007] Completed state [python-oauth] at time 02:05:55.664100 duration_in_ms=10.263
2020-01-09 02:05:55,668 [salt.state       :1780][INFO    ][36007] Running state [/etc/maas/regiond.conf] at time 02:05:55.668688
2020-01-09 02:05:55,668 [salt.state       :1813][INFO    ][36007] Executing state file.replace for [/etc/maas/regiond.conf]
2020-01-09 02:05:55,674 [salt.state       :300 ][INFO    ][36007] No changes needed to be made
2020-01-09 02:05:55,674 [salt.state       :1951][INFO    ][36007] Completed state [/etc/maas/regiond.conf] at time 02:05:55.674780 duration_in_ms=6.092
2020-01-09 02:05:55,675 [salt.state       :1780][INFO    ][36007] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:05:55.675264
2020-01-09 02:05:55,675 [salt.state       :1813][INFO    ][36007] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2020-01-09 02:05:55,736 [salt.state       :300 ][INFO    ][36007] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2020-01-09 02:05:55,737 [salt.state       :1951][INFO    ][36007] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:05:55.737064 duration_in_ms=61.799
2020-01-09 02:05:55,737 [salt.state       :1780][INFO    ][36007] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:05:55.737642
2020-01-09 02:05:55,737 [salt.state       :1813][INFO    ][36007] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2020-01-09 02:05:55,747 [salt.state       :300 ][INFO    ][36007] No changes needed to be made
2020-01-09 02:05:55,747 [salt.state       :1951][INFO    ][36007] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:05:55.747529 duration_in_ms=9.887
2020-01-09 02:05:55,748 [salt.state       :1780][INFO    ][36007] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:05:55.748065
2020-01-09 02:05:55,748 [salt.state       :1813][INFO    ][36007] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2020-01-09 02:05:55,751 [salt.state       :300 ][INFO    ][36007] No changes needed to be made
2020-01-09 02:05:55,752 [salt.state       :1951][INFO    ][36007] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:05:55.751971 duration_in_ms=3.906
2020-01-09 02:05:55,752 [salt.state       :1780][INFO    ][36007] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:05:55.752467
2020-01-09 02:05:55,752 [salt.state       :1813][INFO    ][36007] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2020-01-09 02:05:55,784 [salt.state       :300 ][INFO    ][36007] No changes needed to be made
2020-01-09 02:05:55,785 [salt.state       :1951][INFO    ][36007] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:05:55.785022 duration_in_ms=32.555
2020-01-09 02:05:55,785 [salt.state       :1780][INFO    ][36007] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:05:55.785816
2020-01-09 02:05:55,786 [salt.state       :1813][INFO    ][36007] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2020-01-09 02:05:55,803 [salt.state       :300 ][INFO    ][36007] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2020-01-09 02:05:55,803 [salt.state       :1951][INFO    ][36007] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:05:55.803620 duration_in_ms=17.803
2020-01-09 02:05:55,807 [salt.state       :1780][INFO    ][36007] Running state [a2enmod headers] at time 02:05:55.807570
2020-01-09 02:05:55,807 [salt.state       :1813][INFO    ][36007] Executing state cmd.run for [a2enmod headers]
2020-01-09 02:05:55,808 [salt.loaded.int.module.cmdmod:395 ][INFO    ][36007] Executing command 'a2enmod headers' in directory '/root'
2020-01-09 02:05:55,873 [salt.state       :300 ][INFO    ][36007] {'pid': 36030, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2020-01-09 02:05:55,874 [salt.state       :1951][INFO    ][36007] Completed state [a2enmod headers] at time 02:05:55.873893 duration_in_ms=66.322
2020-01-09 02:05:55,874 [salt.state       :1780][INFO    ][36007] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:05:55.874636
2020-01-09 02:05:55,875 [salt.state       :1813][INFO    ][36007] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2020-01-09 02:05:55,899 [salt.state       :300 ][INFO    ][36007] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2020-01-09 02:05:55,899 [salt.state       :1951][INFO    ][36007] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:05:55.899507 duration_in_ms=24.87
2020-01-09 02:05:55,900 [salt.state       :1780][INFO    ][36007] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:05:55.900309
2020-01-09 02:05:55,900 [salt.state       :1813][INFO    ][36007] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2020-01-09 02:05:55,960 [salt.state       :300 ][INFO    ][36007] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2020-01-09 02:05:55,960 [salt.state       :1951][INFO    ][36007] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:05:55.960358 duration_in_ms=60.05
2020-01-09 02:05:55,961 [salt.state       :1780][INFO    ][36007] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:05:55.960980
2020-01-09 02:05:55,962 [salt.state       :1813][INFO    ][36007] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2020-01-09 02:05:56,022 [salt.state       :300 ][INFO    ][36007] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2020-01-09 02:05:56,022 [salt.state       :1951][INFO    ][36007] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:05:56.022900 duration_in_ms=61.919
2020-01-09 02:05:56,023 [salt.state       :1780][INFO    ][36007] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:05:56.023399
2020-01-09 02:05:56,023 [salt.state       :1813][INFO    ][36007] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2020-01-09 02:05:56,112 [salt.state       :300 ][INFO    ][36007] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2020-01-09 02:05:56,112 [salt.state       :1951][INFO    ][36007] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:05:56.112730 duration_in_ms=89.33
2020-01-09 02:05:56,113 [salt.state       :1780][INFO    ][36007] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_bionic] at time 02:05:56.113225
2020-01-09 02:05:56,113 [salt.state       :1813][INFO    ][36007] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_bionic]
2020-01-09 02:05:56,177 [salt.state       :300 ][INFO    ][36007] File /etc/maas/preseeds/curtin_userdata_amd64_generic_bionic is in the correct state
2020-01-09 02:05:56,178 [salt.state       :1951][INFO    ][36007] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_bionic] at time 02:05:56.177243 duration_in_ms=64.018
2020-01-09 02:05:56,178 [salt.state       :1780][INFO    ][36007] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_bionic] at time 02:05:56.178619
2020-01-09 02:05:56,178 [salt.state       :1813][INFO    ][36007] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_bionic]
2020-01-09 02:05:56,228 [salt.state       :300 ][INFO    ][36007] File /etc/maas/preseeds/curtin_userdata_arm64_generic_bionic is in the correct state
2020-01-09 02:05:56,228 [salt.state       :1951][INFO    ][36007] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_bionic] at time 02:05:56.228619 duration_in_ms=50.0
2020-01-09 02:05:56,228 [salt.state       :1780][INFO    ][36007] Running state [/root/.pgpass] at time 02:05:56.228869
2020-01-09 02:05:56,229 [salt.state       :1813][INFO    ][36007] Executing state file.managed for [/root/.pgpass]
2020-01-09 02:05:56,271 [salt.state       :300 ][INFO    ][36007] File /root/.pgpass is in the correct state
2020-01-09 02:05:56,271 [salt.state       :1951][INFO    ][36007] Completed state [/root/.pgpass] at time 02:05:56.271373 duration_in_ms=42.504
2020-01-09 02:05:56,278 [salt.state       :1780][INFO    ][36007] Running state [maas-region syncdb --noinput] at time 02:05:56.278812
2020-01-09 02:05:56,279 [salt.state       :1813][INFO    ][36007] Executing state cmd.run for [maas-region syncdb --noinput]
2020-01-09 02:05:56,279 [salt.loaded.int.module.cmdmod:395 ][INFO    ][36007] Executing command 'maas-region syncdb --noinput' in directory '/root'
2020-01-09 02:05:59,174 [salt.state       :300 ][INFO    ][36007] {'pid': 36047, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: auth, piston3, maasserver, metadataserver, sessions, sites, contenttypes\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2020-01-09 02:05:59,175 [salt.state       :1951][INFO    ][36007] Completed state [maas-region syncdb --noinput] at time 02:05:59.175226 duration_in_ms=2896.413
2020-01-09 02:05:59,175 [salt.state       :2022][WARNING ][36007] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2020-01-09 02:05:59,179 [salt.state       :1780][INFO    ][36007] Running state [maas-regiond] at time 02:05:59.179414
2020-01-09 02:05:59,179 [salt.state       :1813][INFO    ][36007] Executing state service.running for [maas-regiond]
2020-01-09 02:05:59,180 [salt.loaded.int.module.cmdmod:395 ][INFO    ][36007] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2020-01-09 02:05:59,220 [salt.loaded.int.module.cmdmod:395 ][INFO    ][36007] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2020-01-09 02:05:59,239 [salt.loaded.int.module.cmdmod:395 ][INFO    ][36007] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2020-01-09 02:05:59,258 [salt.state       :300 ][INFO    ][36007] The service maas-regiond is already running
2020-01-09 02:05:59,259 [salt.state       :1951][INFO    ][36007] Completed state [maas-regiond] at time 02:05:59.258932 duration_in_ms=79.517
2020-01-09 02:05:59,263 [salt.state       :1780][INFO    ][36007] Running state [bind9] at time 02:05:59.263529
2020-01-09 02:05:59,264 [salt.state       :1813][INFO    ][36007] Executing state service.running for [bind9]
2020-01-09 02:05:59,265 [salt.loaded.int.module.cmdmod:395 ][INFO    ][36007] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2020-01-09 02:05:59,283 [salt.loaded.int.module.cmdmod:395 ][INFO    ][36007] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2020-01-09 02:05:59,305 [salt.loaded.int.module.cmdmod:395 ][INFO    ][36007] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2020-01-09 02:05:59,327 [salt.state       :300 ][INFO    ][36007] The service bind9 is already running
2020-01-09 02:05:59,328 [salt.state       :1951][INFO    ][36007] Completed state [bind9] at time 02:05:59.328284 duration_in_ms=64.754
2020-01-09 02:05:59,331 [salt.state       :1780][INFO    ][36007] Running state [apache2] at time 02:05:59.331469
2020-01-09 02:05:59,332 [salt.state       :1813][INFO    ][36007] Executing state service.running for [apache2]
2020-01-09 02:05:59,335 [salt.loaded.int.module.cmdmod:395 ][INFO    ][36007] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2020-01-09 02:05:59,355 [salt.loaded.int.module.cmdmod:395 ][INFO    ][36007] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2020-01-09 02:05:59,376 [salt.loaded.int.module.cmdmod:395 ][INFO    ][36007] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2020-01-09 02:05:59,401 [salt.state       :300 ][INFO    ][36007] The service apache2 is already running
2020-01-09 02:05:59,402 [salt.state       :1951][INFO    ][36007] Completed state [apache2] at time 02:05:59.402066 duration_in_ms=70.597
2020-01-09 02:05:59,404 [salt.state       :1780][INFO    ][36007] Running state [maasng.wait_for_http_code] at time 02:05:59.404488
2020-01-09 02:05:59,405 [salt.state       :1813][INFO    ][36007] Executing state module.run for [maasng.wait_for_http_code]
2020-01-09 02:05:59,408 [salt.utils.decorators:613 ][WARNING ][36007] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-09 02:05:59,542 [salt.state       :300 ][INFO    ][36007] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2020-01-09 02:05:59,542 [salt.state       :1951][INFO    ][36007] Completed state [maasng.wait_for_http_code] at time 02:05:59.542660 duration_in_ms=138.172
2020-01-09 02:05:59,543 [salt.state       :1780][INFO    ][36007] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:05:59.543789
2020-01-09 02:05:59,544 [salt.state       :1813][INFO    ][36007] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2020-01-09 02:05:59,545 [salt.state       :300 ][INFO    ][36007] /var/lib/maas/.setup_admin exists
2020-01-09 02:05:59,545 [salt.state       :1951][INFO    ][36007] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:05:59.545247 duration_in_ms=1.458
2020-01-09 02:05:59,546 [salt.state       :1780][INFO    ][36007] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:05:59.546629
2020-01-09 02:05:59,546 [salt.state       :1813][INFO    ][36007] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-01-09 02:05:59,547 [salt.loaded.int.module.cmdmod:395 ][INFO    ][36007] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-01-09 02:06:01,239 [salt.state       :300 ][INFO    ][36007] {'pid': 36068, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-01-09 02:06:01,239 [salt.state       :1951][INFO    ][36007] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:06:01.239769 duration_in_ms=1693.139
2020-01-09 02:06:01,246 [salt.state       :1780][INFO    ][36007] Running state [maas_region_boot_source_resources_mirror] at time 02:06:01.246888
2020-01-09 02:06:01,247 [salt.state       :1813][INFO    ][36007] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2020-01-09 02:06:01,333 [salt.state       :300 ][INFO    ][36007] {'changes': {}}
2020-01-09 02:06:01,333 [salt.state       :1951][INFO    ][36007] Completed state [maas_region_boot_source_resources_mirror] at time 02:06:01.333892 duration_in_ms=87.003
2020-01-09 02:06:01,334 [salt.state       :1780][INFO    ][36007] Running state [maasng.boot_resources_import] at time 02:06:01.334773
2020-01-09 02:06:01,335 [salt.state       :1813][INFO    ][36007] Executing state module.run for [maasng.boot_resources_import]
2020-01-09 02:06:01,335 [salt.utils.decorators:613 ][WARNING ][36007] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-09 02:06:01,340 [salt.minion      :1308][INFO    ][35926] User sudo_ubuntu Executing command saltutil.find_job with jid 20200109020601329155
2020-01-09 02:06:01,358 [salt.minion      :1432][INFO    ][36077] Starting a new job with PID 36077
2020-01-09 02:06:01,381 [salt.minion      :1711][INFO    ][36077] Returning information for job: 20200109020601329155
2020-01-09 02:06:01,427 [salt.loaded.ext.module.maasng:1600][INFO    ][36007] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2020-01-09 02:06:06,466 [salt.loaded.ext.module.maasng:1600][INFO    ][36007] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2020-01-09 02:06:11,557 [salt.state       :300 ][INFO    ][36007] {'ret': True}
2020-01-09 02:06:11,558 [salt.state       :1951][INFO    ][36007] Completed state [maasng.boot_resources_import] at time 02:06:11.558676 duration_in_ms=10223.901
2020-01-09 02:06:11,559 [salt.state       :1780][INFO    ][36007] Running state [maas_region_boot_sources_selection_bionic] at time 02:06:11.559851
2020-01-09 02:06:11,560 [salt.state       :1813][INFO    ][36007] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_bionic]
2020-01-09 02:06:11,727 [salt.state       :300 ][INFO    ][36007] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2020-01-09 02:06:11,727 [salt.state       :1951][INFO    ][36007] Completed state [maas_region_boot_sources_selection_bionic] at time 02:06:11.727432 duration_in_ms=167.58
2020-01-09 02:06:11,728 [salt.state       :1780][INFO    ][36007] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 02:06:11.728730
2020-01-09 02:06:11,729 [salt.state       :1813][INFO    ][36007] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2020-01-09 02:06:11,732 [salt.utils.decorators:613 ][WARNING ][36007] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-09 02:06:11,732 [salt.loaded.ext.module.maasng:1771][INFO    ][36007] boot-sources sync initiated for ALL Rack's
2020-01-09 02:06:12,542 [salt.state       :300 ][INFO    ][36007] {'ret': True}
2020-01-09 02:06:12,543 [salt.state       :1951][INFO    ][36007] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 02:06:12.542956 duration_in_ms=814.226
2020-01-09 02:06:12,545 [salt.state       :1780][INFO    ][36007] Running state [maas.process_maas_config] at time 02:06:12.545020
2020-01-09 02:06:12,546 [salt.state       :1813][INFO    ][36007] Executing state module.run for [maas.process_maas_config]
2020-01-09 02:06:12,547 [salt.utils.decorators:613 ][WARNING ][36007] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-09 02:06:12,548 [salt.loaded.ext.module.maas:92  ][INFO    ][36007] maasconfig name=enable_http_proxy value=True
2020-01-09 02:06:12,599 [salt.loaded.ext.module.maas:92  ][INFO    ][36007] maasconfig name=upstream_dns value=8.8.8.8
2020-01-09 02:06:12,648 [salt.loaded.ext.module.maas:92  ][INFO    ][36007] maasconfig name=commissioning_distro_series value=bionic
2020-01-09 02:06:12,702 [salt.loaded.ext.module.maas:92  ][INFO    ][36007] maasconfig name=default_osystem value=ubuntu
2020-01-09 02:06:12,754 [salt.loaded.ext.module.maas:92  ][INFO    ][36007] maasconfig name=active_discovery_interval value=600
2020-01-09 02:06:12,803 [salt.loaded.ext.module.maas:92  ][INFO    ][36007] maasconfig name=dnssec_validation value=no
2020-01-09 02:06:12,852 [salt.loaded.ext.module.maas:92  ][INFO    ][36007] maasconfig name=maas_name value=mas01
2020-01-09 02:06:12,900 [salt.loaded.ext.module.maas:92  ][INFO    ][36007] maasconfig name=network_discovery value=enabled
2020-01-09 02:06:13,008 [salt.loaded.ext.module.maas:92  ][INFO    ][36007] maasconfig name=enable_third_party_drivers value=True
2020-01-09 02:06:13,060 [salt.loaded.ext.module.maas:92  ][INFO    ][36007] maasconfig name=default_storage_layout value=lvm
2020-01-09 02:06:13,912 [salt.loaded.ext.module.maas:92  ][INFO    ][36007] maasconfig name=ntp_external_only value=True
2020-01-09 02:06:13,965 [salt.loaded.ext.module.maas:92  ][INFO    ][36007] maasconfig name=disk_erase_with_secure_erase value=False
2020-01-09 02:06:14,015 [salt.loaded.ext.module.maas:92  ][INFO    ][36007] maasconfig name=default_distro_series value=bionic
2020-01-09 02:06:14,069 [salt.loaded.ext.module.maas:92  ][INFO    ][36007] maasconfig name=default_min_hwe_kernel value=ga-18.04
2020-01-09 02:06:14,147 [salt.state       :300 ][INFO    ][36007] {'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']}}
2020-01-09 02:06:14,148 [salt.state       :1951][INFO    ][36007] Completed state [maas.process_maas_config] at time 02:06:14.148160 duration_in_ms=1603.139
2020-01-09 02:06:14,148 [salt.state       :1780][INFO    ][36007] Running state [pxe_admin] at time 02:06:14.148867
2020-01-09 02:06:14,149 [salt.state       :1813][INFO    ][36007] Executing state maasng.fabric_present for [pxe_admin]
2020-01-09 02:06:14,198 [salt.loaded.ext.module.maasng:945 ][INFO    ][36007] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': 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'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'g4bynn', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2020-01-09 02:06:14,253 [salt.loaded.ext.module.maasng:1008][WARNING ][36007] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2020-01-09 02:06:14,254 [salt.loaded.ext.module.maasng:1011][WARNING ][36007] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2020-01-09 02:06:14,310 [salt.state       :300 ][INFO    ][36007] {'new': 'Fabric  pxe_admin created', 'result': True}
2020-01-09 02:06:14,310 [salt.state       :1951][INFO    ][36007] Completed state [pxe_admin] at time 02:06:14.310542 duration_in_ms=161.674
2020-01-09 02:06:14,310 [salt.state       :1780][INFO    ][36007] Running state [vlan 0] at time 02:06:14.310873
2020-01-09 02:06:14,311 [salt.state       :1813][INFO    ][36007] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2020-01-09 02:06:14,358 [salt.loaded.ext.module.maasng:945 ][INFO    ][36007] [{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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None, u'name': u'fabric-2', u'id': 2}, {u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'g4bynn', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u'', u'name': u'pxe_admin', u'id': 1}]
2020-01-09 02:06:14,466 [salt.loaded.ext.module.maasng:945 ][INFO    ][36007] [{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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None, u'name': u'fabric-2', u'id': 2}, {u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'g4bynn', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u'', u'name': u'pxe_admin', u'id': 1}]
2020-01-09 02:06:14,696 [salt.loaded.ext.module.maasng:945 ][INFO    ][36007] [{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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None, u'name': u'fabric-2', u'id': 2}, {u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'g4bynn', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u'', u'name': u'pxe_admin', u'id': 1}]
2020-01-09 02:06:14,782 [salt.state       :300 ][INFO    ][36007] {'new': 'Vlan untagged was updated'}
2020-01-09 02:06:14,782 [salt.state       :1951][INFO    ][36007] Completed state [vlan 0] at time 02:06:14.782328 duration_in_ms=471.454
2020-01-09 02:06:14,783 [salt.state       :1780][INFO    ][36007] Running state [192.168.11.0/24] at time 02:06:14.783450
2020-01-09 02:06:14,783 [salt.state       :1813][INFO    ][36007] Executing state maasng.subnet_present for [192.168.11.0/24]
2020-01-09 02:06:14,985 [salt.loaded.ext.module.maasng:945 ][INFO    ][36007] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-2', 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'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'g4bynn', u'fabric': u'pxe_admin', 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'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2020-01-09 02:06:14,986 [salt.loaded.ext.module.maasng:1235][WARNING ][36007] Ignoring parameter vlan:0
2020-01-09 02:06:15,044 [salt.state       :300 ][INFO    ][36007] Subnet 192.168.11.0/24 has been updated for pxe_admin
2020-01-09 02:06:15,044 [salt.state       :1951][INFO    ][36007] Completed state [192.168.11.0/24] at time 02:06:15.044512 duration_in_ms=261.061
2020-01-09 02:06:15,045 [salt.state       :1780][INFO    ][36007] Running state [maas_create_iprange_1] at time 02:06:15.045334
2020-01-09 02:06:15,045 [salt.state       :1813][INFO    ][36007] Executing state maasng.iprange_present for [maas_create_iprange_1]
2020-01-09 02:06:15,088 [salt.state       :300 ][INFO    ][36007] Iprange maas_create_iprange_1 already exist.
2020-01-09 02:06:15,088 [salt.state       :1951][INFO    ][36007] Completed state [maas_create_iprange_1] at time 02:06:15.088361 duration_in_ms=43.027
2020-01-09 02:06:15,088 [salt.state       :1780][INFO    ][36007] Running state [vlan 0] at time 02:06:15.088627
2020-01-09 02:06:15,088 [salt.state       :1813][INFO    ][36007] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2020-01-09 02:06:15,131 [salt.loaded.ext.module.maasng:945 ][INFO    ][36007] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-2', 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'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'g4bynn', u'fabric': u'pxe_admin', 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'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2020-01-09 02:06:15,210 [salt.loaded.ext.module.maasng:945 ][INFO    ][36007] [{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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None, u'name': u'fabric-2', u'id': 2}, {u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'g4bynn', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u'', u'name': u'pxe_admin', u'id': 1}]
2020-01-09 02:06:15,415 [salt.loaded.ext.module.maasng:945 ][INFO    ][36007] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'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'fabric': u'fabric-0'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'g4bynn', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2020-01-09 02:06:15,517 [salt.state       :300 ][INFO    ][36007] {'new': 'Vlan untagged was updated'}
2020-01-09 02:06:15,518 [salt.state       :1951][INFO    ][36007] Completed state [vlan 0] at time 02:06:15.517949 duration_in_ms=429.321
2020-01-09 02:06:15,518 [salt.state       :1780][INFO    ][36007] Running state [opnfv] at time 02:06:15.518619
2020-01-09 02:06:15,518 [salt.state       :1813][INFO    ][36007] Executing state maasng.sshkey_present for [opnfv]
2020-01-09 02:06:15,577 [salt.loaded.ext.module.maasng:1903][INFO    ][36007] [{u'keysource': u'', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b', u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/'}]
2020-01-09 02:06:15,577 [salt.state       :300 ][INFO    ][36007] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2020-01-09 02:06:15,578 [salt.state       :1951][INFO    ][36007] Completed state [opnfv] at time 02:06:15.577924 duration_in_ms=59.305
2020-01-09 02:06:15,578 [salt.state       :1780][INFO    ][36007] Running state [maas.process_tags] at time 02:06:15.578567
2020-01-09 02:06:15,578 [salt.state       :1813][INFO    ][36007] Executing state module.run for [maas.process_tags]
2020-01-09 02:06:15,579 [salt.utils.decorators:613 ][WARNING ][36007] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-09 02:06:15,627 [salt.loaded.ext.module.maas:92  ][INFO    ][36007] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"]|//capability[@id="cp15_barrier"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2020-01-09 02:06:15,709 [salt.state       :300 ][INFO    ][36007] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2020-01-09 02:06:15,710 [salt.state       :1951][INFO    ][36007] Completed state [maas.process_tags] at time 02:06:15.709987 duration_in_ms=131.419
2020-01-09 02:06:15,712 [salt.minion      :1711][INFO    ][36007] Returning information for job: 20200109020546274007
2020-01-09 02:06:16,320 [salt.minion      :1308][INFO    ][35926] User sudo_ubuntu Executing command state.apply with jid 20200109020616307905
2020-01-09 02:06:16,334 [salt.minion      :1432][INFO    ][36383] Starting a new job with PID 36383
2020-01-09 02:06:24,367 [salt.state       :915 ][INFO    ][36383] Loading fresh modules for state activity
2020-01-09 02:06:24,431 [salt.fileclient  :1219][INFO    ][36383] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2020-01-09 02:06:24,474 [salt.state       :1780][INFO    ][36383] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:06:24.474811
2020-01-09 02:06:24,475 [salt.state       :1813][INFO    ][36383] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-01-09 02:06:24,476 [salt.loaded.int.module.cmdmod:395 ][INFO    ][36383] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-01-09 02:06:26,240 [salt.state       :300 ][INFO    ][36383] {'pid': 36460, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-01-09 02:06:26,242 [salt.state       :1951][INFO    ][36383] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:06:26.242192 duration_in_ms=1767.38
2020-01-09 02:06:26,245 [salt.state       :1780][INFO    ][36383] Running state [maas.process_machines] at time 02:06:26.245082
2020-01-09 02:06:26,245 [salt.state       :1813][INFO    ][36383] Executing state module.run for [maas.process_machines]
2020-01-09 02:06:26,246 [salt.utils.decorators:613 ][WARNING ][36383] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-09 02:06:26,314 [salt.loaded.ext.module.maas:412 ][WARNING ][36383] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-01-09 02:06:26,314 [salt.loaded.ext.module.maas:92  ][INFO    ][36383] machine hostname=gtw01 power_type=ipmi mac_addresses=14:58:d0:54:6a:60 power_parameters_power_address=172.16.1.17 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2020-01-09 02:06:27,792 [salt.loaded.ext.module.maas:412 ][WARNING ][36383] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-01-09 02:06:27,793 [salt.loaded.ext.module.maas:92  ][INFO    ][36383] machine hostname=cmp002 power_type=ipmi mac_addresses=9c:b6:54:8a:10:18 power_parameters_power_address=172.16.1.20 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2020-01-09 02:06:29,075 [salt.loaded.ext.module.maas:412 ][WARNING ][36383] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-01-09 02:06:29,076 [salt.loaded.ext.module.maas:92  ][INFO    ][36383] machine hostname=cmp001 power_type=ipmi mac_addresses=9c:b6:54:8a:95:a0 power_parameters_power_address=172.16.1.19 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2020-01-09 02:06:30,327 [salt.loaded.ext.module.maas:412 ][WARNING ][36383] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-01-09 02:06:30,327 [salt.loaded.ext.module.maas:92  ][INFO    ][36383] machine hostname=ctl01 power_type=ipmi mac_addresses=14:58:d0:54:e7:88 power_parameters_power_address=172.16.1.16 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2020-01-09 02:06:31,343 [salt.minion      :1308][INFO    ][35926] User sudo_ubuntu Executing command saltutil.find_job with jid 20200109020631334442
2020-01-09 02:06:31,357 [salt.minion      :1432][INFO    ][36672] Starting a new job with PID 36672
2020-01-09 02:06:31,380 [salt.minion      :1711][INFO    ][36672] Returning information for job: 20200109020631334442
2020-01-09 02:06:31,437 [salt.state       :300 ][INFO    ][36383] {'ret': {'updated': [], 'errors': {}, 'success': ['gtw01', 'cmp002', 'cmp001', 'ctl01']}}
2020-01-09 02:06:31,438 [salt.state       :1951][INFO    ][36383] Completed state [maas.process_machines] at time 02:06:31.438213 duration_in_ms=5193.131
2020-01-09 02:06:31,440 [salt.minion      :1711][INFO    ][36383] Returning information for job: 20200109020616307905
2020-01-09 02:07:02,532 [salt.minion      :1308][INFO    ][35926] User sudo_ubuntu Executing command state.apply with jid 20200109020702521649
2020-01-09 02:07:02,551 [salt.minion      :1432][INFO    ][36709] Starting a new job with PID 36709
2020-01-09 02:07:10,222 [salt.state       :915 ][INFO    ][36709] Loading fresh modules for state activity
2020-01-09 02:07:10,279 [salt.fileclient  :1219][INFO    ][36709] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2020-01-09 02:07:10,321 [salt.state       :1780][INFO    ][36709] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:07:10.321616
2020-01-09 02:07:10,321 [salt.state       :1813][INFO    ][36709] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-01-09 02:07:10,323 [salt.loaded.int.module.cmdmod:395 ][INFO    ][36709] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-01-09 02:07:12,012 [salt.state       :300 ][INFO    ][36709] {'pid': 36728, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-01-09 02:07:12,013 [salt.state       :1951][INFO    ][36709] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:07:12.013170 duration_in_ms=1691.552
2020-01-09 02:07:12,015 [salt.state       :1780][INFO    ][36709] Running state [maas.wait_for_machine_status] at time 02:07:12.015493
2020-01-09 02:07:12,015 [salt.state       :1813][INFO    ][36709] Executing state module.run for [maas.wait_for_machine_status]
2020-01-09 02:07:12,016 [salt.utils.decorators:613 ][WARNING ][36709] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-09 02:07:12,595 [salt.loaded.ext.module.maas:1023][INFO    ][36709] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1499.43003702s left)
2020-01-09 02:07:17,633 [salt.minion      :1308][INFO    ][35926] User sudo_ubuntu Executing command saltutil.find_job with jid 20200109020717626156
2020-01-09 02:07:17,645 [salt.minion      :1432][INFO    ][36738] Starting a new job with PID 36738
2020-01-09 02:07:17,666 [salt.minion      :1711][INFO    ][36738] Returning information for job: 20200109020717626156
2020-01-09 02:07:43,199 [salt.loaded.ext.module.maas:1023][INFO    ][36709] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1468.82629919s left)
2020-01-09 02:07:47,675 [salt.minion      :1308][INFO    ][35926] User sudo_ubuntu Executing command saltutil.find_job with jid 20200109020747663606
2020-01-09 02:07:47,697 [salt.minion      :1432][INFO    ][36783] Starting a new job with PID 36783
2020-01-09 02:07:47,719 [salt.minion      :1711][INFO    ][36783] Returning information for job: 20200109020747663606
2020-01-09 02:08:13,790 [salt.loaded.ext.module.maas:1023][INFO    ][36709] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1438.23498702s left)
2020-01-09 02:08:17,761 [salt.minion      :1308][INFO    ][35926] User sudo_ubuntu Executing command saltutil.find_job with jid 20200109020817749845
2020-01-09 02:08:17,782 [salt.minion      :1432][INFO    ][36810] Starting a new job with PID 36810
2020-01-09 02:08:17,803 [salt.minion      :1711][INFO    ][36810] Returning information for job: 20200109020817749845
2020-01-09 02:08:44,254 [salt.loaded.ext.module.maas:1023][INFO    ][36709] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1407.7712121s left)
2020-01-09 02:08:47,808 [salt.minion      :1308][INFO    ][35926] User sudo_ubuntu Executing command saltutil.find_job with jid 20200109020847800802
2020-01-09 02:08:47,828 [salt.minion      :1432][INFO    ][36856] Starting a new job with PID 36856
2020-01-09 02:08:47,851 [salt.minion      :1711][INFO    ][36856] Returning information for job: 20200109020847800802
2020-01-09 02:09:15,135 [salt.loaded.ext.module.maas:1023][INFO    ][36709] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1376.89088511s left)
2020-01-09 02:09:17,863 [salt.minion      :1308][INFO    ][35926] User sudo_ubuntu Executing command saltutil.find_job with jid 20200109020917848947
2020-01-09 02:09:17,883 [salt.minion      :1432][INFO    ][36912] Starting a new job with PID 36912
2020-01-09 02:09:17,911 [salt.minion      :1711][INFO    ][36912] Returning information for job: 20200109020917848947
2020-01-09 02:09:45,961 [salt.loaded.ext.module.maas:1023][INFO    ][36709] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1346.06399202s left)
2020-01-09 02:09:47,949 [salt.minion      :1308][INFO    ][35926] User sudo_ubuntu Executing command saltutil.find_job with jid 20200109020947941985
2020-01-09 02:09:47,962 [salt.minion      :1432][INFO    ][37024] Starting a new job with PID 37024
2020-01-09 02:09:47,984 [salt.minion      :1711][INFO    ][37024] Returning information for job: 20200109020947941985
2020-01-09 02:10:16,954 [salt.loaded.ext.module.maas:1023][INFO    ][36709] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1315.07104421s left)
2020-01-09 02:10:18,012 [salt.minion      :1308][INFO    ][35926] User sudo_ubuntu Executing command saltutil.find_job with jid 20200109021017999115
2020-01-09 02:10:18,036 [salt.minion      :1432][INFO    ][37113] Starting a new job with PID 37113
2020-01-09 02:10:18,059 [salt.minion      :1711][INFO    ][37113] Returning information for job: 20200109021017999115
2020-01-09 02:10:48,006 [salt.loaded.ext.module.maas:1023][INFO    ][36709] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1284.01964521s left)
2020-01-09 02:10:48,151 [salt.minion      :1308][INFO    ][35926] User sudo_ubuntu Executing command saltutil.find_job with jid 20200109021048139431
2020-01-09 02:10:48,169 [salt.minion      :1432][INFO    ][37470] Starting a new job with PID 37470
2020-01-09 02:10:48,191 [salt.minion      :1711][INFO    ][37470] Returning information for job: 20200109021048139431
2020-01-09 02:11:18,231 [salt.minion      :1308][INFO    ][35926] User sudo_ubuntu Executing command saltutil.find_job with jid 20200109021118220292
2020-01-09 02:11:18,256 [salt.minion      :1432][INFO    ][37579] Starting a new job with PID 37579
2020-01-09 02:11:18,279 [salt.minion      :1711][INFO    ][37579] Returning information for job: 20200109021118220292
2020-01-09 02:11:19,515 [salt.loaded.ext.module.maas:1023][INFO    ][36709] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1252.5100491s left)
2020-01-09 02:11:48,371 [salt.minion      :1308][INFO    ][35926] User sudo_ubuntu Executing command saltutil.find_job with jid 20200109021148357951
2020-01-09 02:11:48,397 [salt.minion      :1432][INFO    ][37848] Starting a new job with PID 37848
2020-01-09 02:11:48,419 [salt.minion      :1711][INFO    ][37848] Returning information for job: 20200109021148357951
2020-01-09 02:11:51,285 [salt.loaded.ext.module.maas:1023][INFO    ][36709] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1220.7400682s left)
2020-01-09 02:12:18,480 [salt.minion      :1308][INFO    ][35926] User sudo_ubuntu Executing command saltutil.find_job with jid 20200109021218466206
2020-01-09 02:12:18,510 [salt.minion      :1432][INFO    ][37938] Starting a new job with PID 37938
2020-01-09 02:12:18,539 [salt.minion      :1711][INFO    ][37938] Returning information for job: 20200109021218466206
2020-01-09 02:12:23,878 [salt.loaded.ext.module.maas:1023][INFO    ][36709] Waiting status:Ready|Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:1500s (1188.1475451s left)
2020-01-09 02:12:48,645 [salt.minion      :1308][INFO    ][35926] User sudo_ubuntu Executing command saltutil.find_job with jid 20200109021248630630
2020-01-09 02:12:48,674 [salt.minion      :1432][INFO    ][38164] Starting a new job with PID 38164
2020-01-09 02:12:48,698 [salt.minion      :1711][INFO    ][38164] Returning information for job: 20200109021248630630
2020-01-09 02:12:55,980 [salt.loaded.ext.module.maas:1023][INFO    ][36709] Waiting status:Ready|Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:1500s (1156.04516101s left)
2020-01-09 02:13:18,767 [salt.minion      :1308][INFO    ][35926] User sudo_ubuntu Executing command saltutil.find_job with jid 20200109021318757342
2020-01-09 02:13:18,793 [salt.minion      :1432][INFO    ][38306] Starting a new job with PID 38306
2020-01-09 02:13:18,813 [salt.minion      :1711][INFO    ][38306] Returning information for job: 20200109021318757342
2020-01-09 02:13:28,113 [salt.state       :300 ][INFO    ][36709] {'ret': True}
2020-01-09 02:13:28,114 [salt.state       :1951][INFO    ][36709] Completed state [maas.wait_for_machine_status] at time 02:13:28.114449 duration_in_ms=376098.952
2020-01-09 02:13:28,119 [salt.minion      :1711][INFO    ][36709] Returning information for job: 20200109020702521649
2020-01-09 02:13:28,681 [salt.minion      :1308][INFO    ][35926] User sudo_ubuntu Executing command state.apply with jid 20200109021328667261
2020-01-09 02:13:28,705 [salt.minion      :1432][INFO    ][38379] Starting a new job with PID 38379
2020-01-09 02:13:36,427 [salt.state       :915 ][INFO    ][38379] Loading fresh modules for state activity
2020-01-09 02:13:36,492 [salt.fileclient  :1219][INFO    ][38379] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2020-01-09 02:13:36,597 [salt.state       :1780][INFO    ][38379] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:13:36.597239
2020-01-09 02:13:36,597 [salt.state       :1813][INFO    ][38379] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-01-09 02:13:36,599 [salt.loaded.int.module.cmdmod:395 ][INFO    ][38379] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-01-09 02:13:38,352 [salt.state       :300 ][INFO    ][38379] {'pid': 38397, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-01-09 02:13:38,354 [salt.state       :1951][INFO    ][38379] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:13:38.354365 duration_in_ms=1757.124
2020-01-09 02:13:38,358 [salt.state       :1780][INFO    ][38379] Running state [maas_machines_storage_cmp002_lvm] at time 02:13:38.358912
2020-01-09 02:13:38,359 [salt.state       :1813][INFO    ][38379] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2020-01-09 02:13:39,348 [salt.loaded.ext.module.maasng:610 ][INFO    ][38379] ks63tn
2020-01-09 02:13:39,348 [salt.loaded.ext.module.maasng:626 ][INFO    ][38379] sda
2020-01-09 02:13:39,844 [salt.loaded.ext.module.maasng:361 ][INFO    ][38379] ks63tn
2020-01-09 02:13:39,935 [salt.loaded.ext.module.maasng:367 ][INFO    ][38379] [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'75de7581-e5b5-41be-89e7-8c8ffd4c7b8b', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ks63tn', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'a6e01796-ecd6-4218-bbe3-21efac1afef6', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/ks63tn/blockdevices/1/partition/1', u'type': u'partition', u'id': 1, u'device_id': 1}], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'ks63tn', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'type': u'physical', u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/ks63tn/blockdevices/1/'}, {u'size': 800097042432, u'model': None, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'partitions': [], u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'431d631f-e4f4-4905-8c93-e8dca418417b', u'fstype': u'ext4', u'label': u'root'}, u'uuid': u'75be05e9-e23d-4a0d-bd60-8afd6b33014b', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'ks63tn', 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': 3, u'resource_uri': u'/MAAS/api/2.0/nodes/ks63tn/blockdevices/3/'}]
2020-01-09 02:13:39,936 [salt.loaded.ext.module.maasng:632 ][INFO    ][38379] vgroot
2020-01-09 02:13:39,936 [salt.loaded.ext.module.maasng:635 ][INFO    ][38379] lvroot
2020-01-09 02:13:39,936 [salt.loaded.ext.module.maasng:639 ][INFO    ][38379] 107374182400
2020-01-09 02:13:40,526 [salt.loaded.ext.module.maasng:645 ][INFO    ][38379] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'memory_test_status': -1, 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'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'status_action': u'', u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/ks63tn/', u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'ks63tn', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'9b030c94-66e1-44eb-bc71-7bee081c9905', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'ks63tn', u'partition_table_type': None, u'filesystem': {u'uuid': u'54ca2f85-c4ae-4a7f-903c-996fbbc2d02f', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 9, u'resource_uri': u'/MAAS/api/2.0/nodes/ks63tn/blockdevices/9/'}], u'architecture': u'amd64/generic', u'status': 4, u'storage_test_status': 2, u'cpu_count': 40, u'raids': [], u'physicalblockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'1caa0a5a-2c83-4ec4-8015-81f05e880c11', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ks63tn', u'filesystem': {u'uuid': u'cb1bd8ca-adb7-4db7-8864-de6d67da3234', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/ks63tn/blockdevices/1/partition/5'}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'ks63tn', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/ks63tn/blockdevices/1/'}], u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'ks63tn', u'id': 5}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'commissioning_status_name': u'Passed', u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'g4bynn', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'id': 16, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'g4bynn', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'mac_address': u'9c:b6:54:8a:10:18', u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'g4bynn', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'ks63tn', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/ks63tn/interfaces/5/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'mac_address': u'38:ea:a7:8f:07:50', u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'ks63tn', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/ks63tn/interfaces/11/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'mac_address': u'38:ea:a7:8f:07:51', u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'ks63tn', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/ks63tn/interfaces/12/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'g4bynn', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 17, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'g4bynn', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'mac_address': u'9c:b6:54:8a:10:1c', u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'g4bynn', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.40'}], u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'ks63tn', u'type': u'physical', u'id': 10, u'resource_uri': u'/MAAS/api/2.0/nodes/ks63tn/interfaces/10/'}, {u'name': u'ens2f1', u'links': [{u'id': 18, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'mac_address': u'38:ea:a7:8f:12:49', u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'ks63tn', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/ks63tn/interfaces/13/'}, {u'name': u'ens2f0', u'links': [{u'id': 19, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'mac_address': u'38:ea:a7:8f:12:48', u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'ks63tn', u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/ks63tn/interfaces/14/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'commissioning_status': 2, u'min_hwe_kernel': u'ga-18.04', u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'g4bynn', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'id': 16, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'g4bynn', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'mac_address': u'9c:b6:54:8a:10:18', u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'g4bynn', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'ks63tn', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/ks63tn/interfaces/5/'}, u'boot_disk': {u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'1caa0a5a-2c83-4ec4-8015-81f05e880c11', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ks63tn', u'filesystem': {u'uuid': u'cb1bd8ca-adb7-4db7-8864-de6d67da3234', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/ks63tn/blockdevices/1/partition/5'}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'ks63tn', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/ks63tn/blockdevices/1/'}, u'current_commissioning_result_id': 4, u'address_ttl': None, u'blockdevice_set': [{u'size': 800109715456, u'available_size': 0, u'uuid': None, u'resource_uri': u'/MAAS/api/2.0/nodes/ks63tn/blockdevices/1/', u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'tags': [u'ssd'], u'name': u'sda', u'system_id': u'ks63tn', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'size': 800101236736, u'uuid': u'1caa0a5a-2c83-4ec4-8015-81f05e880c11', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ks63tn', u'filesystem': {u'uuid': u'cb1bd8ca-adb7-4db7-8864-de6d67da3234', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/ks63tn/blockdevices/1/partition/5'}]}, {u'size': 107374182400, u'available_size': 0, u'uuid': u'9b030c94-66e1-44eb-bc71-7bee081c9905', u'resource_uri': u'/MAAS/api/2.0/nodes/ks63tn/blockdevices/9/', u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'tags': [], u'name': u'vgroot-lvroot', u'system_id': u'ks63tn', u'partition_table_type': None, u'filesystem': {u'uuid': u'54ca2f85-c4ae-4a7f-903c-996fbbc2d02f', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 9, u'serial': None, u'partitions': []}], u'distro_series': u'', u'node_type_name': u'Machine'}
2020-01-09 02:13:40,529 [salt.state       :300 ][INFO    ][38379] {'new': {'storage_layout': 'lvm'}}
2020-01-09 02:13:40,531 [salt.state       :1951][INFO    ][38379] Completed state [maas_machines_storage_cmp002_lvm] at time 02:13:40.531652 duration_in_ms=2172.739
2020-01-09 02:13:40,532 [salt.state       :1780][INFO    ][38379] Running state [maas_machines_storage_cmp001_lvm] at time 02:13:40.532038
2020-01-09 02:13:40,532 [salt.state       :1813][INFO    ][38379] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2020-01-09 02:13:41,486 [salt.loaded.ext.module.maasng:610 ][INFO    ][38379] ynehde
2020-01-09 02:13:41,487 [salt.loaded.ext.module.maasng:626 ][INFO    ][38379] sda
2020-01-09 02:13:42,011 [salt.loaded.ext.module.maasng:361 ][INFO    ][38379] ynehde
2020-01-09 02:13:42,099 [salt.loaded.ext.module.maasng:367 ][INFO    ][38379] [{u'size': 800109715456, u'available_size': 0, u'uuid': None, u'resource_uri': u'/MAAS/api/2.0/nodes/ynehde/blockdevices/2/', u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'tags': [u'ssd'], u'name': u'sda', u'system_id': u'ynehde', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'size': 800101236736, u'uuid': u'ce89697b-399c-4b31-9f61-5c5135974ef7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ynehde', u'filesystem': {u'uuid': u'6e84df7b-2c7d-459e-8187-ca38f0b6a281', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/ynehde/blockdevices/2/partition/2'}]}, {u'size': 800097042432, u'available_size': 0, u'uuid': u'2d751c67-3818-443a-8755-dddba6ba0dd5', u'resource_uri': u'/MAAS/api/2.0/nodes/ynehde/blockdevices/4/', u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'tags': [], u'name': u'vgroot-lvroot', u'system_id': u'ynehde', u'partition_table_type': None, u'filesystem': {u'uuid': u'26033e00-f667-420d-bc34-d927b2c084aa', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'used_size': 800097042432, u'id': 4, u'serial': None, u'partitions': []}]
2020-01-09 02:13:42,100 [salt.loaded.ext.module.maasng:632 ][INFO    ][38379] vgroot
2020-01-09 02:13:42,100 [salt.loaded.ext.module.maasng:635 ][INFO    ][38379] lvroot
2020-01-09 02:13:42,101 [salt.loaded.ext.module.maasng:639 ][INFO    ][38379] 107374182400
2020-01-09 02:13:42,732 [salt.loaded.ext.module.maasng:645 ][INFO    ][38379] {u'hwe_kernel': u'', u'status_action': u'', u'swap_size': None, u'disable_ipv4': False, u'cpu_count': 40, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'boot_interface': {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'g4bynn', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'g4bynn', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 22}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 6, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'g4bynn', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'system_id': u'ynehde', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ynehde/interfaces/6/'}, u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 800109715456, u'block_size': 4096, u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'2432103c-7441-4bed-b264-4d71dd9aa6ac', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ynehde', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'd6f4f07c-5212-4a42-bfe3-af7b93f668a9', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/ynehde/blockdevices/2/partition/6'}], u'id': 2, u'system_id': u'ynehde', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/ynehde/blockdevices/2/', u'type': u'physical', u'model': u'LOGICAL VOLUME', u'name': u'sda'}, u'blockdevice_set': [{u'size': 800109715456, u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'2432103c-7441-4bed-b264-4d71dd9aa6ac', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ynehde', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'd6f4f07c-5212-4a42-bfe3-af7b93f668a9', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/ynehde/blockdevices/2/partition/6'}], u'id': 2, u'path': u'/dev/disk/by-dname/sda', u'system_id': u'ynehde', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'used_for': u'MBR partitioned with 1 partition', u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/ynehde/blockdevices/2/', u'type': u'physical', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'uuid': None}, {u'size': 107374182400, u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'id': 10, u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'ynehde', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'b922abb6-2269-440c-bd59-2142aeece9d6', u'label': u'root'}, u'id_path': None, u'used_for': u'ext4 formatted filesystem mounted at /', u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/ynehde/blockdevices/10/', u'type': u'virtual', u'serial': None, u'uuid': u'cab08816-70e1-4602-bded-6643cf0e7862'}], u'zone': {u'description': u'', u'id': 1, u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/ynehde/', u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'ynehde', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'owner_data': {}, u'architecture': u'amd64/generic', u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'physicalblockdevice_set': [{u'size': 800109715456, u'block_size': 4096, u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'2432103c-7441-4bed-b264-4d71dd9aa6ac', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ynehde', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'd6f4f07c-5212-4a42-bfe3-af7b93f668a9', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/ynehde/blockdevices/2/partition/6'}], u'id': 2, u'system_id': u'ynehde', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/ynehde/blockdevices/2/', u'type': u'physical', u'model': u'LOGICAL VOLUME', u'name': u'sda'}], u'ip_addresses': [u'192.168.11.39', u'192.168.11.43'], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'ynehde', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 6, u'memory_test_status_name': u'Unknown', u'current_testing_result_id': 7, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'virtualblockdevice_set': [{u'size': 107374182400, u'block_size': 4096, u'available_size': 0, u'uuid': u'cab08816-70e1-4602-bded-6643cf0e7862', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'partitions': [], u'id': 10, u'system_id': u'ynehde', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'b922abb6-2269-440c-bd59-2142aeece9d6', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/ynehde/blockdevices/10/', u'type': u'virtual', u'model': None, u'name': u'vgroot-lvroot'}], u'commissioning_status': 2, u'min_hwe_kernel': u'ga-18.04', u'commissioning_status_name': u'Passed', u'interface_set': [{u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'g4bynn', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'g4bynn', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 22}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 6, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'g4bynn', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'system_id': u'ynehde', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ynehde/interfaces/6/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'fabric': u'fabric-0'}, u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 23}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 15, u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cd', u'parents': [], u'system_id': u'ynehde', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ynehde/interfaces/15/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'g4bynn', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'g4bynn', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'mode': u'link_up', u'id': 24}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 16, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'g4bynn', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.43'}], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a4', u'parents': [], u'system_id': u'ynehde', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ynehde/interfaces/16/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'fabric': u'fabric-0'}, u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 25}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 19, u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cc', u'parents': [], u'system_id': u'ynehde', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ynehde/interfaces/19/'}, {u'vlan': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 17, u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d5', u'parents': [], u'system_id': u'ynehde', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ynehde/interfaces/17/'}, {u'vlan': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 18, u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d4', u'parents': [], u'system_id': u'ynehde', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ynehde/interfaces/18/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2020-01-09 02:13:42,735 [salt.state       :300 ][INFO    ][38379] {'new': {'storage_layout': 'lvm'}}
2020-01-09 02:13:42,735 [salt.state       :1951][INFO    ][38379] Completed state [maas_machines_storage_cmp001_lvm] at time 02:13:42.735677 duration_in_ms=2203.628
2020-01-09 02:13:42,740 [salt.minion      :1711][INFO    ][38379] Returning information for job: 20200109021328667261
2020-01-09 02:13:43,297 [salt.minion      :1308][INFO    ][35926] User sudo_ubuntu Executing command state.apply with jid 20200109021343281151
2020-01-09 02:13:43,324 [salt.minion      :1432][INFO    ][38425] Starting a new job with PID 38425
2020-01-09 02:13:44,357 [salt.state       :915 ][INFO    ][38425] Loading fresh modules for state activity
2020-01-09 02:13:44,421 [salt.fileclient  :1219][INFO    ][38425] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2020-01-09 02:13:44,470 [salt.state       :1780][INFO    ][38425] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:13:44.470554
2020-01-09 02:13:44,470 [salt.state       :1813][INFO    ][38425] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-01-09 02:13:44,472 [salt.loaded.int.module.cmdmod:395 ][INFO    ][38425] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-01-09 02:13:46,245 [salt.state       :300 ][INFO    ][38425] {'pid': 38433, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-01-09 02:13:46,246 [salt.state       :1951][INFO    ][38425] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:13:46.246536 duration_in_ms=1775.982
2020-01-09 02:13:46,250 [salt.state       :1780][INFO    ][38425] Running state [maas.deploy_machines] at time 02:13:46.250081
2020-01-09 02:13:46,250 [salt.state       :1813][INFO    ][38425] Executing state module.run for [maas.deploy_machines]
2020-01-09 02:13:46,251 [salt.utils.decorators:613 ][WARNING ][38425] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-09 02:13:46,910 [salt.loaded.ext.module.maas:684 ][INFO    ][38425] deploymachines hwe_kernel=ga-18.04 system_id=xmbe63 distro_series=bionic
2020-01-09 02:13:49,316 [salt.loaded.ext.module.maas:684 ][INFO    ][38425] deploymachines hwe_kernel=ga-18.04 system_id=ks63tn distro_series=bionic
2020-01-09 02:13:51,854 [salt.loaded.ext.module.maas:684 ][INFO    ][38425] deploymachines hwe_kernel=ga-18.04 system_id=ynehde distro_series=bionic
2020-01-09 02:13:54,320 [salt.loaded.ext.module.maas:684 ][INFO    ][38425] deploymachines hwe_kernel=ga-18.04 system_id=deswrs distro_series=bionic
2020-01-09 02:13:56,847 [salt.state       :300 ][INFO    ][38425] {'ret': {'updated': [], 'errors': {}, 'success': ['gtw01', 'cmp002', 'cmp001', 'ctl01']}}
2020-01-09 02:13:56,847 [salt.state       :1951][INFO    ][38425] Completed state [maas.deploy_machines] at time 02:13:56.847608 duration_in_ms=10597.526
2020-01-09 02:13:56,852 [salt.minion      :1711][INFO    ][38425] Returning information for job: 20200109021343281151
2020-01-09 02:13:57,410 [salt.minion      :1308][INFO    ][35926] User sudo_ubuntu Executing command state.apply with jid 20200109021357396754
2020-01-09 02:13:57,435 [salt.minion      :1432][INFO    ][38653] Starting a new job with PID 38653
2020-01-09 02:14:05,282 [salt.state       :915 ][INFO    ][38653] Loading fresh modules for state activity
2020-01-09 02:14:05,349 [salt.fileclient  :1219][INFO    ][38653] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2020-01-09 02:14:05,401 [salt.state       :1780][INFO    ][38653] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:14:05.401680
2020-01-09 02:14:05,402 [salt.state       :1813][INFO    ][38653] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-01-09 02:14:05,403 [salt.loaded.int.module.cmdmod:395 ][INFO    ][38653] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-01-09 02:14:07,162 [salt.state       :300 ][INFO    ][38653] {'pid': 38684, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-01-09 02:14:07,164 [salt.state       :1951][INFO    ][38653] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:14:07.164047 duration_in_ms=1762.364
2020-01-09 02:14:07,167 [salt.state       :1780][INFO    ][38653] Running state [maas.wait_for_machine_status] at time 02:14:07.167468
2020-01-09 02:14:07,168 [salt.state       :1813][INFO    ][38653] Executing state module.run for [maas.wait_for_machine_status]
2020-01-09 02:14:07,168 [salt.utils.decorators:613 ][WARNING ][38653] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-09 02:14:09,103 [salt.loaded.ext.module.maas:1023][INFO    ][38653] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2248.075212s left)
2020-01-09 02:14:12,462 [salt.minion      :1308][INFO    ][35926] User sudo_ubuntu Executing command saltutil.find_job with jid 20200109021412453665
2020-01-09 02:14:12,482 [salt.minion      :1432][INFO    ][38694] Starting a new job with PID 38694
2020-01-09 02:14:12,504 [salt.minion      :1711][INFO    ][38694] Returning information for job: 20200109021412453665
2020-01-09 02:14:41,208 [salt.loaded.ext.module.maas:1023][INFO    ][38653] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2215.97045279s left)
2020-01-09 02:14:42,517 [salt.minion      :1308][INFO    ][35926] User sudo_ubuntu Executing command saltutil.find_job with jid 20200109021442501014
2020-01-09 02:14:42,547 [salt.minion      :1432][INFO    ][38739] Starting a new job with PID 38739
2020-01-09 02:14:42,572 [salt.minion      :1711][INFO    ][38739] Returning information for job: 20200109021442501014
2020-01-09 02:15:12,671 [salt.minion      :1308][INFO    ][35926] User sudo_ubuntu Executing command saltutil.find_job with jid 20200109021512626114
2020-01-09 02:15:12,693 [salt.minion      :1432][INFO    ][38767] Starting a new job with PID 38767
2020-01-09 02:15:12,720 [salt.minion      :1711][INFO    ][38767] Returning information for job: 20200109021512626114
2020-01-09 02:15:13,195 [salt.loaded.ext.module.maas:1023][INFO    ][38653] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2183.983814s left)
2020-01-09 02:15:42,736 [salt.minion      :1308][INFO    ][35926] User sudo_ubuntu Executing command saltutil.find_job with jid 20200109021542719803
2020-01-09 02:15:42,763 [salt.minion      :1432][INFO    ][38809] Starting a new job with PID 38809
2020-01-09 02:15:42,785 [salt.minion      :1711][INFO    ][38809] Returning information for job: 20200109021542719803
2020-01-09 02:15:45,127 [salt.loaded.ext.module.maas:1023][INFO    ][38653] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2152.05113387s left)
2020-01-09 02:16:12,814 [salt.minion      :1308][INFO    ][35926] User sudo_ubuntu Executing command saltutil.find_job with jid 20200109021612802533
2020-01-09 02:16:12,844 [salt.minion      :1432][INFO    ][38837] Starting a new job with PID 38837
2020-01-09 02:16:12,864 [salt.minion      :1711][INFO    ][38837] Returning information for job: 20200109021612802533
2020-01-09 02:16:17,071 [salt.loaded.ext.module.maas:1023][INFO    ][38653] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2120.107481s left)
2020-01-09 02:16:42,895 [salt.minion      :1308][INFO    ][35926] User sudo_ubuntu Executing command saltutil.find_job with jid 20200109021642879931
2020-01-09 02:16:42,914 [salt.minion      :1432][INFO    ][38902] Starting a new job with PID 38902
2020-01-09 02:16:42,938 [salt.minion      :1711][INFO    ][38902] Returning information for job: 20200109021642879931
2020-01-09 02:16:49,144 [salt.loaded.ext.module.maas:1023][INFO    ][38653] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2088.03502178s left)
2020-01-09 02:17:12,994 [salt.minion      :1308][INFO    ][35926] User sudo_ubuntu Executing command saltutil.find_job with jid 20200109021712982893
2020-01-09 02:17:13,023 [salt.minion      :1432][INFO    ][38988] Starting a new job with PID 38988
2020-01-09 02:17:13,045 [salt.minion      :1711][INFO    ][38988] Returning information for job: 20200109021712982893
2020-01-09 02:17:21,483 [salt.loaded.ext.module.maas:1023][INFO    ][38653] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2055.69542694s left)
2020-01-09 02:17:43,123 [salt.minion      :1308][INFO    ][35926] User sudo_ubuntu Executing command saltutil.find_job with jid 20200109021743107984
2020-01-09 02:17:43,151 [salt.minion      :1432][INFO    ][39225] Starting a new job with PID 39225
2020-01-09 02:17:43,174 [salt.minion      :1711][INFO    ][39225] Returning information for job: 20200109021743107984
2020-01-09 02:17:53,705 [salt.loaded.ext.module.maas:1023][INFO    ][38653] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2023.47389698s left)
2020-01-09 02:18:13,217 [salt.minion      :1308][INFO    ][35926] User sudo_ubuntu Executing command saltutil.find_job with jid 20200109021813205223
2020-01-09 02:18:13,245 [salt.minion      :1432][INFO    ][39295] Starting a new job with PID 39295
2020-01-09 02:18:13,268 [salt.minion      :1711][INFO    ][39295] Returning information for job: 20200109021813205223
2020-01-09 02:18:25,835 [salt.loaded.ext.module.maas:1023][INFO    ][38653] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1991.34397292s left)
2020-01-09 02:18:43,344 [salt.minion      :1308][INFO    ][35926] User sudo_ubuntu Executing command saltutil.find_job with jid 20200109021843331596
2020-01-09 02:18:43,375 [salt.minion      :1432][INFO    ][39567] Starting a new job with PID 39567
2020-01-09 02:18:43,403 [salt.minion      :1711][INFO    ][39567] Returning information for job: 20200109021843331596
2020-01-09 02:18:57,935 [salt.loaded.ext.module.maas:1023][INFO    ][38653] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1959.24358678s left)
2020-01-09 02:19:13,473 [salt.minion      :1308][INFO    ][35926] User sudo_ubuntu Executing command saltutil.find_job with jid 20200109021913462226
2020-01-09 02:19:13,495 [salt.minion      :1432][INFO    ][39637] Starting a new job with PID 39637
2020-01-09 02:19:13,517 [salt.minion      :1711][INFO    ][39637] Returning information for job: 20200109021913462226
2020-01-09 02:19:30,044 [salt.loaded.ext.module.maas:1023][INFO    ][38653] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1927.13501382s left)
2020-01-09 02:19:43,646 [salt.minion      :1308][INFO    ][35926] User sudo_ubuntu Executing command saltutil.find_job with jid 20200109021943635465
2020-01-09 02:19:43,668 [salt.minion      :1432][INFO    ][40008] Starting a new job with PID 40008
2020-01-09 02:19:43,690 [salt.minion      :1711][INFO    ][40008] Returning information for job: 20200109021943635465
2020-01-09 02:20:02,123 [salt.loaded.ext.module.maas:1023][INFO    ][38653] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1895.05580592s left)
2020-01-09 02:20:13,780 [salt.minion      :1308][INFO    ][35926] User sudo_ubuntu Executing command saltutil.find_job with jid 20200109022013764296
2020-01-09 02:20:13,807 [salt.minion      :1432][INFO    ][40044] Starting a new job with PID 40044
2020-01-09 02:20:13,831 [salt.minion      :1711][INFO    ][40044] Returning information for job: 20200109022013764296
2020-01-09 02:20:34,291 [salt.loaded.ext.module.maas:1023][INFO    ][38653] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1862.88706994s left)
2020-01-09 02:20:43,936 [salt.minion      :1308][INFO    ][35926] User sudo_ubuntu Executing command saltutil.find_job with jid 20200109022043927220
2020-01-09 02:20:43,959 [salt.minion      :1432][INFO    ][40317] Starting a new job with PID 40317
2020-01-09 02:20:43,983 [salt.minion      :1711][INFO    ][40317] Returning information for job: 20200109022043927220
2020-01-09 02:21:06,670 [salt.loaded.ext.module.maas:1023][INFO    ][38653] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1830.50870395s left)
2020-01-09 02:21:14,052 [salt.minion      :1308][INFO    ][35926] User sudo_ubuntu Executing command saltutil.find_job with jid 20200109022114042648
2020-01-09 02:21:14,079 [salt.minion      :1432][INFO    ][40352] Starting a new job with PID 40352
2020-01-09 02:21:14,102 [salt.minion      :1711][INFO    ][40352] Returning information for job: 20200109022114042648
2020-01-09 02:21:38,784 [salt.loaded.ext.module.maas:1023][INFO    ][38653] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1798.39446878s left)
2020-01-09 02:21:44,216 [salt.minion      :1308][INFO    ][35926] User sudo_ubuntu Executing command saltutil.find_job with jid 20200109022144202382
2020-01-09 02:21:44,245 [salt.minion      :1432][INFO    ][40585] Starting a new job with PID 40585
2020-01-09 02:21:44,272 [salt.minion      :1711][INFO    ][40585] Returning information for job: 20200109022144202382
2020-01-09 02:22:10,915 [salt.loaded.ext.module.maas:1023][INFO    ][38653] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1766.2640059s left)
2020-01-09 02:22:14,378 [salt.minion      :1308][INFO    ][35926] User sudo_ubuntu Executing command saltutil.find_job with jid 20200109022214364402
2020-01-09 02:22:14,408 [salt.minion      :1432][INFO    ][40721] Starting a new job with PID 40721
2020-01-09 02:22:14,430 [salt.minion      :1711][INFO    ][40721] Returning information for job: 20200109022214364402
2020-01-09 02:22:43,082 [salt.loaded.ext.module.maas:1023][INFO    ][38653] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1734.09606886s left)
2020-01-09 02:22:44,552 [salt.minion      :1308][INFO    ][35926] User sudo_ubuntu Executing command saltutil.find_job with jid 20200109022244538009
2020-01-09 02:22:44,575 [salt.minion      :1432][INFO    ][40974] Starting a new job with PID 40974
2020-01-09 02:22:44,599 [salt.minion      :1711][INFO    ][40974] Returning information for job: 20200109022244538009
2020-01-09 02:23:14,699 [salt.minion      :1308][INFO    ][35926] User sudo_ubuntu Executing command saltutil.find_job with jid 20200109022314689228
2020-01-09 02:23:14,723 [salt.minion      :1432][INFO    ][41026] Starting a new job with PID 41026
2020-01-09 02:23:14,743 [salt.minion      :1711][INFO    ][41026] Returning information for job: 20200109022314689228
2020-01-09 02:23:15,072 [salt.loaded.ext.module.maas:1023][INFO    ][38653] Waiting status:Deployed for machines:['gtw01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1702.10615492s left)
2020-01-09 02:23:44,852 [salt.minion      :1308][INFO    ][35926] User sudo_ubuntu Executing command saltutil.find_job with jid 20200109022344838861
2020-01-09 02:23:44,879 [salt.minion      :1432][INFO    ][41186] Starting a new job with PID 41186
2020-01-09 02:23:44,903 [salt.minion      :1711][INFO    ][41186] Returning information for job: 20200109022344838861
2020-01-09 02:23:47,023 [salt.loaded.ext.module.maas:1023][INFO    ][38653] Waiting status:Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:2250s (1670.15588093s left)
2020-01-09 02:24:15,009 [salt.minion      :1308][INFO    ][35926] User sudo_ubuntu Executing command saltutil.find_job with jid 20200109022415000198
2020-01-09 02:24:15,036 [salt.minion      :1432][INFO    ][41228] Starting a new job with PID 41228
2020-01-09 02:24:15,057 [salt.minion      :1711][INFO    ][41228] Returning information for job: 20200109022415000198
2020-01-09 02:24:18,983 [salt.loaded.ext.module.maas:1023][INFO    ][38653] Waiting status:Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:2250s (1638.19562793s left)
2020-01-09 02:24:45,208 [salt.minion      :1308][INFO    ][35926] User sudo_ubuntu Executing command saltutil.find_job with jid 20200109022445194413
2020-01-09 02:24:45,237 [salt.minion      :1432][INFO    ][41430] Starting a new job with PID 41430
2020-01-09 02:24:45,261 [salt.minion      :1711][INFO    ][41430] Returning information for job: 20200109022445194413
2020-01-09 02:24:50,974 [salt.loaded.ext.module.maas:1023][INFO    ][38653] Waiting status:Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:2250s (1606.20468092s left)
2020-01-09 02:25:15,380 [salt.minion      :1308][INFO    ][35926] User sudo_ubuntu Executing command saltutil.find_job with jid 20200109022515366860
2020-01-09 02:25:15,407 [salt.minion      :1432][INFO    ][41457] Starting a new job with PID 41457
2020-01-09 02:25:15,429 [salt.minion      :1711][INFO    ][41457] Returning information for job: 20200109022515366860
2020-01-09 02:25:23,205 [salt.loaded.ext.module.maas:1023][INFO    ][38653] Waiting status:Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:2250s (1573.97323298s left)
2020-01-09 02:25:45,566 [salt.minion      :1308][INFO    ][35926] User sudo_ubuntu Executing command saltutil.find_job with jid 20200109022545559051
2020-01-09 02:25:45,591 [salt.minion      :1432][INFO    ][41502] Starting a new job with PID 41502
2020-01-09 02:25:45,615 [salt.minion      :1711][INFO    ][41502] Returning information for job: 20200109022545559051
2020-01-09 02:25:55,490 [salt.loaded.ext.module.maas:1023][INFO    ][38653] Waiting status:Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:2250s (1541.68824387s left)
2020-01-09 02:26:15,755 [salt.minion      :1308][INFO    ][35926] User sudo_ubuntu Executing command saltutil.find_job with jid 20200109022615746017
2020-01-09 02:26:15,778 [salt.minion      :1432][INFO    ][41579] Starting a new job with PID 41579
2020-01-09 02:26:15,813 [salt.minion      :1711][INFO    ][41579] Returning information for job: 20200109022615746017
2020-01-09 02:26:27,436 [salt.state       :300 ][INFO    ][38653] {'ret': True}
2020-01-09 02:26:27,436 [salt.state       :1951][INFO    ][38653] Completed state [maas.wait_for_machine_status] at time 02:26:27.436835 duration_in_ms=740269.367
2020-01-09 02:26:27,442 [salt.minion      :1711][INFO    ][38653] Returning information for job: 20200109021357396754
2020-01-09 03:05:45,580 [salt.utils.schedule:1377][INFO    ][35926] Running scheduled job: __mine_interval
2020-01-09 03:22:32,148 [salt.minion      :1308][INFO    ][35926] User sudo_ubuntu Executing command cp.push_dir with jid 20200109032232137353
2020-01-09 03:22:32,176 [salt.minion      :1432][INFO    ][45691] Starting a new job with PID 45691
