2019-04-30 02:03:21,113 [salt.minion      :870 ][ERROR   ][353] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-30 02:04:11,152 [salt.minion      :870 ][ERROR   ][353] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-30 02:05:01,199 [salt.minion      :870 ][ERROR   ][353] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-30 02:05:51,247 [salt.minion      :870 ][ERROR   ][353] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-30 02:06:41,277 [salt.minion      :870 ][ERROR   ][353] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-30 02:08:51,770 [salt.utils.decorators:613 ][WARNING ][2839] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-30 02:08:52,390 [salt.utils.decorators:613 ][WARNING ][2839] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-30 02:08:54,551 [salt.loaded.int.states.file:2298][WARNING ][2987] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-04-30 02:09:00,135 [salt.state       :2022][WARNING ][3091] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-04-30 02:09:02,783 [salt.utils.decorators:613 ][WARNING ][3091] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-30 02:24:09,315 [salt.utils.decorators:613 ][WARNING ][3091] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-30 02:50:18,647 [salt.utils.decorators:613 ][WARNING ][3091] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-30 03:05:21,577 [salt.utils.decorators:613 ][WARNING ][3091] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-30 03:05:25,260 [salt.loaded.ext.module.maasng:1008][WARNING ][3091] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-04-30 03:05:25,260 [salt.loaded.ext.module.maasng:1011][WARNING ][3091] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-04-30 03:05:26,021 [salt.loaded.ext.module.maasng:1235][WARNING ][3091] Ignoring parameter vlan:0
2019-04-30 03:05:32,798 [salt.utils.decorators:613 ][WARNING ][7597] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-30 03:05:32,861 [salt.loaded.ext.module.maas:412 ][WARNING ][7597] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-30 03:05:36,628 [salt.loaded.ext.module.maas:412 ][WARNING ][7597] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-30 03:05:39,382 [salt.loaded.ext.module.maas:412 ][WARNING ][7597] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-30 03:05:43,263 [salt.loaded.ext.module.maas:412 ][WARNING ][7597] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-30 03:05:47,640 [salt.loaded.ext.module.maas:412 ][WARNING ][7597] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-30 03:05:53,838 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8498] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-04-30 03:05:53,870 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8498] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-04-30 03:05:53,893 [salt.utils.parsers:1051][WARNING ][353] Minion received a SIGTERM. Exiting.
2019-04-30 03:05:54,963 [salt.cli.daemons :293 ][INFO    ][8543] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-04-30 03:05:55,062 [salt.cli.daemons :82  ][INFO    ][8543] Starting up the Salt Minion
2019-04-30 03:05:55,063 [salt.utils.event :1017][INFO    ][8543] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-04-30 03:05:56,045 [salt.minion      :976 ][INFO    ][8543] Creating minion process manager
2019-04-30 03:05:57,510 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][8543] Executing command ['date', '+%z'] in directory '/root'
2019-04-30 03:05:57,529 [salt.utils.schedule:568 ][INFO    ][8543] Updating job settings for scheduled job: __mine_interval
2019-04-30 03:05:57,530 [salt.minion      :1108][INFO    ][8543] Added mine.update to scheduler
2019-04-30 03:05:57,534 [salt.minion      :1975][INFO    ][8543] Minion is starting as user 'root'
2019-04-30 03:05:57,547 [salt.minion      :2336][INFO    ][8543] Minion is ready to receive requests!
2019-04-30 03:06:22,909 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command state.apply with jid 20190430030622896649
2019-04-30 03:06:22,930 [salt.minion      :1432][INFO    ][8673] Starting a new job with PID 8673
2019-04-30 03:06:26,555 [salt.state       :915 ][INFO    ][8673] Loading fresh modules for state activity
2019-04-30 03:06:26,580 [salt.fileclient  :1219][INFO    ][8673] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-04-30 03:06:26,603 [salt.state       :1780][INFO    ][8673] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:06:26.603104
2019-04-30 03:06:26,603 [salt.state       :1813][INFO    ][8673] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-30 03:06:26,604 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8673] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-30 03:06:27,744 [salt.state       :300 ][INFO    ][8673] {'pid': 8687, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-30 03:06:27,744 [salt.state       :1951][INFO    ][8673] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:06:27.744932 duration_in_ms=1141.828
2019-04-30 03:06:27,745 [salt.state       :1780][INFO    ][8673] Running state [maas.wait_for_machine_status] at time 03:06:27.745907
2019-04-30 03:06:27,746 [salt.state       :1813][INFO    ][8673] Executing state module.run for [maas.wait_for_machine_status]
2019-04-30 03:06:27,746 [salt.utils.decorators:613 ][WARNING ][8673] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-30 03:06:28,635 [salt.loaded.ext.module.maas:1023][INFO    ][8673] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.11460781s left)
2019-04-30 03:06:37,955 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command saltutil.find_job with jid 20190430030637941773
2019-04-30 03:06:37,979 [salt.minion      :1432][INFO    ][8698] Starting a new job with PID 8698
2019-04-30 03:06:38,003 [salt.minion      :1711][INFO    ][8698] Returning information for job: 20190430030637941773
2019-04-30 03:06:59,635 [salt.loaded.ext.module.maas:1023][INFO    ][8673] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.11423779s left)
2019-04-30 03:07:07,999 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command saltutil.find_job with jid 20190430030707986961
2019-04-30 03:07:08,023 [salt.minion      :1432][INFO    ][8754] Starting a new job with PID 8754
2019-04-30 03:07:08,046 [salt.minion      :1711][INFO    ][8754] Returning information for job: 20190430030707986961
2019-04-30 03:07:30,639 [salt.loaded.ext.module.maas:1023][INFO    ][8673] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.11087298s left)
2019-04-30 03:07:38,116 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command saltutil.find_job with jid 20190430030738100240
2019-04-30 03:07:38,139 [salt.minion      :1432][INFO    ][8908] Starting a new job with PID 8908
2019-04-30 03:07:38,157 [salt.minion      :1711][INFO    ][8908] Returning information for job: 20190430030738100240
2019-04-30 03:08:02,135 [salt.loaded.ext.module.maas:1023][INFO    ][8673] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1405.61500382s left)
2019-04-30 03:08:08,162 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command saltutil.find_job with jid 20190430030808149815
2019-04-30 03:08:08,185 [salt.minion      :1432][INFO    ][9434] Starting a new job with PID 9434
2019-04-30 03:08:08,207 [salt.minion      :1711][INFO    ][9434] Returning information for job: 20190430030808149815
2019-04-30 03:08:33,888 [salt.loaded.ext.module.maas:1023][INFO    ][8673] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1373.86214089s left)
2019-04-30 03:08:38,222 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command saltutil.find_job with jid 20190430030838209212
2019-04-30 03:08:38,245 [salt.minion      :1432][INFO    ][9586] Starting a new job with PID 9586
2019-04-30 03:08:38,268 [salt.minion      :1711][INFO    ][9586] Returning information for job: 20190430030838209212
2019-04-30 03:09:05,761 [salt.loaded.ext.module.maas:1023][INFO    ][8673] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1341.98887181s left)
2019-04-30 03:09:08,280 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command saltutil.find_job with jid 20190430030908267903
2019-04-30 03:09:08,305 [salt.minion      :1432][INFO    ][9855] Starting a new job with PID 9855
2019-04-30 03:09:08,326 [salt.minion      :1711][INFO    ][9855] Returning information for job: 20190430030908267903
2019-04-30 03:09:38,351 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command saltutil.find_job with jid 20190430030938343856
2019-04-30 03:09:38,365 [salt.minion      :1432][INFO    ][9986] Starting a new job with PID 9986
2019-04-30 03:09:38,384 [salt.minion      :1711][INFO    ][9986] Returning information for job: 20190430030938343856
2019-04-30 03:10:08,440 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command saltutil.find_job with jid 20190430031008426901
2019-04-30 03:10:08,465 [salt.minion      :1432][INFO    ][10078] Starting a new job with PID 10078
2019-04-30 03:10:08,485 [salt.minion      :1711][INFO    ][10078] Returning information for job: 20190430031008426901
2019-04-30 03:10:38,536 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command saltutil.find_job with jid 20190430031038522573
2019-04-30 03:10:38,559 [salt.minion      :1432][INFO    ][10130] Starting a new job with PID 10130
2019-04-30 03:10:38,580 [salt.minion      :1711][INFO    ][10130] Returning information for job: 20190430031038522573
2019-04-30 03:11:08,621 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command saltutil.find_job with jid 20190430031108609507
2019-04-30 03:11:08,642 [salt.minion      :1432][INFO    ][10192] Starting a new job with PID 10192
2019-04-30 03:11:08,662 [salt.minion      :1711][INFO    ][10192] Returning information for job: 20190430031108609507
2019-04-30 03:11:38,729 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command saltutil.find_job with jid 20190430031138717502
2019-04-30 03:11:38,752 [salt.minion      :1432][INFO    ][10235] Starting a new job with PID 10235
2019-04-30 03:11:38,770 [salt.minion      :1711][INFO    ][10235] Returning information for job: 20190430031138717502
2019-04-30 03:12:08,822 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command saltutil.find_job with jid 20190430031208809055
2019-04-30 03:12:08,845 [salt.minion      :1432][INFO    ][10307] Starting a new job with PID 10307
2019-04-30 03:12:08,867 [salt.minion      :1711][INFO    ][10307] Returning information for job: 20190430031208809055
2019-04-30 03:12:38,929 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command saltutil.find_job with jid 20190430031238916243
2019-04-30 03:12:38,951 [salt.minion      :1432][INFO    ][10349] Starting a new job with PID 10349
2019-04-30 03:12:38,969 [salt.minion      :1711][INFO    ][10349] Returning information for job: 20190430031238916243
2019-04-30 03:13:09,032 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command saltutil.find_job with jid 20190430031309021058
2019-04-30 03:13:09,054 [salt.minion      :1432][INFO    ][10407] Starting a new job with PID 10407
2019-04-30 03:13:09,075 [salt.minion      :1711][INFO    ][10407] Returning information for job: 20190430031309021058
2019-04-30 03:13:39,144 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command saltutil.find_job with jid 20190430031339133804
2019-04-30 03:13:39,164 [salt.minion      :1432][INFO    ][10446] Starting a new job with PID 10446
2019-04-30 03:13:39,187 [salt.minion      :1711][INFO    ][10446] Returning information for job: 20190430031339133804
2019-04-30 03:14:09,263 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command saltutil.find_job with jid 20190430031409250391
2019-04-30 03:14:09,279 [salt.minion      :1432][INFO    ][10492] Starting a new job with PID 10492
2019-04-30 03:14:09,292 [salt.minion      :1711][INFO    ][10492] Returning information for job: 20190430031409250391
2019-04-30 03:14:39,373 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command saltutil.find_job with jid 20190430031439364128
2019-04-30 03:14:39,393 [salt.minion      :1432][INFO    ][10525] Starting a new job with PID 10525
2019-04-30 03:14:39,417 [salt.minion      :1711][INFO    ][10525] Returning information for job: 20190430031439364128
2019-04-30 03:14:52,522 [salt.state       :302 ][ERROR   ][8673] Module function maas.wait_for_machine_status threw an exception. Exception: HTTP Error 401: OK
2019-04-30 03:14:52,523 [salt.state       :1951][INFO    ][8673] Completed state [maas.wait_for_machine_status] at time 03:14:52.523411 duration_in_ms=504777.501
2019-04-30 03:14:52,527 [salt.minion      :1711][INFO    ][8673] Returning information for job: 20190430030622896649
2019-04-30 03:15:03,113 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command pillar.get with jid 20190430031503101082
2019-04-30 03:15:03,128 [salt.minion      :1432][INFO    ][10915] Starting a new job with PID 10915
2019-04-30 03:15:03,133 [salt.minion      :1711][INFO    ][10915] Returning information for job: 20190430031503101082
2019-04-30 03:15:03,665 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command service.status with jid 20190430031503652919
2019-04-30 03:15:03,686 [salt.minion      :1432][INFO    ][10920] Starting a new job with PID 10920
2019-04-30 03:15:04,087 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][10920] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-04-30 03:15:04,122 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][10920] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-04-30 03:15:04,138 [salt.minion      :1711][INFO    ][10920] Returning information for job: 20190430031503652919
2019-04-30 03:15:04,681 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command state.apply with jid 20190430031504669346
2019-04-30 03:15:04,703 [salt.minion      :1432][INFO    ][10937] Starting a new job with PID 10937
2019-04-30 03:15:08,456 [salt.state       :915 ][INFO    ][10937] Loading fresh modules for state activity
2019-04-30 03:15:08,880 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10937] Executing command 'salt-minion --version' in directory '/root'
2019-04-30 03:15:09,209 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10937] Executing command 'salt-minion --version' in directory '/root'
2019-04-30 03:15:09,984 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10937] Executing command 'salt-minion --version' in directory '/root'
2019-04-30 03:15:10,341 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10937] Executing command 'salt-minion --version' in directory '/root'
2019-04-30 03:15:11,714 [salt.state       :1780][INFO    ][10937] Running state [salt-minion] at time 03:15:11.714598
2019-04-30 03:15:11,714 [salt.state       :1813][INFO    ][10937] Executing state pkg.installed for [salt-minion]
2019-04-30 03:15:11,715 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10937] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-30 03:15:11,791 [salt.state       :300 ][INFO    ][10937] All specified packages are already installed
2019-04-30 03:15:11,791 [salt.state       :1951][INFO    ][10937] Completed state [salt-minion] at time 03:15:11.791394 duration_in_ms=76.797
2019-04-30 03:15:11,791 [salt.state       :1780][INFO    ][10937] Running state [salt_minion_dependency_packages] at time 03:15:11.791670
2019-04-30 03:15:11,791 [salt.state       :1813][INFO    ][10937] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-04-30 03:15:11,796 [salt.state       :300 ][INFO    ][10937] All specified packages are already installed
2019-04-30 03:15:11,796 [salt.state       :1951][INFO    ][10937] Completed state [salt_minion_dependency_packages] at time 03:15:11.796759 duration_in_ms=5.089
2019-04-30 03:15:11,799 [salt.state       :1780][INFO    ][10937] Running state [/etc/salt/minion.d/minion.conf] at time 03:15:11.799105
2019-04-30 03:15:11,799 [salt.state       :1813][INFO    ][10937] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-04-30 03:15:11,974 [salt.state       :300 ][INFO    ][10937] File /etc/salt/minion.d/minion.conf is in the correct state
2019-04-30 03:15:11,974 [salt.state       :1951][INFO    ][10937] Completed state [/etc/salt/minion.d/minion.conf] at time 03:15:11.974211 duration_in_ms=175.105
2019-04-30 03:15:11,974 [salt.state       :1780][INFO    ][10937] Running state [python-netaddr] at time 03:15:11.974388
2019-04-30 03:15:11,974 [salt.state       :1813][INFO    ][10937] Executing state pkg.installed for [python-netaddr]
2019-04-30 03:15:11,978 [salt.state       :300 ][INFO    ][10937] All specified packages are already installed
2019-04-30 03:15:11,979 [salt.state       :1951][INFO    ][10937] Completed state [python-netaddr] at time 03:15:11.979036 duration_in_ms=4.648
2019-04-30 03:15:11,981 [salt.state       :1780][INFO    ][10937] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 03:15:11.981049
2019-04-30 03:15:11,981 [salt.state       :1813][INFO    ][10937] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-04-30 03:15:11,990 [salt.state       :300 ][INFO    ][10937] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-04-30 03:15:11,990 [salt.state       :1951][INFO    ][10937] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 03:15:11.990321 duration_in_ms=9.272
2019-04-30 03:15:11,990 [salt.state       :1780][INFO    ][10937] Running state [salt-minion] at time 03:15:11.990965
2019-04-30 03:15:11,991 [salt.state       :1813][INFO    ][10937] Executing state service.running for [salt-minion]
2019-04-30 03:15:11,991 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10937] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-04-30 03:15:12,030 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10937] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-04-30 03:15:12,048 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10937] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-04-30 03:15:12,065 [salt.state       :300 ][INFO    ][10937] The service salt-minion is already running
2019-04-30 03:15:12,065 [salt.state       :1951][INFO    ][10937] Completed state [salt-minion] at time 03:15:12.065761 duration_in_ms=74.795
2019-04-30 03:15:12,068 [salt.state       :1780][INFO    ][10937] Running state [/etc/salt/grains.d] at time 03:15:12.068017
2019-04-30 03:15:12,068 [salt.state       :1813][INFO    ][10937] Executing state file.directory for [/etc/salt/grains.d]
2019-04-30 03:15:12,069 [salt.state       :300 ][INFO    ][10937] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-04-30 03:15:12,070 [salt.state       :1951][INFO    ][10937] Completed state [/etc/salt/grains.d] at time 03:15:12.070175 duration_in_ms=2.157
2019-04-30 03:15:12,071 [salt.state       :1780][INFO    ][10937] Running state [/etc/salt/grains] at time 03:15:12.071125
2019-04-30 03:15:12,071 [salt.state       :1813][INFO    ][10937] Executing state file.managed for [/etc/salt/grains]
2019-04-30 03:15:12,072 [salt.state       :300 ][INFO    ][10937] File /etc/salt/grains exists with proper permissions. No changes made.
2019-04-30 03:15:12,072 [salt.state       :1951][INFO    ][10937] Completed state [/etc/salt/grains] at time 03:15:12.072621 duration_in_ms=1.496
2019-04-30 03:15:12,073 [salt.state       :1780][INFO    ][10937] Running state [/etc/salt/grains.d/placeholder] at time 03:15:12.073227
2019-04-30 03:15:12,073 [salt.state       :1813][INFO    ][10937] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-04-30 03:15:12,074 [salt.state       :300 ][INFO    ][10937] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-04-30 03:15:12,074 [salt.state       :1951][INFO    ][10937] Completed state [/etc/salt/grains.d/placeholder] at time 03:15:12.074584 duration_in_ms=1.358
2019-04-30 03:15:12,075 [salt.state       :1780][INFO    ][10937] Running state [/etc/salt/grains.d/sphinx] at time 03:15:12.075199
2019-04-30 03:15:12,075 [salt.state       :1813][INFO    ][10937] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-04-30 03:15:12,093 [salt.state       :300 ][INFO    ][10937] File /etc/salt/grains.d/sphinx is in the correct state
2019-04-30 03:15:12,094 [salt.state       :1951][INFO    ][10937] Completed state [/etc/salt/grains.d/sphinx] at time 03:15:12.094111 duration_in_ms=18.911
2019-04-30 03:15:12,097 [salt.state       :1780][INFO    ][10937] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 03:15:12.097236
2019-04-30 03:15:12,097 [salt.state       :1813][INFO    ][10937] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-04-30 03:15:12,098 [salt.state       :300 ][INFO    ][10937] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-04-30 03:15:12,098 [salt.state       :1951][INFO    ][10937] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 03:15:12.098371 duration_in_ms=1.136
2019-04-30 03:15:12,099 [salt.state       :1780][INFO    ][10937] Running state [/etc/salt/grains.d/dns_records] at time 03:15:12.099025
2019-04-30 03:15:12,099 [salt.state       :1813][INFO    ][10937] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-04-30 03:15:12,111 [salt.state       :300 ][INFO    ][10937] File /etc/salt/grains.d/dns_records is in the correct state
2019-04-30 03:15:12,112 [salt.state       :1951][INFO    ][10937] Completed state [/etc/salt/grains.d/dns_records] at time 03:15:12.111994 duration_in_ms=12.968
2019-04-30 03:15:12,113 [salt.state       :1780][INFO    ][10937] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 03:15:12.113317
2019-04-30 03:15:12,113 [salt.state       :1813][INFO    ][10937] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-04-30 03:15:12,114 [salt.state       :300 ][INFO    ][10937] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-04-30 03:15:12,114 [salt.state       :1951][INFO    ][10937] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 03:15:12.114513 duration_in_ms=1.196
2019-04-30 03:15:12,115 [salt.state       :1780][INFO    ][10937] Running state [/etc/salt/grains.d/salt] at time 03:15:12.115190
2019-04-30 03:15:12,115 [salt.state       :1813][INFO    ][10937] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-04-30 03:15:12,129 [salt.state       :300 ][INFO    ][10937] File /etc/salt/grains.d/salt is in the correct state
2019-04-30 03:15:12,130 [salt.state       :1951][INFO    ][10937] Completed state [/etc/salt/grains.d/salt] at time 03:15:12.130246 duration_in_ms=15.055
2019-04-30 03:15:12,131 [salt.state       :1780][INFO    ][10937] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 03:15:12.131876
2019-04-30 03:15:12,132 [salt.state       :1813][INFO    ][10937] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-04-30 03:15:12,133 [salt.state       :300 ][INFO    ][10937] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-04-30 03:15:12,133 [salt.state       :1951][INFO    ][10937] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 03:15:12.133294 duration_in_ms=1.418
2019-04-30 03:15:12,136 [salt.state       :1780][INFO    ][10937] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 03:15:12.136640
2019-04-30 03:15:12,137 [salt.state       :1813][INFO    ][10937] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-04-30 03:15:12,137 [salt.state       :300 ][INFO    ][10937] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-04-30 03:15:12,138 [salt.state       :1951][INFO    ][10937] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 03:15:12.138067 duration_in_ms=1.427
2019-04-30 03:15:12,139 [salt.state       :1780][INFO    ][10937] Running state [mine.update] at time 03:15:12.139220
2019-04-30 03:15:12,139 [salt.state       :1813][INFO    ][10937] Executing state module.wait for [mine.update]
2019-04-30 03:15:12,140 [salt.state       :300 ][INFO    ][10937] No changes made for mine.update
2019-04-30 03:15:12,140 [salt.state       :1951][INFO    ][10937] Completed state [mine.update] at time 03:15:12.140496 duration_in_ms=1.277
2019-04-30 03:15:12,140 [salt.state       :1780][INFO    ][10937] Running state [ca-certificates] at time 03:15:12.140886
2019-04-30 03:15:12,141 [salt.state       :1813][INFO    ][10937] Executing state pkg.installed for [ca-certificates]
2019-04-30 03:15:12,153 [salt.state       :300 ][INFO    ][10937] All specified packages are already installed
2019-04-30 03:15:12,153 [salt.state       :1951][INFO    ][10937] Completed state [ca-certificates] at time 03:15:12.153582 duration_in_ms=12.696
2019-04-30 03:15:12,154 [salt.state       :1780][INFO    ][10937] Running state [update-ca-certificates] at time 03:15:12.154631
2019-04-30 03:15:12,155 [salt.state       :1813][INFO    ][10937] Executing state cmd.wait for [update-ca-certificates]
2019-04-30 03:15:12,155 [salt.state       :300 ][INFO    ][10937] No changes made for update-ca-certificates
2019-04-30 03:15:12,155 [salt.state       :1951][INFO    ][10937] Completed state [update-ca-certificates] at time 03:15:12.155818 duration_in_ms=1.187
2019-04-30 03:15:12,156 [salt.state       :1780][INFO    ][10937] Running state [iptables] at time 03:15:12.156193
2019-04-30 03:15:12,156 [salt.state       :1813][INFO    ][10937] Executing state pkg.installed for [iptables]
2019-04-30 03:15:12,166 [salt.state       :300 ][INFO    ][10937] All specified packages are already installed
2019-04-30 03:15:12,167 [salt.state       :1951][INFO    ][10937] Completed state [iptables] at time 03:15:12.167260 duration_in_ms=11.067
2019-04-30 03:15:12,167 [salt.state       :1780][INFO    ][10937] Running state [iptables-persistent] at time 03:15:12.167626
2019-04-30 03:15:12,167 [salt.state       :1813][INFO    ][10937] Executing state pkg.installed for [iptables-persistent]
2019-04-30 03:15:12,177 [salt.state       :300 ][INFO    ][10937] All specified packages are already installed
2019-04-30 03:15:12,178 [salt.state       :1951][INFO    ][10937] Completed state [iptables-persistent] at time 03:15:12.178102 duration_in_ms=10.476
2019-04-30 03:15:12,179 [salt.state       :1780][INFO    ][10937] Running state [iptables_modules_v4_load] at time 03:15:12.179544
2019-04-30 03:15:12,179 [salt.state       :1813][INFO    ][10937] Executing state kmod.present for [iptables_modules_v4_load]
2019-04-30 03:15:12,180 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10937] Executing command 'lsmod' in directory '/root'
2019-04-30 03:15:12,205 [salt.state       :300 ][INFO    ][10937] Kernel modules iptable_filter, ip_tables are already present
2019-04-30 03:15:12,205 [salt.state       :1951][INFO    ][10937] Completed state [iptables_modules_v4_load] at time 03:15:12.205538 duration_in_ms=25.993
2019-04-30 03:15:12,206 [salt.state       :1780][INFO    ][10937] Running state [/etc/iptables/rules.v4] at time 03:15:12.206391
2019-04-30 03:15:12,206 [salt.state       :1813][INFO    ][10937] Executing state file.managed for [/etc/iptables/rules.v4]
2019-04-30 03:15:12,327 [salt.state       :300 ][INFO    ][10937] File /etc/iptables/rules.v4 is in the correct state
2019-04-30 03:15:12,328 [salt.state       :1951][INFO    ][10937] Completed state [/etc/iptables/rules.v4] at time 03:15:12.327934 duration_in_ms=121.543
2019-04-30 03:15:12,329 [salt.state       :1780][INFO    ][10937] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 03:15:12.329285
2019-04-30 03:15:12,329 [salt.state       :1813][INFO    ][10937] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-04-30 03:15:12,330 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10937] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-04-30 03:15:12,350 [salt.state       :300 ][INFO    ][10937] onlyif execution failed
2019-04-30 03:15:12,351 [salt.state       :1951][INFO    ][10937] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 03:15:12.351293 duration_in_ms=22.008
2019-04-30 03:15:12,352 [salt.state       :1780][INFO    ][10937] Running state [netfilter-persistent] at time 03:15:12.352451
2019-04-30 03:15:12,352 [salt.state       :1813][INFO    ][10937] Executing state service.running for [netfilter-persistent]
2019-04-30 03:15:12,353 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10937] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-04-30 03:15:12,372 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10937] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-04-30 03:15:12,388 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10937] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-04-30 03:15:12,404 [salt.state       :300 ][INFO    ][10937] The service netfilter-persistent is already running
2019-04-30 03:15:12,405 [salt.state       :1951][INFO    ][10937] Completed state [netfilter-persistent] at time 03:15:12.405254 duration_in_ms=52.804
2019-04-30 03:15:12,406 [salt.state       :1780][INFO    ][10937] Running state [iptables_extra.remove_stale_tables] at time 03:15:12.406275
2019-04-30 03:15:12,406 [salt.state       :1813][INFO    ][10937] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-04-30 03:15:12,407 [salt.state       :300 ][INFO    ][10937] No changes made for iptables_extra.remove_stale_tables
2019-04-30 03:15:12,407 [salt.state       :1951][INFO    ][10937] Completed state [iptables_extra.remove_stale_tables] at time 03:15:12.407353 duration_in_ms=1.077
2019-04-30 03:15:12,407 [salt.state       :1780][INFO    ][10937] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 03:15:12.407666
2019-04-30 03:15:12,408 [salt.state       :1813][INFO    ][10937] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-04-30 03:15:12,408 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10937] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-04-30 03:15:12,422 [salt.state       :300 ][INFO    ][10937] onlyif execution failed
2019-04-30 03:15:12,422 [salt.state       :1951][INFO    ][10937] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 03:15:12.422910 duration_in_ms=15.244
2019-04-30 03:15:12,424 [salt.state       :1780][INFO    ][10937] Running state [/etc/iptables/rules.v6] at time 03:15:12.424065
2019-04-30 03:15:12,424 [salt.state       :1813][INFO    ][10937] Executing state file.absent for [/etc/iptables/rules.v6]
2019-04-30 03:15:12,425 [salt.state       :300 ][INFO    ][10937] File /etc/iptables/rules.v6 is not present
2019-04-30 03:15:12,425 [salt.state       :1951][INFO    ][10937] Completed state [/etc/iptables/rules.v6] at time 03:15:12.425285 duration_in_ms=1.219
2019-04-30 03:15:12,426 [salt.state       :1780][INFO    ][10937] Running state [iptables_extra.flush_all] at time 03:15:12.426112
2019-04-30 03:15:12,426 [salt.state       :1813][INFO    ][10937] Executing state module.wait for [iptables_extra.flush_all]
2019-04-30 03:15:12,426 [salt.state       :300 ][INFO    ][10937] No changes made for iptables_extra.flush_all
2019-04-30 03:15:12,427 [salt.state       :1951][INFO    ][10937] Completed state [iptables_extra.flush_all] at time 03:15:12.427057 duration_in_ms=0.946
2019-04-30 03:15:12,430 [salt.minion      :1711][INFO    ][10937] Returning information for job: 20190430031504669346
2019-04-30 03:15:13,061 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command state.apply with jid 20190430031513048901
2019-04-30 03:15:13,084 [salt.minion      :1432][INFO    ][11023] Starting a new job with PID 11023
2019-04-30 03:15:13,890 [salt.state       :915 ][INFO    ][11023] Loading fresh modules for state activity
2019-04-30 03:15:14,456 [salt.state       :1780][INFO    ][11023] Running state [maas-rack-controller] at time 03:15:14.456732
2019-04-30 03:15:14,457 [salt.state       :1813][INFO    ][11023] Executing state pkg.installed for [maas-rack-controller]
2019-04-30 03:15:14,457 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11023] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-30 03:15:14,579 [salt.state       :300 ][INFO    ][11023] All specified packages are already installed
2019-04-30 03:15:14,579 [salt.state       :1951][INFO    ][11023] Completed state [maas-rack-controller] at time 03:15:14.579328 duration_in_ms=122.596
2019-04-30 03:15:14,579 [salt.state       :1780][INFO    ][11023] Running state [ipmitool] at time 03:15:14.579572
2019-04-30 03:15:14,579 [salt.state       :1813][INFO    ][11023] Executing state pkg.installed for [ipmitool]
2019-04-30 03:15:14,584 [salt.state       :300 ][INFO    ][11023] All specified packages are already installed
2019-04-30 03:15:14,584 [salt.state       :1951][INFO    ][11023] Completed state [ipmitool] at time 03:15:14.584323 duration_in_ms=4.752
2019-04-30 03:15:14,586 [salt.state       :1780][INFO    ][11023] Running state [/etc/maas/rackd.conf] at time 03:15:14.586523
2019-04-30 03:15:14,586 [salt.state       :1813][INFO    ][11023] Executing state file.line for [/etc/maas/rackd.conf]
2019-04-30 03:15:14,587 [salt.state       :300 ][INFO    ][11023] No changes needed to be made
2019-04-30 03:15:14,587 [salt.state       :1951][INFO    ][11023] Completed state [/etc/maas/rackd.conf] at time 03:15:14.587618 duration_in_ms=1.095
2019-04-30 03:15:14,587 [salt.state       :1780][INFO    ][11023] Running state [/etc/maas/rackd.conf] at time 03:15:14.587779
2019-04-30 03:15:14,587 [salt.state       :1813][INFO    ][11023] Executing state file.managed for [/etc/maas/rackd.conf]
2019-04-30 03:15:14,588 [salt.loaded.int.states.file:2298][WARNING ][11023] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-04-30 03:15:14,588 [salt.state       :300 ][INFO    ][11023] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-04-30 03:15:14,588 [salt.state       :1951][INFO    ][11023] Completed state [/etc/maas/rackd.conf] at time 03:15:14.588636 duration_in_ms=0.857
2019-04-30 03:15:14,589 [salt.state       :1780][INFO    ][11023] Running state [maas-rackd] at time 03:15:14.589290
2019-04-30 03:15:14,589 [salt.state       :1813][INFO    ][11023] Executing state service.running for [maas-rackd]
2019-04-30 03:15:14,589 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11023] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-04-30 03:15:14,622 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11023] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-04-30 03:15:14,638 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11023] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-04-30 03:15:14,654 [salt.state       :300 ][INFO    ][11023] The service maas-rackd is already running
2019-04-30 03:15:14,654 [salt.state       :1951][INFO    ][11023] Completed state [maas-rackd] at time 03:15:14.654354 duration_in_ms=65.064
2019-04-30 03:15:14,655 [salt.minion      :1711][INFO    ][11023] Returning information for job: 20190430031513048901
2019-04-30 03:15:15,252 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command state.apply with jid 20190430031515240164
2019-04-30 03:15:15,274 [salt.minion      :1432][INFO    ][11046] Starting a new job with PID 11046
2019-04-30 03:15:16,059 [salt.state       :915 ][INFO    ][11046] Loading fresh modules for state activity
2019-04-30 03:15:16,747 [salt.state       :1780][INFO    ][11046] Running state [maas-region-controller] at time 03:15:16.747746
2019-04-30 03:15:16,748 [salt.state       :1813][INFO    ][11046] Executing state pkg.installed for [maas-region-controller]
2019-04-30 03:15:16,748 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11046] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-30 03:15:16,824 [salt.state       :300 ][INFO    ][11046] All specified packages are already installed
2019-04-30 03:15:16,824 [salt.state       :1951][INFO    ][11046] Completed state [maas-region-controller] at time 03:15:16.824926 duration_in_ms=77.181
2019-04-30 03:15:16,825 [salt.state       :1780][INFO    ][11046] Running state [python-oauth] at time 03:15:16.825171
2019-04-30 03:15:16,825 [salt.state       :1813][INFO    ][11046] Executing state pkg.installed for [python-oauth]
2019-04-30 03:15:16,830 [salt.state       :300 ][INFO    ][11046] All specified packages are already installed
2019-04-30 03:15:16,830 [salt.state       :1951][INFO    ][11046] Completed state [python-oauth] at time 03:15:16.830203 duration_in_ms=5.032
2019-04-30 03:15:16,832 [salt.state       :1780][INFO    ][11046] Running state [/etc/maas/regiond.conf] at time 03:15:16.832563
2019-04-30 03:15:16,832 [salt.state       :1813][INFO    ][11046] Executing state file.replace for [/etc/maas/regiond.conf]
2019-04-30 03:15:16,866 [salt.state       :300 ][INFO    ][11046] No changes needed to be made
2019-04-30 03:15:16,867 [salt.state       :1951][INFO    ][11046] Completed state [/etc/maas/regiond.conf] at time 03:15:16.866991 duration_in_ms=34.428
2019-04-30 03:15:16,867 [salt.state       :1780][INFO    ][11046] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 03:15:16.867354
2019-04-30 03:15:16,867 [salt.state       :1813][INFO    ][11046] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-04-30 03:15:16,926 [salt.state       :300 ][INFO    ][11046] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-04-30 03:15:16,926 [salt.state       :1951][INFO    ][11046] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 03:15:16.926341 duration_in_ms=58.986
2019-04-30 03:15:16,926 [salt.state       :1780][INFO    ][11046] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 03:15:16.926715
2019-04-30 03:15:16,926 [salt.state       :1813][INFO    ][11046] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-04-30 03:15:16,939 [salt.state       :300 ][INFO    ][11046] No changes needed to be made
2019-04-30 03:15:16,939 [salt.state       :1951][INFO    ][11046] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 03:15:16.939421 duration_in_ms=12.705
2019-04-30 03:15:16,939 [salt.state       :1780][INFO    ][11046] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 03:15:16.939794
2019-04-30 03:15:16,940 [salt.state       :1813][INFO    ][11046] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-04-30 03:15:16,969 [salt.state       :300 ][INFO    ][11046] No changes needed to be made
2019-04-30 03:15:16,969 [salt.state       :1951][INFO    ][11046] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 03:15:16.969402 duration_in_ms=29.608
2019-04-30 03:15:16,969 [salt.state       :1780][INFO    ][11046] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 03:15:16.969802
2019-04-30 03:15:16,970 [salt.state       :1813][INFO    ][11046] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-04-30 03:15:16,988 [salt.state       :300 ][INFO    ][11046] No changes needed to be made
2019-04-30 03:15:16,988 [salt.state       :1951][INFO    ][11046] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 03:15:16.988619 duration_in_ms=18.817
2019-04-30 03:15:16,989 [salt.state       :1780][INFO    ][11046] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 03:15:16.988978
2019-04-30 03:15:16,989 [salt.state       :1813][INFO    ][11046] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-04-30 03:15:16,999 [salt.state       :300 ][INFO    ][11046] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-04-30 03:15:17,000 [salt.state       :1951][INFO    ][11046] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 03:15:17.000037 duration_in_ms=11.059
2019-04-30 03:15:17,001 [salt.state       :1780][INFO    ][11046] Running state [a2enmod headers] at time 03:15:17.001015
2019-04-30 03:15:17,001 [salt.state       :1813][INFO    ][11046] Executing state cmd.run for [a2enmod headers]
2019-04-30 03:15:17,001 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11046] Executing command 'a2enmod headers' in directory '/root'
2019-04-30 03:15:17,071 [salt.state       :300 ][INFO    ][11046] {'pid': 11065, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-04-30 03:15:17,072 [salt.state       :1951][INFO    ][11046] Completed state [a2enmod headers] at time 03:15:17.072550 duration_in_ms=71.533
2019-04-30 03:15:17,073 [salt.state       :1780][INFO    ][11046] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 03:15:17.073126
2019-04-30 03:15:17,073 [salt.state       :1813][INFO    ][11046] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-04-30 03:15:17,093 [salt.state       :300 ][INFO    ][11046] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-04-30 03:15:17,093 [salt.state       :1951][INFO    ][11046] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 03:15:17.093383 duration_in_ms=20.258
2019-04-30 03:15:17,094 [salt.state       :1780][INFO    ][11046] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 03:15:17.094296
2019-04-30 03:15:17,094 [salt.state       :1813][INFO    ][11046] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-04-30 03:15:17,208 [salt.state       :300 ][INFO    ][11046] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-04-30 03:15:17,208 [salt.state       :1951][INFO    ][11046] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 03:15:17.208704 duration_in_ms=114.408
2019-04-30 03:15:17,209 [salt.state       :1780][INFO    ][11046] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 03:15:17.209540
2019-04-30 03:15:17,210 [salt.state       :1813][INFO    ][11046] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-04-30 03:15:17,292 [salt.state       :300 ][INFO    ][11046] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-04-30 03:15:17,293 [salt.state       :1951][INFO    ][11046] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 03:15:17.292949 duration_in_ms=83.409
2019-04-30 03:15:17,293 [salt.state       :1780][INFO    ][11046] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 03:15:17.293810
2019-04-30 03:15:17,294 [salt.state       :1813][INFO    ][11046] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-04-30 03:15:17,363 [salt.state       :300 ][INFO    ][11046] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-04-30 03:15:17,364 [salt.state       :1951][INFO    ][11046] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 03:15:17.364114 duration_in_ms=70.303
2019-04-30 03:15:17,364 [salt.state       :1780][INFO    ][11046] Running state [/root/.pgpass] at time 03:15:17.364428
2019-04-30 03:15:17,364 [salt.state       :1813][INFO    ][11046] Executing state file.managed for [/root/.pgpass]
2019-04-30 03:15:17,417 [salt.state       :300 ][INFO    ][11046] File /root/.pgpass is in the correct state
2019-04-30 03:15:17,417 [salt.state       :1951][INFO    ][11046] Completed state [/root/.pgpass] at time 03:15:17.417923 duration_in_ms=53.495
2019-04-30 03:15:17,423 [salt.state       :1780][INFO    ][11046] Running state [maas-region syncdb --noinput] at time 03:15:17.423719
2019-04-30 03:15:17,424 [salt.state       :1813][INFO    ][11046] Executing state cmd.run for [maas-region syncdb --noinput]
2019-04-30 03:15:17,424 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11046] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-04-30 03:15:19,327 [salt.state       :300 ][INFO    ][11046] {'pid': 11078, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: contenttypes, maasserver, auth, metadataserver, piston3, sites, sessions\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-04-30 03:15:19,327 [salt.state       :1951][INFO    ][11046] Completed state [maas-region syncdb --noinput] at time 03:15:19.327363 duration_in_ms=1903.644
2019-04-30 03:15:19,327 [salt.state       :2022][WARNING ][11046] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-04-30 03:15:19,328 [salt.state       :1780][INFO    ][11046] Running state [maas-regiond] at time 03:15:19.328589
2019-04-30 03:15:19,328 [salt.state       :1813][INFO    ][11046] Executing state service.running for [maas-regiond]
2019-04-30 03:15:19,329 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11046] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-04-30 03:15:19,362 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11046] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-04-30 03:15:19,377 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11046] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-04-30 03:15:19,391 [salt.state       :300 ][INFO    ][11046] The service maas-regiond is already running
2019-04-30 03:15:19,391 [salt.state       :1951][INFO    ][11046] Completed state [maas-regiond] at time 03:15:19.391693 duration_in_ms=63.104
2019-04-30 03:15:19,392 [salt.state       :1780][INFO    ][11046] Running state [bind9] at time 03:15:19.392706
2019-04-30 03:15:19,392 [salt.state       :1813][INFO    ][11046] Executing state service.running for [bind9]
2019-04-30 03:15:19,393 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11046] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-04-30 03:15:19,408 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11046] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-04-30 03:15:19,421 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11046] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-04-30 03:15:19,435 [salt.state       :300 ][INFO    ][11046] The service bind9 is already running
2019-04-30 03:15:19,435 [salt.state       :1951][INFO    ][11046] Completed state [bind9] at time 03:15:19.435305 duration_in_ms=42.6
2019-04-30 03:15:19,436 [salt.state       :1780][INFO    ][11046] Running state [apache2] at time 03:15:19.436280
2019-04-30 03:15:19,436 [salt.state       :1813][INFO    ][11046] Executing state service.running for [apache2]
2019-04-30 03:15:19,436 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11046] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-04-30 03:15:19,451 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11046] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-04-30 03:15:19,463 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11046] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-04-30 03:15:19,478 [salt.state       :300 ][INFO    ][11046] The service apache2 is already running
2019-04-30 03:15:19,478 [salt.state       :1951][INFO    ][11046] Completed state [apache2] at time 03:15:19.478552 duration_in_ms=42.273
2019-04-30 03:15:19,479 [salt.state       :1780][INFO    ][11046] Running state [maasng.wait_for_http_code] at time 03:15:19.479551
2019-04-30 03:15:19,479 [salt.state       :1813][INFO    ][11046] Executing state module.run for [maasng.wait_for_http_code]
2019-04-30 03:15:19,480 [salt.utils.decorators:613 ][WARNING ][11046] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-30 03:15:19,486 [salt.state       :300 ][INFO    ][11046] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-04-30 03:15:19,486 [salt.state       :1951][INFO    ][11046] Completed state [maasng.wait_for_http_code] at time 03:15:19.486441 duration_in_ms=6.888
2019-04-30 03:15:19,487 [salt.state       :1780][INFO    ][11046] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 03:15:19.487726
2019-04-30 03:15:19,488 [salt.state       :1813][INFO    ][11046] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-04-30 03:15:19,488 [salt.state       :300 ][INFO    ][11046] /var/lib/maas/.setup_admin exists
2019-04-30 03:15:19,489 [salt.state       :1951][INFO    ][11046] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 03:15:19.489217 duration_in_ms=1.491
2019-04-30 03:15:19,490 [salt.state       :1780][INFO    ][11046] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:15:19.490222
2019-04-30 03:15:19,490 [salt.state       :1813][INFO    ][11046] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-30 03:15:19,491 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11046] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-30 03:15:20,914 [salt.state       :300 ][INFO    ][11046] {'pid': 11097, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-30 03:15:20,914 [salt.state       :1951][INFO    ][11046] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:15:20.914497 duration_in_ms=1424.275
2019-04-30 03:15:20,922 [salt.state       :1780][INFO    ][11046] Running state [maas_region_boot_source_resources_mirror] at time 03:15:20.922880
2019-04-30 03:15:20,923 [salt.state       :1813][INFO    ][11046] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-04-30 03:15:21,044 [salt.state       :300 ][INFO    ][11046] {'changes': {}}
2019-04-30 03:15:21,044 [salt.state       :1951][INFO    ][11046] Completed state [maas_region_boot_source_resources_mirror] at time 03:15:21.044839 duration_in_ms=121.959
2019-04-30 03:15:21,046 [salt.state       :1780][INFO    ][11046] Running state [maasng.boot_resources_import] at time 03:15:21.045984
2019-04-30 03:15:21,046 [salt.state       :1813][INFO    ][11046] Executing state module.run for [maasng.boot_resources_import]
2019-04-30 03:15:21,047 [salt.utils.decorators:613 ][WARNING ][11046] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-30 03:15:21,182 [salt.loaded.ext.module.maasng:1600][INFO    ][11046] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-04-30 03:15:26,238 [salt.loaded.ext.module.maasng:1600][INFO    ][11046] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-04-30 03:15:30,338 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command saltutil.find_job with jid 20190430031530325173
2019-04-30 03:15:30,362 [salt.minion      :1432][INFO    ][11166] Starting a new job with PID 11166
2019-04-30 03:15:30,384 [salt.minion      :1711][INFO    ][11166] Returning information for job: 20190430031530325173
2019-04-30 03:15:31,298 [salt.loaded.ext.module.maasng:1600][INFO    ][11046] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-04-30 03:15:36,418 [salt.state       :300 ][INFO    ][11046] {'ret': True}
2019-04-30 03:15:36,419 [salt.state       :1951][INFO    ][11046] Completed state [maasng.boot_resources_import] at time 03:15:36.419330 duration_in_ms=15373.345
2019-04-30 03:15:36,420 [salt.state       :1780][INFO    ][11046] Running state [maas_region_boot_sources_selection_xenial] at time 03:15:36.420733
2019-04-30 03:15:36,421 [salt.state       :1813][INFO    ][11046] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-04-30 03:15:36,628 [salt.state       :300 ][INFO    ][11046] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-04-30 03:15:36,628 [salt.state       :1951][INFO    ][11046] Completed state [maas_region_boot_sources_selection_xenial] at time 03:15:36.628439 duration_in_ms=207.7
2019-04-30 03:15:36,629 [salt.state       :1780][INFO    ][11046] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 03:15:36.629777
2019-04-30 03:15:36,630 [salt.state       :1813][INFO    ][11046] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-04-30 03:15:36,630 [salt.utils.decorators:613 ][WARNING ][11046] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-30 03:15:36,631 [salt.loaded.ext.module.maasng:1771][INFO    ][11046] boot-sources sync initiated for ALL Rack's
2019-04-30 03:15:37,777 [salt.state       :300 ][INFO    ][11046] {'ret': True}
2019-04-30 03:15:37,778 [salt.state       :1951][INFO    ][11046] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 03:15:37.778305 duration_in_ms=1148.527
2019-04-30 03:15:37,780 [salt.state       :1780][INFO    ][11046] Running state [maas.process_maas_config] at time 03:15:37.780137
2019-04-30 03:15:37,780 [salt.state       :1813][INFO    ][11046] Executing state module.run for [maas.process_maas_config]
2019-04-30 03:15:37,781 [salt.utils.decorators:613 ][WARNING ][11046] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-30 03:15:37,781 [salt.loaded.ext.module.maas:92  ][INFO    ][11046] maasconfig name=enable_http_proxy value=True
2019-04-30 03:15:37,845 [salt.loaded.ext.module.maas:92  ][INFO    ][11046] maasconfig name=upstream_dns value=8.8.8.8
2019-04-30 03:15:37,916 [salt.loaded.ext.module.maas:92  ][INFO    ][11046] maasconfig name=commissioning_distro_series value=xenial
2019-04-30 03:15:40,911 [salt.loaded.ext.module.maas:92  ][INFO    ][11046] maasconfig name=default_osystem value=ubuntu
2019-04-30 03:15:40,990 [salt.loaded.ext.module.maas:92  ][INFO    ][11046] maasconfig name=active_discovery_interval value=600
2019-04-30 03:15:41,048 [salt.loaded.ext.module.maas:92  ][INFO    ][11046] maasconfig name=dnssec_validation value=no
2019-04-30 03:15:41,107 [salt.loaded.ext.module.maas:92  ][INFO    ][11046] maasconfig name=maas_name value=mas01
2019-04-30 03:15:41,164 [salt.loaded.ext.module.maas:92  ][INFO    ][11046] maasconfig name=network_discovery value=enabled
2019-04-30 03:15:41,273 [salt.loaded.ext.module.maas:92  ][INFO    ][11046] maasconfig name=enable_third_party_drivers value=True
2019-04-30 03:15:41,329 [salt.loaded.ext.module.maas:92  ][INFO    ][11046] maasconfig name=default_storage_layout value=lvm
2019-04-30 03:15:41,382 [salt.loaded.ext.module.maas:92  ][INFO    ][11046] maasconfig name=ntp_external_only value=True
2019-04-30 03:15:41,437 [salt.loaded.ext.module.maas:92  ][INFO    ][11046] maasconfig name=disk_erase_with_secure_erase value=False
2019-04-30 03:15:41,489 [salt.loaded.ext.module.maas:92  ][INFO    ][11046] maasconfig name=default_distro_series value=xenial
2019-04-30 03:15:41,551 [salt.loaded.ext.module.maas:92  ][INFO    ][11046] maasconfig name=default_min_hwe_kernel value=ga-16.04
2019-04-30 03:15:41,689 [salt.state       :300 ][INFO    ][11046] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-04-30 03:15:41,689 [salt.state       :1951][INFO    ][11046] Completed state [maas.process_maas_config] at time 03:15:41.689685 duration_in_ms=3909.549
2019-04-30 03:15:41,690 [salt.state       :1780][INFO    ][11046] Running state [pxe_admin] at time 03:15:41.690312
2019-04-30 03:15:41,690 [salt.state       :1813][INFO    ][11046] Executing state maasng.fabric_present for [pxe_admin]
2019-04-30 03:15:41,754 [salt.loaded.ext.module.maasng:945 ][INFO    ][11046] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None, u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'cbdwqr', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u'', u'name': u'pxe_admin'}]
2019-04-30 03:15:41,846 [salt.loaded.ext.module.maasng:1008][WARNING ][11046] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-04-30 03:15:41,846 [salt.loaded.ext.module.maasng:1011][WARNING ][11046] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-04-30 03:15:41,916 [salt.state       :300 ][INFO    ][11046] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-04-30 03:15:41,917 [salt.state       :1951][INFO    ][11046] Completed state [pxe_admin] at time 03:15:41.917061 duration_in_ms=226.748
2019-04-30 03:15:41,917 [salt.state       :1780][INFO    ][11046] Running state [vlan 0] at time 03:15:41.917433
2019-04-30 03:15:41,917 [salt.state       :1813][INFO    ][11046] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-04-30 03:15:41,987 [salt.loaded.ext.module.maasng:945 ][INFO    ][11046] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'cbdwqr', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-04-30 03:15:42,119 [salt.loaded.ext.module.maasng:945 ][INFO    ][11046] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0', u'class_type': None}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1', u'class_type': None}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'cbdwqr', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin', u'class_type': u''}]
2019-04-30 03:15:42,449 [salt.loaded.ext.module.maasng:945 ][INFO    ][11046] [{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'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-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'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {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'cbdwqr', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-04-30 03:15:42,550 [salt.state       :300 ][INFO    ][11046] {'new': 'Vlan untagged was updated'}
2019-04-30 03:15:42,551 [salt.state       :1951][INFO    ][11046] Completed state [vlan 0] at time 03:15:42.551216 duration_in_ms=633.783
2019-04-30 03:15:42,552 [salt.state       :1780][INFO    ][11046] Running state [192.168.11.0/24] at time 03:15:42.552520
2019-04-30 03:15:42,552 [salt.state       :1813][INFO    ][11046] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-04-30 03:15:42,779 [salt.loaded.ext.module.maasng:945 ][INFO    ][11046] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0', u'class_type': None}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1', u'class_type': None}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'cbdwqr', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin', u'class_type': u''}]
2019-04-30 03:15:42,780 [salt.loaded.ext.module.maasng:1235][WARNING ][11046] Ignoring parameter vlan:0
2019-04-30 03:15:42,862 [salt.state       :300 ][INFO    ][11046] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-04-30 03:15:42,863 [salt.state       :1951][INFO    ][11046] Completed state [192.168.11.0/24] at time 03:15:42.863282 duration_in_ms=310.762
2019-04-30 03:15:42,864 [salt.state       :1780][INFO    ][11046] Running state [maas_create_iprange_1] at time 03:15:42.864652
2019-04-30 03:15:42,865 [salt.state       :1813][INFO    ][11046] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-04-30 03:15:42,928 [salt.state       :300 ][INFO    ][11046] Iprange maas_create_iprange_1 already exist.
2019-04-30 03:15:42,928 [salt.state       :1951][INFO    ][11046] Completed state [maas_create_iprange_1] at time 03:15:42.928585 duration_in_ms=63.933
2019-04-30 03:15:42,929 [salt.state       :1780][INFO    ][11046] Running state [vlan 0] at time 03:15:42.929013
2019-04-30 03:15:42,929 [salt.state       :1813][INFO    ][11046] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-04-30 03:15:42,999 [salt.loaded.ext.module.maasng:945 ][INFO    ][11046] [{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'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-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'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {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'cbdwqr', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-04-30 03:15:43,113 [salt.loaded.ext.module.maasng:945 ][INFO    ][11046] [{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'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-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'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {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'cbdwqr', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-04-30 03:15:43,359 [salt.loaded.ext.module.maasng:945 ][INFO    ][11046] [{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'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-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'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {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'cbdwqr', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-04-30 03:15:43,444 [salt.state       :300 ][INFO    ][11046] {'new': 'Vlan untagged was updated'}
2019-04-30 03:15:43,444 [salt.state       :1951][INFO    ][11046] Completed state [vlan 0] at time 03:15:43.444361 duration_in_ms=515.348
2019-04-30 03:15:43,445 [salt.state       :1780][INFO    ][11046] Running state [opnfv] at time 03:15:43.444999
2019-04-30 03:15:43,445 [salt.state       :1813][INFO    ][11046] Executing state maasng.sshkey_present for [opnfv]
2019-04-30 03:15:43,491 [salt.loaded.ext.module.maasng:1903][INFO    ][11046] [{u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP', u'keysource': u''}]
2019-04-30 03:15:43,492 [salt.state       :300 ][INFO    ][11046] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-04-30 03:15:43,492 [salt.state       :1951][INFO    ][11046] Completed state [opnfv] at time 03:15:43.492272 duration_in_ms=47.272
2019-04-30 03:15:43,496 [salt.minion      :1711][INFO    ][11046] Returning information for job: 20190430031515240164
2019-04-30 03:15:44,116 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command state.apply with jid 20190430031544110449
2019-04-30 03:15:44,135 [salt.minion      :1432][INFO    ][11526] Starting a new job with PID 11526
2019-04-30 03:15:47,969 [salt.state       :915 ][INFO    ][11526] Loading fresh modules for state activity
2019-04-30 03:15:48,067 [salt.state       :1780][INFO    ][11526] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:15:48.067385
2019-04-30 03:15:48,067 [salt.state       :1813][INFO    ][11526] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-30 03:15:48,069 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11526] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-30 03:15:49,500 [salt.state       :300 ][INFO    ][11526] {'pid': 11550, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-30 03:15:49,501 [salt.state       :1951][INFO    ][11526] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:15:49.501073 duration_in_ms=1433.689
2019-04-30 03:15:49,502 [salt.state       :1780][INFO    ][11526] Running state [maas.process_machines] at time 03:15:49.502225
2019-04-30 03:15:49,502 [salt.state       :1813][INFO    ][11526] Executing state module.run for [maas.process_machines]
2019-04-30 03:15:49,502 [salt.utils.decorators:613 ][WARNING ][11526] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-30 03:15:49,983 [salt.loaded.ext.module.maas:412 ][WARNING ][11526] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-30 03:15:49,984 [salt.loaded.ext.module.maas:92  ][INFO    ][11526] machine hostname=cmp002 power_type=ipmi mac_addresses=['00:25:b5:a0:00:6a'] power_parameters_power_address=172.30.8.72 power_parameters_power_pass=octopus system_id=fr7wqr architecture=amd64/generic power_parameters_power_user=admin
2019-04-30 03:15:51,067 [salt.loaded.ext.module.maas:412 ][WARNING ][11526] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-30 03:15:51,068 [salt.loaded.ext.module.maas:92  ][INFO    ][11526] 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=mcwye6 architecture=amd64/generic power_parameters_power_user=admin
2019-04-30 03:15:52,523 [salt.loaded.ext.module.maas:412 ][WARNING ][11526] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-30 03:15:52,524 [salt.loaded.ext.module.maas:92  ][INFO    ][11526] 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=bfrht3 architecture=amd64/generic power_parameters_power_user=admin
2019-04-30 03:15:53,492 [salt.loaded.ext.module.maas:412 ][WARNING ][11526] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-30 03:15:53,493 [salt.loaded.ext.module.maas:92  ][INFO    ][11526] 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=fbrqcy architecture=amd64/generic power_parameters_power_user=admin
2019-04-30 03:15:54,476 [salt.loaded.ext.module.maas:412 ][WARNING ][11526] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-30 03:15:54,477 [salt.loaded.ext.module.maas:92  ][INFO    ][11526] 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=86cpm6 architecture=amd64/generic power_parameters_power_user=admin
2019-04-30 03:15:55,655 [salt.state       :300 ][INFO    ][11526] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-04-30 03:15:55,656 [salt.state       :1951][INFO    ][11526] Completed state [maas.process_machines] at time 03:15:55.656440 duration_in_ms=6154.213
2019-04-30 03:15:55,660 [salt.minion      :1711][INFO    ][11526] Returning information for job: 20190430031544110449
2019-04-30 03:16:26,780 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command state.apply with jid 20190430031626767073
2019-04-30 03:16:26,802 [salt.minion      :1432][INFO    ][11878] Starting a new job with PID 11878
2019-04-30 03:16:30,550 [salt.state       :915 ][INFO    ][11878] Loading fresh modules for state activity
2019-04-30 03:16:30,644 [salt.state       :1780][INFO    ][11878] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:16:30.644143
2019-04-30 03:16:30,644 [salt.state       :1813][INFO    ][11878] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-30 03:16:30,646 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11878] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-30 03:16:32,004 [salt.state       :300 ][INFO    ][11878] {'pid': 11885, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-30 03:16:32,005 [salt.state       :1951][INFO    ][11878] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:16:32.005065 duration_in_ms=1360.924
2019-04-30 03:16:32,006 [salt.state       :1780][INFO    ][11878] Running state [maas.wait_for_machine_status] at time 03:16:32.006456
2019-04-30 03:16:32,006 [salt.state       :1813][INFO    ][11878] Executing state module.run for [maas.wait_for_machine_status]
2019-04-30 03:16:32,006 [salt.utils.decorators:613 ][WARNING ][11878] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-30 03:16:33,962 [salt.loaded.ext.module.maas:981 ][INFO    ][11878] Machine bfrht3 deleted
2019-04-30 03:16:34,781 [salt.loaded.ext.module.maas:412 ][WARNING ][11878] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-30 03:16:34,782 [salt.loaded.ext.module.maas:92  ][INFO    ][11878] machine hostname=cmp002 power_type=ipmi mac_addresses=['00:25:b5:a0:00:6a'] power_parameters_power_address=172.30.8.72 power_parameters_power_pass=octopus system_id=fr7wqr architecture=amd64/generic power_parameters_power_user=admin
2019-04-30 03:16:35,730 [salt.loaded.ext.module.maas:412 ][WARNING ][11878] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-30 03:16:35,731 [salt.loaded.ext.module.maas:92  ][INFO    ][11878] 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=mcwye6 architecture=amd64/generic power_parameters_power_user=admin
2019-04-30 03:16:37,061 [salt.loaded.ext.module.maas:412 ][WARNING ][11878] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-30 03:16:37,062 [salt.loaded.ext.module.maas:92  ][INFO    ][11878] machine hostname=kvm01 power_type=ipmi mac_addresses=00:25:b5:a0:00:2a power_parameters_power_address=172.30.8.75 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-04-30 03:16:38,306 [salt.loaded.ext.module.maas:412 ][WARNING ][11878] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-30 03:16:38,306 [salt.loaded.ext.module.maas:92  ][INFO    ][11878] 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=fbrqcy architecture=amd64/generic power_parameters_power_user=admin
2019-04-30 03:16:39,648 [salt.loaded.ext.module.maas:412 ][WARNING ][11878] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-30 03:16:39,649 [salt.loaded.ext.module.maas:92  ][INFO    ][11878] 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=86cpm6 architecture=amd64/generic power_parameters_power_user=admin
2019-04-30 03:16:41,839 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command saltutil.find_job with jid 20190430031641826137
2019-04-30 03:16:41,861 [salt.minion      :1432][INFO    ][12146] Starting a new job with PID 12146
2019-04-30 03:16:41,883 [salt.minion      :1711][INFO    ][12146] Returning information for job: 20190430031641826137
2019-04-30 03:16:42,233 [salt.loaded.ext.module.maas:1023][INFO    ][11878] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1489.77728009s left)
2019-04-30 03:17:11,883 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command saltutil.find_job with jid 20190430031711869751
2019-04-30 03:17:11,907 [salt.minion      :1432][INFO    ][12204] Starting a new job with PID 12204
2019-04-30 03:17:11,928 [salt.minion      :1711][INFO    ][12204] Returning information for job: 20190430031711869751
2019-04-30 03:17:15,156 [salt.loaded.ext.module.maas:1023][INFO    ][11878] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1456.85430408s left)
2019-04-30 03:17:41,953 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command saltutil.find_job with jid 20190430031741940348
2019-04-30 03:17:41,976 [salt.minion      :1432][INFO    ][12240] Starting a new job with PID 12240
2019-04-30 03:17:41,999 [salt.minion      :1711][INFO    ][12240] Returning information for job: 20190430031741940348
2019-04-30 03:17:47,561 [salt.loaded.ext.module.maas:1023][INFO    ][11878] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1424.44986701s left)
2019-04-30 03:18:12,001 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command saltutil.find_job with jid 20190430031811988867
2019-04-30 03:18:12,022 [salt.minion      :1432][INFO    ][12306] Starting a new job with PID 12306
2019-04-30 03:18:12,046 [salt.minion      :1711][INFO    ][12306] Returning information for job: 20190430031811988867
2019-04-30 03:18:20,247 [salt.loaded.ext.module.maas:1023][INFO    ][11878] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1391.76412797s left)
2019-04-30 03:18:42,047 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command saltutil.find_job with jid 20190430031842034476
2019-04-30 03:18:42,070 [salt.minion      :1432][INFO    ][12368] Starting a new job with PID 12368
2019-04-30 03:18:42,093 [salt.minion      :1711][INFO    ][12368] Returning information for job: 20190430031842034476
2019-04-30 03:18:52,927 [salt.loaded.ext.module.maas:1023][INFO    ][11878] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1359.08413315s left)
2019-04-30 03:19:12,099 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command saltutil.find_job with jid 20190430031912086715
2019-04-30 03:19:12,123 [salt.minion      :1432][INFO    ][12512] Starting a new job with PID 12512
2019-04-30 03:19:12,146 [salt.minion      :1711][INFO    ][12512] Returning information for job: 20190430031912086715
2019-04-30 03:19:25,496 [salt.loaded.ext.module.maas:1023][INFO    ][11878] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1326.51461315s left)
2019-04-30 03:19:42,156 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command saltutil.find_job with jid 20190430031942142978
2019-04-30 03:19:42,180 [salt.minion      :1432][INFO    ][12565] Starting a new job with PID 12565
2019-04-30 03:19:42,203 [salt.minion      :1711][INFO    ][12565] Returning information for job: 20190430031942142978
2019-04-30 03:19:58,147 [salt.loaded.ext.module.maas:1023][INFO    ][11878] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1293.86413312s left)
2019-04-30 03:20:12,214 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command saltutil.find_job with jid 20190430032012200435
2019-04-30 03:20:12,237 [salt.minion      :1432][INFO    ][12671] Starting a new job with PID 12671
2019-04-30 03:20:12,260 [salt.minion      :1711][INFO    ][12671] Returning information for job: 20190430032012200435
2019-04-30 03:20:31,301 [salt.state       :300 ][INFO    ][11878] {'ret': True}
2019-04-30 03:20:31,302 [salt.state       :1951][INFO    ][11878] Completed state [maas.wait_for_machine_status] at time 03:20:31.302232 duration_in_ms=239295.772
2019-04-30 03:20:31,306 [salt.minion      :1711][INFO    ][11878] Returning information for job: 20190430031626767073
2019-04-30 03:20:31,920 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command state.apply with jid 20190430032031911608
2019-04-30 03:20:31,941 [salt.minion      :1432][INFO    ][12724] Starting a new job with PID 12724
2019-04-30 03:20:35,724 [salt.state       :915 ][INFO    ][12724] Loading fresh modules for state activity
2019-04-30 03:20:35,776 [salt.fileclient  :1219][INFO    ][12724] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-04-30 03:20:35,860 [salt.state       :1780][INFO    ][12724] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:20:35.860830
2019-04-30 03:20:35,861 [salt.state       :1813][INFO    ][12724] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-30 03:20:35,862 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12724] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-30 03:20:37,296 [salt.state       :300 ][INFO    ][12724] {'pid': 12732, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-30 03:20:37,297 [salt.state       :1951][INFO    ][12724] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:20:37.297240 duration_in_ms=1436.411
2019-04-30 03:20:37,298 [salt.state       :1780][INFO    ][12724] Running state [maas_machines_storage_cmp002_lvm] at time 03:20:37.298726
2019-04-30 03:20:37,299 [salt.state       :1813][INFO    ][12724] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-04-30 03:20:38,307 [salt.loaded.ext.module.maasng:610 ][INFO    ][12724] fr7wqr
2019-04-30 03:20:38,308 [salt.loaded.ext.module.maasng:626 ][INFO    ][12724] sda
2019-04-30 03:20:38,766 [salt.loaded.ext.module.maasng:361 ][INFO    ][12724] fr7wqr
2019-04-30 03:20:38,889 [salt.loaded.ext.module.maasng:367 ][INFO    ][12724] [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'133c4d69-8aae-42cd-b79e-0fd8bfaf74f4', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'fr7wqr', u'device_id': 3, u'filesystem': {u'uuid': u'6d7e60e5-9ba7-4e3e-a433-977f03a41bdc', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/fr7wqr/blockdevices/3/partition/1', u'type': u'partition', u'id': 1, u'size': 2397992648704}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'system_id': u'fr7wqr', u'partition_table_type': u'GPT', u'filesystem': None, u'id': 3, u'available_size': 0, u'path': u'/dev/disk/by-dname/sda', u'block_size': 4096, u'type': u'physical', u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/fr7wqr/blockdevices/3/'}, {u'size': 2397988454400, u'model': None, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 2397988454400, u'partitions': [], u'uuid': u'c85b5acb-b487-49e8-9c9d-0a90e1ccf376', u'used_for': u'ext4 formatted filesystem mounted at /', u'id_path': None, u'system_id': u'fr7wqr', u'partition_table_type': None, u'filesystem': {u'uuid': u'3eb774dd-afb9-4626-9009-67d6236064de', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id': 6, u'available_size': 0, u'path': u'/dev/disk/by-dname/lvroot', u'block_size': 4096, u'type': u'virtual', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/fr7wqr/blockdevices/6/'}]
2019-04-30 03:20:38,890 [salt.loaded.ext.module.maasng:632 ][INFO    ][12724] vgroot
2019-04-30 03:20:38,891 [salt.loaded.ext.module.maasng:635 ][INFO    ][12724] lvroot
2019-04-30 03:20:38,891 [salt.loaded.ext.module.maasng:639 ][INFO    ][12724] 107374182400
2019-04-30 03:20:39,599 [salt.loaded.ext.module.maasng:645 ][INFO    ][12724] {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'swap_size': None, u'memory_test_status': -1, u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'cbdwqr', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'id': 17, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'cbdwqr', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'children': [], u'discovered': [], u'mac_address': u'00:25:b5:a0:00:6a', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'fr7wqr', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/fr7wqr/interfaces/4/'}, u'fqdn': u'cmp002.maas', u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'boot_disk': {u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/fr7wqr/blockdevices/3/', u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'fr7wqr', u'block_size': 4096, u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'path': u'/dev/disk/by-dname/sda', u'size': 2397998940160, u'used_size': 2397998940160, u'id': 3, u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'uuid': u'9fd510f5-012a-4d54-8e72-cb5553d22bf6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'fr7wqr', u'device_id': 3, u'filesystem': {u'uuid': u'35b15c5b-c951-4b11-a989-5a3d77bd6b53', u'mount_point': None, u'mount_options': None, 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/fr7wqr/blockdevices/3/partition/6', u'type': u'partition', u'id': 6, u'size': 2397992648704}]}, u'blockdevice_set': [{u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/fr7wqr/blockdevices/3/', u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'fr7wqr', u'block_size': 4096, u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'size': 2397998940160, u'used_size': 2397998940160, u'id': 3, u'partitions': [{u'uuid': u'9fd510f5-012a-4d54-8e72-cb5553d22bf6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'fr7wqr', u'device_id': 3, u'filesystem': {u'uuid': u'35b15c5b-c951-4b11-a989-5a3d77bd6b53', u'mount_point': None, u'mount_options': None, 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/fr7wqr/blockdevices/3/partition/6', u'type': u'partition', u'id': 6, u'size': 2397992648704}]}, {u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/fr7wqr/blockdevices/12/', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'filesystem': {u'uuid': u'68d69bfc-9b3b-4341-ac8c-168fb7b92d7e', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'uuid': u'97b2b51b-145c-40b8-af0f-282c7d271b1c', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'fr7wqr', u'block_size': 4096, u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'size': 107374182400, u'used_size': 107374182400, u'id': 12, u'partitions': []}], u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default'}, u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'fr7wqr', u'power_state': u'off', u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/fr7wqr/blockdevices/12/', u'uuid': u'97b2b51b-145c-40b8-af0f-282c7d271b1c', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'fr7wqr', u'block_size': 4096, u'partition_table_type': None, u'filesystem': {u'uuid': u'68d69bfc-9b3b-4341-ac8c-168fb7b92d7e', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'size': 107374182400, u'used_size': 107374182400, u'id': 12, u'serial': None, u'partitions': []}], u'architecture': u'amd64/generic', u'status': 4, u'storage_test_status': 2, u'cpu_count': 16, u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'fr7wqr', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'node_type_name': u'Machine', u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'cbdwqr', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'id': 17, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'cbdwqr', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'children': [], u'discovered': [], u'mac_address': u'00:25:b5:a0:00:6a', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'fr7wqr', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/fr7wqr/interfaces/4/'}, {u'name': u'enp7s0', u'links': [{u'id': 18, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'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'fr7wqr', u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/fr7wqr/interfaces/15/'}, {u'name': u'enp9s0', u'links': [{u'id': 19, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'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'fr7wqr', u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/fr7wqr/interfaces/16/'}, {u'name': u'enp8s0', u'links': [{u'id': 20, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'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'fr7wqr', u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/fr7wqr/interfaces/17/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/fr7wqr/blockdevices/3/', u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'fr7wqr', u'block_size': 4096, u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'path': u'/dev/disk/by-dname/sda', u'size': 2397998940160, u'used_size': 2397998940160, u'id': 3, u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'uuid': u'9fd510f5-012a-4d54-8e72-cb5553d22bf6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'fr7wqr', u'device_id': 3, u'filesystem': {u'uuid': u'35b15c5b-c951-4b11-a989-5a3d77bd6b53', u'mount_point': None, u'mount_options': None, 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/fr7wqr/blockdevices/3/partition/6', u'type': u'partition', u'id': 6, u'size': 2397992648704}]}], u'netboot': True, u'osystem': u'', u'status_action': u'', u'memory_test_status_name': u'Unknown', u'commissioning_status': 2, u'min_hwe_kernel': u'ga-16.04', u'ip_addresses': [], u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/fr7wqr/', u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2019-04-30 03:20:39,602 [salt.state       :300 ][INFO    ][12724] {'new': {'storage_layout': 'lvm'}}
2019-04-30 03:20:39,603 [salt.state       :1951][INFO    ][12724] Completed state [maas_machines_storage_cmp002_lvm] at time 03:20:39.603024 duration_in_ms=2304.294
2019-04-30 03:20:39,603 [salt.state       :1780][INFO    ][12724] Running state [maas_machines_storage_cmp001_lvm] at time 03:20:39.603885
2019-04-30 03:20:39,604 [salt.state       :1813][INFO    ][12724] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-04-30 03:20:40,751 [salt.loaded.ext.module.maasng:610 ][INFO    ][12724] mcwye6
2019-04-30 03:20:40,751 [salt.loaded.ext.module.maasng:626 ][INFO    ][12724] sda
2019-04-30 03:20:41,235 [salt.loaded.ext.module.maasng:361 ][INFO    ][12724] mcwye6
2019-04-30 03:20:41,369 [salt.loaded.ext.module.maasng:367 ][INFO    ][12724] [{u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/mcwye6/blockdevices/1/', u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'5dcce137-88cb-411d-9fad-6dd01a8db8a1', u'resource_uri': u'/MAAS/api/2.0/nodes/mcwye6/blockdevices/1/partition/2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'mcwye6', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'ed630a67-b445-466f-af23-849366953b7e', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 1, u'type': u'partition', u'id': 2, u'size': 2397992648704}], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'mcwye6', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'used_size': 2397998940160, u'id': 1, u'size': 2397998940160}, {u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/mcwye6/blockdevices/8/', u'uuid': u'554ff13c-3a85-421a-8265-3ef74af70d18', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'3545aaeb-67ee-449b-9455-5c0f543184d0', u'fstype': u'ext4', u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'mcwye6', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'used_size': 2397988454400, u'id': 8, u'size': 2397988454400}]
2019-04-30 03:20:41,370 [salt.loaded.ext.module.maasng:632 ][INFO    ][12724] vgroot
2019-04-30 03:20:41,370 [salt.loaded.ext.module.maasng:635 ][INFO    ][12724] lvroot
2019-04-30 03:20:41,370 [salt.loaded.ext.module.maasng:639 ][INFO    ][12724] 107374182400
2019-04-30 03:20:41,914 [salt.loaded.ext.module.maasng:645 ][INFO    ][12724] {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'swap_size': None, u'memory_test_status': -1, u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'cbdwqr', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'id': 22, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'cbdwqr', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'children': [], u'discovered': [], u'mac_address': u'00:25:b5:a0:00:5a', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'mcwye6', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/mcwye6/interfaces/5/'}, u'fqdn': u'cmp001.maas', u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'boot_disk': {u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/mcwye6/blockdevices/1/', u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'mcwye6', u'block_size': 4096, u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'path': u'/dev/disk/by-dname/sda', u'size': 2397998940160, u'used_size': 2397998940160, u'id': 1, u'serial': u'618e72837274f1901cc7889705aa1b02', u'partitions': [{u'uuid': u'789da979-85f8-4299-91c1-369990ff5309', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'mcwye6', u'device_id': 1, u'filesystem': {u'uuid': u'aa63f47d-8a15-4056-9bbd-6ea62105a584', u'mount_point': None, u'mount_options': None, 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/mcwye6/blockdevices/1/partition/7', u'type': u'partition', u'id': 7, u'size': 2397992648704}]}, u'blockdevice_set': [{u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/mcwye6/blockdevices/1/', u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'mcwye6', u'block_size': 4096, u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160, u'used_size': 2397998940160, u'id': 1, u'partitions': [{u'uuid': u'789da979-85f8-4299-91c1-369990ff5309', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'mcwye6', u'device_id': 1, u'filesystem': {u'uuid': u'aa63f47d-8a15-4056-9bbd-6ea62105a584', u'mount_point': None, u'mount_options': None, 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/mcwye6/blockdevices/1/partition/7', u'type': u'partition', u'id': 7, u'size': 2397992648704}]}, {u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/mcwye6/blockdevices/13/', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'filesystem': {u'uuid': u'272680f4-ab50-4750-a792-184a8b1f9d28', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'uuid': u'bc745141-399a-40b0-84b0-30e3b09cd2f9', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'mcwye6', u'block_size': 4096, u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'size': 107374182400, u'used_size': 107374182400, u'id': 13, u'partitions': []}], u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default'}, u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'mcwye6', u'power_state': u'off', u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/mcwye6/blockdevices/13/', u'uuid': u'bc745141-399a-40b0-84b0-30e3b09cd2f9', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'mcwye6', u'block_size': 4096, u'partition_table_type': None, u'filesystem': {u'uuid': u'272680f4-ab50-4750-a792-184a8b1f9d28', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'size': 107374182400, u'used_size': 107374182400, u'id': 13, u'serial': None, u'partitions': []}], u'architecture': u'amd64/generic', u'status': 4, u'storage_test_status': 2, u'cpu_count': 16, u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'mcwye6', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'node_type_name': u'Machine', u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'cbdwqr', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'id': 22, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'cbdwqr', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'children': [], u'discovered': [], u'mac_address': u'00:25:b5:a0:00:5a', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'mcwye6', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/mcwye6/interfaces/5/'}, {u'name': u'enp9s0', u'links': [{u'id': 24, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5d', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'mcwye6', u'type': u'physical', u'id': 9, u'resource_uri': u'/MAAS/api/2.0/nodes/mcwye6/interfaces/9/'}, {u'name': u'enp7s0', u'links': [{u'id': 27, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5b', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'mcwye6', u'type': u'physical', u'id': 10, u'resource_uri': u'/MAAS/api/2.0/nodes/mcwye6/interfaces/10/'}, {u'name': u'enp8s0', u'links': [{u'id': 28, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5c', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'mcwye6', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/mcwye6/interfaces/11/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/mcwye6/blockdevices/1/', u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'mcwye6', u'block_size': 4096, u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'path': u'/dev/disk/by-dname/sda', u'size': 2397998940160, u'used_size': 2397998940160, u'id': 1, u'serial': u'618e72837274f1901cc7889705aa1b02', u'partitions': [{u'uuid': u'789da979-85f8-4299-91c1-369990ff5309', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'mcwye6', u'device_id': 1, u'filesystem': {u'uuid': u'aa63f47d-8a15-4056-9bbd-6ea62105a584', u'mount_point': None, u'mount_options': None, 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/mcwye6/blockdevices/1/partition/7', u'type': u'partition', u'id': 7, u'size': 2397992648704}]}], u'netboot': True, u'osystem': u'', u'status_action': u'', u'memory_test_status_name': u'Unknown', u'commissioning_status': 2, u'min_hwe_kernel': u'ga-16.04', u'ip_addresses': [], u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/mcwye6/', u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2019-04-30 03:20:41,916 [salt.state       :300 ][INFO    ][12724] {'new': {'storage_layout': 'lvm'}}
2019-04-30 03:20:41,917 [salt.state       :1951][INFO    ][12724] Completed state [maas_machines_storage_cmp001_lvm] at time 03:20:41.916941 duration_in_ms=2313.058
2019-04-30 03:20:41,920 [salt.minion      :1711][INFO    ][12724] Returning information for job: 20190430032031911608
2019-04-30 03:20:42,537 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command state.apply with jid 20190430032042528325
2019-04-30 03:20:42,560 [salt.minion      :1432][INFO    ][12751] Starting a new job with PID 12751
2019-04-30 03:20:43,259 [salt.state       :915 ][INFO    ][12751] Loading fresh modules for state activity
2019-04-30 03:20:43,310 [salt.fileclient  :1219][INFO    ][12751] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-04-30 03:20:43,352 [salt.state       :1780][INFO    ][12751] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:20:43.352292
2019-04-30 03:20:43,352 [salt.state       :1813][INFO    ][12751] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-30 03:20:43,354 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12751] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-30 03:20:44,716 [salt.state       :300 ][INFO    ][12751] {'pid': 12767, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-30 03:20:44,716 [salt.state       :1951][INFO    ][12751] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:20:44.716913 duration_in_ms=1364.622
2019-04-30 03:20:44,718 [salt.state       :1780][INFO    ][12751] Running state [maas.deploy_machines] at time 03:20:44.717976
2019-04-30 03:20:44,718 [salt.state       :1813][INFO    ][12751] Executing state module.run for [maas.deploy_machines]
2019-04-30 03:20:44,718 [salt.utils.decorators:613 ][WARNING ][12751] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-30 03:20:45,379 [salt.loaded.ext.module.maas:684 ][INFO    ][12751] deploymachines hwe_kernel=ga-16.04 system_id=fr7wqr distro_series=xenial
2019-04-30 03:20:48,142 [salt.loaded.ext.module.maas:684 ][INFO    ][12751] deploymachines hwe_kernel=ga-16.04 system_id=mcwye6 distro_series=xenial
2019-04-30 03:20:50,751 [salt.loaded.ext.module.maas:684 ][INFO    ][12751] deploymachines hwe_kernel=ga-16.04 system_id=fmxwbb distro_series=xenial
2019-04-30 03:20:53,284 [salt.loaded.ext.module.maas:684 ][INFO    ][12751] deploymachines hwe_kernel=ga-16.04 system_id=fbrqcy distro_series=xenial
2019-04-30 03:20:55,781 [salt.loaded.ext.module.maas:684 ][INFO    ][12751] deploymachines hwe_kernel=ga-16.04 system_id=86cpm6 distro_series=xenial
2019-04-30 03:20:57,646 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command saltutil.find_job with jid 20190430032057633341
2019-04-30 03:20:57,669 [salt.minion      :1432][INFO    ][13042] Starting a new job with PID 13042
2019-04-30 03:20:57,691 [salt.minion      :1711][INFO    ][13042] Returning information for job: 20190430032057633341
2019-04-30 03:20:58,399 [salt.state       :300 ][INFO    ][12751] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-04-30 03:20:58,400 [salt.state       :1951][INFO    ][12751] Completed state [maas.deploy_machines] at time 03:20:58.400138 duration_in_ms=13682.16
2019-04-30 03:20:58,403 [salt.minion      :1711][INFO    ][12751] Returning information for job: 20190430032042528325
2019-04-30 03:20:59,021 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command state.apply with jid 20190430032059009400
2019-04-30 03:20:59,043 [salt.minion      :1432][INFO    ][13057] Starting a new job with PID 13057
2019-04-30 03:21:02,758 [salt.state       :915 ][INFO    ][13057] Loading fresh modules for state activity
2019-04-30 03:21:02,810 [salt.fileclient  :1219][INFO    ][13057] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-04-30 03:21:02,853 [salt.state       :1780][INFO    ][13057] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:21:02.853351
2019-04-30 03:21:02,853 [salt.state       :1813][INFO    ][13057] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-30 03:21:02,855 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13057] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-30 03:21:04,209 [salt.state       :300 ][INFO    ][13057] {'pid': 13101, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-30 03:21:04,210 [salt.state       :1951][INFO    ][13057] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:21:04.210722 duration_in_ms=1357.371
2019-04-30 03:21:04,214 [salt.state       :1780][INFO    ][13057] Running state [maas.wait_for_machine_status] at time 03:21:04.213947
2019-04-30 03:21:04,214 [salt.state       :1813][INFO    ][13057] Executing state module.run for [maas.wait_for_machine_status]
2019-04-30 03:21:04,215 [salt.utils.decorators:613 ][WARNING ][13057] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-30 03:21:07,014 [salt.loaded.ext.module.maas:1023][INFO    ][13057] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.21084189s left)
2019-04-30 03:21:14,044 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command saltutil.find_job with jid 20190430032114031353
2019-04-30 03:21:14,067 [salt.minion      :1432][INFO    ][13114] Starting a new job with PID 13114
2019-04-30 03:21:14,090 [salt.minion      :1711][INFO    ][13114] Returning information for job: 20190430032114031353
2019-04-30 03:21:40,133 [salt.loaded.ext.module.maas:1023][INFO    ][13057] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.09117198s left)
2019-04-30 03:21:44,084 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command saltutil.find_job with jid 20190430032144072368
2019-04-30 03:21:44,108 [salt.minion      :1432][INFO    ][13133] Starting a new job with PID 13133
2019-04-30 03:21:44,131 [salt.minion      :1711][INFO    ][13133] Returning information for job: 20190430032144072368
2019-04-30 03:22:13,021 [salt.loaded.ext.module.maas:1023][INFO    ][13057] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.20326495s left)
2019-04-30 03:22:14,161 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command saltutil.find_job with jid 20190430032214148187
2019-04-30 03:22:14,184 [salt.minion      :1432][INFO    ][13194] Starting a new job with PID 13194
2019-04-30 03:22:14,205 [salt.minion      :1711][INFO    ][13194] Returning information for job: 20190430032214148187
2019-04-30 03:22:44,209 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command saltutil.find_job with jid 20190430032244196235
2019-04-30 03:22:44,233 [salt.minion      :1432][INFO    ][13321] Starting a new job with PID 13321
2019-04-30 03:22:44,255 [salt.minion      :1711][INFO    ][13321] Returning information for job: 20190430032244196235
2019-04-30 03:22:46,466 [salt.loaded.ext.module.maas:1023][INFO    ][13057] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2147.75873303s left)
2019-04-30 03:23:14,274 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command saltutil.find_job with jid 20190430032314261529
2019-04-30 03:23:14,297 [salt.minion      :1432][INFO    ][13815] Starting a new job with PID 13815
2019-04-30 03:23:14,319 [salt.minion      :1711][INFO    ][13815] Returning information for job: 20190430032314261529
2019-04-30 03:23:20,119 [salt.loaded.ext.module.maas:1023][INFO    ][13057] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2114.10500503s left)
2019-04-30 03:23:44,328 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command saltutil.find_job with jid 20190430032344315419
2019-04-30 03:23:44,352 [salt.minion      :1432][INFO    ][13865] Starting a new job with PID 13865
2019-04-30 03:23:44,375 [salt.minion      :1711][INFO    ][13865] Returning information for job: 20190430032344315419
2019-04-30 03:23:53,609 [salt.loaded.ext.module.maas:1023][INFO    ][13057] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2080.61528993s left)
2019-04-30 03:24:14,386 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command saltutil.find_job with jid 20190430032414373593
2019-04-30 03:24:14,410 [salt.minion      :1432][INFO    ][14364] Starting a new job with PID 14364
2019-04-30 03:24:14,432 [salt.minion      :1711][INFO    ][14364] Returning information for job: 20190430032414373593
2019-04-30 03:24:26,706 [salt.loaded.ext.module.maas:1023][INFO    ][13057] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2047.518538s left)
2019-04-30 03:24:44,446 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command saltutil.find_job with jid 20190430032444433129
2019-04-30 03:24:44,469 [salt.minion      :1432][INFO    ][14384] Starting a new job with PID 14384
2019-04-30 03:24:44,493 [salt.minion      :1711][INFO    ][14384] Returning information for job: 20190430032444433129
2019-04-30 03:25:00,115 [salt.loaded.ext.module.maas:1023][INFO    ][13057] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2014.10931206s left)
2019-04-30 03:25:14,512 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command saltutil.find_job with jid 20190430032514499336
2019-04-30 03:25:14,536 [salt.minion      :1432][INFO    ][14485] Starting a new job with PID 14485
2019-04-30 03:25:14,558 [salt.minion      :1711][INFO    ][14485] Returning information for job: 20190430032514499336
2019-04-30 03:25:33,776 [salt.loaded.ext.module.maas:1023][INFO    ][13057] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1980.4488759s left)
2019-04-30 03:25:44,583 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command saltutil.find_job with jid 20190430032544570408
2019-04-30 03:25:44,599 [salt.minion      :1432][INFO    ][14515] Starting a new job with PID 14515
2019-04-30 03:25:44,610 [salt.minion      :1711][INFO    ][14515] Returning information for job: 20190430032544570408
2019-04-30 03:26:06,653 [salt.loaded.ext.module.maas:1023][INFO    ][13057] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1947.57167006s left)
2019-04-30 03:26:14,632 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command saltutil.find_job with jid 20190430032614619910
2019-04-30 03:26:14,656 [salt.minion      :1432][INFO    ][14781] Starting a new job with PID 14781
2019-04-30 03:26:14,680 [salt.minion      :1711][INFO    ][14781] Returning information for job: 20190430032614619910
2019-04-30 03:26:39,873 [salt.loaded.ext.module.maas:1023][INFO    ][13057] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1914.35122204s left)
2019-04-30 03:26:44,718 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command saltutil.find_job with jid 20190430032644705042
2019-04-30 03:26:44,741 [salt.minion      :1432][INFO    ][14801] Starting a new job with PID 14801
2019-04-30 03:26:44,764 [salt.minion      :1711][INFO    ][14801] Returning information for job: 20190430032644705042
2019-04-30 03:27:13,376 [salt.loaded.ext.module.maas:1023][INFO    ][13057] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1880.848768s left)
2019-04-30 03:27:14,798 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command saltutil.find_job with jid 20190430032714786609
2019-04-30 03:27:14,822 [salt.minion      :1432][INFO    ][15092] Starting a new job with PID 15092
2019-04-30 03:27:14,844 [salt.minion      :1711][INFO    ][15092] Returning information for job: 20190430032714786609
2019-04-30 03:27:44,888 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command saltutil.find_job with jid 20190430032744874190
2019-04-30 03:27:44,912 [salt.minion      :1432][INFO    ][15113] Starting a new job with PID 15113
2019-04-30 03:27:44,934 [salt.minion      :1711][INFO    ][15113] Returning information for job: 20190430032744874190
2019-04-30 03:27:46,621 [salt.loaded.ext.module.maas:1023][INFO    ][13057] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1847.60340595s left)
2019-04-30 03:28:14,977 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command saltutil.find_job with jid 20190430032814964438
2019-04-30 03:28:15,001 [salt.minion      :1432][INFO    ][15398] Starting a new job with PID 15398
2019-04-30 03:28:15,023 [salt.minion      :1711][INFO    ][15398] Returning information for job: 20190430032814964438
2019-04-30 03:28:20,244 [salt.loaded.ext.module.maas:1023][INFO    ][13057] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1813.9804821s left)
2019-04-30 03:28:45,101 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command saltutil.find_job with jid 20190430032845089024
2019-04-30 03:28:45,127 [salt.minion      :1432][INFO    ][15436] Starting a new job with PID 15436
2019-04-30 03:28:45,148 [salt.minion      :1711][INFO    ][15436] Returning information for job: 20190430032845089024
2019-04-30 03:28:54,073 [salt.loaded.ext.module.maas:1023][INFO    ][13057] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1780.15163207s left)
2019-04-30 03:29:15,213 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command saltutil.find_job with jid 20190430032915201145
2019-04-30 03:29:15,238 [salt.minion      :1432][INFO    ][15776] Starting a new job with PID 15776
2019-04-30 03:29:15,263 [salt.minion      :1711][INFO    ][15776] Returning information for job: 20190430032915201145
2019-04-30 03:29:27,710 [salt.loaded.ext.module.maas:1023][INFO    ][13057] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1746.51455998s left)
2019-04-30 03:29:45,332 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command saltutil.find_job with jid 20190430032945319731
2019-04-30 03:29:45,357 [salt.minion      :1432][INFO    ][15803] Starting a new job with PID 15803
2019-04-30 03:29:45,381 [salt.minion      :1711][INFO    ][15803] Returning information for job: 20190430032945319731
2019-04-30 03:30:00,557 [salt.loaded.ext.module.maas:1023][INFO    ][13057] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1713.66746306s left)
2019-04-30 03:30:15,457 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command saltutil.find_job with jid 20190430033015446880
2019-04-30 03:30:15,480 [salt.minion      :1432][INFO    ][15980] Starting a new job with PID 15980
2019-04-30 03:30:15,500 [salt.minion      :1711][INFO    ][15980] Returning information for job: 20190430033015446880
2019-04-30 03:30:33,929 [salt.state       :300 ][INFO    ][13057] {'ret': True}
2019-04-30 03:30:33,930 [salt.state       :1951][INFO    ][13057] Completed state [maas.wait_for_machine_status] at time 03:30:33.929983 duration_in_ms=569716.034
2019-04-30 03:30:33,933 [salt.minion      :1711][INFO    ][13057] Returning information for job: 20190430032059009400
2019-04-30 04:05:58,549 [salt.utils.schedule:1377][INFO    ][8543] Running scheduled job: __mine_interval
2019-04-30 05:05:58,550 [salt.utils.schedule:1377][INFO    ][8543] Running scheduled job: __mine_interval
2019-04-30 05:22:21,665 [salt.minion      :1308][INFO    ][8543] User sudo_ubuntu Executing command cp.push_dir with jid 20190430052221653346
2019-04-30 05:22:21,686 [salt.minion      :1432][INFO    ][24196] Starting a new job with PID 24196
