2020-01-12 18:12:39,541 [salt.utils.decorators:613 ][WARNING ][1957] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-12 18:12:40,490 [salt.utils.decorators:613 ][WARNING ][1957] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-12 18:12:43,475 [salt.loaded.int.states.file:2298][WARNING ][2351] 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-12 18:13:13,475 [salt.state       :2022][WARNING ][2840] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2020-01-12 18:13:16,454 [salt.utils.decorators:613 ][WARNING ][2840] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-12 18:28:22,993 [salt.utils.decorators:613 ][WARNING ][2840] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-12 18:32:17,243 [salt.utils.decorators:613 ][WARNING ][2840] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-12 18:34:58,639 [salt.utils.decorators:613 ][WARNING ][2840] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-12 18:35:00,638 [salt.loaded.ext.module.maas:160 ][ERROR   ][2840] Failed for object default_osystem reason {"default_osystem": ["'ubuntu' is not a valid osystem.  It should be one of: '---'."]}
2020-01-12 18:35:01,642 [salt.loaded.ext.module.maas:160 ][ERROR   ][2840] Failed for object default_distro_series reason {"default_distro_series": ["'bionic' is not a valid release.  It should be one of: '---'."]}
2020-01-12 18:35:01,763 [salt.state       :302 ][ERROR   ][2840] Module function maas.process_maas_config threw an exception. Exception: {'updated': [], 'errors': {'default_distro_series': '{"default_distro_series": ["\'bionic\' is not a valid release.  It should be one of: \'---\'."]}', 'default_osystem': '{"default_osystem": ["\'ubuntu\' is not a valid osystem.  It should be one of: \'---\'."]}'}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', '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_min_hwe_kernel']}
2020-01-12 18:35:01,910 [salt.loaded.ext.module.maasng:1008][WARNING ][2840] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2020-01-12 18:35:01,911 [salt.loaded.ext.module.maasng:1011][WARNING ][2840] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2020-01-12 18:35:02,617 [salt.loaded.ext.module.maasng:1235][WARNING ][2840] Ignoring parameter vlan:0
2020-01-12 18:35:03,830 [salt.utils.decorators:613 ][WARNING ][2840] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-12 18:35:08,415 [salt.loaded.int.module.cmdmod:395 ][INFO    ][35475] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2020-01-12 18:35:08,460 [salt.loaded.int.module.cmdmod:395 ][INFO    ][35475] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2020-01-12 18:35:08,522 [salt.utils.parsers:1051][WARNING ][380] Minion received a SIGTERM. Exiting.
2020-01-12 18:35:10,515 [salt.cli.daemons :293 ][INFO    ][35523] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2020-01-12 18:35:10,703 [salt.cli.daemons :82  ][INFO    ][35523] Starting up the Salt Minion
2020-01-12 18:35:10,704 [salt.utils.event :1017][INFO    ][35523] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2020-01-12 18:35:11,988 [salt.minion      :976 ][INFO    ][35523] Creating minion process manager
2020-01-12 18:35:13,929 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][35523] Executing command ['date', '+%z'] in directory '/root'
2020-01-12 18:35:13,958 [salt.utils.schedule:568 ][INFO    ][35523] Updating job settings for scheduled job: __mine_interval
2020-01-12 18:35:13,960 [salt.minion      :1108][INFO    ][35523] Added mine.update to scheduler
2020-01-12 18:35:13,967 [salt.minion      :1975][INFO    ][35523] Minion is starting as user 'root'
2020-01-12 18:35:13,983 [salt.minion      :2336][INFO    ][35523] Minion is ready to receive requests!
2020-01-12 18:35:15,135 [salt.minion      :1308][INFO    ][35523] User sudo_ubuntu Executing command state.apply with jid 20200112183515121390
2020-01-12 18:35:15,158 [salt.minion      :1432][INFO    ][35604] Starting a new job with PID 35604
2020-01-12 18:35:22,001 [salt.state       :915 ][INFO    ][35604] Loading fresh modules for state activity
2020-01-12 18:35:23,532 [salt.state       :1780][INFO    ][35604] Running state [maas-region-controller] at time 18:35:23.531900
2020-01-12 18:35:23,532 [salt.state       :1813][INFO    ][35604] Executing state pkg.installed for [maas-region-controller]
2020-01-12 18:35:23,533 [salt.loaded.int.module.cmdmod:395 ][INFO    ][35604] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2020-01-12 18:35:23,670 [salt.state       :300 ][INFO    ][35604] All specified packages are already installed
2020-01-12 18:35:23,671 [salt.state       :1951][INFO    ][35604] Completed state [maas-region-controller] at time 18:35:23.671332 duration_in_ms=139.433
2020-01-12 18:35:23,671 [salt.state       :1780][INFO    ][35604] Running state [python-oauth] at time 18:35:23.671851
2020-01-12 18:35:23,672 [salt.state       :1813][INFO    ][35604] Executing state pkg.installed for [python-oauth]
2020-01-12 18:35:23,688 [salt.state       :300 ][INFO    ][35604] All specified packages are already installed
2020-01-12 18:35:23,688 [salt.state       :1951][INFO    ][35604] Completed state [python-oauth] at time 18:35:23.688900 duration_in_ms=17.05
2020-01-12 18:35:23,696 [salt.state       :1780][INFO    ][35604] Running state [/etc/maas/regiond.conf] at time 18:35:23.696526
2020-01-12 18:35:23,697 [salt.state       :1813][INFO    ][35604] Executing state file.replace for [/etc/maas/regiond.conf]
2020-01-12 18:35:23,708 [salt.state       :300 ][INFO    ][35604] No changes needed to be made
2020-01-12 18:35:23,708 [salt.state       :1951][INFO    ][35604] Completed state [/etc/maas/regiond.conf] at time 18:35:23.708394 duration_in_ms=11.868
2020-01-12 18:35:23,709 [salt.state       :1780][INFO    ][35604] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 18:35:23.708970
2020-01-12 18:35:23,709 [salt.state       :1813][INFO    ][35604] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2020-01-12 18:35:23,781 [salt.state       :300 ][INFO    ][35604] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2020-01-12 18:35:23,782 [salt.state       :1951][INFO    ][35604] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 18:35:23.782064 duration_in_ms=73.093
2020-01-12 18:35:23,783 [salt.state       :1780][INFO    ][35604] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 18:35:23.783211
2020-01-12 18:35:23,783 [salt.state       :1813][INFO    ][35604] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2020-01-12 18:35:23,791 [salt.state       :300 ][INFO    ][35604] No changes needed to be made
2020-01-12 18:35:23,792 [salt.state       :1951][INFO    ][35604] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 18:35:23.791860 duration_in_ms=8.649
2020-01-12 18:35:23,793 [salt.state       :1780][INFO    ][35604] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 18:35:23.793002
2020-01-12 18:35:23,793 [salt.state       :1813][INFO    ][35604] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2020-01-12 18:35:23,803 [salt.state       :300 ][INFO    ][35604] No changes needed to be made
2020-01-12 18:35:23,804 [salt.state       :1951][INFO    ][35604] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 18:35:23.804075 duration_in_ms=11.073
2020-01-12 18:35:23,805 [salt.state       :1780][INFO    ][35604] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 18:35:23.805139
2020-01-12 18:35:23,805 [salt.state       :1813][INFO    ][35604] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2020-01-12 18:35:23,851 [salt.state       :300 ][INFO    ][35604] No changes needed to be made
2020-01-12 18:35:23,852 [salt.state       :1951][INFO    ][35604] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 18:35:23.852047 duration_in_ms=46.909
2020-01-12 18:35:23,853 [salt.state       :1780][INFO    ][35604] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 18:35:23.853132
2020-01-12 18:35:23,853 [salt.state       :1813][INFO    ][35604] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2020-01-12 18:35:23,883 [salt.state       :300 ][INFO    ][35604] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2020-01-12 18:35:23,883 [salt.state       :1951][INFO    ][35604] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 18:35:23.883815 duration_in_ms=30.683
2020-01-12 18:35:23,887 [salt.state       :1780][INFO    ][35604] Running state [a2enmod headers] at time 18:35:23.887368
2020-01-12 18:35:23,888 [salt.state       :1813][INFO    ][35604] Executing state cmd.run for [a2enmod headers]
2020-01-12 18:35:23,889 [salt.loaded.int.module.cmdmod:395 ][INFO    ][35604] Executing command 'a2enmod headers' in directory '/root'
2020-01-12 18:35:23,975 [salt.state       :300 ][INFO    ][35604] {'pid': 35652, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2020-01-12 18:35:23,975 [salt.state       :1951][INFO    ][35604] Completed state [a2enmod headers] at time 18:35:23.975411 duration_in_ms=88.044
2020-01-12 18:35:23,975 [salt.state       :1780][INFO    ][35604] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 18:35:23.975822
2020-01-12 18:35:23,976 [salt.state       :1813][INFO    ][35604] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2020-01-12 18:35:24,001 [salt.state       :300 ][INFO    ][35604] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2020-01-12 18:35:24,002 [salt.state       :1951][INFO    ][35604] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 18:35:24.002129 duration_in_ms=26.306
2020-01-12 18:35:24,002 [salt.state       :1780][INFO    ][35604] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 18:35:24.002812
2020-01-12 18:35:24,003 [salt.state       :1813][INFO    ][35604] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2020-01-12 18:35:24,067 [salt.state       :300 ][INFO    ][35604] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2020-01-12 18:35:24,067 [salt.state       :1951][INFO    ][35604] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 18:35:24.067366 duration_in_ms=64.554
2020-01-12 18:35:24,068 [salt.state       :1780][INFO    ][35604] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 18:35:24.067972
2020-01-12 18:35:24,068 [salt.state       :1813][INFO    ][35604] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2020-01-12 18:35:24,135 [salt.state       :300 ][INFO    ][35604] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2020-01-12 18:35:24,136 [salt.state       :1951][INFO    ][35604] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 18:35:24.135935 duration_in_ms=67.962
2020-01-12 18:35:24,136 [salt.state       :1780][INFO    ][35604] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 18:35:24.136524
2020-01-12 18:35:24,136 [salt.state       :1813][INFO    ][35604] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2020-01-12 18:35:24,205 [salt.state       :300 ][INFO    ][35604] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2020-01-12 18:35:24,205 [salt.state       :1951][INFO    ][35604] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 18:35:24.205467 duration_in_ms=68.943
2020-01-12 18:35:24,206 [salt.state       :1780][INFO    ][35604] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_bionic] at time 18:35:24.206059
2020-01-12 18:35:24,206 [salt.state       :1813][INFO    ][35604] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_bionic]
2020-01-12 18:35:24,274 [salt.state       :300 ][INFO    ][35604] File /etc/maas/preseeds/curtin_userdata_amd64_generic_bionic is in the correct state
2020-01-12 18:35:24,275 [salt.state       :1951][INFO    ][35604] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_bionic] at time 18:35:24.275186 duration_in_ms=69.126
2020-01-12 18:35:24,275 [salt.state       :1780][INFO    ][35604] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_bionic] at time 18:35:24.275865
2020-01-12 18:35:24,276 [salt.state       :1813][INFO    ][35604] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_bionic]
2020-01-12 18:35:24,343 [salt.state       :300 ][INFO    ][35604] File /etc/maas/preseeds/curtin_userdata_arm64_generic_bionic is in the correct state
2020-01-12 18:35:24,343 [salt.state       :1951][INFO    ][35604] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_bionic] at time 18:35:24.343298 duration_in_ms=67.433
2020-01-12 18:35:24,343 [salt.state       :1780][INFO    ][35604] Running state [/root/.pgpass] at time 18:35:24.343627
2020-01-12 18:35:24,343 [salt.state       :1813][INFO    ][35604] Executing state file.managed for [/root/.pgpass]
2020-01-12 18:35:24,403 [salt.state       :300 ][INFO    ][35604] File /root/.pgpass is in the correct state
2020-01-12 18:35:24,403 [salt.state       :1951][INFO    ][35604] Completed state [/root/.pgpass] at time 18:35:24.403388 duration_in_ms=59.759
2020-01-12 18:35:24,413 [salt.state       :1780][INFO    ][35604] Running state [maas-region syncdb --noinput] at time 18:35:24.412973
2020-01-12 18:35:24,415 [salt.state       :1813][INFO    ][35604] Executing state cmd.run for [maas-region syncdb --noinput]
2020-01-12 18:35:24,416 [salt.loaded.int.module.cmdmod:395 ][INFO    ][35604] Executing command 'maas-region syncdb --noinput' in directory '/root'
2020-01-12 18:35:27,808 [salt.state       :300 ][INFO    ][35604] {'pid': 35669, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: metadataserver, contenttypes, sites, sessions, auth, maasserver, piston3\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2020-01-12 18:35:27,809 [salt.state       :1951][INFO    ][35604] Completed state [maas-region syncdb --noinput] at time 18:35:27.808952 duration_in_ms=3395.979
2020-01-12 18:35:27,810 [salt.state       :2022][WARNING ][35604] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2020-01-12 18:35:27,812 [salt.state       :1780][INFO    ][35604] Running state [maas-regiond] at time 18:35:27.812487
2020-01-12 18:35:27,812 [salt.state       :1813][INFO    ][35604] Executing state service.running for [maas-regiond]
2020-01-12 18:35:27,814 [salt.loaded.int.module.cmdmod:395 ][INFO    ][35604] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2020-01-12 18:35:27,862 [salt.loaded.int.module.cmdmod:395 ][INFO    ][35604] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2020-01-12 18:35:27,886 [salt.loaded.int.module.cmdmod:395 ][INFO    ][35604] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2020-01-12 18:35:27,910 [salt.state       :300 ][INFO    ][35604] The service maas-regiond is already running
2020-01-12 18:35:27,911 [salt.state       :1951][INFO    ][35604] Completed state [maas-regiond] at time 18:35:27.910977 duration_in_ms=98.489
2020-01-12 18:35:27,915 [salt.state       :1780][INFO    ][35604] Running state [bind9] at time 18:35:27.915027
2020-01-12 18:35:27,915 [salt.state       :1813][INFO    ][35604] Executing state service.running for [bind9]
2020-01-12 18:35:27,917 [salt.loaded.int.module.cmdmod:395 ][INFO    ][35604] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2020-01-12 18:35:27,943 [salt.loaded.int.module.cmdmod:395 ][INFO    ][35604] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2020-01-12 18:35:27,962 [salt.loaded.int.module.cmdmod:395 ][INFO    ][35604] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2020-01-12 18:35:27,984 [salt.state       :300 ][INFO    ][35604] The service bind9 is already running
2020-01-12 18:35:27,985 [salt.state       :1951][INFO    ][35604] Completed state [bind9] at time 18:35:27.985365 duration_in_ms=70.338
2020-01-12 18:35:27,989 [salt.state       :1780][INFO    ][35604] Running state [apache2] at time 18:35:27.988957
2020-01-12 18:35:27,991 [salt.state       :1813][INFO    ][35604] Executing state service.running for [apache2]
2020-01-12 18:35:27,992 [salt.loaded.int.module.cmdmod:395 ][INFO    ][35604] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2020-01-12 18:35:28,016 [salt.loaded.int.module.cmdmod:395 ][INFO    ][35604] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2020-01-12 18:35:28,037 [salt.loaded.int.module.cmdmod:395 ][INFO    ][35604] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2020-01-12 18:35:28,072 [salt.state       :300 ][INFO    ][35604] The service apache2 is already running
2020-01-12 18:35:28,076 [salt.state       :1951][INFO    ][35604] Completed state [apache2] at time 18:35:28.073115 duration_in_ms=84.158
2020-01-12 18:35:28,079 [salt.state       :1780][INFO    ][35604] Running state [maasng.wait_for_http_code] at time 18:35:28.079368
2020-01-12 18:35:28,080 [salt.state       :1813][INFO    ][35604] Executing state module.run for [maasng.wait_for_http_code]
2020-01-12 18:35:28,080 [salt.utils.decorators:613 ][WARNING ][35604] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-12 18:35:28,219 [salt.state       :300 ][INFO    ][35604] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2020-01-12 18:35:28,219 [salt.state       :1951][INFO    ][35604] Completed state [maasng.wait_for_http_code] at time 18:35:28.219773 duration_in_ms=140.405
2020-01-12 18:35:28,221 [salt.state       :1780][INFO    ][35604] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 18:35:28.221358
2020-01-12 18:35:28,221 [salt.state       :1813][INFO    ][35604] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2020-01-12 18:35:28,222 [salt.state       :300 ][INFO    ][35604] /var/lib/maas/.setup_admin exists
2020-01-12 18:35:28,223 [salt.state       :1951][INFO    ][35604] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 18:35:28.223000 duration_in_ms=1.641
2020-01-12 18:35:28,224 [salt.state       :1780][INFO    ][35604] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 18:35:28.224345
2020-01-12 18:35:28,224 [salt.state       :1813][INFO    ][35604] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-01-12 18:35:28,225 [salt.loaded.int.module.cmdmod:395 ][INFO    ][35604] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-01-12 18:35:30,139 [salt.state       :300 ][INFO    ][35604] {'pid': 35690, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-01-12 18:35:30,140 [salt.state       :1951][INFO    ][35604] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 18:35:30.140325 duration_in_ms=1915.981
2020-01-12 18:35:30,151 [salt.state       :1780][INFO    ][35604] Running state [maas_region_boot_source_resources_mirror] at time 18:35:30.151571
2020-01-12 18:35:30,152 [salt.state       :1813][INFO    ][35604] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2020-01-12 18:35:30,191 [salt.minion      :1308][INFO    ][35523] User sudo_ubuntu Executing command saltutil.find_job with jid 20200112183530179762
2020-01-12 18:35:30,220 [salt.minion      :1432][INFO    ][35698] Starting a new job with PID 35698
2020-01-12 18:35:30,252 [salt.state       :300 ][INFO    ][35604] {'changes': {}}
2020-01-12 18:35:30,252 [salt.state       :1951][INFO    ][35604] Completed state [maas_region_boot_source_resources_mirror] at time 18:35:30.252303 duration_in_ms=100.732
2020-01-12 18:35:30,253 [salt.state       :1780][INFO    ][35604] Running state [maasng.boot_resources_import] at time 18:35:30.253162
2020-01-12 18:35:30,253 [salt.state       :1813][INFO    ][35604] Executing state module.run for [maasng.boot_resources_import]
2020-01-12 18:35:30,254 [salt.utils.decorators:613 ][WARNING ][35604] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-12 18:35:30,253 [salt.minion      :1711][INFO    ][35698] Returning information for job: 20200112183530179762
2020-01-12 18:35:30,347 [salt.loaded.ext.module.maasng:1600][INFO    ][35604] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2020-01-12 18:35:35,406 [salt.loaded.ext.module.maasng:1600][INFO    ][35604] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2020-01-12 18:35:40,510 [salt.state       :300 ][INFO    ][35604] {'ret': True}
2020-01-12 18:35:40,510 [salt.state       :1951][INFO    ][35604] Completed state [maasng.boot_resources_import] at time 18:35:40.510796 duration_in_ms=10257.632
2020-01-12 18:35:40,512 [salt.state       :1780][INFO    ][35604] Running state [maas_region_boot_sources_selection_bionic] at time 18:35:40.512136
2020-01-12 18:35:40,512 [salt.state       :1813][INFO    ][35604] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_bionic]
2020-01-12 18:35:40,694 [salt.state       :300 ][INFO    ][35604] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2020-01-12 18:35:40,694 [salt.state       :1951][INFO    ][35604] Completed state [maas_region_boot_sources_selection_bionic] at time 18:35:40.694613 duration_in_ms=182.476
2020-01-12 18:35:40,696 [salt.state       :1780][INFO    ][35604] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 18:35:40.696368
2020-01-12 18:35:40,697 [salt.state       :1813][INFO    ][35604] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2020-01-12 18:35:40,699 [salt.utils.decorators:613 ][WARNING ][35604] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-12 18:35:40,700 [salt.loaded.ext.module.maasng:1771][INFO    ][35604] boot-sources sync initiated for ALL Rack's
2020-01-12 18:35:41,553 [salt.state       :300 ][INFO    ][35604] {'ret': True}
2020-01-12 18:35:41,554 [salt.state       :1951][INFO    ][35604] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 18:35:41.554088 duration_in_ms=857.72
2020-01-12 18:35:41,556 [salt.state       :1780][INFO    ][35604] Running state [maas.process_maas_config] at time 18:35:41.556119
2020-01-12 18:35:41,556 [salt.state       :1813][INFO    ][35604] Executing state module.run for [maas.process_maas_config]
2020-01-12 18:35:41,557 [salt.utils.decorators:613 ][WARNING ][35604] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-12 18:35:41,558 [salt.loaded.ext.module.maas:92  ][INFO    ][35604] maasconfig name=enable_http_proxy value=True
2020-01-12 18:35:41,614 [salt.loaded.ext.module.maas:92  ][INFO    ][35604] maasconfig name=upstream_dns value=8.8.8.8
2020-01-12 18:35:41,666 [salt.loaded.ext.module.maas:92  ][INFO    ][35604] maasconfig name=commissioning_distro_series value=bionic
2020-01-12 18:35:43,320 [salt.loaded.ext.module.maas:92  ][INFO    ][35604] maasconfig name=default_osystem value=ubuntu
2020-01-12 18:35:43,376 [salt.loaded.ext.module.maas:92  ][INFO    ][35604] maasconfig name=active_discovery_interval value=600
2020-01-12 18:35:43,424 [salt.loaded.ext.module.maas:92  ][INFO    ][35604] maasconfig name=dnssec_validation value=no
2020-01-12 18:35:43,479 [salt.loaded.ext.module.maas:92  ][INFO    ][35604] maasconfig name=maas_name value=mas01
2020-01-12 18:35:43,524 [salt.loaded.ext.module.maas:92  ][INFO    ][35604] maasconfig name=network_discovery value=enabled
2020-01-12 18:35:43,619 [salt.loaded.ext.module.maas:92  ][INFO    ][35604] maasconfig name=enable_third_party_drivers value=True
2020-01-12 18:35:43,667 [salt.loaded.ext.module.maas:92  ][INFO    ][35604] maasconfig name=default_storage_layout value=lvm
2020-01-12 18:35:43,722 [salt.loaded.ext.module.maas:92  ][INFO    ][35604] maasconfig name=ntp_external_only value=True
2020-01-12 18:35:43,771 [salt.loaded.ext.module.maas:92  ][INFO    ][35604] maasconfig name=disk_erase_with_secure_erase value=False
2020-01-12 18:35:43,831 [salt.loaded.ext.module.maas:92  ][INFO    ][35604] maasconfig name=default_distro_series value=bionic
2020-01-12 18:35:43,904 [salt.loaded.ext.module.maas:92  ][INFO    ][35604] maasconfig name=default_min_hwe_kernel value=ga-18.04
2020-01-12 18:35:43,995 [salt.state       :300 ][INFO    ][35604] {'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-12 18:35:43,995 [salt.state       :1951][INFO    ][35604] Completed state [maas.process_maas_config] at time 18:35:43.995627 duration_in_ms=2439.507
2020-01-12 18:35:43,996 [salt.state       :1780][INFO    ][35604] Running state [pxe_admin] at time 18:35:43.996737
2020-01-12 18:35:43,998 [salt.state       :1813][INFO    ][35604] Executing state maasng.fabric_present for [pxe_admin]
2020-01-12 18:35:44,058 [salt.loaded.ext.module.maasng:945 ][INFO    ][35604] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None, u'name': u'fabric-2'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'eddycb', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u'', u'name': u'pxe_admin'}]
2020-01-12 18:35:44,120 [salt.loaded.ext.module.maasng:1008][WARNING ][35604] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2020-01-12 18:35:44,121 [salt.loaded.ext.module.maasng:1011][WARNING ][35604] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2020-01-12 18:35:44,188 [salt.state       :300 ][INFO    ][35604] {'new': 'Fabric  pxe_admin created', 'result': True}
2020-01-12 18:35:44,188 [salt.state       :1951][INFO    ][35604] Completed state [pxe_admin] at time 18:35:44.188813 duration_in_ms=192.075
2020-01-12 18:35:44,189 [salt.state       :1780][INFO    ][35604] Running state [vlan 0] at time 18:35:44.189146
2020-01-12 18:35:44,190 [salt.state       :1813][INFO    ][35604] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2020-01-12 18:35:44,244 [salt.loaded.ext.module.maasng:945 ][INFO    ][35604] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/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'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/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'id': 2}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{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'eddycb', 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'id': 1}]
2020-01-12 18:35:44,388 [salt.loaded.ext.module.maasng:945 ][INFO    ][35604] [{u'class_type': None, u'vlans': [{u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'vid': 0, u'fabric': u'fabric-2', u'name': u'untagged', 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'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'class_type': u'', u'vlans': [{u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'eddycb', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2020-01-12 18:35:44,680 [salt.loaded.ext.module.maasng:945 ][INFO    ][35604] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/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'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/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'id': 2}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{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'eddycb', 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'id': 1}]
2020-01-12 18:35:44,757 [salt.state       :300 ][INFO    ][35604] {'new': 'Vlan untagged was updated'}
2020-01-12 18:35:44,757 [salt.state       :1951][INFO    ][35604] Completed state [vlan 0] at time 18:35:44.757737 duration_in_ms=568.589
2020-01-12 18:35:44,759 [salt.state       :1780][INFO    ][35604] Running state [192.168.11.0/24] at time 18:35:44.759065
2020-01-12 18:35:44,759 [salt.state       :1813][INFO    ][35604] Executing state maasng.subnet_present for [192.168.11.0/24]
2020-01-12 18:35:44,985 [salt.loaded.ext.module.maasng:945 ][INFO    ][35604] [{u'class_type': None, u'vlans': [{u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'vid': 0, u'fabric': u'fabric-2', u'name': u'untagged', 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'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'class_type': u'', u'vlans': [{u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'eddycb', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2020-01-12 18:35:44,985 [salt.loaded.ext.module.maasng:1235][WARNING ][35604] Ignoring parameter vlan:0
2020-01-12 18:35:45,046 [salt.state       :300 ][INFO    ][35604] Subnet 192.168.11.0/24 has been updated for pxe_admin
2020-01-12 18:35:45,046 [salt.state       :1951][INFO    ][35604] Completed state [192.168.11.0/24] at time 18:35:45.046656 duration_in_ms=287.592
2020-01-12 18:35:45,047 [salt.state       :1780][INFO    ][35604] Running state [maas_create_iprange_1] at time 18:35:45.047585
2020-01-12 18:35:45,047 [salt.state       :1813][INFO    ][35604] Executing state maasng.iprange_present for [maas_create_iprange_1]
2020-01-12 18:35:45,092 [salt.state       :300 ][INFO    ][35604] Iprange maas_create_iprange_1 already exist.
2020-01-12 18:35:45,093 [salt.state       :1951][INFO    ][35604] Completed state [maas_create_iprange_1] at time 18:35:45.093166 duration_in_ms=45.58
2020-01-12 18:35:45,094 [salt.state       :1780][INFO    ][35604] Running state [vlan 0] at time 18:35:45.094392
2020-01-12 18:35:45,094 [salt.state       :1813][INFO    ][35604] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2020-01-12 18:35:45,139 [salt.loaded.ext.module.maasng:945 ][INFO    ][35604] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/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'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/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'id': 2}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/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'eddycb', 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'id': 1}]
2020-01-12 18:35:45,238 [salt.loaded.ext.module.maasng:945 ][INFO    ][35604] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'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'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'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'fabric': u'fabric-2'}], 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'eddycb', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2020-01-12 18:35:45,442 [salt.loaded.ext.module.maasng:945 ][INFO    ][35604] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None, u'name': u'fabric-2'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'eddycb', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u'', u'name': u'pxe_admin'}]
2020-01-12 18:35:45,519 [salt.state       :300 ][INFO    ][35604] {'new': 'Vlan untagged was updated'}
2020-01-12 18:35:45,519 [salt.state       :1951][INFO    ][35604] Completed state [vlan 0] at time 18:35:45.519461 duration_in_ms=425.068
2020-01-12 18:35:45,520 [salt.state       :1780][INFO    ][35604] Running state [opnfv] at time 18:35:45.520177
2020-01-12 18:35:45,520 [salt.state       :1813][INFO    ][35604] Executing state maasng.sshkey_present for [opnfv]
2020-01-12 18:35:45,568 [salt.loaded.ext.module.maasng:1903][INFO    ][35604] [{u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b', u'keysource': u''}]
2020-01-12 18:35:45,568 [salt.state       :300 ][INFO    ][35604] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2020-01-12 18:35:45,569 [salt.state       :1951][INFO    ][35604] Completed state [opnfv] at time 18:35:45.569056 duration_in_ms=48.879
2020-01-12 18:35:45,574 [salt.state       :1780][INFO    ][35604] Running state [maas.process_tags] at time 18:35:45.574553
2020-01-12 18:35:45,574 [salt.state       :1813][INFO    ][35604] Executing state module.run for [maas.process_tags]
2020-01-12 18:35:45,575 [salt.utils.decorators:613 ][WARNING ][35604] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-12 18:35:45,621 [salt.loaded.ext.module.maas:92  ][INFO    ][35604] 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-12 18:35:45,682 [salt.state       :300 ][INFO    ][35604] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2020-01-12 18:35:45,682 [salt.state       :1951][INFO    ][35604] Completed state [maas.process_tags] at time 18:35:45.682464 duration_in_ms=107.91
2020-01-12 18:35:45,686 [salt.minion      :1711][INFO    ][35604] Returning information for job: 20200112183515121390
2020-01-12 18:35:46,485 [salt.minion      :1308][INFO    ][35523] User sudo_ubuntu Executing command state.apply with jid 20200112183546464103
2020-01-12 18:35:46,505 [salt.minion      :1432][INFO    ][35989] Starting a new job with PID 35989
2020-01-12 18:35:52,754 [salt.state       :915 ][INFO    ][35989] Loading fresh modules for state activity
2020-01-12 18:35:52,827 [salt.fileclient  :1219][INFO    ][35989] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2020-01-12 18:35:52,890 [salt.state       :1780][INFO    ][35989] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 18:35:52.890036
2020-01-12 18:35:52,890 [salt.state       :1813][INFO    ][35989] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-01-12 18:35:52,892 [salt.loaded.int.module.cmdmod:395 ][INFO    ][35989] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-01-12 18:35:54,856 [salt.state       :300 ][INFO    ][35989] {'pid': 36015, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-01-12 18:35:54,858 [salt.state       :1951][INFO    ][35989] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 18:35:54.858523 duration_in_ms=1968.486
2020-01-12 18:35:54,862 [salt.state       :1780][INFO    ][35989] Running state [maas.process_machines] at time 18:35:54.862562
2020-01-12 18:35:54,863 [salt.state       :1813][INFO    ][35989] Executing state module.run for [maas.process_machines]
2020-01-12 18:35:54,863 [salt.utils.decorators:613 ][WARNING ][35989] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-12 18:35:54,931 [salt.loaded.ext.module.maas:412 ][WARNING ][35989] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-01-12 18:35:54,932 [salt.loaded.ext.module.maas:92  ][INFO    ][35989] 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-12 18:35:56,804 [salt.loaded.ext.module.maas:412 ][WARNING ][35989] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-01-12 18:35:56,804 [salt.loaded.ext.module.maas:92  ][INFO    ][35989] 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-12 18:35:58,210 [salt.loaded.ext.module.maas:412 ][WARNING ][35989] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-01-12 18:35:58,210 [salt.loaded.ext.module.maas:92  ][INFO    ][35989] machine hostname=kvm01 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-12 18:35:59,636 [salt.loaded.ext.module.maas:412 ][WARNING ][35989] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-01-12 18:35:59,636 [salt.loaded.ext.module.maas:92  ][INFO    ][35989] machine hostname=kvm03 power_type=ipmi mac_addresses=14:58:d0:54:7a:28 power_parameters_power_address=172.16.1.18 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2020-01-12 18:36:01,043 [salt.loaded.ext.module.maas:412 ][WARNING ][35989] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-01-12 18:36:01,043 [salt.loaded.ext.module.maas:92  ][INFO    ][35989] machine hostname=kvm02 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-12 18:36:01,514 [salt.minion      :1308][INFO    ][35523] User sudo_ubuntu Executing command saltutil.find_job with jid 20200112183601498560
2020-01-12 18:36:01,540 [salt.minion      :1432][INFO    ][36263] Starting a new job with PID 36263
2020-01-12 18:36:01,576 [salt.minion      :1711][INFO    ][36263] Returning information for job: 20200112183601498560
2020-01-12 18:36:02,524 [salt.state       :300 ][INFO    ][35989] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2020-01-12 18:36:02,525 [salt.state       :1951][INFO    ][35989] Completed state [maas.process_machines] at time 18:36:02.524941 duration_in_ms=7662.379
2020-01-12 18:36:02,530 [salt.minion      :1711][INFO    ][35989] Returning information for job: 20200112183546464103
2020-01-12 18:36:33,980 [salt.minion      :1308][INFO    ][35523] User sudo_ubuntu Executing command state.apply with jid 20200112183633967854
2020-01-12 18:36:34,004 [salt.minion      :1432][INFO    ][36357] Starting a new job with PID 36357
2020-01-12 18:36:40,272 [salt.state       :915 ][INFO    ][36357] Loading fresh modules for state activity
2020-01-12 18:36:40,343 [salt.fileclient  :1219][INFO    ][36357] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2020-01-12 18:36:40,389 [salt.state       :1780][INFO    ][36357] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 18:36:40.389063
2020-01-12 18:36:40,389 [salt.state       :1813][INFO    ][36357] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-01-12 18:36:40,391 [salt.loaded.int.module.cmdmod:395 ][INFO    ][36357] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-01-12 18:36:42,355 [salt.state       :300 ][INFO    ][36357] {'pid': 36364, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-01-12 18:36:42,356 [salt.state       :1951][INFO    ][36357] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 18:36:42.356772 duration_in_ms=1967.708
2020-01-12 18:36:42,360 [salt.state       :1780][INFO    ][36357] Running state [maas.wait_for_machine_status] at time 18:36:42.360862
2020-01-12 18:36:42,361 [salt.state       :1813][INFO    ][36357] Executing state module.run for [maas.wait_for_machine_status]
2020-01-12 18:36:42,362 [salt.utils.decorators:613 ][WARNING ][36357] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-12 18:36:43,224 [salt.loaded.ext.module.maas:1023][INFO    ][36357] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.15065384s left)
2020-01-12 18:36:49,012 [salt.minion      :1308][INFO    ][35523] User sudo_ubuntu Executing command saltutil.find_job with jid 20200112183649002360
2020-01-12 18:36:49,040 [salt.minion      :1432][INFO    ][36380] Starting a new job with PID 36380
2020-01-12 18:36:49,068 [salt.minion      :1711][INFO    ][36380] Returning information for job: 20200112183649002360
2020-01-12 18:37:14,162 [salt.loaded.ext.module.maas:1023][INFO    ][36357] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.21258998s left)
2020-01-12 18:37:19,096 [salt.minion      :1308][INFO    ][35523] User sudo_ubuntu Executing command saltutil.find_job with jid 20200112183719084752
2020-01-12 18:37:19,123 [salt.minion      :1432][INFO    ][36438] Starting a new job with PID 36438
2020-01-12 18:37:19,153 [salt.minion      :1711][INFO    ][36438] Returning information for job: 20200112183719084752
2020-01-12 18:37:45,044 [salt.loaded.ext.module.maas:1023][INFO    ][36357] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.33098888s left)
2020-01-12 18:37:49,186 [salt.minion      :1308][INFO    ][35523] User sudo_ubuntu Executing command saltutil.find_job with jid 20200112183749173829
2020-01-12 18:37:49,214 [salt.minion      :1432][INFO    ][36458] Starting a new job with PID 36458
2020-01-12 18:37:49,239 [salt.minion      :1711][INFO    ][36458] Returning information for job: 20200112183749173829
2020-01-12 18:38:15,938 [salt.loaded.ext.module.maas:1023][INFO    ][36357] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.43652391s left)
2020-01-12 18:38:19,283 [salt.minion      :1308][INFO    ][35523] User sudo_ubuntu Executing command saltutil.find_job with jid 20200112183819274002
2020-01-12 18:38:19,307 [salt.minion      :1432][INFO    ][36516] Starting a new job with PID 36516
2020-01-12 18:38:19,335 [salt.minion      :1711][INFO    ][36516] Returning information for job: 20200112183819274002
2020-01-12 18:38:47,023 [salt.loaded.ext.module.maas:1023][INFO    ][36357] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.35153198s left)
2020-01-12 18:38:49,382 [salt.minion      :1308][INFO    ][35523] User sudo_ubuntu Executing command saltutil.find_job with jid 20200112183849366437
2020-01-12 18:38:49,410 [salt.minion      :1432][INFO    ][36567] Starting a new job with PID 36567
2020-01-12 18:38:49,439 [salt.minion      :1711][INFO    ][36567] Returning information for job: 20200112183849366437
2020-01-12 18:39:18,597 [salt.loaded.ext.module.maas:1023][INFO    ][36357] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1343.77730799s left)
2020-01-12 18:39:19,499 [salt.minion      :1308][INFO    ][35523] User sudo_ubuntu Executing command saltutil.find_job with jid 20200112183919488888
2020-01-12 18:39:19,525 [salt.minion      :1432][INFO    ][36802] Starting a new job with PID 36802
2020-01-12 18:39:19,551 [salt.minion      :1711][INFO    ][36802] Returning information for job: 20200112183919488888
2020-01-12 18:39:49,584 [salt.minion      :1308][INFO    ][35523] User sudo_ubuntu Executing command saltutil.find_job with jid 20200112183949573930
2020-01-12 18:39:49,606 [salt.minion      :1432][INFO    ][36884] Starting a new job with PID 36884
2020-01-12 18:39:49,634 [salt.minion      :1711][INFO    ][36884] Returning information for job: 20200112183949573930
2020-01-12 18:39:49,948 [salt.loaded.ext.module.maas:1023][INFO    ][36357] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1312.42694497s left)
2020-01-12 18:40:19,699 [salt.minion      :1308][INFO    ][35523] User sudo_ubuntu Executing command saltutil.find_job with jid 20200112184019688236
2020-01-12 18:40:19,718 [salt.minion      :1432][INFO    ][37135] Starting a new job with PID 37135
2020-01-12 18:40:19,743 [salt.minion      :1711][INFO    ][37135] Returning information for job: 20200112184019688236
2020-01-12 18:40:21,221 [salt.loaded.ext.module.maas:1023][INFO    ][36357] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1281.15375805s left)
2020-01-12 18:40:49,787 [salt.minion      :1308][INFO    ][35523] User sudo_ubuntu Executing command saltutil.find_job with jid 20200112184049771158
2020-01-12 18:40:49,813 [salt.minion      :1432][INFO    ][37208] Starting a new job with PID 37208
2020-01-12 18:40:49,845 [salt.minion      :1711][INFO    ][37208] Returning information for job: 20200112184049771158
2020-01-12 18:40:53,306 [salt.loaded.ext.module.maas:1023][INFO    ][36357] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1249.06824303s left)
2020-01-12 18:41:19,956 [salt.minion      :1308][INFO    ][35523] User sudo_ubuntu Executing command saltutil.find_job with jid 20200112184119936835
2020-01-12 18:41:19,983 [salt.minion      :1432][INFO    ][37651] Starting a new job with PID 37651
2020-01-12 18:41:20,042 [salt.minion      :1711][INFO    ][37651] Returning information for job: 20200112184119936835
2020-01-12 18:41:25,681 [salt.loaded.ext.module.maas:1023][INFO    ][36357] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1216.69306684s left)
2020-01-12 18:41:50,125 [salt.minion      :1308][INFO    ][35523] User sudo_ubuntu Executing command saltutil.find_job with jid 20200112184150108959
2020-01-12 18:41:50,152 [salt.minion      :1432][INFO    ][37867] Starting a new job with PID 37867
2020-01-12 18:41:50,184 [salt.minion      :1711][INFO    ][37867] Returning information for job: 20200112184150108959
2020-01-12 18:41:57,794 [salt.loaded.ext.module.maas:1023][INFO    ][36357] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1184.58020782s left)
2020-01-12 18:42:20,256 [salt.minion      :1308][INFO    ][35523] User sudo_ubuntu Executing command saltutil.find_job with jid 20200112184220240237
2020-01-12 18:42:20,283 [salt.minion      :1432][INFO    ][37945] Starting a new job with PID 37945
2020-01-12 18:42:20,311 [salt.minion      :1711][INFO    ][37945] Returning information for job: 20200112184220240237
2020-01-12 18:42:30,997 [salt.loaded.ext.module.maas:1023][INFO    ][36357] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1151.37772584s left)
2020-01-12 18:42:50,387 [salt.minion      :1308][INFO    ][35523] User sudo_ubuntu Executing command saltutil.find_job with jid 20200112184250373124
2020-01-12 18:42:50,412 [salt.minion      :1432][INFO    ][38071] Starting a new job with PID 38071
2020-01-12 18:42:50,443 [salt.minion      :1711][INFO    ][38071] Returning information for job: 20200112184250373124
2020-01-12 18:43:04,046 [salt.state       :300 ][INFO    ][36357] {'ret': True}
2020-01-12 18:43:04,047 [salt.state       :1951][INFO    ][36357] Completed state [maas.wait_for_machine_status] at time 18:43:04.047019 duration_in_ms=381686.156
2020-01-12 18:43:04,053 [salt.minion      :1711][INFO    ][36357] Returning information for job: 20200112183633967854
2020-01-12 18:43:04,754 [salt.minion      :1308][INFO    ][35523] User sudo_ubuntu Executing command state.apply with jid 20200112184304744235
2020-01-12 18:43:04,780 [salt.minion      :1432][INFO    ][38120] Starting a new job with PID 38120
2020-01-12 18:43:10,998 [salt.state       :915 ][INFO    ][38120] Loading fresh modules for state activity
2020-01-12 18:43:11,050 [salt.fileclient  :1219][INFO    ][38120] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2020-01-12 18:43:11,162 [salt.state       :1780][INFO    ][38120] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 18:43:11.162295
2020-01-12 18:43:11,162 [salt.state       :1813][INFO    ][38120] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-01-12 18:43:11,164 [salt.loaded.int.module.cmdmod:395 ][INFO    ][38120] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-01-12 18:43:13,093 [salt.state       :300 ][INFO    ][38120] {'pid': 38130, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-01-12 18:43:13,094 [salt.state       :1951][INFO    ][38120] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 18:43:13.094294 duration_in_ms=1931.999
2020-01-12 18:43:13,096 [salt.state       :1780][INFO    ][38120] Running state [maas_machines_storage_cmp002_lvm] at time 18:43:13.096511
2020-01-12 18:43:13,096 [salt.state       :1813][INFO    ][38120] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2020-01-12 18:43:14,266 [salt.loaded.ext.module.maasng:610 ][INFO    ][38120] wahcpb
2020-01-12 18:43:14,267 [salt.loaded.ext.module.maasng:626 ][INFO    ][38120] sda
2020-01-12 18:43:14,831 [salt.loaded.ext.module.maasng:361 ][INFO    ][38120] wahcpb
2020-01-12 18:43:14,924 [salt.loaded.ext.module.maasng:367 ][INFO    ][38120] [{u'size': 800109715456, u'block_size': 4096, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'used_for': u'MBR partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'system_id': u'wahcpb', u'partition_table_type': u'MBR', u'filesystem': None, u'id': 1, u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/wahcpb/blockdevices/1/', u'type': u'physical', u'model': u'LOGICAL VOLUME', u'partitions': [{u'size': 800101236736, u'uuid': u'5d6c199d-7624-4290-afa1-f8c8555b8dba', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'wahcpb', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'691a7039-cd5c-4f57-9c03-5e916fc1dacd', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/wahcpb/blockdevices/1/partition/1'}]}, {u'size': 800097042432, u'block_size': 4096, u'available_size': 0, u'uuid': u'32380520-b143-435f-a285-0dbc7fc0b6a3', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'used_for': u'ext4 formatted filesystem mounted at /', u'id_path': None, u'system_id': u'wahcpb', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'2f06b3cd-8658-4903-a54c-b7ec40e3c5d0', u'fstype': u'ext4', u'label': u'root'}, u'id': 3, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/wahcpb/blockdevices/3/', u'type': u'virtual', u'model': None, u'partitions': []}]
2020-01-12 18:43:14,925 [salt.loaded.ext.module.maasng:632 ][INFO    ][38120] vgroot
2020-01-12 18:43:14,926 [salt.loaded.ext.module.maasng:635 ][INFO    ][38120] lvroot
2020-01-12 18:43:14,926 [salt.loaded.ext.module.maasng:639 ][INFO    ][38120] 107374182400
2020-01-12 18:43:15,566 [salt.loaded.ext.module.maasng:645 ][INFO    ][38120] {u'hwe_kernel': u'', u'swap_size': None, u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'boot_interface': {u'name': u'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'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', 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'eddycb', 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': 18, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', 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'eddycb', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', 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'eddycb', 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'system_id': u'wahcpb', u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/wahcpb/interfaces/4/'}, u'fqdn': u'cmp002.maas', 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'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default'}, u'resource_uri': u'/MAAS/api/2.0/machines/wahcpb/', u'disable_ipv4': False, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'wahcpb', 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'physicalblockdevice_set': [{u'size': 800109715456, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'08e90a6f-430d-44cc-b786-e94b60a0941a', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'wahcpb', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'e7c668ff-2491-4b50-ab6a-d0adb29a0fc6', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/wahcpb/blockdevices/1/partition/6'}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'wahcpb', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/wahcpb/blockdevices/1/'}], u'blockdevice_set': [{u'size': 800109715456, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'08e90a6f-430d-44cc-b786-e94b60a0941a', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'wahcpb', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'e7c668ff-2491-4b50-ab6a-d0adb29a0fc6', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/wahcpb/blockdevices/1/partition/6'}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'wahcpb', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/wahcpb/blockdevices/1/'}, {u'size': 107374182400, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'6deaa8a3-45bb-42f1-827d-1cfb774ec04e', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'wahcpb', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'2accb469-87a7-44f4-b035-3628b79b9cd6', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 11, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/wahcpb/blockdevices/11/'}], u'status': 4, u'storage_test_status': 2, u'cpu_count': 40, u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'wahcpb', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'boot_disk': {u'size': 800109715456, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'08e90a6f-430d-44cc-b786-e94b60a0941a', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'wahcpb', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'e7c668ff-2491-4b50-ab6a-d0adb29a0fc6', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/wahcpb/blockdevices/1/partition/6'}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'wahcpb', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/wahcpb/blockdevices/1/'}, u'current_testing_result_id': 3, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'bcaches': [], u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'status_action': u'', u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'size': 107374182400, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'6deaa8a3-45bb-42f1-827d-1cfb774ec04e', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'wahcpb', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'2accb469-87a7-44f4-b035-3628b79b9cd6', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 11, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/wahcpb/blockdevices/11/'}], u'commissioning_status': 2, u'min_hwe_kernel': u'ga-18.04', 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'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', 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'eddycb', 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': 18, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', 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'eddycb', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', 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'eddycb', 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'system_id': u'wahcpb', u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/wahcpb/interfaces/4/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'vlan': None, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'system_id': u'wahcpb', u'params': u'', u'mac_address': u'38:ea:a7:8f:07:50', u'parents': [], u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/wahcpb/interfaces/12/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'vlan': None, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'system_id': u'wahcpb', u'params': u'', u'mac_address': u'38:ea:a7:8f:07:51', u'parents': [], u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/wahcpb/interfaces/13/'}, {u'name': u'ens2f0', u'links': [{u'id': 19, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'system_id': u'wahcpb', u'params': u'', u'mac_address': u'38:ea:a7:8f:12:48', u'parents': [], u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/wahcpb/interfaces/14/'}, {u'name': u'ens2f1', u'links': [{u'id': 20, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'system_id': u'wahcpb', u'params': u'', u'mac_address': u'38:ea:a7:8f:12:49', u'parents': [], u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/wahcpb/interfaces/15/'}, {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'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', 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'eddycb', 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': 21, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', 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'eddycb', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', 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'eddycb', 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'system_id': u'wahcpb', u'params': u'', u'mac_address': u'9c:b6:54:8a:10:1c', u'parents': [], u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/wahcpb/interfaces/11/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2020-01-12 18:43:15,572 [salt.state       :300 ][INFO    ][38120] {'new': {'storage_layout': 'lvm'}}
2020-01-12 18:43:15,572 [salt.state       :1951][INFO    ][38120] Completed state [maas_machines_storage_cmp002_lvm] at time 18:43:15.572918 duration_in_ms=2476.406
2020-01-12 18:43:15,574 [salt.state       :1780][INFO    ][38120] Running state [maas_machines_storage_cmp001_lvm] at time 18:43:15.574004
2020-01-12 18:43:15,574 [salt.state       :1813][INFO    ][38120] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2020-01-12 18:43:17,005 [salt.loaded.ext.module.maasng:610 ][INFO    ][38120] xkgywe
2020-01-12 18:43:17,006 [salt.loaded.ext.module.maasng:626 ][INFO    ][38120] sda
2020-01-12 18:43:17,735 [salt.loaded.ext.module.maasng:361 ][INFO    ][38120] xkgywe
2020-01-12 18:43:17,831 [salt.loaded.ext.module.maasng:367 ][INFO    ][38120] [{u'size': 800109715456, u'block_size': 4096, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'used_for': u'MBR partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'system_id': u'xkgywe', u'partition_table_type': u'MBR', u'filesystem': None, u'id': 2, u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/xkgywe/blockdevices/2/', u'type': u'physical', u'model': u'LOGICAL VOLUME', u'partitions': [{u'size': 800101236736, u'uuid': u'ae641d52-a6a4-47c8-b8d8-766e66e9080b', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xkgywe', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'46c04c1a-58c6-4b39-ab97-c63e874481ab', u'fstype': u'lvm-pv', 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/xkgywe/blockdevices/2/partition/2'}]}, {u'size': 800097042432, u'block_size': 4096, u'available_size': 0, u'uuid': u'24cdb08b-b734-4870-9cb1-603855e0c48d', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'used_for': u'ext4 formatted filesystem mounted at /', u'id_path': None, u'system_id': u'xkgywe', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'6439f884-ff48-4010-bd92-3710686d0a62', u'fstype': u'ext4', u'label': u'root'}, u'id': 4, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/xkgywe/blockdevices/4/', u'type': u'virtual', u'model': None, u'partitions': []}]
2020-01-12 18:43:17,831 [salt.loaded.ext.module.maasng:632 ][INFO    ][38120] vgroot
2020-01-12 18:43:17,831 [salt.loaded.ext.module.maasng:635 ][INFO    ][38120] lvroot
2020-01-12 18:43:17,832 [salt.loaded.ext.module.maasng:639 ][INFO    ][38120] 107374182400
2020-01-12 18:43:18,500 [salt.loaded.ext.module.maasng:645 ][INFO    ][38120] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'testing_status_name': u'Passed', u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.39', u'192.168.11.43'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'commissioning_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'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'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'xkgywe', 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'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'uuid': u'f1a1e49a-a5ec-400b-9973-b37b793e4b13', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'xkgywe', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'uuid': u'1f3084bb-02f1-4924-aba7-f08fad0d6281', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'partitions': [], u'type': u'virtual', u'id': 12, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/xkgywe/blockdevices/12/'}], u'blockdevice_set': [{u'size': 800109715456, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 2, u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'partitions': [{u'size': 800101236736, u'uuid': u'189e7351-17a0-4413-a554-f93760d57938', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xkgywe', u'filesystem': {u'label': None, u'uuid': u'682189b4-af8c-471e-b863-c6b2e7e8f78d', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/xkgywe/blockdevices/2/partition/7'}], u'system_id': u'xkgywe', u'partition_table_type': u'MBR', u'available_size': 0, 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'type': u'physical', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/xkgywe/blockdevices/2/'}, {u'size': 107374182400, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'id': 12, u'filesystem': {u'label': u'root', u'uuid': u'1f3084bb-02f1-4924-aba7-f08fad0d6281', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'uuid': u'f1a1e49a-a5ec-400b-9973-b37b793e4b13', u'used_for': u'ext4 formatted filesystem mounted at /', u'partitions': [], u'system_id': u'xkgywe', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/xkgywe/blockdevices/12/'}], u'status': 4, u'storage_test_status': 2, u'cpu_count': 40, u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'xkgywe', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'size': 800109715456, u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'xkgywe', 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'partitions': [{u'size': 800101236736, u'uuid': u'189e7351-17a0-4413-a554-f93760d57938', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xkgywe', u'filesystem': {u'label': None, u'uuid': u'682189b4-af8c-471e-b863-c6b2e7e8f78d', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/xkgywe/blockdevices/2/partition/7'}], u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/xkgywe/blockdevices/2/'}, 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'eddycb', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'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'id': 25, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'eddycb', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'children': [], u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'eddycb', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'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'mac_address': u'9c:b6:54:8a:95:a0', u'system_id': u'xkgywe', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/xkgywe/interfaces/5/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'vlan': None, u'children': [], u'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d4', u'system_id': u'xkgywe', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/xkgywe/interfaces/18/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'vlan': None, u'children': [], u'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d5', u'system_id': u'xkgywe', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/xkgywe/interfaces/20/'}, {u'name': u'ens2f0', u'links': [{u'id': 26, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'children': [], u'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cc', u'system_id': u'xkgywe', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/xkgywe/interfaces/16/'}, {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'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'eddycb', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'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'id': 27, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'eddycb', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'children': [], u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'eddycb', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'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'mac_address': u'9c:b6:54:8a:95:a4', u'system_id': u'xkgywe', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/xkgywe/interfaces/17/'}, {u'name': u'ens2f1', u'links': [{u'id': 28, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'children': [], u'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cd', u'system_id': u'xkgywe', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/xkgywe/interfaces/19/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'bcaches': [], u'status_name': u'Ready', u'physicalblockdevice_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'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'xkgywe', 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'partitions': [{u'size': 800101236736, u'uuid': u'189e7351-17a0-4413-a554-f93760d57938', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xkgywe', u'filesystem': {u'label': None, u'uuid': u'682189b4-af8c-471e-b863-c6b2e7e8f78d', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/xkgywe/blockdevices/2/partition/7'}], u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/xkgywe/blockdevices/2/'}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'disable_ipv4': False, 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'eddycb', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'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'id': 25, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'eddycb', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'children': [], u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'eddycb', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'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'mac_address': u'9c:b6:54:8a:95:a0', u'system_id': u'xkgywe', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/xkgywe/interfaces/5/'}, u'current_commissioning_result_id': 4, u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/xkgywe/'}
2020-01-12 18:43:18,502 [salt.state       :300 ][INFO    ][38120] {'new': {'storage_layout': 'lvm'}}
2020-01-12 18:43:18,502 [salt.state       :1951][INFO    ][38120] Completed state [maas_machines_storage_cmp001_lvm] at time 18:43:18.502850 duration_in_ms=2928.844
2020-01-12 18:43:18,507 [salt.minion      :1711][INFO    ][38120] Returning information for job: 20200112184304744235
2020-01-12 18:43:19,232 [salt.minion      :1308][INFO    ][35523] User sudo_ubuntu Executing command state.apply with jid 20200112184319219176
2020-01-12 18:43:19,258 [salt.minion      :1432][INFO    ][38261] Starting a new job with PID 38261
2020-01-12 18:43:20,222 [salt.state       :915 ][INFO    ][38261] Loading fresh modules for state activity
2020-01-12 18:43:20,294 [salt.fileclient  :1219][INFO    ][38261] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2020-01-12 18:43:20,352 [salt.state       :1780][INFO    ][38261] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 18:43:20.352481
2020-01-12 18:43:20,352 [salt.state       :1813][INFO    ][38261] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-01-12 18:43:20,355 [salt.loaded.int.module.cmdmod:395 ][INFO    ][38261] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-01-12 18:43:22,283 [salt.state       :300 ][INFO    ][38261] {'pid': 38268, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-01-12 18:43:22,284 [salt.state       :1951][INFO    ][38261] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 18:43:22.284694 duration_in_ms=1932.212
2020-01-12 18:43:22,288 [salt.state       :1780][INFO    ][38261] Running state [maas.deploy_machines] at time 18:43:22.288365
2020-01-12 18:43:22,289 [salt.state       :1813][INFO    ][38261] Executing state module.run for [maas.deploy_machines]
2020-01-12 18:43:22,292 [salt.utils.decorators:613 ][WARNING ][38261] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-12 18:43:22,906 [salt.loaded.ext.module.maas:684 ][INFO    ][38261] deploymachines hwe_kernel=ga-18.04 system_id=wahcpb distro_series=bionic
2020-01-12 18:43:25,405 [salt.loaded.ext.module.maas:684 ][INFO    ][38261] deploymachines hwe_kernel=ga-18.04 system_id=xkgywe distro_series=bionic
2020-01-12 18:43:28,037 [salt.loaded.ext.module.maas:684 ][INFO    ][38261] deploymachines hwe_kernel=ga-18.04 system_id=4bpcy6 distro_series=bionic
2020-01-12 18:43:30,543 [salt.loaded.ext.module.maas:684 ][INFO    ][38261] deploymachines hwe_kernel=ga-18.04 system_id=fw8bk7 distro_series=bionic
2020-01-12 18:43:33,004 [salt.loaded.ext.module.maas:684 ][INFO    ][38261] deploymachines hwe_kernel=ga-18.04 system_id=7r3xer distro_series=bionic
2020-01-12 18:43:34,295 [salt.minion      :1308][INFO    ][35523] User sudo_ubuntu Executing command saltutil.find_job with jid 20200112184334282448
2020-01-12 18:43:34,323 [salt.minion      :1432][INFO    ][38529] Starting a new job with PID 38529
2020-01-12 18:43:34,378 [salt.minion      :1711][INFO    ][38529] Returning information for job: 20200112184334282448
2020-01-12 18:43:35,534 [salt.state       :300 ][INFO    ][38261] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2020-01-12 18:43:35,534 [salt.state       :1951][INFO    ][38261] Completed state [maas.deploy_machines] at time 18:43:35.534810 duration_in_ms=13246.445
2020-01-12 18:43:35,541 [salt.minion      :1711][INFO    ][38261] Returning information for job: 20200112184319219176
2020-01-12 18:43:36,255 [salt.minion      :1308][INFO    ][35523] User sudo_ubuntu Executing command state.apply with jid 20200112184336247616
2020-01-12 18:43:36,283 [salt.minion      :1432][INFO    ][38552] Starting a new job with PID 38552
2020-01-12 18:43:42,538 [salt.state       :915 ][INFO    ][38552] Loading fresh modules for state activity
2020-01-12 18:43:42,602 [salt.fileclient  :1219][INFO    ][38552] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2020-01-12 18:43:42,659 [salt.state       :1780][INFO    ][38552] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 18:43:42.659572
2020-01-12 18:43:42,659 [salt.state       :1813][INFO    ][38552] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-01-12 18:43:42,662 [salt.loaded.int.module.cmdmod:395 ][INFO    ][38552] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-01-12 18:43:44,512 [salt.state       :300 ][INFO    ][38552] {'pid': 38565, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-01-12 18:43:44,514 [salt.state       :1951][INFO    ][38552] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 18:43:44.514605 duration_in_ms=1855.034
2020-01-12 18:43:44,518 [salt.state       :1780][INFO    ][38552] Running state [maas.wait_for_machine_status] at time 18:43:44.518535
2020-01-12 18:43:44,519 [salt.state       :1813][INFO    ][38552] Executing state module.run for [maas.wait_for_machine_status]
2020-01-12 18:43:44,520 [salt.utils.decorators:613 ][WARNING ][38552] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-12 18:43:47,378 [salt.loaded.ext.module.maas:1023][INFO    ][38552] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.15573096s left)
2020-01-12 18:43:51,284 [salt.minion      :1308][INFO    ][35523] User sudo_ubuntu Executing command saltutil.find_job with jid 20200112184351268393
2020-01-12 18:43:51,314 [salt.minion      :1432][INFO    ][38584] Starting a new job with PID 38584
2020-01-12 18:43:51,346 [salt.minion      :1711][INFO    ][38584] Returning information for job: 20200112184351268393
2020-01-12 18:44:20,311 [salt.loaded.ext.module.maas:1023][INFO    ][38552] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.22289014s left)
2020-01-12 18:44:21,371 [salt.minion      :1308][INFO    ][35523] User sudo_ubuntu Executing command saltutil.find_job with jid 20200112184421358206
2020-01-12 18:44:21,399 [salt.minion      :1432][INFO    ][38639] Starting a new job with PID 38639
2020-01-12 18:44:21,429 [salt.minion      :1711][INFO    ][38639] Returning information for job: 20200112184421358206
2020-01-12 18:44:51,453 [salt.minion      :1308][INFO    ][35523] User sudo_ubuntu Executing command saltutil.find_job with jid 20200112184451439780
2020-01-12 18:44:51,473 [salt.minion      :1432][INFO    ][38656] Starting a new job with PID 38656
2020-01-12 18:44:51,511 [salt.minion      :1711][INFO    ][38656] Returning information for job: 20200112184451439780
2020-01-12 18:44:53,224 [salt.loaded.ext.module.maas:1023][INFO    ][38552] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.30959415s left)
2020-01-12 18:45:21,536 [salt.minion      :1308][INFO    ][35523] User sudo_ubuntu Executing command saltutil.find_job with jid 20200112184521521720
2020-01-12 18:45:21,561 [salt.minion      :1432][INFO    ][38714] Starting a new job with PID 38714
2020-01-12 18:45:21,592 [salt.minion      :1711][INFO    ][38714] Returning information for job: 20200112184521521720
2020-01-12 18:45:26,339 [salt.loaded.ext.module.maas:1023][INFO    ][38552] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2148.19473314s left)
2020-01-12 18:45:51,624 [salt.minion      :1308][INFO    ][35523] User sudo_ubuntu Executing command saltutil.find_job with jid 20200112184551610751
2020-01-12 18:45:51,652 [salt.minion      :1432][INFO    ][38733] Starting a new job with PID 38733
2020-01-12 18:45:51,695 [salt.minion      :1711][INFO    ][38733] Returning information for job: 20200112184551610751
2020-01-12 18:45:59,275 [salt.loaded.ext.module.maas:1023][INFO    ][38552] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2115.2590661s left)
2020-01-12 18:46:21,707 [salt.minion      :1308][INFO    ][35523] User sudo_ubuntu Executing command saltutil.find_job with jid 20200112184621695486
2020-01-12 18:46:21,730 [salt.minion      :1432][INFO    ][38813] Starting a new job with PID 38813
2020-01-12 18:46:21,768 [salt.minion      :1711][INFO    ][38813] Returning information for job: 20200112184621695486
2020-01-12 18:46:31,991 [salt.loaded.ext.module.maas:1023][INFO    ][38552] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2082.54237103s left)
2020-01-12 18:46:51,792 [salt.minion      :1308][INFO    ][35523] User sudo_ubuntu Executing command saltutil.find_job with jid 20200112184651777887
2020-01-12 18:46:51,820 [salt.minion      :1432][INFO    ][38889] Starting a new job with PID 38889
2020-01-12 18:46:51,851 [salt.minion      :1711][INFO    ][38889] Returning information for job: 20200112184651777887
2020-01-12 18:47:05,088 [salt.loaded.ext.module.maas:1023][INFO    ][38552] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2049.44584203s left)
2020-01-12 18:47:21,940 [salt.minion      :1308][INFO    ][35523] User sudo_ubuntu Executing command saltutil.find_job with jid 20200112184721927788
2020-01-12 18:47:21,970 [salt.minion      :1432][INFO    ][39241] Starting a new job with PID 39241
2020-01-12 18:47:22,005 [salt.minion      :1711][INFO    ][39241] Returning information for job: 20200112184721927788
2020-01-12 18:47:38,059 [salt.loaded.ext.module.maas:1023][INFO    ][38552] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2016.47703314s left)
2020-01-12 18:47:52,003 [salt.minion      :1308][INFO    ][35523] User sudo_ubuntu Executing command saltutil.find_job with jid 20200112184751995336
2020-01-12 18:47:52,022 [salt.minion      :1432][INFO    ][39321] Starting a new job with PID 39321
2020-01-12 18:47:52,050 [salt.minion      :1711][INFO    ][39321] Returning information for job: 20200112184751995336
2020-01-12 18:48:10,927 [salt.loaded.ext.module.maas:1023][INFO    ][38552] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1983.60659313s left)
2020-01-12 18:48:22,139 [salt.minion      :1308][INFO    ][35523] User sudo_ubuntu Executing command saltutil.find_job with jid 20200112184822128365
2020-01-12 18:48:22,163 [salt.minion      :1432][INFO    ][39493] Starting a new job with PID 39493
2020-01-12 18:48:22,193 [salt.minion      :1711][INFO    ][39493] Returning information for job: 20200112184822128365
2020-01-12 18:48:43,857 [salt.loaded.ext.module.maas:1023][INFO    ][38552] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1950.67645693s left)
2020-01-12 18:48:52,258 [salt.minion      :1308][INFO    ][35523] User sudo_ubuntu Executing command saltutil.find_job with jid 20200112184852243520
2020-01-12 18:48:52,284 [salt.minion      :1432][INFO    ][39592] Starting a new job with PID 39592
2020-01-12 18:48:52,314 [salt.minion      :1711][INFO    ][39592] Returning information for job: 20200112184852243520
2020-01-12 18:49:17,544 [salt.loaded.ext.module.maas:1023][INFO    ][38552] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1916.98970294s left)
2020-01-12 18:49:22,484 [salt.minion      :1308][INFO    ][35523] User sudo_ubuntu Executing command saltutil.find_job with jid 20200112184922471574
2020-01-12 18:49:22,514 [salt.minion      :1432][INFO    ][40145] Starting a new job with PID 40145
2020-01-12 18:49:22,543 [salt.minion      :1711][INFO    ][40145] Returning information for job: 20200112184922471574
2020-01-12 18:49:50,475 [salt.loaded.ext.module.maas:1023][INFO    ][38552] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1884.05834198s left)
2020-01-12 18:49:52,616 [salt.minion      :1308][INFO    ][35523] User sudo_ubuntu Executing command saltutil.find_job with jid 20200112184952604873
2020-01-12 18:49:52,637 [salt.minion      :1432][INFO    ][40179] Starting a new job with PID 40179
2020-01-12 18:49:52,666 [salt.minion      :1711][INFO    ][40179] Returning information for job: 20200112184952604873
2020-01-12 18:50:22,771 [salt.minion      :1308][INFO    ][35523] User sudo_ubuntu Executing command saltutil.find_job with jid 20200112185022758867
2020-01-12 18:50:22,790 [salt.minion      :1432][INFO    ][40493] Starting a new job with PID 40493
2020-01-12 18:50:22,830 [salt.minion      :1711][INFO    ][40493] Returning information for job: 20200112185022758867
2020-01-12 18:50:23,571 [salt.loaded.ext.module.maas:1023][INFO    ][38552] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1850.96258593s left)
2020-01-12 18:50:52,920 [salt.minion      :1308][INFO    ][35523] User sudo_ubuntu Executing command saltutil.find_job with jid 20200112185052910222
2020-01-12 18:50:52,947 [salt.minion      :1432][INFO    ][40518] Starting a new job with PID 40518
2020-01-12 18:50:52,975 [salt.minion      :1711][INFO    ][40518] Returning information for job: 20200112185052910222
2020-01-12 18:50:56,633 [salt.loaded.ext.module.maas:1023][INFO    ][38552] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1817.90118599s left)
2020-01-12 18:51:23,108 [salt.minion      :1308][INFO    ][35523] User sudo_ubuntu Executing command saltutil.find_job with jid 20200112185123092526
2020-01-12 18:51:23,133 [salt.minion      :1432][INFO    ][40850] Starting a new job with PID 40850
2020-01-12 18:51:23,162 [salt.minion      :1711][INFO    ][40850] Returning information for job: 20200112185123092526
2020-01-12 18:51:29,746 [salt.loaded.ext.module.maas:1023][INFO    ][38552] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1784.78803897s left)
2020-01-12 18:51:53,256 [salt.minion      :1308][INFO    ][35523] User sudo_ubuntu Executing command saltutil.find_job with jid 20200112185153239834
2020-01-12 18:51:53,284 [salt.minion      :1432][INFO    ][40898] Starting a new job with PID 40898
2020-01-12 18:51:53,310 [salt.minion      :1711][INFO    ][40898] Returning information for job: 20200112185153239834
2020-01-12 18:52:02,891 [salt.loaded.ext.module.maas:1023][INFO    ][38552] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1751.64300299s left)
2020-01-12 18:52:23,431 [salt.minion      :1308][INFO    ][35523] User sudo_ubuntu Executing command saltutil.find_job with jid 20200112185223418396
2020-01-12 18:52:23,456 [salt.minion      :1432][INFO    ][41121] Starting a new job with PID 41121
2020-01-12 18:52:23,483 [salt.minion      :1711][INFO    ][41121] Returning information for job: 20200112185223418396
2020-01-12 18:52:35,781 [salt.loaded.ext.module.maas:1023][INFO    ][38552] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1718.75345016s left)
2020-01-12 18:52:53,543 [salt.minion      :1308][INFO    ][35523] User sudo_ubuntu Executing command saltutil.find_job with jid 20200112185253532819
2020-01-12 18:52:53,571 [salt.minion      :1432][INFO    ][41166] Starting a new job with PID 41166
2020-01-12 18:52:53,596 [salt.minion      :1711][INFO    ][41166] Returning information for job: 20200112185253532819
2020-01-12 18:53:08,636 [salt.loaded.ext.module.maas:1023][INFO    ][38552] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1685.89819598s left)
2020-01-12 18:53:23,735 [salt.minion      :1308][INFO    ][35523] User sudo_ubuntu Executing command saltutil.find_job with jid 20200112185323723021
2020-01-12 18:53:23,762 [salt.minion      :1432][INFO    ][41502] Starting a new job with PID 41502
2020-01-12 18:53:23,788 [salt.minion      :1711][INFO    ][41502] Returning information for job: 20200112185323723021
2020-01-12 18:53:41,752 [salt.loaded.ext.module.maas:1023][INFO    ][38552] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1652.78221798s left)
2020-01-12 18:53:53,904 [salt.minion      :1308][INFO    ][35523] User sudo_ubuntu Executing command saltutil.find_job with jid 20200112185353888887
2020-01-12 18:53:53,935 [salt.minion      :1432][INFO    ][41541] Starting a new job with PID 41541
2020-01-12 18:53:53,965 [salt.minion      :1711][INFO    ][41541] Returning information for job: 20200112185353888887
2020-01-12 18:54:14,724 [salt.loaded.ext.module.maas:1023][INFO    ][38552] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1619.81020904s left)
2020-01-12 18:54:24,090 [salt.minion      :1308][INFO    ][35523] User sudo_ubuntu Executing command saltutil.find_job with jid 20200112185424077037
2020-01-12 18:54:24,115 [salt.minion      :1432][INFO    ][41675] Starting a new job with PID 41675
2020-01-12 18:54:24,142 [salt.minion      :1711][INFO    ][41675] Returning information for job: 20200112185424077037
2020-01-12 18:54:47,768 [salt.loaded.ext.module.maas:1023][INFO    ][38552] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1586.76602697s left)
2020-01-12 18:54:54,269 [salt.minion      :1308][INFO    ][35523] User sudo_ubuntu Executing command saltutil.find_job with jid 20200112185454261017
2020-01-12 18:54:54,292 [salt.minion      :1432][INFO    ][41695] Starting a new job with PID 41695
2020-01-12 18:54:54,322 [salt.minion      :1711][INFO    ][41695] Returning information for job: 20200112185454261017
2020-01-12 18:55:20,751 [salt.loaded.ext.module.maas:1023][INFO    ][38552] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1553.78309393s left)
2020-01-12 18:55:24,467 [salt.minion      :1308][INFO    ][35523] User sudo_ubuntu Executing command saltutil.find_job with jid 20200112185524454230
2020-01-12 18:55:24,496 [salt.minion      :1432][INFO    ][41775] Starting a new job with PID 41775
2020-01-12 18:55:24,528 [salt.minion      :1711][INFO    ][41775] Returning information for job: 20200112185524454230
2020-01-12 18:55:53,738 [salt.loaded.ext.module.maas:1023][INFO    ][38552] Waiting status:Deployed for machines:['kvm01', 'kvm03']
sleep for:30s Timeout:2250s (1520.79536796s left)
2020-01-12 18:55:54,645 [salt.minion      :1308][INFO    ][35523] User sudo_ubuntu Executing command saltutil.find_job with jid 20200112185554630747
2020-01-12 18:55:54,672 [salt.minion      :1432][INFO    ][41845] Starting a new job with PID 41845
2020-01-12 18:55:54,699 [salt.minion      :1711][INFO    ][41845] Returning information for job: 20200112185554630747
2020-01-12 18:56:24,766 [salt.minion      :1308][INFO    ][35523] User sudo_ubuntu Executing command saltutil.find_job with jid 20200112185624750713
2020-01-12 18:56:24,790 [salt.minion      :1432][INFO    ][42070] Starting a new job with PID 42070
2020-01-12 18:56:24,817 [salt.minion      :1711][INFO    ][42070] Returning information for job: 20200112185624750713
2020-01-12 18:56:27,032 [salt.state       :300 ][INFO    ][38552] {'ret': True}
2020-01-12 18:56:27,033 [salt.state       :1951][INFO    ][38552] Completed state [maas.wait_for_machine_status] at time 18:56:27.033273 duration_in_ms=762514.717
2020-01-12 18:56:27,038 [salt.minion      :1711][INFO    ][38552] Returning information for job: 20200112184336247616
2020-01-12 19:35:14,988 [salt.utils.schedule:1377][INFO    ][35523] Running scheduled job: __mine_interval
2020-01-12 20:35:14,988 [salt.utils.schedule:1377][INFO    ][35523] Running scheduled job: __mine_interval
2020-01-12 20:38:44,268 [salt.minion      :1308][INFO    ][35523] User sudo_ubuntu Executing command cp.push_dir with jid 20200112203844255697
2020-01-12 20:38:44,290 [salt.minion      :1432][INFO    ][49041] Starting a new job with PID 49041
