2019-07-28 05:03:30,367 [salt.minion      :870 ][ERROR   ][364] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-07-28 05:04:20,418 [salt.minion      :870 ][ERROR   ][364] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-07-28 05:05:10,458 [salt.minion      :870 ][ERROR   ][364] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-07-28 05:06:00,503 [salt.minion      :870 ][ERROR   ][364] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-07-28 05:06:50,548 [salt.minion      :870 ][ERROR   ][364] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-07-28 05:08:59,594 [salt.utils.decorators:613 ][WARNING ][2721] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-28 05:09:00,032 [salt.utils.decorators:613 ][WARNING ][2721] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-28 05:09:02,043 [salt.loaded.int.states.file:2298][WARNING ][2862] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-07-28 05:09:07,680 [salt.state       :2022][WARNING ][2971] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-07-28 05:09:10,367 [salt.utils.decorators:613 ][WARNING ][2971] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-28 05:24:16,510 [salt.utils.decorators:613 ][WARNING ][2971] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-28 05:53:09,016 [salt.utils.decorators:613 ][WARNING ][2971] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-28 06:08:14,685 [salt.utils.decorators:613 ][WARNING ][2971] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-28 06:08:18,019 [salt.loaded.ext.module.maasng:1008][WARNING ][2971] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-07-28 06:08:18,020 [salt.loaded.ext.module.maasng:1011][WARNING ][2971] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-07-28 06:08:18,756 [salt.loaded.ext.module.maasng:1235][WARNING ][2971] Ignoring parameter vlan:0
2019-07-28 06:08:19,673 [salt.utils.decorators:613 ][WARNING ][2971] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-28 06:08:25,446 [salt.utils.decorators:613 ][WARNING ][12818] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-28 06:08:25,492 [salt.loaded.ext.module.maas:412 ][WARNING ][12818] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-28 06:08:29,464 [salt.loaded.ext.module.maas:412 ][WARNING ][12818] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-28 06:08:34,171 [salt.loaded.ext.module.maas:412 ][WARNING ][12818] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-28 06:08:38,403 [salt.loaded.ext.module.maas:412 ][WARNING ][12818] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-28 06:08:41,541 [salt.loaded.ext.module.maas:412 ][WARNING ][12818] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-28 06:08:48,038 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13771] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-07-28 06:08:48,069 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13771] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-07-28 06:08:48,093 [salt.utils.parsers:1051][WARNING ][364] Minion received a SIGTERM. Exiting.
2019-07-28 06:08:49,164 [salt.cli.daemons :293 ][INFO    ][13817] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-07-28 06:08:49,264 [salt.cli.daemons :82  ][INFO    ][13817] Starting up the Salt Minion
2019-07-28 06:08:49,265 [salt.utils.event :1017][INFO    ][13817] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-07-28 06:08:50,116 [salt.minion      :976 ][INFO    ][13817] Creating minion process manager
2019-07-28 06:08:51,500 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13817] Executing command ['date', '+%z'] in directory '/root'
2019-07-28 06:08:51,522 [salt.utils.schedule:568 ][INFO    ][13817] Updating job settings for scheduled job: __mine_interval
2019-07-28 06:08:51,523 [salt.minion      :1108][INFO    ][13817] Added mine.update to scheduler
2019-07-28 06:08:51,527 [salt.minion      :1975][INFO    ][13817] Minion is starting as user 'root'
2019-07-28 06:08:51,542 [salt.minion      :2336][INFO    ][13817] Minion is ready to receive requests!
2019-07-28 06:09:16,249 [salt.minion      :1308][INFO    ][13817] User sudo_ubuntu Executing command state.apply with jid 20190728060916239100
2019-07-28 06:09:16,267 [salt.minion      :1432][INFO    ][13976] Starting a new job with PID 13976
2019-07-28 06:09:19,760 [salt.state       :915 ][INFO    ][13976] Loading fresh modules for state activity
2019-07-28 06:09:19,807 [salt.fileclient  :1219][INFO    ][13976] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-07-28 06:09:19,847 [salt.state       :1780][INFO    ][13976] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:09:19.847284
2019-07-28 06:09:19,847 [salt.state       :1813][INFO    ][13976] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-28 06:09:19,849 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13976] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-28 06:09:21,198 [salt.state       :300 ][INFO    ][13976] {'pid': 13991, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-28 06:09:21,199 [salt.state       :1951][INFO    ][13976] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:09:21.199049 duration_in_ms=1351.766
2019-07-28 06:09:21,200 [salt.state       :1780][INFO    ][13976] Running state [maas.wait_for_machine_status] at time 06:09:21.200429
2019-07-28 06:09:21,200 [salt.state       :1813][INFO    ][13976] Executing state module.run for [maas.wait_for_machine_status]
2019-07-28 06:09:21,200 [salt.utils.decorators:613 ][WARNING ][13976] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-28 06:09:22,142 [salt.loaded.ext.module.maas:1023][INFO    ][13976] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.06300402s left)
2019-07-28 06:09:31,272 [salt.minion      :1308][INFO    ][13817] User sudo_ubuntu Executing command saltutil.find_job with jid 20190728060931259017
2019-07-28 06:09:31,295 [salt.minion      :1432][INFO    ][14042] Starting a new job with PID 14042
2019-07-28 06:09:31,320 [salt.minion      :1711][INFO    ][14042] Returning information for job: 20190728060931259017
2019-07-28 06:10:01,353 [salt.minion      :1308][INFO    ][13817] User sudo_ubuntu Executing command saltutil.find_job with jid 20190728061001340089
2019-07-28 06:10:01,373 [salt.minion      :1432][INFO    ][14091] Starting a new job with PID 14091
2019-07-28 06:10:01,393 [salt.minion      :1711][INFO    ][14091] Returning information for job: 20190728061001340089
2019-07-28 06:10:31,411 [salt.minion      :1308][INFO    ][13817] User sudo_ubuntu Executing command saltutil.find_job with jid 20190728061031405712
2019-07-28 06:10:31,426 [salt.minion      :1432][INFO    ][14311] Starting a new job with PID 14311
2019-07-28 06:10:31,437 [salt.minion      :1711][INFO    ][14311] Returning information for job: 20190728061031405712
2019-07-28 06:11:01,466 [salt.minion      :1308][INFO    ][13817] User sudo_ubuntu Executing command saltutil.find_job with jid 20190728061101456555
2019-07-28 06:11:01,486 [salt.minion      :1432][INFO    ][14391] Starting a new job with PID 14391
2019-07-28 06:11:01,504 [salt.minion      :1711][INFO    ][14391] Returning information for job: 20190728061101456555
2019-07-28 06:11:31,545 [salt.minion      :1308][INFO    ][13817] User sudo_ubuntu Executing command saltutil.find_job with jid 20190728061131532869
2019-07-28 06:11:31,567 [salt.minion      :1432][INFO    ][14522] Starting a new job with PID 14522
2019-07-28 06:11:31,591 [salt.minion      :1711][INFO    ][14522] Returning information for job: 20190728061131532869
2019-07-28 06:12:01,626 [salt.minion      :1308][INFO    ][13817] User sudo_ubuntu Executing command saltutil.find_job with jid 20190728061201613103
2019-07-28 06:12:01,647 [salt.minion      :1432][INFO    ][14568] Starting a new job with PID 14568
2019-07-28 06:12:01,667 [salt.minion      :1711][INFO    ][14568] Returning information for job: 20190728061201613103
2019-07-28 06:12:31,711 [salt.minion      :1308][INFO    ][13817] User sudo_ubuntu Executing command saltutil.find_job with jid 20190728061231698827
2019-07-28 06:12:31,733 [salt.minion      :1432][INFO    ][14696] Starting a new job with PID 14696
2019-07-28 06:12:31,752 [salt.minion      :1711][INFO    ][14696] Returning information for job: 20190728061231698827
2019-07-28 06:13:01,786 [salt.minion      :1308][INFO    ][13817] User sudo_ubuntu Executing command saltutil.find_job with jid 20190728061301774144
2019-07-28 06:13:01,804 [salt.minion      :1432][INFO    ][14745] Starting a new job with PID 14745
2019-07-28 06:13:01,825 [salt.minion      :1711][INFO    ][14745] Returning information for job: 20190728061301774144
2019-07-28 06:13:31,868 [salt.minion      :1308][INFO    ][13817] User sudo_ubuntu Executing command saltutil.find_job with jid 20190728061331854988
2019-07-28 06:13:31,889 [salt.minion      :1432][INFO    ][14810] Starting a new job with PID 14810
2019-07-28 06:13:31,910 [salt.minion      :1711][INFO    ][14810] Returning information for job: 20190728061331854988
2019-07-28 06:14:01,961 [salt.minion      :1308][INFO    ][13817] User sudo_ubuntu Executing command saltutil.find_job with jid 20190728061401947884
2019-07-28 06:14:01,985 [salt.minion      :1432][INFO    ][14846] Starting a new job with PID 14846
2019-07-28 06:14:02,010 [salt.minion      :1711][INFO    ][14846] Returning information for job: 20190728061401947884
2019-07-28 06:14:32,061 [salt.minion      :1308][INFO    ][13817] User sudo_ubuntu Executing command saltutil.find_job with jid 20190728061432048757
2019-07-28 06:14:32,086 [salt.minion      :1432][INFO    ][14898] Starting a new job with PID 14898
2019-07-28 06:14:32,112 [salt.minion      :1711][INFO    ][14898] Returning information for job: 20190728061432048757
2019-07-28 06:15:01,590 [salt.state       :302 ][ERROR   ][13976] Module function maas.wait_for_machine_status threw an exception. Exception: HTTP Error 401: OK
2019-07-28 06:15:01,590 [salt.state       :1951][INFO    ][13976] Completed state [maas.wait_for_machine_status] at time 06:15:01.590727 duration_in_ms=340390.297
2019-07-28 06:15:01,593 [salt.minion      :1711][INFO    ][13976] Returning information for job: 20190728060916239100
2019-07-28 06:15:12,291 [salt.minion      :1308][INFO    ][13817] User sudo_ubuntu Executing command pillar.get with jid 20190728061512282362
2019-07-28 06:15:12,306 [salt.minion      :1432][INFO    ][15314] Starting a new job with PID 15314
2019-07-28 06:15:12,312 [salt.minion      :1711][INFO    ][15314] Returning information for job: 20190728061512282362
2019-07-28 06:15:12,733 [salt.minion      :1308][INFO    ][13817] User sudo_ubuntu Executing command service.status with jid 20190728061512727111
2019-07-28 06:15:12,744 [salt.minion      :1432][INFO    ][15338] Starting a new job with PID 15338
2019-07-28 06:15:13,063 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][15338] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-07-28 06:15:13,085 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][15338] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-07-28 06:15:13,094 [salt.minion      :1711][INFO    ][15338] Returning information for job: 20190728061512727111
2019-07-28 06:15:13,507 [salt.minion      :1308][INFO    ][13817] User sudo_ubuntu Executing command state.apply with jid 20190728061513499672
2019-07-28 06:15:13,520 [salt.minion      :1432][INFO    ][15456] Starting a new job with PID 15456
2019-07-28 06:15:16,990 [salt.state       :915 ][INFO    ][15456] Loading fresh modules for state activity
2019-07-28 06:15:17,372 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15456] Executing command 'salt-minion --version' in directory '/root'
2019-07-28 06:15:17,662 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15456] Executing command 'salt-minion --version' in directory '/root'
2019-07-28 06:15:18,457 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15456] Executing command 'salt-minion --version' in directory '/root'
2019-07-28 06:15:18,838 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15456] Executing command 'salt-minion --version' in directory '/root'
2019-07-28 06:15:20,326 [salt.state       :1780][INFO    ][15456] Running state [salt-minion] at time 06:15:20.326294
2019-07-28 06:15:20,327 [salt.state       :1813][INFO    ][15456] Executing state pkg.installed for [salt-minion]
2019-07-28 06:15:20,328 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15456] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-07-28 06:15:20,423 [salt.state       :300 ][INFO    ][15456] All specified packages are already installed
2019-07-28 06:15:20,423 [salt.state       :1951][INFO    ][15456] Completed state [salt-minion] at time 06:15:20.423563 duration_in_ms=97.27
2019-07-28 06:15:20,423 [salt.state       :1780][INFO    ][15456] Running state [salt_minion_dependency_packages] at time 06:15:20.423923
2019-07-28 06:15:20,424 [salt.state       :1813][INFO    ][15456] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-07-28 06:15:20,431 [salt.state       :300 ][INFO    ][15456] All specified packages are already installed
2019-07-28 06:15:20,431 [salt.state       :1951][INFO    ][15456] Completed state [salt_minion_dependency_packages] at time 06:15:20.431282 duration_in_ms=7.358
2019-07-28 06:15:20,434 [salt.state       :1780][INFO    ][15456] Running state [/etc/salt/minion.d/minion.conf] at time 06:15:20.434677
2019-07-28 06:15:20,434 [salt.state       :1813][INFO    ][15456] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-07-28 06:15:20,634 [salt.state       :300 ][INFO    ][15456] File /etc/salt/minion.d/minion.conf is in the correct state
2019-07-28 06:15:20,634 [salt.state       :1951][INFO    ][15456] Completed state [/etc/salt/minion.d/minion.conf] at time 06:15:20.634780 duration_in_ms=200.102
2019-07-28 06:15:20,635 [salt.state       :1780][INFO    ][15456] Running state [python-netaddr] at time 06:15:20.635170
2019-07-28 06:15:20,635 [salt.state       :1813][INFO    ][15456] Executing state pkg.installed for [python-netaddr]
2019-07-28 06:15:20,646 [salt.state       :300 ][INFO    ][15456] All specified packages are already installed
2019-07-28 06:15:20,646 [salt.state       :1951][INFO    ][15456] Completed state [python-netaddr] at time 06:15:20.646910 duration_in_ms=11.74
2019-07-28 06:15:20,651 [salt.state       :1780][INFO    ][15456] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 06:15:20.651556
2019-07-28 06:15:20,651 [salt.state       :1813][INFO    ][15456] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-07-28 06:15:20,664 [salt.state       :300 ][INFO    ][15456] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-07-28 06:15:20,665 [salt.state       :1951][INFO    ][15456] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 06:15:20.665120 duration_in_ms=13.564
2019-07-28 06:15:20,666 [salt.state       :1780][INFO    ][15456] Running state [salt-minion] at time 06:15:20.666535
2019-07-28 06:15:20,666 [salt.state       :1813][INFO    ][15456] Executing state service.running for [salt-minion]
2019-07-28 06:15:20,667 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15456] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-07-28 06:15:20,706 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15456] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-07-28 06:15:20,724 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15456] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-07-28 06:15:20,742 [salt.state       :300 ][INFO    ][15456] The service salt-minion is already running
2019-07-28 06:15:20,742 [salt.state       :1951][INFO    ][15456] Completed state [salt-minion] at time 06:15:20.742728 duration_in_ms=76.193
2019-07-28 06:15:20,745 [salt.state       :1780][INFO    ][15456] Running state [/etc/salt/grains.d] at time 06:15:20.745029
2019-07-28 06:15:20,745 [salt.state       :1813][INFO    ][15456] Executing state file.directory for [/etc/salt/grains.d]
2019-07-28 06:15:20,746 [salt.state       :300 ][INFO    ][15456] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-07-28 06:15:20,747 [salt.state       :1951][INFO    ][15456] Completed state [/etc/salt/grains.d] at time 06:15:20.747227 duration_in_ms=2.198
2019-07-28 06:15:20,748 [salt.state       :1780][INFO    ][15456] Running state [/etc/salt/grains] at time 06:15:20.748237
2019-07-28 06:15:20,748 [salt.state       :1813][INFO    ][15456] Executing state file.managed for [/etc/salt/grains]
2019-07-28 06:15:20,749 [salt.state       :300 ][INFO    ][15456] File /etc/salt/grains exists with proper permissions. No changes made.
2019-07-28 06:15:20,749 [salt.state       :1951][INFO    ][15456] Completed state [/etc/salt/grains] at time 06:15:20.749804 duration_in_ms=1.568
2019-07-28 06:15:20,750 [salt.state       :1780][INFO    ][15456] Running state [/etc/salt/grains.d/placeholder] at time 06:15:20.750506
2019-07-28 06:15:20,750 [salt.state       :1813][INFO    ][15456] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-07-28 06:15:20,751 [salt.state       :300 ][INFO    ][15456] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-07-28 06:15:20,752 [salt.state       :1951][INFO    ][15456] Completed state [/etc/salt/grains.d/placeholder] at time 06:15:20.752001 duration_in_ms=1.494
2019-07-28 06:15:20,752 [salt.state       :1780][INFO    ][15456] Running state [/etc/salt/grains.d/sphinx] at time 06:15:20.752710
2019-07-28 06:15:20,753 [salt.state       :1813][INFO    ][15456] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-07-28 06:15:20,771 [salt.state       :300 ][INFO    ][15456] File /etc/salt/grains.d/sphinx is in the correct state
2019-07-28 06:15:20,772 [salt.state       :1951][INFO    ][15456] Completed state [/etc/salt/grains.d/sphinx] at time 06:15:20.771949 duration_in_ms=19.239
2019-07-28 06:15:20,775 [salt.state       :1780][INFO    ][15456] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 06:15:20.775035
2019-07-28 06:15:20,775 [salt.state       :1813][INFO    ][15456] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-07-28 06:15:20,775 [salt.state       :300 ][INFO    ][15456] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-07-28 06:15:20,776 [salt.state       :1951][INFO    ][15456] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 06:15:20.776240 duration_in_ms=1.206
2019-07-28 06:15:20,776 [salt.state       :1780][INFO    ][15456] Running state [/etc/salt/grains.d/dns_records] at time 06:15:20.776925
2019-07-28 06:15:20,777 [salt.state       :1813][INFO    ][15456] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-07-28 06:15:20,789 [salt.state       :300 ][INFO    ][15456] File /etc/salt/grains.d/dns_records is in the correct state
2019-07-28 06:15:20,789 [salt.state       :1951][INFO    ][15456] Completed state [/etc/salt/grains.d/dns_records] at time 06:15:20.789726 duration_in_ms=12.801
2019-07-28 06:15:20,791 [salt.state       :1780][INFO    ][15456] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 06:15:20.791018
2019-07-28 06:15:20,791 [salt.state       :1813][INFO    ][15456] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-07-28 06:15:20,791 [salt.state       :300 ][INFO    ][15456] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-07-28 06:15:20,792 [salt.state       :1951][INFO    ][15456] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 06:15:20.792219 duration_in_ms=1.201
2019-07-28 06:15:20,792 [salt.state       :1780][INFO    ][15456] Running state [/etc/salt/grains.d/salt] at time 06:15:20.792887
2019-07-28 06:15:20,793 [salt.state       :1813][INFO    ][15456] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-07-28 06:15:20,801 [salt.state       :300 ][INFO    ][15456] File /etc/salt/grains.d/salt is in the correct state
2019-07-28 06:15:20,801 [salt.state       :1951][INFO    ][15456] Completed state [/etc/salt/grains.d/salt] at time 06:15:20.801805 duration_in_ms=8.919
2019-07-28 06:15:20,803 [salt.state       :1780][INFO    ][15456] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 06:15:20.803036
2019-07-28 06:15:20,803 [salt.state       :1813][INFO    ][15456] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-07-28 06:15:20,803 [salt.state       :300 ][INFO    ][15456] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-07-28 06:15:20,804 [salt.state       :1951][INFO    ][15456] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 06:15:20.804204 duration_in_ms=1.168
2019-07-28 06:15:20,806 [salt.state       :1780][INFO    ][15456] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 06:15:20.806905
2019-07-28 06:15:20,807 [salt.state       :1813][INFO    ][15456] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-07-28 06:15:20,807 [salt.state       :300 ][INFO    ][15456] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-07-28 06:15:20,808 [salt.state       :1951][INFO    ][15456] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 06:15:20.808085 duration_in_ms=1.179
2019-07-28 06:15:20,809 [salt.state       :1780][INFO    ][15456] Running state [mine.update] at time 06:15:20.809022
2019-07-28 06:15:20,809 [salt.state       :1813][INFO    ][15456] Executing state module.wait for [mine.update]
2019-07-28 06:15:20,809 [salt.state       :300 ][INFO    ][15456] No changes made for mine.update
2019-07-28 06:15:20,810 [salt.state       :1951][INFO    ][15456] Completed state [mine.update] at time 06:15:20.810119 duration_in_ms=1.096
2019-07-28 06:15:20,810 [salt.state       :1780][INFO    ][15456] Running state [ca-certificates] at time 06:15:20.810467
2019-07-28 06:15:20,810 [salt.state       :1813][INFO    ][15456] Executing state pkg.installed for [ca-certificates]
2019-07-28 06:15:20,821 [salt.state       :300 ][INFO    ][15456] All specified packages are already installed
2019-07-28 06:15:20,821 [salt.state       :1951][INFO    ][15456] Completed state [ca-certificates] at time 06:15:20.821685 duration_in_ms=11.218
2019-07-28 06:15:20,822 [salt.state       :1780][INFO    ][15456] Running state [update-ca-certificates] at time 06:15:20.822631
2019-07-28 06:15:20,823 [salt.state       :1813][INFO    ][15456] Executing state cmd.wait for [update-ca-certificates]
2019-07-28 06:15:20,823 [salt.state       :300 ][INFO    ][15456] No changes made for update-ca-certificates
2019-07-28 06:15:20,823 [salt.state       :1951][INFO    ][15456] Completed state [update-ca-certificates] at time 06:15:20.823718 duration_in_ms=1.087
2019-07-28 06:15:20,824 [salt.state       :1780][INFO    ][15456] Running state [iptables] at time 06:15:20.824036
2019-07-28 06:15:20,824 [salt.state       :1813][INFO    ][15456] Executing state pkg.installed for [iptables]
2019-07-28 06:15:20,833 [salt.state       :300 ][INFO    ][15456] All specified packages are already installed
2019-07-28 06:15:20,833 [salt.state       :1951][INFO    ][15456] Completed state [iptables] at time 06:15:20.833867 duration_in_ms=9.831
2019-07-28 06:15:20,834 [salt.state       :1780][INFO    ][15456] Running state [iptables-persistent] at time 06:15:20.834181
2019-07-28 06:15:20,834 [salt.state       :1813][INFO    ][15456] Executing state pkg.installed for [iptables-persistent]
2019-07-28 06:15:20,843 [salt.state       :300 ][INFO    ][15456] All specified packages are already installed
2019-07-28 06:15:20,843 [salt.state       :1951][INFO    ][15456] Completed state [iptables-persistent] at time 06:15:20.843620 duration_in_ms=9.439
2019-07-28 06:15:20,844 [salt.state       :1780][INFO    ][15456] Running state [iptables_modules_v4_load] at time 06:15:20.844857
2019-07-28 06:15:20,845 [salt.state       :1813][INFO    ][15456] Executing state kmod.present for [iptables_modules_v4_load]
2019-07-28 06:15:20,845 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15456] Executing command 'lsmod' in directory '/root'
2019-07-28 06:15:20,871 [salt.state       :300 ][INFO    ][15456] Kernel modules iptable_filter, ip_tables are already present
2019-07-28 06:15:20,871 [salt.state       :1951][INFO    ][15456] Completed state [iptables_modules_v4_load] at time 06:15:20.871668 duration_in_ms=26.81
2019-07-28 06:15:20,872 [salt.state       :1780][INFO    ][15456] Running state [/etc/iptables/rules.v4] at time 06:15:20.872564
2019-07-28 06:15:20,873 [salt.state       :1813][INFO    ][15456] Executing state file.managed for [/etc/iptables/rules.v4]
2019-07-28 06:15:20,975 [salt.state       :300 ][INFO    ][15456] File /etc/iptables/rules.v4 is in the correct state
2019-07-28 06:15:20,976 [salt.state       :1951][INFO    ][15456] Completed state [/etc/iptables/rules.v4] at time 06:15:20.975977 duration_in_ms=103.412
2019-07-28 06:15:20,977 [salt.state       :1780][INFO    ][15456] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 06:15:20.977647
2019-07-28 06:15:20,978 [salt.state       :1813][INFO    ][15456] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-07-28 06:15:20,979 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15456] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-07-28 06:15:21,000 [salt.state       :300 ][INFO    ][15456] onlyif execution failed
2019-07-28 06:15:21,001 [salt.state       :1951][INFO    ][15456] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 06:15:21.000967 duration_in_ms=23.32
2019-07-28 06:15:21,002 [salt.state       :1780][INFO    ][15456] Running state [netfilter-persistent] at time 06:15:21.002638
2019-07-28 06:15:21,003 [salt.state       :1813][INFO    ][15456] Executing state service.running for [netfilter-persistent]
2019-07-28 06:15:21,004 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15456] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-07-28 06:15:21,025 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15456] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-07-28 06:15:21,044 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15456] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-07-28 06:15:21,062 [salt.state       :300 ][INFO    ][15456] The service netfilter-persistent is already running
2019-07-28 06:15:21,063 [salt.state       :1951][INFO    ][15456] Completed state [netfilter-persistent] at time 06:15:21.063417 duration_in_ms=60.779
2019-07-28 06:15:21,065 [salt.state       :1780][INFO    ][15456] Running state [iptables_extra.remove_stale_tables] at time 06:15:21.064948
2019-07-28 06:15:21,065 [salt.state       :1813][INFO    ][15456] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-07-28 06:15:21,066 [salt.state       :300 ][INFO    ][15456] No changes made for iptables_extra.remove_stale_tables
2019-07-28 06:15:21,066 [salt.state       :1951][INFO    ][15456] Completed state [iptables_extra.remove_stale_tables] at time 06:15:21.066504 duration_in_ms=1.556
2019-07-28 06:15:21,067 [salt.state       :1780][INFO    ][15456] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 06:15:21.066932
2019-07-28 06:15:21,067 [salt.state       :1813][INFO    ][15456] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-07-28 06:15:21,068 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15456] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-07-28 06:15:21,084 [salt.state       :300 ][INFO    ][15456] onlyif execution failed
2019-07-28 06:15:21,084 [salt.state       :1951][INFO    ][15456] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 06:15:21.084750 duration_in_ms=17.818
2019-07-28 06:15:21,086 [salt.state       :1780][INFO    ][15456] Running state [/etc/iptables/rules.v6] at time 06:15:21.086307
2019-07-28 06:15:21,086 [salt.state       :1813][INFO    ][15456] Executing state file.absent for [/etc/iptables/rules.v6]
2019-07-28 06:15:21,087 [salt.state       :300 ][INFO    ][15456] File /etc/iptables/rules.v6 is not present
2019-07-28 06:15:21,088 [salt.state       :1951][INFO    ][15456] Completed state [/etc/iptables/rules.v6] at time 06:15:21.087919 duration_in_ms=1.612
2019-07-28 06:15:21,089 [salt.state       :1780][INFO    ][15456] Running state [iptables_extra.flush_all] at time 06:15:21.089045
2019-07-28 06:15:21,089 [salt.state       :1813][INFO    ][15456] Executing state module.wait for [iptables_extra.flush_all]
2019-07-28 06:15:21,090 [salt.state       :300 ][INFO    ][15456] No changes made for iptables_extra.flush_all
2019-07-28 06:15:21,090 [salt.state       :1951][INFO    ][15456] Completed state [iptables_extra.flush_all] at time 06:15:21.090306 duration_in_ms=1.262
2019-07-28 06:15:21,095 [salt.minion      :1711][INFO    ][15456] Returning information for job: 20190728061513499672
2019-07-28 06:15:21,720 [salt.minion      :1308][INFO    ][13817] User sudo_ubuntu Executing command state.apply with jid 20190728061521707615
2019-07-28 06:15:21,742 [salt.minion      :1432][INFO    ][15543] Starting a new job with PID 15543
2019-07-28 06:15:22,515 [salt.state       :915 ][INFO    ][15543] Loading fresh modules for state activity
2019-07-28 06:15:23,091 [salt.state       :1780][INFO    ][15543] Running state [maas-rack-controller] at time 06:15:23.090962
2019-07-28 06:15:23,091 [salt.state       :1813][INFO    ][15543] Executing state pkg.installed for [maas-rack-controller]
2019-07-28 06:15:23,091 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15543] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-07-28 06:15:23,222 [salt.state       :300 ][INFO    ][15543] All specified packages are already installed
2019-07-28 06:15:23,222 [salt.state       :1951][INFO    ][15543] Completed state [maas-rack-controller] at time 06:15:23.222803 duration_in_ms=131.841
2019-07-28 06:15:23,223 [salt.state       :1780][INFO    ][15543] Running state [ipmitool] at time 06:15:23.223060
2019-07-28 06:15:23,223 [salt.state       :1813][INFO    ][15543] Executing state pkg.installed for [ipmitool]
2019-07-28 06:15:23,228 [salt.state       :300 ][INFO    ][15543] All specified packages are already installed
2019-07-28 06:15:23,228 [salt.state       :1951][INFO    ][15543] Completed state [ipmitool] at time 06:15:23.228437 duration_in_ms=5.377
2019-07-28 06:15:23,230 [salt.state       :1780][INFO    ][15543] Running state [/etc/maas/rackd.conf] at time 06:15:23.230561
2019-07-28 06:15:23,230 [salt.state       :1813][INFO    ][15543] Executing state file.line for [/etc/maas/rackd.conf]
2019-07-28 06:15:23,231 [salt.state       :300 ][INFO    ][15543] No changes needed to be made
2019-07-28 06:15:23,231 [salt.state       :1951][INFO    ][15543] Completed state [/etc/maas/rackd.conf] at time 06:15:23.231753 duration_in_ms=1.192
2019-07-28 06:15:23,231 [salt.state       :1780][INFO    ][15543] Running state [/etc/maas/rackd.conf] at time 06:15:23.231933
2019-07-28 06:15:23,232 [salt.state       :1813][INFO    ][15543] Executing state file.managed for [/etc/maas/rackd.conf]
2019-07-28 06:15:23,232 [salt.loaded.int.states.file:2298][WARNING ][15543] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-07-28 06:15:23,232 [salt.state       :300 ][INFO    ][15543] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-07-28 06:15:23,232 [salt.state       :1951][INFO    ][15543] Completed state [/etc/maas/rackd.conf] at time 06:15:23.232907 duration_in_ms=0.974
2019-07-28 06:15:23,233 [salt.state       :1780][INFO    ][15543] Running state [maas-rackd] at time 06:15:23.233664
2019-07-28 06:15:23,233 [salt.state       :1813][INFO    ][15543] Executing state service.running for [maas-rackd]
2019-07-28 06:15:23,234 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15543] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-07-28 06:15:23,269 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15543] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-07-28 06:15:23,285 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15543] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-07-28 06:15:23,301 [salt.state       :300 ][INFO    ][15543] The service maas-rackd is already running
2019-07-28 06:15:23,301 [salt.state       :1951][INFO    ][15543] Completed state [maas-rackd] at time 06:15:23.301345 duration_in_ms=67.681
2019-07-28 06:15:23,302 [salt.minion      :1711][INFO    ][15543] Returning information for job: 20190728061521707615
2019-07-28 06:15:23,902 [salt.minion      :1308][INFO    ][13817] User sudo_ubuntu Executing command state.apply with jid 20190728061523890061
2019-07-28 06:15:23,923 [salt.minion      :1432][INFO    ][15575] Starting a new job with PID 15575
2019-07-28 06:15:24,672 [salt.state       :915 ][INFO    ][15575] Loading fresh modules for state activity
2019-07-28 06:15:25,402 [salt.state       :1780][INFO    ][15575] Running state [maas-region-controller] at time 06:15:25.402894
2019-07-28 06:15:25,403 [salt.state       :1813][INFO    ][15575] Executing state pkg.installed for [maas-region-controller]
2019-07-28 06:15:25,403 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15575] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-07-28 06:15:25,496 [salt.state       :300 ][INFO    ][15575] All specified packages are already installed
2019-07-28 06:15:25,497 [salt.state       :1951][INFO    ][15575] Completed state [maas-region-controller] at time 06:15:25.497322 duration_in_ms=94.428
2019-07-28 06:15:25,497 [salt.state       :1780][INFO    ][15575] Running state [python-oauth] at time 06:15:25.497725
2019-07-28 06:15:25,498 [salt.state       :1813][INFO    ][15575] Executing state pkg.installed for [python-oauth]
2019-07-28 06:15:25,505 [salt.state       :300 ][INFO    ][15575] All specified packages are already installed
2019-07-28 06:15:25,505 [salt.state       :1951][INFO    ][15575] Completed state [python-oauth] at time 06:15:25.505763 duration_in_ms=8.038
2019-07-28 06:15:25,509 [salt.state       :1780][INFO    ][15575] Running state [/etc/maas/regiond.conf] at time 06:15:25.509120
2019-07-28 06:15:25,509 [salt.state       :1813][INFO    ][15575] Executing state file.replace for [/etc/maas/regiond.conf]
2019-07-28 06:15:25,588 [salt.state       :300 ][INFO    ][15575] No changes needed to be made
2019-07-28 06:15:25,588 [salt.state       :1951][INFO    ][15575] Completed state [/etc/maas/regiond.conf] at time 06:15:25.588575 duration_in_ms=79.454
2019-07-28 06:15:25,589 [salt.state       :1780][INFO    ][15575] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 06:15:25.589371
2019-07-28 06:15:25,589 [salt.state       :1813][INFO    ][15575] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-07-28 06:15:25,683 [salt.state       :300 ][INFO    ][15575] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-07-28 06:15:25,684 [salt.state       :1951][INFO    ][15575] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 06:15:25.684645 duration_in_ms=95.264
2019-07-28 06:15:25,686 [salt.state       :1780][INFO    ][15575] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:15:25.685934
2019-07-28 06:15:25,686 [salt.state       :1813][INFO    ][15575] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-07-28 06:15:25,702 [salt.state       :300 ][INFO    ][15575] No changes needed to be made
2019-07-28 06:15:25,703 [salt.state       :1951][INFO    ][15575] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:15:25.703048 duration_in_ms=17.113
2019-07-28 06:15:25,703 [salt.state       :1780][INFO    ][15575] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:15:25.703665
2019-07-28 06:15:25,704 [salt.state       :1813][INFO    ][15575] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-07-28 06:15:25,726 [salt.state       :300 ][INFO    ][15575] No changes needed to be made
2019-07-28 06:15:25,726 [salt.state       :1951][INFO    ][15575] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:15:25.726818 duration_in_ms=23.153
2019-07-28 06:15:25,727 [salt.state       :1780][INFO    ][15575] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 06:15:25.727443
2019-07-28 06:15:25,727 [salt.state       :1813][INFO    ][15575] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-07-28 06:15:25,758 [salt.state       :300 ][INFO    ][15575] No changes needed to be made
2019-07-28 06:15:25,759 [salt.state       :1951][INFO    ][15575] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 06:15:25.759123 duration_in_ms=31.681
2019-07-28 06:15:25,759 [salt.state       :1780][INFO    ][15575] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 06:15:25.759705
2019-07-28 06:15:25,760 [salt.state       :1813][INFO    ][15575] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-07-28 06:15:25,772 [salt.state       :300 ][INFO    ][15575] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-07-28 06:15:25,773 [salt.state       :1951][INFO    ][15575] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 06:15:25.773196 duration_in_ms=13.49
2019-07-28 06:15:25,777 [salt.state       :1780][INFO    ][15575] Running state [a2enmod headers] at time 06:15:25.777667
2019-07-28 06:15:25,778 [salt.state       :1813][INFO    ][15575] Executing state cmd.run for [a2enmod headers]
2019-07-28 06:15:25,779 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15575] Executing command 'a2enmod headers' in directory '/root'
2019-07-28 06:15:25,850 [salt.state       :300 ][INFO    ][15575] {'pid': 15597, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-07-28 06:15:25,851 [salt.state       :1951][INFO    ][15575] Completed state [a2enmod headers] at time 06:15:25.851108 duration_in_ms=73.44
2019-07-28 06:15:25,851 [salt.state       :1780][INFO    ][15575] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 06:15:25.851701
2019-07-28 06:15:25,852 [salt.state       :1813][INFO    ][15575] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-07-28 06:15:25,871 [salt.state       :300 ][INFO    ][15575] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-07-28 06:15:25,871 [salt.state       :1951][INFO    ][15575] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 06:15:25.871728 duration_in_ms=20.027
2019-07-28 06:15:25,872 [salt.state       :1780][INFO    ][15575] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 06:15:25.872667
2019-07-28 06:15:25,873 [salt.state       :1813][INFO    ][15575] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-07-28 06:15:25,958 [salt.state       :300 ][INFO    ][15575] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-07-28 06:15:25,959 [salt.state       :1951][INFO    ][15575] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 06:15:25.959211 duration_in_ms=86.544
2019-07-28 06:15:25,960 [salt.state       :1780][INFO    ][15575] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 06:15:25.960017
2019-07-28 06:15:25,960 [salt.state       :1813][INFO    ][15575] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-07-28 06:15:26,030 [salt.state       :300 ][INFO    ][15575] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-07-28 06:15:26,030 [salt.state       :1951][INFO    ][15575] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 06:15:26.030792 duration_in_ms=70.776
2019-07-28 06:15:26,031 [salt.state       :1780][INFO    ][15575] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 06:15:26.031414
2019-07-28 06:15:26,031 [salt.state       :1813][INFO    ][15575] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-07-28 06:15:26,096 [salt.state       :300 ][INFO    ][15575] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-07-28 06:15:26,097 [salt.state       :1951][INFO    ][15575] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 06:15:26.096926 duration_in_ms=65.51
2019-07-28 06:15:26,097 [salt.state       :1780][INFO    ][15575] Running state [/root/.pgpass] at time 06:15:26.097347
2019-07-28 06:15:26,097 [salt.state       :1813][INFO    ][15575] Executing state file.managed for [/root/.pgpass]
2019-07-28 06:15:26,144 [salt.state       :300 ][INFO    ][15575] File /root/.pgpass is in the correct state
2019-07-28 06:15:26,144 [salt.state       :1951][INFO    ][15575] Completed state [/root/.pgpass] at time 06:15:26.144311 duration_in_ms=46.964
2019-07-28 06:15:26,149 [salt.state       :1780][INFO    ][15575] Running state [maas-region syncdb --noinput] at time 06:15:26.149880
2019-07-28 06:15:26,150 [salt.state       :1813][INFO    ][15575] Executing state cmd.run for [maas-region syncdb --noinput]
2019-07-28 06:15:26,150 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15575] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-07-28 06:15:27,979 [salt.state       :300 ][INFO    ][15575] {'pid': 15610, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: contenttypes, sites, sessions, auth, metadataserver, piston3, maasserver\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-07-28 06:15:27,979 [salt.state       :1951][INFO    ][15575] Completed state [maas-region syncdb --noinput] at time 06:15:27.979773 duration_in_ms=1829.892
2019-07-28 06:15:27,980 [salt.state       :2022][WARNING ][15575] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-07-28 06:15:27,983 [salt.state       :1780][INFO    ][15575] Running state [maas-regiond] at time 06:15:27.983560
2019-07-28 06:15:27,984 [salt.state       :1813][INFO    ][15575] Executing state service.running for [maas-regiond]
2019-07-28 06:15:27,985 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15575] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-07-28 06:15:28,026 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15575] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-07-28 06:15:28,045 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15575] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-07-28 06:15:28,064 [salt.state       :300 ][INFO    ][15575] The service maas-regiond is already running
2019-07-28 06:15:28,064 [salt.state       :1951][INFO    ][15575] Completed state [maas-regiond] at time 06:15:28.064598 duration_in_ms=81.038
2019-07-28 06:15:28,067 [salt.state       :1780][INFO    ][15575] Running state [bind9] at time 06:15:28.067322
2019-07-28 06:15:28,067 [salt.state       :1813][INFO    ][15575] Executing state service.running for [bind9]
2019-07-28 06:15:28,069 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15575] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-07-28 06:15:28,088 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15575] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-07-28 06:15:28,106 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15575] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-07-28 06:15:28,123 [salt.state       :300 ][INFO    ][15575] The service bind9 is already running
2019-07-28 06:15:28,123 [salt.state       :1951][INFO    ][15575] Completed state [bind9] at time 06:15:28.123785 duration_in_ms=56.463
2019-07-28 06:15:28,126 [salt.state       :1780][INFO    ][15575] Running state [apache2] at time 06:15:28.126306
2019-07-28 06:15:28,126 [salt.state       :1813][INFO    ][15575] Executing state service.running for [apache2]
2019-07-28 06:15:28,127 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15575] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-07-28 06:15:28,146 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15575] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-07-28 06:15:28,163 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15575] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-07-28 06:15:28,185 [salt.state       :300 ][INFO    ][15575] The service apache2 is already running
2019-07-28 06:15:28,185 [salt.state       :1951][INFO    ][15575] Completed state [apache2] at time 06:15:28.185480 duration_in_ms=59.174
2019-07-28 06:15:28,187 [salt.state       :1780][INFO    ][15575] Running state [maasng.wait_for_http_code] at time 06:15:28.187513
2019-07-28 06:15:28,187 [salt.state       :1813][INFO    ][15575] Executing state module.run for [maasng.wait_for_http_code]
2019-07-28 06:15:28,188 [salt.utils.decorators:613 ][WARNING ][15575] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-28 06:15:28,294 [salt.state       :300 ][INFO    ][15575] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-07-28 06:15:28,295 [salt.state       :1951][INFO    ][15575] Completed state [maasng.wait_for_http_code] at time 06:15:28.295285 duration_in_ms=107.772
2019-07-28 06:15:28,296 [salt.state       :1780][INFO    ][15575] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 06:15:28.296651
2019-07-28 06:15:28,297 [salt.state       :1813][INFO    ][15575] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-07-28 06:15:28,297 [salt.state       :300 ][INFO    ][15575] /var/lib/maas/.setup_admin exists
2019-07-28 06:15:28,298 [salt.state       :1951][INFO    ][15575] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 06:15:28.298104 duration_in_ms=1.453
2019-07-28 06:15:28,299 [salt.state       :1780][INFO    ][15575] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:15:28.299114
2019-07-28 06:15:28,299 [salt.state       :1813][INFO    ][15575] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-28 06:15:28,300 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15575] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-28 06:15:29,744 [salt.state       :300 ][INFO    ][15575] {'pid': 15634, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-28 06:15:29,745 [salt.state       :1951][INFO    ][15575] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:15:29.745160 duration_in_ms=1446.047
2019-07-28 06:15:29,751 [salt.state       :1780][INFO    ][15575] Running state [maas_region_boot_source_resources_mirror] at time 06:15:29.751164
2019-07-28 06:15:29,751 [salt.state       :1813][INFO    ][15575] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-07-28 06:15:29,851 [salt.state       :300 ][INFO    ][15575] {'changes': {}}
2019-07-28 06:15:29,851 [salt.state       :1951][INFO    ][15575] Completed state [maas_region_boot_source_resources_mirror] at time 06:15:29.851680 duration_in_ms=100.515
2019-07-28 06:15:29,852 [salt.state       :1780][INFO    ][15575] Running state [maasng.boot_resources_import] at time 06:15:29.852603
2019-07-28 06:15:29,852 [salt.state       :1813][INFO    ][15575] Executing state module.run for [maasng.boot_resources_import]
2019-07-28 06:15:29,853 [salt.utils.decorators:613 ][WARNING ][15575] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-28 06:15:29,998 [salt.loaded.ext.module.maasng:1600][INFO    ][15575] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-07-28 06:15:35,066 [salt.loaded.ext.module.maasng:1600][INFO    ][15575] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-07-28 06:15:38,945 [salt.minion      :1308][INFO    ][13817] User sudo_ubuntu Executing command saltutil.find_job with jid 20190728061538932055
2019-07-28 06:15:38,968 [salt.minion      :1432][INFO    ][15726] Starting a new job with PID 15726
2019-07-28 06:15:38,992 [salt.minion      :1711][INFO    ][15726] Returning information for job: 20190728061538932055
2019-07-28 06:15:40,130 [salt.loaded.ext.module.maasng:1600][INFO    ][15575] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-07-28 06:15:45,238 [salt.state       :300 ][INFO    ][15575] {'ret': True}
2019-07-28 06:15:45,238 [salt.state       :1951][INFO    ][15575] Completed state [maasng.boot_resources_import] at time 06:15:45.238699 duration_in_ms=15386.096
2019-07-28 06:15:45,240 [salt.state       :1780][INFO    ][15575] Running state [maas_region_boot_sources_selection_xenial] at time 06:15:45.239924
2019-07-28 06:15:45,240 [salt.state       :1813][INFO    ][15575] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-07-28 06:15:45,459 [salt.state       :300 ][INFO    ][15575] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-07-28 06:15:45,460 [salt.state       :1951][INFO    ][15575] Completed state [maas_region_boot_sources_selection_xenial] at time 06:15:45.460194 duration_in_ms=220.269
2019-07-28 06:15:45,461 [salt.state       :1780][INFO    ][15575] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 06:15:45.461636
2019-07-28 06:15:45,462 [salt.state       :1813][INFO    ][15575] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-07-28 06:15:45,462 [salt.utils.decorators:613 ][WARNING ][15575] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-28 06:15:45,463 [salt.loaded.ext.module.maasng:1771][INFO    ][15575] boot-sources sync initiated for ALL Rack's
2019-07-28 06:15:46,510 [salt.state       :300 ][INFO    ][15575] {'ret': True}
2019-07-28 06:15:46,510 [salt.state       :1951][INFO    ][15575] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 06:15:46.510801 duration_in_ms=1049.164
2019-07-28 06:15:46,512 [salt.state       :1780][INFO    ][15575] Running state [maas.process_maas_config] at time 06:15:46.512535
2019-07-28 06:15:46,512 [salt.state       :1813][INFO    ][15575] Executing state module.run for [maas.process_maas_config]
2019-07-28 06:15:46,513 [salt.utils.decorators:613 ][WARNING ][15575] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-28 06:15:46,514 [salt.loaded.ext.module.maas:92  ][INFO    ][15575] maasconfig name=enable_http_proxy value=True
2019-07-28 06:15:46,618 [salt.loaded.ext.module.maas:92  ][INFO    ][15575] maasconfig name=upstream_dns value=8.8.8.8
2019-07-28 06:15:46,684 [salt.loaded.ext.module.maas:92  ][INFO    ][15575] maasconfig name=commissioning_distro_series value=xenial
2019-07-28 06:15:49,692 [salt.loaded.ext.module.maas:92  ][INFO    ][15575] maasconfig name=default_osystem value=ubuntu
2019-07-28 06:15:49,757 [salt.loaded.ext.module.maas:92  ][INFO    ][15575] maasconfig name=active_discovery_interval value=600
2019-07-28 06:15:49,810 [salt.loaded.ext.module.maas:92  ][INFO    ][15575] maasconfig name=dnssec_validation value=no
2019-07-28 06:15:49,864 [salt.loaded.ext.module.maas:92  ][INFO    ][15575] maasconfig name=maas_name value=mas01
2019-07-28 06:15:49,917 [salt.loaded.ext.module.maas:92  ][INFO    ][15575] maasconfig name=network_discovery value=enabled
2019-07-28 06:15:50,034 [salt.loaded.ext.module.maas:92  ][INFO    ][15575] maasconfig name=enable_third_party_drivers value=True
2019-07-28 06:15:50,091 [salt.loaded.ext.module.maas:92  ][INFO    ][15575] maasconfig name=default_storage_layout value=lvm
2019-07-28 06:15:50,147 [salt.loaded.ext.module.maas:92  ][INFO    ][15575] maasconfig name=ntp_external_only value=True
2019-07-28 06:15:50,199 [salt.loaded.ext.module.maas:92  ][INFO    ][15575] maasconfig name=disk_erase_with_secure_erase value=False
2019-07-28 06:15:50,259 [salt.loaded.ext.module.maas:92  ][INFO    ][15575] maasconfig name=default_distro_series value=xenial
2019-07-28 06:15:50,325 [salt.loaded.ext.module.maas:92  ][INFO    ][15575] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-07-28 06:15:50,523 [salt.state       :300 ][INFO    ][15575] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-07-28 06:15:50,523 [salt.state       :1951][INFO    ][15575] Completed state [maas.process_maas_config] at time 06:15:50.523657 duration_in_ms=4011.122
2019-07-28 06:15:50,524 [salt.state       :1780][INFO    ][15575] Running state [pxe_admin] at time 06:15:50.524276
2019-07-28 06:15:50,524 [salt.state       :1813][INFO    ][15575] Executing state maasng.fabric_present for [pxe_admin]
2019-07-28 06:15:50,661 [salt.loaded.ext.module.maasng:945 ][INFO    ][15575] [{u'id': 0, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'e83yfc', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-07-28 06:15:50,738 [salt.loaded.ext.module.maasng:1008][WARNING ][15575] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-07-28 06:15:50,738 [salt.loaded.ext.module.maasng:1011][WARNING ][15575] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-07-28 06:15:50,811 [salt.state       :300 ][INFO    ][15575] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-07-28 06:15:50,812 [salt.state       :1951][INFO    ][15575] Completed state [pxe_admin] at time 06:15:50.812271 duration_in_ms=287.995
2019-07-28 06:15:50,812 [salt.state       :1780][INFO    ][15575] Running state [vlan 0] at time 06:15:50.812734
2019-07-28 06:15:50,813 [salt.state       :1813][INFO    ][15575] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-07-28 06:15:50,881 [salt.loaded.ext.module.maasng:945 ][INFO    ][15575] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'e83yfc', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-07-28 06:15:51,014 [salt.loaded.ext.module.maasng:945 ][INFO    ][15575] [{u'id': 0, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'e83yfc', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-07-28 06:15:51,265 [salt.loaded.ext.module.maasng:945 ][INFO    ][15575] [{u'vlans': [{u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0, u'class_type': None, u'name': u'fabric-0'}, {u'vlans': [{u'vid': 0, u'fabric': u'fabric-1', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1, u'class_type': None, u'name': u'fabric-1'}, {u'vlans': [{u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'e83yfc', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2, u'class_type': u'', u'name': u'pxe_admin'}]
2019-07-28 06:15:51,338 [salt.state       :300 ][INFO    ][15575] {'new': 'Vlan untagged was updated'}
2019-07-28 06:15:51,338 [salt.state       :1951][INFO    ][15575] Completed state [vlan 0] at time 06:15:51.338797 duration_in_ms=526.062
2019-07-28 06:15:51,340 [salt.state       :1780][INFO    ][15575] Running state [192.168.11.0/24] at time 06:15:51.340662
2019-07-28 06:15:51,341 [salt.state       :1813][INFO    ][15575] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-07-28 06:15:51,559 [salt.loaded.ext.module.maasng:945 ][INFO    ][15575] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'e83yfc', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u''}]
2019-07-28 06:15:51,559 [salt.loaded.ext.module.maasng:1235][WARNING ][15575] Ignoring parameter vlan:0
2019-07-28 06:15:51,638 [salt.state       :300 ][INFO    ][15575] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-07-28 06:15:51,638 [salt.state       :1951][INFO    ][15575] Completed state [192.168.11.0/24] at time 06:15:51.638432 duration_in_ms=297.768
2019-07-28 06:15:51,639 [salt.state       :1780][INFO    ][15575] Running state [maas_create_iprange_1] at time 06:15:51.639802
2019-07-28 06:15:51,640 [salt.state       :1813][INFO    ][15575] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-07-28 06:15:51,690 [salt.state       :300 ][INFO    ][15575] Iprange maas_create_iprange_1 already exist.
2019-07-28 06:15:51,691 [salt.state       :1951][INFO    ][15575] Completed state [maas_create_iprange_1] at time 06:15:51.691263 duration_in_ms=51.46
2019-07-28 06:15:51,691 [salt.state       :1780][INFO    ][15575] Running state [vlan 0] at time 06:15:51.691845
2019-07-28 06:15:51,692 [salt.state       :1813][INFO    ][15575] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-07-28 06:15:51,744 [salt.loaded.ext.module.maasng:945 ][INFO    ][15575] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'e83yfc', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u''}]
2019-07-28 06:15:51,858 [salt.loaded.ext.module.maasng:945 ][INFO    ][15575] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'e83yfc', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-07-28 06:15:52,104 [salt.loaded.ext.module.maasng:945 ][INFO    ][15575] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'e83yfc', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u''}]
2019-07-28 06:15:52,206 [salt.state       :300 ][INFO    ][15575] {'new': 'Vlan untagged was updated'}
2019-07-28 06:15:52,207 [salt.state       :1951][INFO    ][15575] Completed state [vlan 0] at time 06:15:52.207240 duration_in_ms=515.395
2019-07-28 06:15:52,208 [salt.state       :1780][INFO    ][15575] Running state [opnfv] at time 06:15:52.208190
2019-07-28 06:15:52,208 [salt.state       :1813][INFO    ][15575] Executing state maasng.sshkey_present for [opnfv]
2019-07-28 06:15:52,259 [salt.loaded.ext.module.maasng:1903][INFO    ][15575] [{u'keysource': u'', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP', u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/'}]
2019-07-28 06:15:52,260 [salt.state       :300 ][INFO    ][15575] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-07-28 06:15:52,260 [salt.state       :1951][INFO    ][15575] Completed state [opnfv] at time 06:15:52.260673 duration_in_ms=52.483
2019-07-28 06:15:52,261 [salt.state       :1780][INFO    ][15575] Running state [maas.process_tags] at time 06:15:52.261604
2019-07-28 06:15:52,262 [salt.state       :1813][INFO    ][15575] Executing state module.run for [maas.process_tags]
2019-07-28 06:15:52,262 [salt.utils.decorators:613 ][WARNING ][15575] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-28 06:15:52,326 [salt.loaded.ext.module.maas:92  ][INFO    ][15575] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-07-28 06:15:52,391 [salt.state       :300 ][INFO    ][15575] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-07-28 06:15:52,392 [salt.state       :1951][INFO    ][15575] Completed state [maas.process_tags] at time 06:15:52.392416 duration_in_ms=130.812
2019-07-28 06:15:52,397 [salt.minion      :1711][INFO    ][15575] Returning information for job: 20190728061523890061
2019-07-28 06:15:52,981 [salt.minion      :1308][INFO    ][13817] User sudo_ubuntu Executing command state.apply with jid 20190728061552968924
2019-07-28 06:15:53,000 [salt.minion      :1432][INFO    ][16111] Starting a new job with PID 16111
2019-07-28 06:15:56,652 [salt.state       :915 ][INFO    ][16111] Loading fresh modules for state activity
2019-07-28 06:15:56,744 [salt.state       :1780][INFO    ][16111] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:15:56.744882
2019-07-28 06:15:56,745 [salt.state       :1813][INFO    ][16111] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-28 06:15:56,747 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16111] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-28 06:15:58,163 [salt.state       :300 ][INFO    ][16111] {'pid': 16135, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-28 06:15:58,164 [salt.state       :1951][INFO    ][16111] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:15:58.164178 duration_in_ms=1419.297
2019-07-28 06:15:58,165 [salt.state       :1780][INFO    ][16111] Running state [maas.process_machines] at time 06:15:58.165366
2019-07-28 06:15:58,165 [salt.state       :1813][INFO    ][16111] Executing state module.run for [maas.process_machines]
2019-07-28 06:15:58,166 [salt.utils.decorators:613 ][WARNING ][16111] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-28 06:15:58,513 [salt.loaded.ext.module.maas:412 ][WARNING ][16111] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-28 06:15:58,514 [salt.loaded.ext.module.maas:92  ][INFO    ][16111] machine hostname=cmp002 power_type=ipmi mac_addresses=[u'00:25:b5:a0:00:6a'] power_parameters_power_address=172.30.8.72 power_parameters_power_pass=octopus system_id=ndrxg3 architecture=amd64/generic power_parameters_power_user=admin
2019-07-28 06:15:59,291 [salt.loaded.ext.module.maas:412 ][WARNING ][16111] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-28 06:15:59,291 [salt.loaded.ext.module.maas:92  ][INFO    ][16111] machine hostname=cmp001 power_type=ipmi mac_addresses=[u'00:25:b5:a0:00:5a'] power_parameters_power_address=172.30.8.73 power_parameters_power_pass=octopus system_id=qfkkft architecture=amd64/generic power_parameters_power_user=admin
2019-07-28 06:16:00,327 [salt.loaded.ext.module.maas:412 ][WARNING ][16111] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-28 06:16:00,328 [salt.loaded.ext.module.maas:92  ][INFO    ][16111] machine hostname=kvm01 power_type=ipmi mac_addresses=[u'00:25:b5:a0:00:2a'] power_parameters_power_address=172.30.8.75 power_parameters_power_pass=octopus system_id=c8n6q3 architecture=amd64/generic power_parameters_power_user=admin
2019-07-28 06:16:01,375 [salt.loaded.ext.module.maas:412 ][WARNING ][16111] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-28 06:16:01,375 [salt.loaded.ext.module.maas:92  ][INFO    ][16111] machine hostname=kvm03 power_type=ipmi mac_addresses=[u'00:25:b5:a0:00:4a'] power_parameters_power_address=172.30.8.74 power_parameters_power_pass=octopus system_id=pb4qen architecture=amd64/generic power_parameters_power_user=admin
2019-07-28 06:16:02,410 [salt.loaded.ext.module.maas:412 ][WARNING ][16111] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-28 06:16:02,412 [salt.loaded.ext.module.maas:92  ][INFO    ][16111] machine hostname=kvm02 power_type=ipmi mac_addresses=[u'00:25:b5:a0:00:3a'] power_parameters_power_address=172.30.8.65 power_parameters_power_pass=octopus system_id=n4phwm architecture=amd64/generic power_parameters_power_user=admin
2019-07-28 06:16:03,465 [salt.state       :300 ][INFO    ][16111] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-07-28 06:16:03,465 [salt.state       :1951][INFO    ][16111] Completed state [maas.process_machines] at time 06:16:03.465556 duration_in_ms=5300.188
2019-07-28 06:16:03,469 [salt.minion      :1711][INFO    ][16111] Returning information for job: 20190728061552968924
2019-07-28 06:16:34,448 [salt.minion      :1308][INFO    ][13817] User sudo_ubuntu Executing command state.apply with jid 20190728061634435477
2019-07-28 06:16:34,470 [salt.minion      :1432][INFO    ][16642] Starting a new job with PID 16642
2019-07-28 06:16:38,292 [salt.state       :915 ][INFO    ][16642] Loading fresh modules for state activity
2019-07-28 06:16:38,380 [salt.state       :1780][INFO    ][16642] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:16:38.380208
2019-07-28 06:16:38,380 [salt.state       :1813][INFO    ][16642] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-28 06:16:38,382 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16642] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-28 06:16:39,807 [salt.state       :300 ][INFO    ][16642] {'pid': 16736, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-28 06:16:39,808 [salt.state       :1951][INFO    ][16642] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:16:39.808301 duration_in_ms=1428.094
2019-07-28 06:16:39,809 [salt.state       :1780][INFO    ][16642] Running state [maas.wait_for_machine_status] at time 06:16:39.809825
2019-07-28 06:16:39,810 [salt.state       :1813][INFO    ][16642] Executing state module.run for [maas.wait_for_machine_status]
2019-07-28 06:16:39,810 [salt.utils.decorators:613 ][WARNING ][16642] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-28 06:16:43,178 [salt.loaded.ext.module.maas:1023][INFO    ][16642] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:1500s (1496.63615298s left)
2019-07-28 06:16:49,466 [salt.minion      :1308][INFO    ][13817] User sudo_ubuntu Executing command saltutil.find_job with jid 20190728061649454315
2019-07-28 06:16:49,490 [salt.minion      :1432][INFO    ][16767] Starting a new job with PID 16767
2019-07-28 06:16:49,514 [salt.minion      :1711][INFO    ][16767] Returning information for job: 20190728061649454315
2019-07-28 06:17:16,655 [salt.loaded.ext.module.maas:1023][INFO    ][16642] Waiting status:Ready|Deployed for machines:['cmp002']
sleep for:30s Timeout:1500s (1463.15995193s left)
2019-07-28 06:17:19,512 [salt.minion      :1308][INFO    ][13817] User sudo_ubuntu Executing command saltutil.find_job with jid 20190728061719500736
2019-07-28 06:17:19,533 [salt.minion      :1432][INFO    ][16856] Starting a new job with PID 16856
2019-07-28 06:17:19,554 [salt.minion      :1711][INFO    ][16856] Returning information for job: 20190728061719500736
2019-07-28 06:17:49,558 [salt.minion      :1308][INFO    ][13817] User sudo_ubuntu Executing command saltutil.find_job with jid 20190728061749545437
2019-07-28 06:17:49,579 [salt.minion      :1432][INFO    ][16876] Starting a new job with PID 16876
2019-07-28 06:17:49,603 [salt.minion      :1711][INFO    ][16876] Returning information for job: 20190728061749545437
2019-07-28 06:17:49,853 [salt.loaded.ext.module.maas:1023][INFO    ][16642] Waiting status:Ready|Deployed for machines:['cmp002']
sleep for:30s Timeout:1500s (1429.96173978s left)
2019-07-28 06:18:19,603 [salt.minion      :1308][INFO    ][13817] User sudo_ubuntu Executing command saltutil.find_job with jid 20190728061819591158
2019-07-28 06:18:19,626 [salt.minion      :1432][INFO    ][16931] Starting a new job with PID 16931
2019-07-28 06:18:19,649 [salt.minion      :1711][INFO    ][16931] Returning information for job: 20190728061819591158
2019-07-28 06:18:22,750 [salt.loaded.ext.module.maas:1023][INFO    ][16642] Waiting status:Ready|Deployed for machines:['cmp002']
sleep for:30s Timeout:1500s (1397.0649209s left)
2019-07-28 06:18:49,649 [salt.minion      :1308][INFO    ][13817] User sudo_ubuntu Executing command saltutil.find_job with jid 20190728061849637307
2019-07-28 06:18:49,672 [salt.minion      :1432][INFO    ][16951] Starting a new job with PID 16951
2019-07-28 06:18:49,696 [salt.minion      :1711][INFO    ][16951] Returning information for job: 20190728061849637307
2019-07-28 06:18:55,852 [salt.loaded.ext.module.maas:1023][INFO    ][16642] Waiting status:Ready|Deployed for machines:['cmp002']
sleep for:30s Timeout:1500s (1363.96230793s left)
2019-07-28 06:19:19,702 [salt.minion      :1308][INFO    ][13817] User sudo_ubuntu Executing command saltutil.find_job with jid 20190728061919689563
2019-07-28 06:19:19,725 [salt.minion      :1432][INFO    ][17023] Starting a new job with PID 17023
2019-07-28 06:19:19,750 [salt.minion      :1711][INFO    ][17023] Returning information for job: 20190728061919689563
2019-07-28 06:19:26,460 [salt.loaded.ext.module.maas:981 ][INFO    ][16642] Machine ndrxg3 deleted
2019-07-28 06:19:27,188 [salt.loaded.ext.module.maas:412 ][WARNING ][16642] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-28 06:19:27,189 [salt.loaded.ext.module.maas:92  ][INFO    ][16642] machine hostname=cmp002 power_type=ipmi mac_addresses=00:25:b5:a0:00:6a power_parameters_power_address=172.30.8.72 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-07-28 06:19:28,341 [salt.loaded.ext.module.maas:412 ][WARNING ][16642] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-28 06:19:28,342 [salt.loaded.ext.module.maas:92  ][INFO    ][16642] machine hostname=cmp001 power_type=ipmi mac_addresses=['00:25:b5:a0:00:5a'] power_parameters_power_address=172.30.8.73 power_parameters_power_pass=octopus system_id=qfkkft architecture=amd64/generic power_parameters_power_user=admin
2019-07-28 06:19:29,402 [salt.loaded.ext.module.maas:412 ][WARNING ][16642] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-28 06:19:29,403 [salt.loaded.ext.module.maas:92  ][INFO    ][16642] machine hostname=kvm01 power_type=ipmi mac_addresses=['00:25:b5:a0:00:2a'] power_parameters_power_address=172.30.8.75 power_parameters_power_pass=octopus system_id=c8n6q3 architecture=amd64/generic power_parameters_power_user=admin
2019-07-28 06:19:30,533 [salt.loaded.ext.module.maas:412 ][WARNING ][16642] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-28 06:19:30,534 [salt.loaded.ext.module.maas:92  ][INFO    ][16642] machine hostname=kvm03 power_type=ipmi mac_addresses=['00:25:b5:a0:00:4a'] power_parameters_power_address=172.30.8.74 power_parameters_power_pass=octopus system_id=pb4qen architecture=amd64/generic power_parameters_power_user=admin
2019-07-28 06:19:31,701 [salt.loaded.ext.module.maas:412 ][WARNING ][16642] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-28 06:19:31,702 [salt.loaded.ext.module.maas:92  ][INFO    ][16642] machine hostname=kvm02 power_type=ipmi mac_addresses=['00:25:b5:a0:00:3a'] power_parameters_power_address=172.30.8.65 power_parameters_power_pass=octopus system_id=n4phwm architecture=amd64/generic power_parameters_power_user=admin
2019-07-28 06:19:35,484 [salt.loaded.ext.module.maas:1023][INFO    ][16642] Waiting status:Ready|Deployed for machines:['cmp002']
sleep for:30s Timeout:1500s (1324.33068085s left)
2019-07-28 06:19:49,765 [salt.minion      :1308][INFO    ][13817] User sudo_ubuntu Executing command saltutil.find_job with jid 20190728061949752663
2019-07-28 06:19:49,788 [salt.minion      :1432][INFO    ][17305] Starting a new job with PID 17305
2019-07-28 06:19:49,812 [salt.minion      :1711][INFO    ][17305] Returning information for job: 20190728061949752663
2019-07-28 06:20:08,622 [salt.loaded.ext.module.maas:1023][INFO    ][16642] Waiting status:Ready|Deployed for machines:['cmp002']
sleep for:30s Timeout:1500s (1291.19256186s left)
2019-07-28 06:20:19,829 [salt.minion      :1308][INFO    ][13817] User sudo_ubuntu Executing command saltutil.find_job with jid 20190728062019816752
2019-07-28 06:20:19,851 [salt.minion      :1432][INFO    ][17362] Starting a new job with PID 17362
2019-07-28 06:20:19,875 [salt.minion      :1711][INFO    ][17362] Returning information for job: 20190728062019816752
2019-07-28 06:20:41,849 [salt.loaded.ext.module.maas:1023][INFO    ][16642] Waiting status:Ready|Deployed for machines:['cmp002']
sleep for:30s Timeout:1500s (1257.96608281s left)
2019-07-28 06:20:49,895 [salt.minion      :1308][INFO    ][13817] User sudo_ubuntu Executing command saltutil.find_job with jid 20190728062049882844
2019-07-28 06:20:49,917 [salt.minion      :1432][INFO    ][17386] Starting a new job with PID 17386
2019-07-28 06:20:49,942 [salt.minion      :1711][INFO    ][17386] Returning information for job: 20190728062049882844
2019-07-28 06:21:15,083 [salt.loaded.ext.module.maas:1023][INFO    ][16642] Waiting status:Ready|Deployed for machines:['cmp002']
sleep for:30s Timeout:1500s (1224.73194289s left)
2019-07-28 06:21:19,964 [salt.minion      :1308][INFO    ][13817] User sudo_ubuntu Executing command saltutil.find_job with jid 20190728062119951812
2019-07-28 06:21:19,987 [salt.minion      :1432][INFO    ][17445] Starting a new job with PID 17445
2019-07-28 06:21:20,010 [salt.minion      :1711][INFO    ][17445] Returning information for job: 20190728062119951812
2019-07-28 06:21:48,241 [salt.loaded.ext.module.maas:1023][INFO    ][16642] Waiting status:Ready|Deployed for machines:['cmp002']
sleep for:30s Timeout:1500s (1191.57310295s left)
2019-07-28 06:21:50,037 [salt.minion      :1308][INFO    ][13817] User sudo_ubuntu Executing command saltutil.find_job with jid 20190728062150024299
2019-07-28 06:21:50,059 [salt.minion      :1432][INFO    ][17498] Starting a new job with PID 17498
2019-07-28 06:21:50,083 [salt.minion      :1711][INFO    ][17498] Returning information for job: 20190728062150024299
2019-07-28 06:22:20,115 [salt.minion      :1308][INFO    ][13817] User sudo_ubuntu Executing command saltutil.find_job with jid 20190728062220103051
2019-07-28 06:22:20,136 [salt.minion      :1432][INFO    ][17687] Starting a new job with PID 17687
2019-07-28 06:22:20,152 [salt.minion      :1711][INFO    ][17687] Returning information for job: 20190728062220103051
2019-07-28 06:22:21,464 [salt.loaded.ext.module.maas:1023][INFO    ][16642] Waiting status:Ready|Deployed for machines:['cmp002']
sleep for:30s Timeout:1500s (1158.35072589s left)
2019-07-28 06:22:50,190 [salt.minion      :1308][INFO    ][13817] User sudo_ubuntu Executing command saltutil.find_job with jid 20190728062250177686
2019-07-28 06:22:50,213 [salt.minion      :1432][INFO    ][17710] Starting a new job with PID 17710
2019-07-28 06:22:50,237 [salt.minion      :1711][INFO    ][17710] Returning information for job: 20190728062250177686
2019-07-28 06:22:54,848 [salt.loaded.ext.module.maas:1023][INFO    ][16642] Waiting status:Ready|Deployed for machines:['cmp002']
sleep for:30s Timeout:1500s (1124.96652293s left)
2019-07-28 06:23:20,278 [salt.minion      :1308][INFO    ][13817] User sudo_ubuntu Executing command saltutil.find_job with jid 20190728062320266078
2019-07-28 06:23:20,301 [salt.minion      :1432][INFO    ][17782] Starting a new job with PID 17782
2019-07-28 06:23:20,322 [salt.minion      :1711][INFO    ][17782] Returning information for job: 20190728062320266078
2019-07-28 06:23:28,443 [salt.state       :300 ][INFO    ][16642] {'ret': True}
2019-07-28 06:23:28,443 [salt.state       :1951][INFO    ][16642] Completed state [maas.wait_for_machine_status] at time 06:23:28.443566 duration_in_ms=408633.738
2019-07-28 06:23:28,447 [salt.minion      :1711][INFO    ][16642] Returning information for job: 20190728061634435477
2019-07-28 06:23:29,047 [salt.minion      :1308][INFO    ][13817] User sudo_ubuntu Executing command state.apply with jid 20190728062329035267
2019-07-28 06:23:29,068 [salt.minion      :1432][INFO    ][17799] Starting a new job with PID 17799
2019-07-28 06:23:32,762 [salt.state       :915 ][INFO    ][17799] Loading fresh modules for state activity
2019-07-28 06:23:32,815 [salt.fileclient  :1219][INFO    ][17799] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-07-28 06:23:32,909 [salt.state       :1780][INFO    ][17799] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:23:32.909542
2019-07-28 06:23:32,909 [salt.state       :1813][INFO    ][17799] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-28 06:23:32,911 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17799] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-28 06:23:34,359 [salt.state       :300 ][INFO    ][17799] {'pid': 17806, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-28 06:23:34,359 [salt.state       :1951][INFO    ][17799] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:23:34.359445 duration_in_ms=1449.903
2019-07-28 06:23:34,360 [salt.state       :1780][INFO    ][17799] Running state [maas_machines_storage_cmp002_lvm] at time 06:23:34.360806
2019-07-28 06:23:34,361 [salt.state       :1813][INFO    ][17799] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-07-28 06:23:35,948 [salt.loaded.ext.module.maasng:610 ][INFO    ][17799] aexyef
2019-07-28 06:23:35,949 [salt.loaded.ext.module.maasng:626 ][INFO    ][17799] sda
2019-07-28 06:23:36,637 [salt.loaded.ext.module.maasng:361 ][INFO    ][17799] aexyef
2019-07-28 06:23:36,768 [salt.loaded.ext.module.maasng:367 ][INFO    ][17799] [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'available_size': 0, u'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/aexyef/blockdevices/9/', u'type': u'physical', u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'aexyef', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'used_size': 2397998940160, u'id': 9, u'partitions': [{u'uuid': u'9013d6ae-d22a-456e-ae5f-3342c96b4126', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'aexyef', u'device_id': 9, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'6229a6b5-168e-4d36-84a5-5f5b9df3f6ea', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/aexyef/blockdevices/9/partition/5', u'type': u'partition', u'id': 5, u'size': 2397992648704}]}, {u'size': 2397988454400, u'model': None, u'available_size': 0, u'uuid': u'ba7e1264-68f9-418e-98c6-af15a69e721d', u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/aexyef/blockdevices/10/', u'type': u'virtual', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'aexyef', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'947bafaf-dcf3-4db5-91f4-f86e2c2db190', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'used_size': 2397988454400, u'id': 10, u'partitions': []}]
2019-07-28 06:23:36,769 [salt.loaded.ext.module.maasng:632 ][INFO    ][17799] vgroot
2019-07-28 06:23:36,769 [salt.loaded.ext.module.maasng:635 ][INFO    ][17799] lvroot
2019-07-28 06:23:36,769 [salt.loaded.ext.module.maasng:639 ][INFO    ][17799] 107374182400
2019-07-28 06:23:37,519 [salt.loaded.ext.module.maasng:645 ][INFO    ][17799] {u'hwe_kernel': u'', u'hostname': u'cmp002', u'swap_size': None, u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.39'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'e83yfc', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 35}], u'tags': [], u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'e83yfc', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'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': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'e83yfc', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'mac_address': u'00:25:b5:a0:00:6a', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'aexyef', u'type': u'physical', u'id': 21, u'resource_uri': u'/MAAS/api/2.0/nodes/aexyef/interfaces/21/'}, u'fqdn': u'cmp002.maas', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 2397998940160, u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'aexyef', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'partitions': [{u'uuid': u'b5d79d0a-d3ef-48d1-ad11-3ea75ba0674b', u'resource_uri': u'/MAAS/api/2.0/nodes/aexyef/blockdevices/9/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'aexyef', u'filesystem': {u'uuid': u'414cc47c-6725-4ca5-b141-1dbadd830b96', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 9, u'type': u'partition', u'id': 6, u'size': 2397992648704}], u'type': u'physical', u'id': 9, u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/aexyef/blockdevices/9/'}, u'blockdevice_set': [{u'size': 2397998940160, u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'aexyef', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'partitions': [{u'uuid': u'b5d79d0a-d3ef-48d1-ad11-3ea75ba0674b', u'resource_uri': u'/MAAS/api/2.0/nodes/aexyef/blockdevices/9/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'aexyef', u'filesystem': {u'uuid': u'414cc47c-6725-4ca5-b141-1dbadd830b96', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 9, u'type': u'partition', u'id': 6, u'size': 2397992648704}], u'type': u'physical', u'id': 9, u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/aexyef/blockdevices/9/'}, {u'size': 107374182400, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'filesystem': {u'uuid': u'905f7080-c60b-4e3a-addb-b05dc2459009', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'label': u'root'}, u'uuid': u'00cedf28-e170-4855-9914-8e89af7e4039', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'aexyef', 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'partitions': [], u'type': u'virtual', u'id': 11, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/aexyef/blockdevices/11/'}], u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default'}, u'disable_ipv4': False, u'node_type_name': u'Machine', u'current_commissioning_result_id': 12, u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'aexyef', u'power_state': u'on', u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u"From 'Testing' to 'Ready'", u'virtualblockdevice_set': [{u'size': 107374182400, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'filesystem': {u'uuid': u'905f7080-c60b-4e3a-addb-b05dc2459009', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'label': u'root'}, u'uuid': u'00cedf28-e170-4855-9914-8e89af7e4039', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'aexyef', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'partitions': [], u'type': u'virtual', u'id': 11, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/aexyef/blockdevices/11/'}], u'min_hwe_kernel': u'hwe-16.04', u'status': 4, u'bcaches': [], u'cpu_count': 16, u'raids': [], u'physicalblockdevice_set': [{u'size': 2397998940160, u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'aexyef', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'partitions': [{u'uuid': u'b5d79d0a-d3ef-48d1-ad11-3ea75ba0674b', u'resource_uri': u'/MAAS/api/2.0/nodes/aexyef/blockdevices/9/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'aexyef', u'filesystem': {u'uuid': u'414cc47c-6725-4ca5-b141-1dbadd830b96', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 9, u'type': u'partition', u'id': 6, u'size': 2397992648704}], u'type': u'physical', u'id': 9, u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/aexyef/blockdevices/9/'}], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'aexyef', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'commissioning_status_name': u'Passed', u'current_testing_result_id': 13, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'node_type': 0, u'memory_test_status_name': u'Unknown', u'commissioning_status': 2, u'architecture': u'amd64/generic', u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'e83yfc', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 35}], u'tags': [], u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'e83yfc', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'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': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'e83yfc', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'mac_address': u'00:25:b5:a0:00:6a', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'aexyef', u'type': u'physical', u'id': 21, u'resource_uri': u'/MAAS/api/2.0/nodes/aexyef/interfaces/21/'}, {u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 37}], u'tags': [], u'vlan': {u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:6d', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'aexyef', u'type': u'physical', u'id': 23, u'resource_uri': u'/MAAS/api/2.0/nodes/aexyef/interfaces/23/'}, {u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 38}], u'tags': [], u'vlan': {u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:6b', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'aexyef', u'type': u'physical', u'id': 24, u'resource_uri': u'/MAAS/api/2.0/nodes/aexyef/interfaces/24/'}, {u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 36}], u'tags': [], u'vlan': {u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:6c', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'aexyef', u'type': u'physical', u'id': 22, u'resource_uri': u'/MAAS/api/2.0/nodes/aexyef/interfaces/22/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/aexyef/'}
2019-07-28 06:23:37,521 [salt.state       :300 ][INFO    ][17799] {'new': {'storage_layout': 'lvm'}}
2019-07-28 06:23:37,522 [salt.state       :1951][INFO    ][17799] Completed state [maas_machines_storage_cmp002_lvm] at time 06:23:37.522110 duration_in_ms=3161.302
2019-07-28 06:23:37,522 [salt.state       :1780][INFO    ][17799] Running state [maas_machines_storage_cmp001_lvm] at time 06:23:37.522687
2019-07-28 06:23:37,523 [salt.state       :1813][INFO    ][17799] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-07-28 06:23:38,992 [salt.loaded.ext.module.maasng:610 ][INFO    ][17799] qfkkft
2019-07-28 06:23:38,992 [salt.loaded.ext.module.maasng:626 ][INFO    ][17799] sda
2019-07-28 06:23:39,696 [salt.loaded.ext.module.maasng:361 ][INFO    ][17799] qfkkft
2019-07-28 06:23:39,822 [salt.loaded.ext.module.maasng:367 ][INFO    ][17799] [{u'block_size': 4096, u'available_size': 0, u'uuid': None, u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/qfkkft/blockdevices/3/', u'partitions': [{u'uuid': u'df202c87-c462-4eaa-b66f-d417d9c34375', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qfkkft', u'device_id': 3, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'd4304693-d7bf-4621-a152-87ada8288ff2', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/nodes/qfkkft/blockdevices/3/partition/3'}], u'id': 3, u'type': u'physical', u'system_id': u'qfkkft', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'size': 2397998940160, u'used_size': 2397998940160, u'serial': u'618e72837274f1901cc7889705aa1b02', u'name': u'sda'}, {u'block_size': 4096, u'available_size': 0, u'uuid': u'1de5c50d-52c6-4dc9-a07f-087e055b0b44', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'resource_uri': u'/MAAS/api/2.0/nodes/qfkkft/blockdevices/7/', u'partitions': [], u'id': 7, u'type': u'virtual', u'system_id': u'qfkkft', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'31582a29-bf60-49e2-9064-40764ab42026', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'size': 2397988454400, u'used_size': 2397988454400, u'serial': None, u'name': u'vgroot-lvroot'}]
2019-07-28 06:23:39,823 [salt.loaded.ext.module.maasng:632 ][INFO    ][17799] vgroot
2019-07-28 06:23:39,823 [salt.loaded.ext.module.maasng:635 ][INFO    ][17799] lvroot
2019-07-28 06:23:39,824 [salt.loaded.ext.module.maasng:639 ][INFO    ][17799] 107374182400
2019-07-28 06:23:40,531 [salt.loaded.ext.module.maasng:645 ][INFO    ][17799] {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'disable_ipv4': False, u'cpu_count': 16, u'power_type': u'ipmi', u'hwe_kernel': u'', u'boot_interface': {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'e83yfc', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'e83yfc', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 25, u'mode': u'dhcp'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:5a', u'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'e83yfc', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'qfkkft', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qfkkft/interfaces/5/'}, u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'status_action': u'', u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'current_commissioning_result_id': 4, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'qfkkft', u'raids': [], u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'ip_addresses': [u'192.168.11.38'], u'architecture': u'amd64/generic', u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'power_state': u'off', u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'qfkkft', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'size': 2397998940160, u'resource_uri': u'/MAAS/api/2.0/nodes/qfkkft/blockdevices/3/', u'available_size': 0, u'uuid': None, u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'uuid': u'17e88b4e-5947-4e06-b0eb-d9b85ba04087', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qfkkft', u'device_id': 3, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'ee00af71-dfb9-455d-881c-bbd990bd9b00', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/qfkkft/blockdevices/3/partition/7'}], u'id': 3, u'system_id': u'qfkkft', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'used_size': 2397998940160, u'serial': u'618e72837274f1901cc7889705aa1b02', u'name': u'sda'}, u'current_testing_result_id': 5, u'cpu_test_status': -1, u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 2397998940160, u'resource_uri': u'/MAAS/api/2.0/nodes/qfkkft/blockdevices/3/', u'available_size': 0, u'uuid': None, u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'uuid': u'17e88b4e-5947-4e06-b0eb-d9b85ba04087', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qfkkft', u'device_id': 3, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'ee00af71-dfb9-455d-881c-bbd990bd9b00', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/qfkkft/blockdevices/3/partition/7'}], u'id': 3, u'system_id': u'qfkkft', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'used_size': 2397998940160, u'serial': u'618e72837274f1901cc7889705aa1b02', u'name': u'sda'}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/qfkkft/blockdevices/12/', u'available_size': 0, u'uuid': u'2a223bdb-f540-4f22-bbb7-ecdd407dfe36', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'partitions': [], u'id': 12, u'system_id': u'qfkkft', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'fa11e64c-acef-4499-89cc-8ea18b2acc71', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'serial': None, u'name': u'vgroot-lvroot'}], u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'commissioning_status_name': u'Passed', u'interface_set': [{u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'e83yfc', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'e83yfc', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 25, u'mode': u'dhcp'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:5a', u'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'e83yfc', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'qfkkft', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qfkkft/interfaces/5/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'enp8s0', u'links': [{u'id': 27, u'mode': u'link_up'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:5c', u'enabled': True, u'id': 15, u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'qfkkft', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qfkkft/interfaces/15/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'enp9s0', u'links': [{u'id': 29, u'mode': u'link_up'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:5d', u'enabled': True, u'id': 16, u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'qfkkft', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qfkkft/interfaces/16/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'enp7s0', u'links': [{u'id': 31, u'mode': u'link_up'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:5b', u'enabled': True, u'id': 17, u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'qfkkft', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qfkkft/interfaces/17/'}], u'address_ttl': None, u'blockdevice_set': [{u'block_size': 4096, u'available_size': 0, u'uuid': None, u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/qfkkft/blockdevices/3/', u'partitions': [{u'uuid': u'17e88b4e-5947-4e06-b0eb-d9b85ba04087', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qfkkft', u'device_id': 3, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'ee00af71-dfb9-455d-881c-bbd990bd9b00', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/qfkkft/blockdevices/3/partition/7'}], u'id': 3, u'type': u'physical', u'system_id': u'qfkkft', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'size': 2397998940160, u'used_size': 2397998940160, u'serial': u'618e72837274f1901cc7889705aa1b02', u'name': u'sda'}, {u'block_size': 4096, u'available_size': 0, u'uuid': u'2a223bdb-f540-4f22-bbb7-ecdd407dfe36', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'resource_uri': u'/MAAS/api/2.0/nodes/qfkkft/blockdevices/12/', u'partitions': [], u'id': 12, u'type': u'virtual', u'system_id': u'qfkkft', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'fa11e64c-acef-4499-89cc-8ea18b2acc71', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'size': 107374182400, u'used_size': 107374182400, u'serial': None, u'name': u'vgroot-lvroot'}], u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/qfkkft/'}
2019-07-28 06:23:40,533 [salt.state       :300 ][INFO    ][17799] {'new': {'storage_layout': 'lvm'}}
2019-07-28 06:23:40,534 [salt.state       :1951][INFO    ][17799] Completed state [maas_machines_storage_cmp001_lvm] at time 06:23:40.534023 duration_in_ms=3011.335
2019-07-28 06:23:40,537 [salt.minion      :1711][INFO    ][17799] Returning information for job: 20190728062329035267
2019-07-28 06:23:41,143 [salt.minion      :1308][INFO    ][13817] User sudo_ubuntu Executing command state.apply with jid 20190728062341133356
2019-07-28 06:23:41,165 [salt.minion      :1432][INFO    ][17833] Starting a new job with PID 17833
2019-07-28 06:23:41,887 [salt.state       :915 ][INFO    ][17833] Loading fresh modules for state activity
2019-07-28 06:23:41,941 [salt.fileclient  :1219][INFO    ][17833] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-07-28 06:23:41,986 [salt.state       :1780][INFO    ][17833] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:23:41.986657
2019-07-28 06:23:41,987 [salt.state       :1813][INFO    ][17833] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-28 06:23:41,989 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17833] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-28 06:23:43,510 [salt.state       :300 ][INFO    ][17833] {'pid': 17840, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-28 06:23:43,511 [salt.state       :1951][INFO    ][17833] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:23:43.511104 duration_in_ms=1524.447
2019-07-28 06:23:43,513 [salt.state       :1780][INFO    ][17833] Running state [maas.deploy_machines] at time 06:23:43.513474
2019-07-28 06:23:43,514 [salt.state       :1813][INFO    ][17833] Executing state module.run for [maas.deploy_machines]
2019-07-28 06:23:43,515 [salt.utils.decorators:613 ][WARNING ][17833] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-28 06:23:44,136 [salt.loaded.ext.module.maas:684 ][INFO    ][17833] deploymachines hwe_kernel=hwe-16.04 system_id=aexyef distro_series=xenial
2019-07-28 06:23:46,870 [salt.loaded.ext.module.maas:684 ][INFO    ][17833] deploymachines hwe_kernel=hwe-16.04 system_id=qfkkft distro_series=xenial
2019-07-28 06:23:49,554 [salt.loaded.ext.module.maas:684 ][INFO    ][17833] deploymachines hwe_kernel=hwe-16.04 system_id=c8n6q3 distro_series=xenial
2019-07-28 06:23:52,291 [salt.loaded.ext.module.maas:684 ][INFO    ][17833] deploymachines hwe_kernel=hwe-16.04 system_id=pb4qen distro_series=xenial
2019-07-28 06:23:55,167 [salt.loaded.ext.module.maas:684 ][INFO    ][17833] deploymachines hwe_kernel=hwe-16.04 system_id=n4phwm distro_series=xenial
2019-07-28 06:23:56,158 [salt.minion      :1308][INFO    ][13817] User sudo_ubuntu Executing command saltutil.find_job with jid 20190728062356146948
2019-07-28 06:23:56,179 [salt.minion      :1432][INFO    ][18086] Starting a new job with PID 18086
2019-07-28 06:23:56,200 [salt.minion      :1711][INFO    ][18086] Returning information for job: 20190728062356146948
2019-07-28 06:23:57,786 [salt.state       :300 ][INFO    ][17833] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-07-28 06:23:57,786 [salt.state       :1951][INFO    ][17833] Completed state [maas.deploy_machines] at time 06:23:57.786724 duration_in_ms=14273.25
2019-07-28 06:23:57,790 [salt.minion      :1711][INFO    ][17833] Returning information for job: 20190728062341133356
2019-07-28 06:23:58,423 [salt.minion      :1308][INFO    ][13817] User sudo_ubuntu Executing command state.apply with jid 20190728062358410213
2019-07-28 06:23:58,445 [salt.minion      :1432][INFO    ][18125] Starting a new job with PID 18125
2019-07-28 06:24:02,057 [salt.state       :915 ][INFO    ][18125] Loading fresh modules for state activity
2019-07-28 06:24:02,085 [salt.fileclient  :1219][INFO    ][18125] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-07-28 06:24:02,110 [salt.state       :1780][INFO    ][18125] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:24:02.110848
2019-07-28 06:24:02,111 [salt.state       :1813][INFO    ][18125] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-28 06:24:02,112 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18125] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-28 06:24:03,493 [salt.state       :300 ][INFO    ][18125] {'pid': 18139, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-28 06:24:03,493 [salt.state       :1951][INFO    ][18125] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:24:03.493674 duration_in_ms=1382.827
2019-07-28 06:24:03,496 [salt.state       :1780][INFO    ][18125] Running state [maas.wait_for_machine_status] at time 06:24:03.496708
2019-07-28 06:24:03,497 [salt.state       :1813][INFO    ][18125] Executing state module.run for [maas.wait_for_machine_status]
2019-07-28 06:24:03,497 [salt.utils.decorators:613 ][WARNING ][18125] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-28 06:24:07,191 [salt.loaded.ext.module.maas:1023][INFO    ][18125] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.31560779s left)
2019-07-28 06:24:13,464 [salt.minion      :1308][INFO    ][13817] User sudo_ubuntu Executing command saltutil.find_job with jid 20190728062413451249
2019-07-28 06:24:13,488 [salt.minion      :1432][INFO    ][18202] Starting a new job with PID 18202
2019-07-28 06:24:13,513 [salt.minion      :1711][INFO    ][18202] Returning information for job: 20190728062413451249
2019-07-28 06:24:40,371 [salt.loaded.ext.module.maas:1023][INFO    ][18125] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.13627386s left)
2019-07-28 06:24:43,523 [salt.minion      :1308][INFO    ][13817] User sudo_ubuntu Executing command saltutil.find_job with jid 20190728062443510802
2019-07-28 06:24:43,546 [salt.minion      :1432][INFO    ][18223] Starting a new job with PID 18223
2019-07-28 06:24:43,566 [salt.minion      :1711][INFO    ][18223] Returning information for job: 20190728062443510802
2019-07-28 06:25:13,613 [salt.minion      :1308][INFO    ][13817] User sudo_ubuntu Executing command saltutil.find_job with jid 20190728062513600816
2019-07-28 06:25:13,636 [salt.minion      :1432][INFO    ][18353] Starting a new job with PID 18353
2019-07-28 06:25:13,658 [salt.minion      :1711][INFO    ][18353] Returning information for job: 20190728062513600816
2019-07-28 06:25:13,845 [salt.loaded.ext.module.maas:1023][INFO    ][18125] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2179.66148782s left)
2019-07-28 06:25:43,659 [salt.minion      :1308][INFO    ][13817] User sudo_ubuntu Executing command saltutil.find_job with jid 20190728062543648278
2019-07-28 06:25:43,678 [salt.minion      :1432][INFO    ][18469] Starting a new job with PID 18469
2019-07-28 06:25:43,701 [salt.minion      :1711][INFO    ][18469] Returning information for job: 20190728062543648278
2019-07-28 06:25:47,206 [salt.loaded.ext.module.maas:1023][INFO    ][18125] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2146.30115485s left)
2019-07-28 06:26:13,706 [salt.minion      :1308][INFO    ][13817] User sudo_ubuntu Executing command saltutil.find_job with jid 20190728062613693522
2019-07-28 06:26:13,729 [salt.minion      :1432][INFO    ][19059] Starting a new job with PID 19059
2019-07-28 06:26:13,753 [salt.minion      :1711][INFO    ][19059] Returning information for job: 20190728062613693522
2019-07-28 06:26:20,560 [salt.loaded.ext.module.maas:1023][INFO    ][18125] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2112.946455s left)
2019-07-28 06:26:43,763 [salt.minion      :1308][INFO    ][13817] User sudo_ubuntu Executing command saltutil.find_job with jid 20190728062643750045
2019-07-28 06:26:43,786 [salt.minion      :1432][INFO    ][19104] Starting a new job with PID 19104
2019-07-28 06:26:43,809 [salt.minion      :1711][INFO    ][19104] Returning information for job: 20190728062643750045
2019-07-28 06:26:54,164 [salt.loaded.ext.module.maas:1023][INFO    ][18125] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2079.34269977s left)
2019-07-28 06:27:13,820 [salt.minion      :1308][INFO    ][13817] User sudo_ubuntu Executing command saltutil.find_job with jid 20190728062713807280
2019-07-28 06:27:13,843 [salt.minion      :1432][INFO    ][19508] Starting a new job with PID 19508
2019-07-28 06:27:13,866 [salt.minion      :1711][INFO    ][19508] Returning information for job: 20190728062713807280
2019-07-28 06:27:27,941 [salt.loaded.ext.module.maas:1023][INFO    ][18125] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2045.56578898s left)
2019-07-28 06:27:43,883 [salt.minion      :1308][INFO    ][13817] User sudo_ubuntu Executing command saltutil.find_job with jid 20190728062743869172
2019-07-28 06:27:43,906 [salt.minion      :1432][INFO    ][19529] Starting a new job with PID 19529
2019-07-28 06:27:43,928 [salt.minion      :1711][INFO    ][19529] Returning information for job: 20190728062743869172
2019-07-28 06:28:01,132 [salt.loaded.ext.module.maas:1023][INFO    ][18125] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2012.37489295s left)
2019-07-28 06:28:13,949 [salt.minion      :1308][INFO    ][13817] User sudo_ubuntu Executing command saltutil.find_job with jid 20190728062813937411
2019-07-28 06:28:13,972 [salt.minion      :1432][INFO    ][19796] Starting a new job with PID 19796
2019-07-28 06:28:13,995 [salt.minion      :1711][INFO    ][19796] Returning information for job: 20190728062813937411
2019-07-28 06:28:34,422 [salt.loaded.ext.module.maas:1023][INFO    ][18125] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1979.08436584s left)
2019-07-28 06:28:44,027 [salt.minion      :1308][INFO    ][13817] User sudo_ubuntu Executing command saltutil.find_job with jid 20190728062844015060
2019-07-28 06:28:44,051 [salt.minion      :1432][INFO    ][19822] Starting a new job with PID 19822
2019-07-28 06:28:44,074 [salt.minion      :1711][INFO    ][19822] Returning information for job: 20190728062844015060
2019-07-28 06:29:07,812 [salt.loaded.ext.module.maas:1023][INFO    ][18125] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1945.69480491s left)
2019-07-28 06:29:14,100 [salt.minion      :1308][INFO    ][13817] User sudo_ubuntu Executing command saltutil.find_job with jid 20190728062914087633
2019-07-28 06:29:14,123 [salt.minion      :1432][INFO    ][20326] Starting a new job with PID 20326
2019-07-28 06:29:14,147 [salt.minion      :1711][INFO    ][20326] Returning information for job: 20190728062914087633
2019-07-28 06:29:41,505 [salt.loaded.ext.module.maas:1023][INFO    ][18125] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1912.00216079s left)
2019-07-28 06:29:44,181 [salt.minion      :1308][INFO    ][13817] User sudo_ubuntu Executing command saltutil.find_job with jid 20190728062944168362
2019-07-28 06:29:44,205 [salt.minion      :1432][INFO    ][20381] Starting a new job with PID 20381
2019-07-28 06:29:44,230 [salt.minion      :1711][INFO    ][20381] Returning information for job: 20190728062944168362
2019-07-28 06:30:14,263 [salt.minion      :1308][INFO    ][13817] User sudo_ubuntu Executing command saltutil.find_job with jid 20190728063014250610
2019-07-28 06:30:14,286 [salt.minion      :1432][INFO    ][20708] Starting a new job with PID 20708
2019-07-28 06:30:14,307 [salt.minion      :1711][INFO    ][20708] Returning information for job: 20190728063014250610
2019-07-28 06:30:14,379 [salt.loaded.ext.module.maas:1023][INFO    ][18125] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1879.12831879s left)
2019-07-28 06:30:44,347 [salt.minion      :1308][INFO    ][13817] User sudo_ubuntu Executing command saltutil.find_job with jid 20190728063044334449
2019-07-28 06:30:44,370 [salt.minion      :1432][INFO    ][20724] Starting a new job with PID 20724
2019-07-28 06:30:44,395 [salt.minion      :1711][INFO    ][20724] Returning information for job: 20190728063044334449
2019-07-28 06:30:47,964 [salt.loaded.ext.module.maas:1023][INFO    ][18125] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1845.54241085s left)
2019-07-28 06:31:14,443 [salt.minion      :1308][INFO    ][13817] User sudo_ubuntu Executing command saltutil.find_job with jid 20190728063114437101
2019-07-28 06:31:14,459 [salt.minion      :1432][INFO    ][20880] Starting a new job with PID 20880
2019-07-28 06:31:14,482 [salt.minion      :1711][INFO    ][20880] Returning information for job: 20190728063114437101
2019-07-28 06:31:21,494 [salt.loaded.ext.module.maas:1023][INFO    ][18125] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1812.01310682s left)
2019-07-28 06:31:44,655 [salt.minion      :1308][INFO    ][13817] User sudo_ubuntu Executing command saltutil.find_job with jid 20190728063144643481
2019-07-28 06:31:44,678 [salt.minion      :1432][INFO    ][20992] Starting a new job with PID 20992
2019-07-28 06:31:44,702 [salt.minion      :1711][INFO    ][20992] Returning information for job: 20190728063144643481
2019-07-28 06:31:55,145 [salt.state       :300 ][INFO    ][18125] {'ret': True}
2019-07-28 06:31:55,146 [salt.state       :1951][INFO    ][18125] Completed state [maas.wait_for_machine_status] at time 06:31:55.146380 duration_in_ms=471649.67
2019-07-28 06:31:55,150 [salt.minion      :1711][INFO    ][18125] Returning information for job: 20190728062358410213
2019-07-28 07:08:52,544 [salt.utils.schedule:1377][INFO    ][13817] Running scheduled job: __mine_interval
2019-07-28 08:02:01,821 [salt.minion      :1308][INFO    ][13817] User sudo_ubuntu Executing command cp.push_dir with jid 20190728080201814474
2019-07-28 08:02:01,844 [salt.minion      :1432][INFO    ][27175] Starting a new job with PID 27175
