2020-01-29 17:47:59,941 [salt.utils.decorators:613 ][WARNING ][1499] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-29 17:48:00,804 [salt.utils.decorators:613 ][WARNING ][1499] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-29 17:48:12,482 [salt.loaded.int.states.file:2298][WARNING ][1648] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2020-01-29 17:48:16,622 [salt.loaded.int.module.cmdmod:395 ][INFO    ][1887] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2020-01-29 17:48:16,652 [salt.loaded.int.module.cmdmod:395 ][INFO    ][1887] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2020-01-29 17:48:16,708 [salt.utils.parsers:1051][WARNING ][154] Minion received a SIGTERM. Exiting.
2020-01-29 17:48:18,009 [salt.cli.daemons :293 ][INFO    ][2039] Setting up the Salt Minion "mas01.mcp-fdio-noha.local"
2020-01-29 17:48:18,256 [salt.cli.daemons :82  ][INFO    ][2039] Starting up the Salt Minion
2020-01-29 17:48:18,256 [salt.utils.event :1017][INFO    ][2039] Starting pull socket on /var/run/salt/minion/minion_event_38d774b16c_pull.ipc
2020-01-29 17:48:19,535 [salt.minion      :976 ][INFO    ][2039] Creating minion process manager
2020-01-29 17:48:21,046 [salt.state       :2022][WARNING ][1893] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2020-01-29 17:48:21,761 [salt.utils.decorators:613 ][WARNING ][1893] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-29 17:48:22,063 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][2039] Executing command ['date', '+%z'] in directory '/root'
2020-01-29 17:48:22,091 [salt.utils.schedule:568 ][INFO    ][2039] Updating job settings for scheduled job: __mine_interval
2020-01-29 17:48:22,093 [salt.minion      :1108][INFO    ][2039] Added mine.update to scheduler
2020-01-29 17:48:22,100 [salt.minion      :1975][INFO    ][2039] Minion is starting as user 'root'
2020-01-29 17:48:22,125 [salt.minion      :2336][INFO    ][2039] Minion is ready to receive requests!
2020-01-29 17:48:28,936 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129174828924366
2020-01-29 17:48:28,954 [salt.minion      :1432][INFO    ][2429] Starting a new job with PID 2429
2020-01-29 17:48:28,980 [salt.minion      :1711][INFO    ][2429] Returning information for job: 20200129174828924366
2020-01-29 17:48:40,503 [salt.utils.decorators:613 ][WARNING ][1893] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-29 17:48:58,982 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129174858963741
2020-01-29 17:48:59,013 [salt.minion      :1432][INFO    ][2623] Starting a new job with PID 2623
2020-01-29 17:48:59,038 [salt.minion      :1711][INFO    ][2623] Returning information for job: 20200129174858963741
2020-01-29 17:49:06,086 [salt.utils.decorators:613 ][WARNING ][1893] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-29 17:49:06,954 [salt.utils.decorators:613 ][WARNING ][1893] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-29 17:49:09,818 [salt.loaded.ext.module.maasng:1008][WARNING ][1893] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2020-01-29 17:49:09,818 [salt.loaded.ext.module.maasng:1011][WARNING ][1893] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2020-01-29 17:49:10,405 [salt.loaded.ext.module.maasng:1235][WARNING ][1893] Ignoring parameter vlan:0
2020-01-29 17:49:11,163 [salt.utils.decorators:613 ][WARNING ][1893] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-29 17:49:12,401 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command state.apply with jid 20200129174912384547
2020-01-29 17:49:12,424 [salt.minion      :1432][INFO    ][2679] Starting a new job with PID 2679
2020-01-29 17:49:20,284 [salt.state       :915 ][INFO    ][2679] Loading fresh modules for state activity
2020-01-29 17:49:20,356 [salt.fileclient  :1219][INFO    ][2679] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2020-01-29 17:49:20,415 [salt.state       :1780][INFO    ][2679] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 17:49:20.414872
2020-01-29 17:49:20,415 [salt.state       :1813][INFO    ][2679] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-01-29 17:49:20,419 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2679] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-01-29 17:49:22,619 [salt.state       :300 ][INFO    ][2679] {'pid': 2693, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-01-29 17:49:22,621 [salt.state       :1951][INFO    ][2679] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 17:49:22.621023 duration_in_ms=2206.152
2020-01-29 17:49:22,624 [salt.state       :1780][INFO    ][2679] Running state [maas.process_machines] at time 17:49:22.624836
2020-01-29 17:49:22,626 [salt.state       :1813][INFO    ][2679] Executing state module.run for [maas.process_machines]
2020-01-29 17:49:22,626 [salt.utils.decorators:613 ][WARNING ][2679] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-29 17:49:22,690 [salt.loaded.ext.module.maas:412 ][WARNING ][2679] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-01-29 17:49:22,691 [salt.loaded.ext.module.maas:92  ][INFO    ][2679] machine hostname=gtw01 power_type=ipmi mac_addresses=14:58:d0:54:6a:60 power_parameters_power_address=172.16.1.17 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2020-01-29 17:49:23,583 [salt.loaded.ext.module.maas:412 ][WARNING ][2679] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-01-29 17:49:23,584 [salt.loaded.ext.module.maas:92  ][INFO    ][2679] machine hostname=cmp002 power_type=ipmi mac_addresses=9c:b6:54:8a:10:18 power_parameters_power_address=172.16.1.20 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2020-01-29 17:49:24,403 [salt.loaded.ext.module.maas:412 ][WARNING ][2679] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-01-29 17:49:24,403 [salt.loaded.ext.module.maas:92  ][INFO    ][2679] machine hostname=cmp001 power_type=ipmi mac_addresses=9c:b6:54:8a:95:a0 power_parameters_power_address=172.16.1.19 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2020-01-29 17:49:25,260 [salt.loaded.ext.module.maas:412 ][WARNING ][2679] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-01-29 17:49:25,261 [salt.loaded.ext.module.maas:92  ][INFO    ][2679] machine hostname=ctl01 power_type=ipmi mac_addresses=14:58:d0:54:e7:88 power_parameters_power_address=172.16.1.16 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2020-01-29 17:49:26,139 [salt.state       :300 ][INFO    ][2679] {'ret': {'updated': [], 'errors': {}, 'success': ['gtw01', 'cmp002', 'cmp001', 'ctl01']}}
2020-01-29 17:49:26,140 [salt.state       :1951][INFO    ][2679] Completed state [maas.process_machines] at time 17:49:26.139977 duration_in_ms=3515.141
2020-01-29 17:49:26,146 [salt.minion      :1711][INFO    ][2679] Returning information for job: 20200129174912384547
2020-01-29 17:49:58,780 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command state.apply with jid 20200129174958767888
2020-01-29 17:49:58,803 [salt.minion      :1432][INFO    ][2770] Starting a new job with PID 2770
2020-01-29 17:50:06,956 [salt.state       :915 ][INFO    ][2770] Loading fresh modules for state activity
2020-01-29 17:50:07,036 [salt.fileclient  :1219][INFO    ][2770] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2020-01-29 17:50:07,102 [salt.state       :1780][INFO    ][2770] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 17:50:07.102334
2020-01-29 17:50:07,102 [salt.state       :1813][INFO    ][2770] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-01-29 17:50:07,104 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2770] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-01-29 17:50:09,219 [salt.state       :300 ][INFO    ][2770] {'pid': 2777, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-01-29 17:50:09,221 [salt.state       :1951][INFO    ][2770] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 17:50:09.220993 duration_in_ms=2118.658
2020-01-29 17:50:09,224 [salt.state       :1780][INFO    ][2770] Running state [maas.wait_for_machine_status] at time 17:50:09.224074
2020-01-29 17:50:09,224 [salt.state       :1813][INFO    ][2770] Executing state module.run for [maas.wait_for_machine_status]
2020-01-29 17:50:09,225 [salt.utils.decorators:613 ][WARNING ][2770] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-29 17:50:09,904 [salt.loaded.ext.module.maas:1024][INFO    ][2770] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1499.33440495s left)
2020-01-29 17:50:13,862 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129175013806000
2020-01-29 17:50:13,887 [salt.minion      :1432][INFO    ][2788] Starting a new job with PID 2788
2020-01-29 17:50:13,913 [salt.minion      :1711][INFO    ][2788] Returning information for job: 20200129175013806000
2020-01-29 17:50:40,610 [salt.loaded.ext.module.maas:1024][INFO    ][2770] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1468.62779999s left)
2020-01-29 17:50:43,972 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129175043952520
2020-01-29 17:50:44,005 [salt.minion      :1432][INFO    ][2815] Starting a new job with PID 2815
2020-01-29 17:50:44,030 [salt.minion      :1711][INFO    ][2815] Returning information for job: 20200129175043952520
2020-01-29 17:51:11,259 [salt.loaded.ext.module.maas:1024][INFO    ][2770] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1437.97959995s left)
2020-01-29 17:51:14,063 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129175114043856
2020-01-29 17:51:14,092 [salt.minion      :1432][INFO    ][2839] Starting a new job with PID 2839
2020-01-29 17:51:14,121 [salt.minion      :1711][INFO    ][2839] Returning information for job: 20200129175114043856
2020-01-29 17:51:41,920 [salt.loaded.ext.module.maas:1024][INFO    ][2770] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1407.31779289s left)
2020-01-29 17:51:44,167 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129175144149898
2020-01-29 17:51:44,192 [salt.minion      :1432][INFO    ][2866] Starting a new job with PID 2866
2020-01-29 17:51:44,214 [salt.minion      :1711][INFO    ][2866] Returning information for job: 20200129175144149898
2020-01-29 17:52:12,834 [salt.loaded.ext.module.maas:1024][INFO    ][2770] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1376.40408587s left)
2020-01-29 17:52:14,210 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129175214197788
2020-01-29 17:52:14,230 [salt.minion      :1432][INFO    ][2914] Starting a new job with PID 2914
2020-01-29 17:52:14,251 [salt.minion      :1711][INFO    ][2914] Returning information for job: 20200129175214197788
2020-01-29 17:52:43,826 [salt.loaded.ext.module.maas:1024][INFO    ][2770] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1345.41231585s left)
2020-01-29 17:52:44,291 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129175244279338
2020-01-29 17:52:44,315 [salt.minion      :1432][INFO    ][2955] Starting a new job with PID 2955
2020-01-29 17:52:44,336 [salt.minion      :1711][INFO    ][2955] Returning information for job: 20200129175244279338
2020-01-29 17:53:14,393 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129175314376485
2020-01-29 17:53:14,428 [salt.minion      :1432][INFO    ][3001] Starting a new job with PID 3001
2020-01-29 17:53:14,487 [salt.minion      :1711][INFO    ][3001] Returning information for job: 20200129175314376485
2020-01-29 17:53:15,085 [salt.loaded.ext.module.maas:1024][INFO    ][2770] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1314.15337706s left)
2020-01-29 17:53:44,536 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129175344528101
2020-01-29 17:53:44,555 [salt.minion      :1432][INFO    ][3057] Starting a new job with PID 3057
2020-01-29 17:53:44,582 [salt.minion      :1711][INFO    ][3057] Returning information for job: 20200129175344528101
2020-01-29 17:53:46,284 [salt.loaded.ext.module.maas:1024][INFO    ][2770] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1282.95463586s left)
2020-01-29 17:54:14,655 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129175414627934
2020-01-29 17:54:14,693 [salt.minion      :1432][INFO    ][3115] Starting a new job with PID 3115
2020-01-29 17:54:14,748 [salt.minion      :1711][INFO    ][3115] Returning information for job: 20200129175414627934
2020-01-29 17:54:17,747 [salt.loaded.ext.module.maas:1024][INFO    ][2770] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1251.49080205s left)
2020-01-29 17:54:44,819 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129175444804618
2020-01-29 17:54:44,847 [salt.minion      :1432][INFO    ][3186] Starting a new job with PID 3186
2020-01-29 17:54:44,873 [salt.minion      :1711][INFO    ][3186] Returning information for job: 20200129175444804618
2020-01-29 17:54:49,472 [salt.loaded.ext.module.maas:1024][INFO    ][2770] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1219.76623106s left)
2020-01-29 17:55:14,939 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129175514925661
2020-01-29 17:55:14,966 [salt.minion      :1432][INFO    ][3220] Starting a new job with PID 3220
2020-01-29 17:55:14,990 [salt.minion      :1711][INFO    ][3220] Returning information for job: 20200129175514925661
2020-01-29 17:55:21,313 [salt.loaded.ext.module.maas:1024][INFO    ][2770] Waiting status:Ready|Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:1500s (1187.92528605s left)
2020-01-29 17:55:45,054 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129175545037000
2020-01-29 17:55:45,083 [salt.minion      :1432][INFO    ][3247] Starting a new job with PID 3247
2020-01-29 17:55:45,112 [salt.minion      :1711][INFO    ][3247] Returning information for job: 20200129175545037000
2020-01-29 17:55:53,063 [salt.loaded.ext.module.maas:1024][INFO    ][2770] Waiting status:Ready|Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:1500s (1156.17559099s left)
2020-01-29 17:56:15,189 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129175615175189
2020-01-29 17:56:15,218 [salt.minion      :1432][INFO    ][3317] Starting a new job with PID 3317
2020-01-29 17:56:15,249 [salt.minion      :1711][INFO    ][3317] Returning information for job: 20200129175615175189
2020-01-29 17:56:25,328 [salt.loaded.ext.module.maas:1024][INFO    ][2770] Waiting status:Ready|Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:1500s (1123.91066885s left)
2020-01-29 17:56:45,323 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129175645311574
2020-01-29 17:56:45,348 [salt.minion      :1432][INFO    ][3344] Starting a new job with PID 3344
2020-01-29 17:56:45,374 [salt.minion      :1711][INFO    ][3344] Returning information for job: 20200129175645311574
2020-01-29 17:56:57,704 [salt.state       :300 ][INFO    ][2770] {'ret': True}
2020-01-29 17:56:57,705 [salt.state       :1951][INFO    ][2770] Completed state [maas.wait_for_machine_status] at time 17:56:57.705286 duration_in_ms=408481.21
2020-01-29 17:56:57,710 [salt.minion      :1711][INFO    ][2770] Returning information for job: 20200129174958767888
2020-01-29 17:56:58,684 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command state.apply with jid 20200129175658670390
2020-01-29 17:56:58,708 [salt.minion      :1432][INFO    ][3370] Starting a new job with PID 3370
2020-01-29 17:57:06,380 [salt.state       :915 ][INFO    ][3370] Loading fresh modules for state activity
2020-01-29 17:57:06,446 [salt.fileclient  :1219][INFO    ][3370] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2020-01-29 17:57:06,544 [salt.state       :1780][INFO    ][3370] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 17:57:06.544193
2020-01-29 17:57:06,544 [salt.state       :1813][INFO    ][3370] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-01-29 17:57:06,547 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3370] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-01-29 17:57:08,798 [salt.state       :300 ][INFO    ][3370] {'pid': 3377, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-01-29 17:57:08,800 [salt.state       :1951][INFO    ][3370] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 17:57:08.799975 duration_in_ms=2255.778
2020-01-29 17:57:08,805 [salt.state       :1780][INFO    ][3370] Running state [maas_machines_storage_cmp002_lvm] at time 17:57:08.805054
2020-01-29 17:57:08,806 [salt.state       :1813][INFO    ][3370] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2020-01-29 17:57:09,923 [salt.loaded.ext.module.maasng:610 ][INFO    ][3370] mx7bck
2020-01-29 17:57:09,924 [salt.loaded.ext.module.maasng:626 ][INFO    ][3370] sda
2020-01-29 17:57:10,464 [salt.loaded.ext.module.maasng:361 ][INFO    ][3370] mx7bck
2020-01-29 17:57:10,546 [salt.loaded.ext.module.maasng:367 ][INFO    ][3370] [{u'resource_uri': u'/MAAS/api/2.0/nodes/mx7bck/blockdevices/1/', u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'9d11f68f-2708-44d0-8d1f-cb735743c5f5', u'resource_uri': u'/MAAS/api/2.0/nodes/mx7bck/blockdevices/1/partition/1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'mx7bck', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'138350cc-c4d3-4834-a0fd-f536094ae814', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 1, u'device_id': 1}], u'id': 1, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'mx7bck', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}, {u'resource_uri': u'/MAAS/api/2.0/nodes/mx7bck/blockdevices/3/', u'available_size': 0, u'uuid': u'e0a94ef0-5c0d-4b14-b0ba-16848e47db01', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'partitions': [], u'id': 3, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'mx7bck', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'ab751956-2e6b-458f-9bd5-3c38b5b4ebab', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'serial': None, u'size': 800097042432}]
2020-01-29 17:57:10,546 [salt.loaded.ext.module.maasng:632 ][INFO    ][3370] vgroot
2020-01-29 17:57:10,547 [salt.loaded.ext.module.maasng:635 ][INFO    ][3370] lvroot
2020-01-29 17:57:10,547 [salt.loaded.ext.module.maasng:639 ][INFO    ][3370] 107374182400
2020-01-29 17:57:11,195 [salt.loaded.ext.module.maasng:645 ][INFO    ][3370] {u'hwe_kernel': None, u'swap_size': None, u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'cpu_count': 40, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'is_default': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'boot_interface': {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'cckwne', 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'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'id': 17, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'cckwne', 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'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'cckwne', 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'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'firmware_version': u'bc 7.8.24', u'system_id': u'mx7bck', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/mx7bck/interfaces/5/'}, u'fqdn': u'cmp002.maas', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'cpu_speed': 2800, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/mx7bck/blockdevices/1/', u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'uuid': None, u'id': 1, u'path': u'/dev/disk/by-dname/sda', u'system_id': u'mx7bck', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'used_for': u'MBR partitioned with 1 partition', u'firmware_version': u'5.22', u'block_size': 4096, u'type': u'physical', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'321fa8a9-9bd5-4314-b8f1-5ae50bbe310b', u'resource_uri': u'/MAAS/api/2.0/nodes/mx7bck/blockdevices/1/partition/5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'mx7bck', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'fbb1c706-06a6-4e01-811e-956dda943705', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 5, u'device_id': 1}]}, u'blockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/mx7bck/blockdevices/1/', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'mx7bck', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'type': u'physical', u'id': 1, u'partitions': [{u'uuid': u'321fa8a9-9bd5-4314-b8f1-5ae50bbe310b', u'resource_uri': u'/MAAS/api/2.0/nodes/mx7bck/blockdevices/1/partition/5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'mx7bck', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'fbb1c706-06a6-4e01-811e-956dda943705', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 5, u'device_id': 1}]}, {u'size': 107374182400, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/mx7bck/blockdevices/9/', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'uuid': u'8907a1ef-3aa0-4d88-9bdd-0bda4c199e76', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'mx7bck', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'af239e19-3622-41c3-93c9-368def31d768', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 9, u'partitions': []}], u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'description': u''}, u'resource_uri': u'/MAAS/api/2.0/machines/mx7bck/', u'disable_ipv4': False, u'hardware_info': {u'mainboard_firmware_version': u'I31', u'mainboard_product': u'Unknown', u'system_vendor': u'HP', u'cpu_model': u'Intel(R) Xeon(R) CPU E5-2680 v2', u'mainboard_firmware_date': u'12/20/2013', u'mainboard_vendor': u'Unknown', u'system_serial': u'USE410T2Y0', u'system_version': u'Unknown', u'system_product': u'ProLiant BL460c Gen8 (735151-B21)'}, u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'mx7bck', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/mx7bck/blockdevices/9/', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'uuid': u'8907a1ef-3aa0-4d88-9bdd-0bda4c199e76', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'mx7bck', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'af239e19-3622-41c3-93c9-368def31d768', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 9, u'partitions': []}], u'architecture': u'amd64/generic', u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'power_state': u'off', u'physicalblockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/mx7bck/blockdevices/1/', u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'uuid': None, u'id': 1, u'path': u'/dev/disk/by-dname/sda', u'system_id': u'mx7bck', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'used_for': u'MBR partitioned with 1 partition', u'firmware_version': u'5.22', u'block_size': 4096, u'type': u'physical', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'321fa8a9-9bd5-4314-b8f1-5ae50bbe310b', u'resource_uri': u'/MAAS/api/2.0/nodes/mx7bck/blockdevices/1/partition/5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'mx7bck', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'fbb1c706-06a6-4e01-811e-956dda943705', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 5, u'device_id': 1}]}], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'mx7bck', u'id': 5}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'node_type_name': u'Machine', u'interface_set': [{u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'cckwne', 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'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'id': 17, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'cckwne', 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'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'cckwne', 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'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'firmware_version': u'bc 7.8.24', u'system_id': u'mx7bck', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/mx7bck/interfaces/5/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'vlan': None, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:07:50', u'parents': [], u'firmware_version': u'bc 7.8.24', u'system_id': u'mx7bck', u'type': u'physical', u'id': 10, u'resource_uri': u'/MAAS/api/2.0/nodes/mx7bck/interfaces/10/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'cckwne', 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'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 18, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'cckwne', 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'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'cckwne', 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'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.40'}], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:1c', u'parents': [], u'firmware_version': u'bc 7.8.24', u'system_id': u'mx7bck', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/mx7bck/interfaces/11/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'vlan': None, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:07:51', u'parents': [], u'firmware_version': u'bc 7.8.24', u'system_id': u'mx7bck', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/mx7bck/interfaces/12/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens2f0', u'links': [{u'id': 19, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {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'effective_mtu': 1500, u'children': [], u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:12:48', u'parents': [], u'firmware_version': u'bc 7.8.24', u'system_id': u'mx7bck', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/mx7bck/interfaces/13/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens2f1', u'links': [{u'id': 20, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {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'effective_mtu': 1500, u'children': [], u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:12:49', u'parents': [], u'firmware_version': u'bc 7.8.24', u'system_id': u'mx7bck', u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/mx7bck/interfaces/14/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'pool': {u'id': 0, u'resource_uri': u'/MAAS/api/2.0/resourcepool/0/', u'name': u'default', u'description': u'Default pool'}, u'bcaches': [], u'locked': False, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'node_type': 0, u'memory_test_status_name': u'Unknown', u'commissioning_status': 2, u'min_hwe_kernel': u'ga-18.04', u'commissioning_status_name': u'Passed', u'current_commissioning_result_id': 4, u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2020-01-29 17:57:11,198 [salt.state       :300 ][INFO    ][3370] {'new': {'storage_layout': 'lvm'}}
2020-01-29 17:57:11,198 [salt.state       :1951][INFO    ][3370] Completed state [maas_machines_storage_cmp002_lvm] at time 17:57:11.198385 duration_in_ms=2393.331
2020-01-29 17:57:11,199 [salt.state       :1780][INFO    ][3370] Running state [maas_machines_storage_cmp001_lvm] at time 17:57:11.198903
2020-01-29 17:57:11,199 [salt.state       :1813][INFO    ][3370] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2020-01-29 17:57:12,291 [salt.loaded.ext.module.maasng:610 ][INFO    ][3370] pt7866
2020-01-29 17:57:12,292 [salt.loaded.ext.module.maasng:626 ][INFO    ][3370] sda
2020-01-29 17:57:12,956 [salt.loaded.ext.module.maasng:361 ][INFO    ][3370] pt7866
2020-01-29 17:57:13,036 [salt.loaded.ext.module.maasng:367 ][INFO    ][3370] [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/pt7866/blockdevices/2/', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'pt7866', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'type': u'physical', u'id': 2, u'partitions': [{u'uuid': u'19a3a929-8e5c-4d7f-9f49-491fda78c627', u'resource_uri': u'/MAAS/api/2.0/nodes/pt7866/blockdevices/2/partition/2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'pt7866', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'b47c7e0b-0977-46b3-80ef-6fb650a36fb5', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 2, u'device_id': 2}]}, {u'size': 800097042432, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/pt7866/blockdevices/4/', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'uuid': u'f28b6a58-944e-47dd-b885-a25a06e54cc3', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'pt7866', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'37a20d3d-b681-4f5e-8e15-7fbe98b5a690', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 4, u'partitions': []}]
2020-01-29 17:57:13,036 [salt.loaded.ext.module.maasng:632 ][INFO    ][3370] vgroot
2020-01-29 17:57:13,036 [salt.loaded.ext.module.maasng:635 ][INFO    ][3370] lvroot
2020-01-29 17:57:13,038 [salt.loaded.ext.module.maasng:639 ][INFO    ][3370] 107374182400
2020-01-29 17:57:13,676 [salt.loaded.ext.module.maasng:645 ][INFO    ][3370] {u'hwe_kernel': None, u'swap_size': None, u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.39', u'192.168.11.41'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'is_default': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'boot_interface': {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'cckwne', 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'vendor': u'HP', u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'cckwne', 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'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'id': 25, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:95:a0', u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'cckwne', 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'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'pt7866', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/pt7866/interfaces/6/'}, u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'cpu_speed': 2800, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/pt7866/blockdevices/2/', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'id': 2, u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'pt7866', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'firmware_version': u'5.22', u'block_size': 4096, u'used_size': 800106479616, u'model': u'LOGICAL VOLUME', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'de8f6f18-c3cc-46d8-b384-368fa82feaa1', u'resource_uri': u'/MAAS/api/2.0/nodes/pt7866/blockdevices/2/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'pt7866', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'1d55c99d-24c6-4648-b3bf-6d07f1c62cb0', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'device_id': 2}]}, u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'description': u''}, u'resource_uri': u'/MAAS/api/2.0/machines/pt7866/', u'hardware_info': {u'mainboard_firmware_version': u'I31', u'mainboard_product': u'Unknown', u'system_vendor': u'HP', u'cpu_model': u'Intel(R) Xeon(R) CPU E5-2680 v2', u'mainboard_firmware_date': u'06/01/2015', u'mainboard_vendor': u'Unknown', u'system_serial': u'USE410T2XY', u'system_version': u'Unknown', u'system_product': u'ProLiant BL460c Gen8 (735151-B21)'}, u'hostname': u'cmp001', u'storage': 800109.715456, u'node_type': 0, u'testing_status': 2, u'system_id': u'pt7866', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/pt7866/blockdevices/10/', u'uuid': u'c869cbfa-98c9-4552-ae2c-5e8fee2db92a', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'bef4fb50-fdb5-49ad-82f3-dc493594382d', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'pt7866', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 10, u'serial': None, u'partitions': []}], u'blockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/pt7866/blockdevices/2/', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'de8f6f18-c3cc-46d8-b384-368fa82feaa1', u'resource_uri': u'/MAAS/api/2.0/nodes/pt7866/blockdevices/2/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'pt7866', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'1d55c99d-24c6-4648-b3bf-6d07f1c62cb0', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'device_id': 2}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'pt7866', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, {u'resource_uri': u'/MAAS/api/2.0/nodes/pt7866/blockdevices/10/', u'uuid': u'c869cbfa-98c9-4552-ae2c-5e8fee2db92a', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'bef4fb50-fdb5-49ad-82f3-dc493594382d', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'pt7866', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 10, u'serial': None, u'size': 107374182400}], u'status': 4, u'bcaches': [], u'cpu_count': 40, u'power_state': u'off', u'owner_data': {}, u'memory_test_status_name': u'Unknown', u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'pt7866', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'node_type_name': u'Machine', u'interface_set': [{u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'cckwne', 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'vendor': u'HP', u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'cckwne', 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'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'id': 25, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:95:a0', u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'cckwne', 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'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'pt7866', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/pt7866/interfaces/6/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': None, u'vendor': u'HP', u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:1f:d4', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'pt7866', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/pt7866/interfaces/15/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'cckwne', 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'vendor': u'HP', u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'cckwne', 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'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 26, u'mode': u'link_up'}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:95:a4', u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'cckwne', 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'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.41'}], u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'pt7866', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/pt7866/interfaces/16/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': None, u'vendor': u'HP', u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:1f:d5', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'pt7866', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/pt7866/interfaces/17/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {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'vendor': u'HP', u'name': u'ens2f0', u'links': [{u'id': 27, u'mode': u'link_up'}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:52:cc', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'pt7866', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/pt7866/interfaces/18/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {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'vendor': u'HP', u'name': u'ens2f1', u'links': [{u'id': 28, u'mode': u'link_up'}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:52:cd', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'pt7866', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/pt7866/interfaces/19/'}], u'current_testing_result_id': 7, u'cpu_test_status': -1, u'pool': {u'id': 0, u'resource_uri': u'/MAAS/api/2.0/resourcepool/0/', u'name': u'default', u'description': u'Default pool'}, u'architecture': u'amd64/generic', u'storage_test_status': 2, u'locked': False, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/pt7866/blockdevices/2/', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'id': 2, u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'pt7866', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'firmware_version': u'5.22', u'block_size': 4096, u'used_size': 800106479616, u'model': u'LOGICAL VOLUME', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'de8f6f18-c3cc-46d8-b384-368fa82feaa1', u'resource_uri': u'/MAAS/api/2.0/nodes/pt7866/blockdevices/2/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'pt7866', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'1d55c99d-24c6-4648-b3bf-6d07f1c62cb0', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'device_id': 2}]}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'disable_ipv4': False, u'commissioning_status': 2, u'min_hwe_kernel': u'ga-18.04', u'current_commissioning_result_id': 6, u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2020-01-29 17:57:13,680 [salt.state       :300 ][INFO    ][3370] {'new': {'storage_layout': 'lvm'}}
2020-01-29 17:57:13,680 [salt.state       :1951][INFO    ][3370] Completed state [maas_machines_storage_cmp001_lvm] at time 17:57:13.680494 duration_in_ms=2481.588
2020-01-29 17:57:13,685 [salt.minion      :1711][INFO    ][3370] Returning information for job: 20200129175658670390
2020-01-29 17:57:14,648 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command state.apply with jid 20200129175714632037
2020-01-29 17:57:14,672 [salt.minion      :1432][INFO    ][3394] Starting a new job with PID 3394
2020-01-29 17:57:15,800 [salt.state       :915 ][INFO    ][3394] Loading fresh modules for state activity
2020-01-29 17:57:15,871 [salt.fileclient  :1219][INFO    ][3394] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2020-01-29 17:57:15,931 [salt.state       :1780][INFO    ][3394] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 17:57:15.930931
2020-01-29 17:57:15,931 [salt.state       :1813][INFO    ][3394] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-01-29 17:57:15,934 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3394] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-01-29 17:57:18,132 [salt.state       :300 ][INFO    ][3394] {'pid': 3402, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-01-29 17:57:18,132 [salt.state       :1951][INFO    ][3394] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 17:57:18.132783 duration_in_ms=2201.855
2020-01-29 17:57:18,135 [salt.state       :1780][INFO    ][3394] Running state [maas.deploy_machines] at time 17:57:18.134986
2020-01-29 17:57:18,135 [salt.state       :1813][INFO    ][3394] Executing state module.run for [maas.deploy_machines]
2020-01-29 17:57:18,135 [salt.utils.decorators:613 ][WARNING ][3394] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-29 17:57:18,679 [salt.loaded.ext.module.maas:684 ][INFO    ][3394] deploymachines hwe_kernel=ga-18.04 system_id=dtaged distro_series=bionic
2020-01-29 17:57:20,630 [salt.loaded.ext.module.maas:684 ][INFO    ][3394] deploymachines hwe_kernel=ga-18.04 system_id=mx7bck distro_series=bionic
2020-01-29 17:57:22,574 [salt.loaded.ext.module.maas:684 ][INFO    ][3394] deploymachines hwe_kernel=ga-18.04 system_id=pt7866 distro_series=bionic
2020-01-29 17:57:24,517 [salt.loaded.ext.module.maas:684 ][INFO    ][3394] deploymachines hwe_kernel=ga-18.04 system_id=xr7kpy distro_series=bionic
2020-01-29 17:57:26,471 [salt.state       :300 ][INFO    ][3394] {'ret': {'updated': [], 'errors': {}, 'success': ['gtw01', 'cmp002', 'cmp001', 'ctl01']}}
2020-01-29 17:57:26,471 [salt.state       :1951][INFO    ][3394] Completed state [maas.deploy_machines] at time 17:57:26.471612 duration_in_ms=8336.625
2020-01-29 17:57:26,474 [salt.minion      :1711][INFO    ][3394] Returning information for job: 20200129175714632037
2020-01-29 17:57:27,423 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command state.apply with jid 20200129175727411282
2020-01-29 17:57:27,448 [salt.minion      :1432][INFO    ][3447] Starting a new job with PID 3447
2020-01-29 17:57:35,626 [salt.state       :915 ][INFO    ][3447] Loading fresh modules for state activity
2020-01-29 17:57:35,691 [salt.fileclient  :1219][INFO    ][3447] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2020-01-29 17:57:35,760 [salt.state       :1780][INFO    ][3447] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 17:57:35.760116
2020-01-29 17:57:35,760 [salt.state       :1813][INFO    ][3447] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-01-29 17:57:35,763 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3447] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-01-29 17:57:38,000 [salt.state       :300 ][INFO    ][3447] {'pid': 3459, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-01-29 17:57:38,002 [salt.state       :1951][INFO    ][3447] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 17:57:38.002062 duration_in_ms=2241.947
2020-01-29 17:57:38,005 [salt.state       :1780][INFO    ][3447] Running state [maas.wait_for_machine_status] at time 17:57:38.005395
2020-01-29 17:57:38,006 [salt.state       :1813][INFO    ][3447] Executing state module.run for [maas.wait_for_machine_status]
2020-01-29 17:57:38,007 [salt.utils.decorators:613 ][WARNING ][3447] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-29 17:57:40,288 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2247.73833299s left)
2020-01-29 17:57:42,490 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129175742475033
2020-01-29 17:57:42,518 [salt.minion      :1432][INFO    ][3468] Starting a new job with PID 3468
2020-01-29 17:57:42,540 [salt.minion      :1711][INFO    ][3468] Returning information for job: 20200129175742475033
2020-01-29 17:58:12,476 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2215.550493s left)
2020-01-29 17:58:12,571 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129175812554832
2020-01-29 17:58:12,596 [salt.minion      :1432][INFO    ][3492] Starting a new job with PID 3492
2020-01-29 17:58:12,621 [salt.minion      :1711][INFO    ][3492] Returning information for job: 20200129175812554832
2020-01-29 17:58:42,651 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129175842639683
2020-01-29 17:58:42,674 [salt.minion      :1432][INFO    ][3537] Starting a new job with PID 3537
2020-01-29 17:58:42,699 [salt.minion      :1711][INFO    ][3537] Returning information for job: 20200129175842639683
2020-01-29 17:58:44,873 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2183.15394497s left)
2020-01-29 17:59:12,745 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129175912728600
2020-01-29 17:59:12,771 [salt.minion      :1432][INFO    ][3563] Starting a new job with PID 3563
2020-01-29 17:59:12,801 [salt.minion      :1711][INFO    ][3563] Returning information for job: 20200129175912728600
2020-01-29 17:59:17,382 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2150.64490008s left)
2020-01-29 17:59:42,835 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129175942818648
2020-01-29 17:59:42,859 [salt.minion      :1432][INFO    ][3594] Starting a new job with PID 3594
2020-01-29 17:59:42,882 [salt.minion      :1711][INFO    ][3594] Returning information for job: 20200129175942818648
2020-01-29 17:59:49,663 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2118.36335301s left)
2020-01-29 18:00:12,888 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129180012870491
2020-01-29 18:00:12,916 [salt.minion      :1432][INFO    ][3628] Starting a new job with PID 3628
2020-01-29 18:00:12,943 [salt.minion      :1711][INFO    ][3628] Returning information for job: 20200129180012870491
2020-01-29 18:00:21,940 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2086.08628106s left)
2020-01-29 18:00:42,982 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129180042966667
2020-01-29 18:00:43,010 [salt.minion      :1432][INFO    ][3689] Starting a new job with PID 3689
2020-01-29 18:00:43,034 [salt.minion      :1711][INFO    ][3689] Returning information for job: 20200129180042966667
2020-01-29 18:00:54,376 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2053.65068197s left)
2020-01-29 18:01:13,071 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129180113060286
2020-01-29 18:01:13,100 [salt.minion      :1432][INFO    ][3740] Starting a new job with PID 3740
2020-01-29 18:01:13,120 [salt.minion      :1711][INFO    ][3740] Returning information for job: 20200129180113060286
2020-01-29 18:01:25,548 [salt.loaded.ext.module.maas:993 ][INFO    ][3447] Machine mx7bck mark broken
2020-01-29 18:01:43,144 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129180143135892
2020-01-29 18:01:43,162 [salt.minion      :1432][INFO    ][3792] Starting a new job with PID 3792
2020-01-29 18:01:43,186 [salt.minion      :1711][INFO    ][3792] Returning information for job: 20200129180143135892
2020-01-29 18:01:56,629 [salt.loaded.ext.module.maas:997 ][INFO    ][3447] Machine mx7bck mark fixed
2020-01-29 18:01:57,632 [salt.loaded.ext.module.maas:684 ][INFO    ][3447] deploymachines hwe_kernel=ga-18.04 system_id=mx7bck distro_series=bionic
2020-01-29 18:02:00,224 [salt.loaded.ext.module.maas:993 ][INFO    ][3447] Machine pt7866 mark broken
2020-01-29 18:02:13,204 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129180213195504
2020-01-29 18:02:13,223 [salt.minion      :1432][INFO    ][3852] Starting a new job with PID 3852
2020-01-29 18:02:13,248 [salt.minion      :1711][INFO    ][3852] Returning information for job: 20200129180213195504
2020-01-29 18:02:30,825 [salt.loaded.ext.module.maas:997 ][INFO    ][3447] Machine pt7866 mark fixed
2020-01-29 18:02:31,873 [salt.loaded.ext.module.maas:684 ][INFO    ][3447] deploymachines hwe_kernel=ga-18.04 system_id=pt7866 distro_series=bionic
2020-01-29 18:02:34,312 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1953.71507192s left)
2020-01-29 18:02:43,295 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129180243282992
2020-01-29 18:02:43,324 [salt.minion      :1432][INFO    ][3921] Starting a new job with PID 3921
2020-01-29 18:02:43,352 [salt.minion      :1711][INFO    ][3921] Returning information for job: 20200129180243282992
2020-01-29 18:03:06,674 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1921.35222006s left)
2020-01-29 18:03:13,413 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129180313398775
2020-01-29 18:03:13,442 [salt.minion      :1432][INFO    ][3947] Starting a new job with PID 3947
2020-01-29 18:03:13,471 [salt.minion      :1711][INFO    ][3947] Returning information for job: 20200129180313398775
2020-01-29 18:03:38,942 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1889.08428407s left)
2020-01-29 18:03:43,539 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129180343519548
2020-01-29 18:03:43,561 [salt.minion      :1432][INFO    ][3982] Starting a new job with PID 3982
2020-01-29 18:03:43,587 [salt.minion      :1711][INFO    ][3982] Returning information for job: 20200129180343519548
2020-01-29 18:04:11,189 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1856.83810711s left)
2020-01-29 18:04:13,644 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129180413631566
2020-01-29 18:04:13,672 [salt.minion      :1432][INFO    ][4007] Starting a new job with PID 4007
2020-01-29 18:04:13,699 [salt.minion      :1711][INFO    ][4007] Returning information for job: 20200129180413631566
2020-01-29 18:04:43,311 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1824.71575308s left)
2020-01-29 18:04:43,777 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129180443764331
2020-01-29 18:04:43,798 [salt.minion      :1432][INFO    ][4044] Starting a new job with PID 4044
2020-01-29 18:04:43,823 [salt.minion      :1711][INFO    ][4044] Returning information for job: 20200129180443764331
2020-01-29 18:05:13,911 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129180513898434
2020-01-29 18:05:13,934 [salt.minion      :1432][INFO    ][4084] Starting a new job with PID 4084
2020-01-29 18:05:13,966 [salt.minion      :1711][INFO    ][4084] Returning information for job: 20200129180513898434
2020-01-29 18:05:15,789 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1792.23728013s left)
2020-01-29 18:05:44,029 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129180544022137
2020-01-29 18:05:44,050 [salt.minion      :1432][INFO    ][4134] Starting a new job with PID 4134
2020-01-29 18:05:44,075 [salt.minion      :1711][INFO    ][4134] Returning information for job: 20200129180544022137
2020-01-29 18:05:48,070 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1759.9564631s left)
2020-01-29 18:06:14,184 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129180614173457
2020-01-29 18:06:14,211 [salt.minion      :1432][INFO    ][4188] Starting a new job with PID 4188
2020-01-29 18:06:14,235 [salt.minion      :1711][INFO    ][4188] Returning information for job: 20200129180614173457
2020-01-29 18:06:19,183 [salt.loaded.ext.module.maas:993 ][INFO    ][3447] Machine mx7bck mark broken
2020-01-29 18:06:44,363 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129180644349101
2020-01-29 18:06:44,387 [salt.minion      :1432][INFO    ][4224] Starting a new job with PID 4224
2020-01-29 18:06:44,425 [salt.minion      :1711][INFO    ][4224] Returning information for job: 20200129180644349101
2020-01-29 18:06:49,857 [salt.loaded.ext.module.maas:997 ][INFO    ][3447] Machine mx7bck mark fixed
2020-01-29 18:06:50,308 [salt.loaded.ext.module.maas:1001][INFO    ][3447] Machine mx7bck fio test
2020-01-29 18:06:52,196 [salt.loaded.ext.module.maas:993 ][INFO    ][3447] Machine pt7866 mark broken
2020-01-29 18:07:14,534 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129180714525067
2020-01-29 18:07:14,555 [salt.minion      :1432][INFO    ][4269] Starting a new job with PID 4269
2020-01-29 18:07:14,580 [salt.minion      :1711][INFO    ][4269] Returning information for job: 20200129180714525067
2020-01-29 18:07:22,835 [salt.loaded.ext.module.maas:997 ][INFO    ][3447] Machine pt7866 mark fixed
2020-01-29 18:07:23,391 [salt.loaded.ext.module.maas:1001][INFO    ][3447] Machine pt7866 fio test
2020-01-29 18:07:25,195 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1662.83160996s left)
2020-01-29 18:07:44,703 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129180744688886
2020-01-29 18:07:44,733 [salt.minion      :1432][INFO    ][4307] Starting a new job with PID 4307
2020-01-29 18:07:44,760 [salt.minion      :1711][INFO    ][4307] Returning information for job: 20200129180744688886
2020-01-29 18:07:57,122 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1630.90425706s left)
2020-01-29 18:08:14,891 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129180814878704
2020-01-29 18:08:14,915 [salt.minion      :1432][INFO    ][4337] Starting a new job with PID 4337
2020-01-29 18:08:14,940 [salt.minion      :1711][INFO    ][4337] Returning information for job: 20200129180814878704
2020-01-29 18:08:29,035 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1598.99128413s left)
2020-01-29 18:08:45,067 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129180845050778
2020-01-29 18:08:45,094 [salt.minion      :1432][INFO    ][4378] Starting a new job with PID 4378
2020-01-29 18:08:45,119 [salt.minion      :1711][INFO    ][4378] Returning information for job: 20200129180845050778
2020-01-29 18:09:01,060 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1566.96711493s left)
2020-01-29 18:09:15,252 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129180915238199
2020-01-29 18:09:15,280 [salt.minion      :1432][INFO    ][4403] Starting a new job with PID 4403
2020-01-29 18:09:15,308 [salt.minion      :1711][INFO    ][4403] Returning information for job: 20200129180915238199
2020-01-29 18:09:32,852 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1535.17476296s left)
2020-01-29 18:09:45,401 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129180945388098
2020-01-29 18:09:45,424 [salt.minion      :1432][INFO    ][4439] Starting a new job with PID 4439
2020-01-29 18:09:45,452 [salt.minion      :1711][INFO    ][4439] Returning information for job: 20200129180945388098
2020-01-29 18:10:04,882 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1503.14508414s left)
2020-01-29 18:10:15,599 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129181015587736
2020-01-29 18:10:15,625 [salt.minion      :1432][INFO    ][4470] Starting a new job with PID 4470
2020-01-29 18:10:15,648 [salt.minion      :1711][INFO    ][4470] Returning information for job: 20200129181015587736
2020-01-29 18:10:36,659 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1471.36780691s left)
2020-01-29 18:10:45,760 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129181045744672
2020-01-29 18:10:45,788 [salt.minion      :1432][INFO    ][4527] Starting a new job with PID 4527
2020-01-29 18:10:45,813 [salt.minion      :1711][INFO    ][4527] Returning information for job: 20200129181045744672
2020-01-29 18:11:08,611 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1439.41548014s left)
2020-01-29 18:11:15,907 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129181115891692
2020-01-29 18:11:15,935 [salt.minion      :1432][INFO    ][4576] Starting a new job with PID 4576
2020-01-29 18:11:15,960 [salt.minion      :1711][INFO    ][4576] Returning information for job: 20200129181115891692
2020-01-29 18:11:40,733 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1407.29397392s left)
2020-01-29 18:11:46,120 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129181146104739
2020-01-29 18:11:46,144 [salt.minion      :1432][INFO    ][4636] Starting a new job with PID 4636
2020-01-29 18:11:46,172 [salt.minion      :1711][INFO    ][4636] Returning information for job: 20200129181146104739
2020-01-29 18:12:12,877 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1375.15035892s left)
2020-01-29 18:12:16,351 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129181216334982
2020-01-29 18:12:16,379 [salt.minion      :1432][INFO    ][4661] Starting a new job with PID 4661
2020-01-29 18:12:16,406 [salt.minion      :1711][INFO    ][4661] Returning information for job: 20200129181216334982
2020-01-29 18:12:45,423 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1342.60406613s left)
2020-01-29 18:12:46,379 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129181246360995
2020-01-29 18:12:46,407 [salt.minion      :1432][INFO    ][4714] Starting a new job with PID 4714
2020-01-29 18:12:46,435 [salt.minion      :1711][INFO    ][4714] Returning information for job: 20200129181246360995
2020-01-29 18:13:16,442 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129181316427183
2020-01-29 18:13:16,467 [salt.minion      :1432][INFO    ][4739] Starting a new job with PID 4739
2020-01-29 18:13:16,496 [salt.minion      :1711][INFO    ][4739] Returning information for job: 20200129181316427183
2020-01-29 18:13:17,697 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1310.32984996s left)
2020-01-29 18:13:46,483 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129181346466799
2020-01-29 18:13:46,510 [salt.minion      :1432][INFO    ][4769] Starting a new job with PID 4769
2020-01-29 18:13:46,538 [salt.minion      :1711][INFO    ][4769] Returning information for job: 20200129181346466799
2020-01-29 18:13:50,094 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1277.93383098s left)
2020-01-29 18:14:16,562 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129181416551777
2020-01-29 18:14:16,593 [salt.minion      :1432][INFO    ][4795] Starting a new job with PID 4795
2020-01-29 18:14:16,620 [salt.minion      :1711][INFO    ][4795] Returning information for job: 20200129181416551777
2020-01-29 18:14:22,392 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1245.63501596s left)
2020-01-29 18:14:46,632 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129181446621010
2020-01-29 18:14:46,657 [salt.minion      :1432][INFO    ][4823] Starting a new job with PID 4823
2020-01-29 18:14:46,681 [salt.minion      :1711][INFO    ][4823] Returning information for job: 20200129181446621010
2020-01-29 18:14:54,679 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1213.3478241s left)
2020-01-29 18:15:16,704 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129181516690925
2020-01-29 18:15:16,731 [salt.minion      :1432][INFO    ][4850] Starting a new job with PID 4850
2020-01-29 18:15:16,772 [salt.minion      :1711][INFO    ][4850] Returning information for job: 20200129181516690925
2020-01-29 18:15:26,876 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1181.15031409s left)
2020-01-29 18:15:46,807 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129181546793677
2020-01-29 18:15:46,837 [salt.minion      :1432][INFO    ][4880] Starting a new job with PID 4880
2020-01-29 18:15:46,860 [salt.minion      :1711][INFO    ][4880] Returning information for job: 20200129181546793677
2020-01-29 18:15:59,305 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1148.72195792s left)
2020-01-29 18:16:16,908 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129181616891854
2020-01-29 18:16:16,935 [salt.minion      :1432][INFO    ][4907] Starting a new job with PID 4907
2020-01-29 18:16:16,957 [salt.minion      :1711][INFO    ][4907] Returning information for job: 20200129181616891854
2020-01-29 18:16:31,662 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1116.36455607s left)
2020-01-29 18:16:47,031 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129181647015522
2020-01-29 18:16:47,056 [salt.minion      :1432][INFO    ][4938] Starting a new job with PID 4938
2020-01-29 18:16:47,082 [salt.minion      :1711][INFO    ][4938] Returning information for job: 20200129181647015522
2020-01-29 18:17:03,951 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1084.07590413s left)
2020-01-29 18:17:17,160 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129181717150633
2020-01-29 18:17:17,184 [salt.minion      :1432][INFO    ][4968] Starting a new job with PID 4968
2020-01-29 18:17:17,208 [salt.minion      :1711][INFO    ][4968] Returning information for job: 20200129181717150633
2020-01-29 18:17:36,177 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1051.85008097s left)
2020-01-29 18:17:47,290 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129181747275372
2020-01-29 18:17:47,315 [salt.minion      :1432][INFO    ][4996] Starting a new job with PID 4996
2020-01-29 18:17:47,350 [salt.minion      :1711][INFO    ][4996] Returning information for job: 20200129181747275372
2020-01-29 18:18:08,435 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1019.59219503s left)
2020-01-29 18:18:17,444 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129181817428879
2020-01-29 18:18:17,472 [salt.minion      :1432][INFO    ][5030] Starting a new job with PID 5030
2020-01-29 18:18:17,498 [salt.minion      :1711][INFO    ][5030] Returning information for job: 20200129181817428879
2020-01-29 18:18:40,806 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (987.220785141s left)
2020-01-29 18:18:47,632 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129181847616417
2020-01-29 18:18:47,659 [salt.minion      :1432][INFO    ][5070] Starting a new job with PID 5070
2020-01-29 18:18:47,684 [salt.minion      :1711][INFO    ][5070] Returning information for job: 20200129181847616417
2020-01-29 18:19:13,083 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (954.943812132s left)
2020-01-29 18:19:17,821 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129181917809143
2020-01-29 18:19:17,847 [salt.minion      :1432][INFO    ][5096] Starting a new job with PID 5096
2020-01-29 18:19:17,874 [salt.minion      :1711][INFO    ][5096] Returning information for job: 20200129181917809143
2020-01-29 18:19:45,456 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (922.570747137s left)
2020-01-29 18:19:48,029 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129181948015323
2020-01-29 18:19:48,057 [salt.minion      :1432][INFO    ][5124] Starting a new job with PID 5124
2020-01-29 18:19:48,083 [salt.minion      :1711][INFO    ][5124] Returning information for job: 20200129181948015323
2020-01-29 18:20:17,590 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (890.43633008s left)
2020-01-29 18:20:18,228 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129182018215561
2020-01-29 18:20:18,255 [salt.minion      :1432][INFO    ][5154] Starting a new job with PID 5154
2020-01-29 18:20:18,286 [salt.minion      :1711][INFO    ][5154] Returning information for job: 20200129182018215561
2020-01-29 18:20:48,446 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129182048435707
2020-01-29 18:20:48,468 [salt.minion      :1432][INFO    ][5184] Starting a new job with PID 5184
2020-01-29 18:20:48,499 [salt.minion      :1711][INFO    ][5184] Returning information for job: 20200129182048435707
2020-01-29 18:20:49,654 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (858.372533083s left)
2020-01-29 18:21:18,672 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129182118658499
2020-01-29 18:21:18,700 [salt.minion      :1432][INFO    ][5214] Starting a new job with PID 5214
2020-01-29 18:21:18,731 [salt.minion      :1711][INFO    ][5214] Returning information for job: 20200129182118658499
2020-01-29 18:21:21,706 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (826.320734024s left)
2020-01-29 18:21:48,715 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129182148705088
2020-01-29 18:21:48,740 [salt.minion      :1432][INFO    ][5244] Starting a new job with PID 5244
2020-01-29 18:21:48,765 [salt.minion      :1711][INFO    ][5244] Returning information for job: 20200129182148705088
2020-01-29 18:21:53,843 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (794.183649063s left)
2020-01-29 18:22:18,768 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129182218760926
2020-01-29 18:22:18,794 [salt.minion      :1432][INFO    ][5271] Starting a new job with PID 5271
2020-01-29 18:22:18,818 [salt.minion      :1711][INFO    ][5271] Returning information for job: 20200129182218760926
2020-01-29 18:22:25,941 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (762.085793972s left)
2020-01-29 18:22:48,834 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129182248817902
2020-01-29 18:22:48,864 [salt.minion      :1432][INFO    ][5300] Starting a new job with PID 5300
2020-01-29 18:22:48,890 [salt.minion      :1711][INFO    ][5300] Returning information for job: 20200129182248817902
2020-01-29 18:22:58,084 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (729.943109035s left)
2020-01-29 18:23:18,962 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129182318946304
2020-01-29 18:23:18,992 [salt.minion      :1432][INFO    ][5329] Starting a new job with PID 5329
2020-01-29 18:23:19,018 [salt.minion      :1711][INFO    ][5329] Returning information for job: 20200129182318946304
2020-01-29 18:23:30,295 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (697.731441975s left)
2020-01-29 18:23:49,081 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129182349067828
2020-01-29 18:23:49,111 [salt.minion      :1432][INFO    ][5358] Starting a new job with PID 5358
2020-01-29 18:23:49,134 [salt.minion      :1711][INFO    ][5358] Returning information for job: 20200129182349067828
2020-01-29 18:24:02,519 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (665.507699013s left)
2020-01-29 18:24:19,210 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129182419196576
2020-01-29 18:24:19,237 [salt.minion      :1432][INFO    ][5387] Starting a new job with PID 5387
2020-01-29 18:24:19,260 [salt.minion      :1711][INFO    ][5387] Returning information for job: 20200129182419196576
2020-01-29 18:24:34,661 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (633.366039038s left)
2020-01-29 18:24:49,340 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129182449322731
2020-01-29 18:24:49,363 [salt.minion      :1432][INFO    ][5414] Starting a new job with PID 5414
2020-01-29 18:24:49,383 [salt.minion      :1711][INFO    ][5414] Returning information for job: 20200129182449322731
2020-01-29 18:25:06,762 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (601.264287949s left)
2020-01-29 18:25:19,511 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129182519500961
2020-01-29 18:25:19,539 [salt.minion      :1432][INFO    ][5440] Starting a new job with PID 5440
2020-01-29 18:25:19,563 [salt.minion      :1711][INFO    ][5440] Returning information for job: 20200129182519500961
2020-01-29 18:25:38,827 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (569.199962139s left)
2020-01-29 18:25:49,680 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129182549668622
2020-01-29 18:25:49,707 [salt.minion      :1432][INFO    ][5468] Starting a new job with PID 5468
2020-01-29 18:25:49,730 [salt.minion      :1711][INFO    ][5468] Returning information for job: 20200129182549668622
2020-01-29 18:26:10,915 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (537.111752033s left)
2020-01-29 18:26:19,896 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129182619881217
2020-01-29 18:26:19,923 [salt.minion      :1432][INFO    ][5494] Starting a new job with PID 5494
2020-01-29 18:26:19,948 [salt.minion      :1711][INFO    ][5494] Returning information for job: 20200129182619881217
2020-01-29 18:26:43,048 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (504.97912693s left)
2020-01-29 18:26:50,098 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129182650084313
2020-01-29 18:26:50,125 [salt.minion      :1432][INFO    ][5521] Starting a new job with PID 5521
2020-01-29 18:26:50,148 [salt.minion      :1711][INFO    ][5521] Returning information for job: 20200129182650084313
2020-01-29 18:27:15,331 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (472.695569992s left)
2020-01-29 18:27:20,126 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129182720114208
2020-01-29 18:27:20,156 [salt.minion      :1432][INFO    ][5547] Starting a new job with PID 5547
2020-01-29 18:27:20,182 [salt.minion      :1711][INFO    ][5547] Returning information for job: 20200129182720114208
2020-01-29 18:27:47,380 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (440.646678925s left)
2020-01-29 18:27:50,173 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129182750160169
2020-01-29 18:27:50,201 [salt.minion      :1432][INFO    ][5574] Starting a new job with PID 5574
2020-01-29 18:27:50,224 [salt.minion      :1711][INFO    ][5574] Returning information for job: 20200129182750160169
2020-01-29 18:28:19,517 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (408.509912014s left)
2020-01-29 18:28:20,230 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129182820214597
2020-01-29 18:28:20,258 [salt.minion      :1432][INFO    ][5610] Starting a new job with PID 5610
2020-01-29 18:28:20,284 [salt.minion      :1711][INFO    ][5610] Returning information for job: 20200129182820214597
2020-01-29 18:28:50,332 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129182850318454
2020-01-29 18:28:50,353 [salt.minion      :1432][INFO    ][5647] Starting a new job with PID 5647
2020-01-29 18:28:50,377 [salt.minion      :1711][INFO    ][5647] Returning information for job: 20200129182850318454
2020-01-29 18:28:51,563 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (376.463208914s left)
2020-01-29 18:29:20,451 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129182920435739
2020-01-29 18:29:20,479 [salt.minion      :1432][INFO    ][5674] Starting a new job with PID 5674
2020-01-29 18:29:20,507 [salt.minion      :1711][INFO    ][5674] Returning information for job: 20200129182920435739
2020-01-29 18:29:23,837 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (344.190145016s left)
2020-01-29 18:29:50,578 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129182950566441
2020-01-29 18:29:50,601 [salt.minion      :1432][INFO    ][5703] Starting a new job with PID 5703
2020-01-29 18:29:50,626 [salt.minion      :1711][INFO    ][5703] Returning information for job: 20200129182950566441
2020-01-29 18:29:56,018 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (312.00911808s left)
2020-01-29 18:30:20,714 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129183020698425
2020-01-29 18:30:20,744 [salt.minion      :1432][INFO    ][5731] Starting a new job with PID 5731
2020-01-29 18:30:20,767 [salt.minion      :1711][INFO    ][5731] Returning information for job: 20200129183020698425
2020-01-29 18:30:28,038 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (279.988413095s left)
2020-01-29 18:30:50,889 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129183050871947
2020-01-29 18:30:50,918 [salt.minion      :1432][INFO    ][5759] Starting a new job with PID 5759
2020-01-29 18:30:50,944 [salt.minion      :1711][INFO    ][5759] Returning information for job: 20200129183050871947
2020-01-29 18:31:00,140 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (247.887155056s left)
2020-01-29 18:31:21,040 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129183121026468
2020-01-29 18:31:21,067 [salt.minion      :1432][INFO    ][5786] Starting a new job with PID 5786
2020-01-29 18:31:21,092 [salt.minion      :1711][INFO    ][5786] Returning information for job: 20200129183121026468
2020-01-29 18:31:32,323 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (215.704008102s left)
2020-01-29 18:31:51,272 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129183151255729
2020-01-29 18:31:51,298 [salt.minion      :1432][INFO    ][5812] Starting a new job with PID 5812
2020-01-29 18:31:51,324 [salt.minion      :1711][INFO    ][5812] Returning information for job: 20200129183151255729
2020-01-29 18:32:04,420 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (183.606961966s left)
2020-01-29 18:32:21,436 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129183221422572
2020-01-29 18:32:21,462 [salt.minion      :1432][INFO    ][5838] Starting a new job with PID 5838
2020-01-29 18:32:21,485 [salt.minion      :1711][INFO    ][5838] Returning information for job: 20200129183221422572
2020-01-29 18:32:36,506 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (151.520537138s left)
2020-01-29 18:32:51,663 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129183251643270
2020-01-29 18:32:51,691 [salt.minion      :1432][INFO    ][5866] Starting a new job with PID 5866
2020-01-29 18:32:51,720 [salt.minion      :1711][INFO    ][5866] Returning information for job: 20200129183251643270
2020-01-29 18:33:08,639 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (119.388189077s left)
2020-01-29 18:33:21,716 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129183321698614
2020-01-29 18:33:21,742 [salt.minion      :1432][INFO    ][5892] Starting a new job with PID 5892
2020-01-29 18:33:21,769 [salt.minion      :1711][INFO    ][5892] Returning information for job: 20200129183321698614
2020-01-29 18:33:40,852 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (87.1744699478s left)
2020-01-29 18:33:51,806 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129183351790177
2020-01-29 18:33:51,835 [salt.minion      :1432][INFO    ][5921] Starting a new job with PID 5921
2020-01-29 18:33:51,862 [salt.minion      :1711][INFO    ][5921] Returning information for job: 20200129183351790177
2020-01-29 18:34:12,952 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (55.0743319988s left)
2020-01-29 18:34:21,876 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129183421864489
2020-01-29 18:34:21,903 [salt.minion      :1432][INFO    ][5947] Starting a new job with PID 5947
2020-01-29 18:34:21,931 [salt.minion      :1711][INFO    ][5947] Returning information for job: 20200129183421864489
2020-01-29 18:34:45,310 [salt.loaded.ext.module.maas:1024][INFO    ][3447] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (22.7166769505s left)
2020-01-29 18:34:51,969 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129183451955036
2020-01-29 18:34:51,994 [salt.minion      :1432][INFO    ][5977] Starting a new job with PID 5977
2020-01-29 18:34:52,019 [salt.minion      :1711][INFO    ][5977] Returning information for job: 20200129183451955036
2020-01-29 18:35:17,284 [salt.state       :302 ][ERROR   ][3447] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['cmp002', 'cmp001']not in Deployed state
2020-01-29 18:35:17,286 [salt.state       :1951][INFO    ][3447] Completed state [maas.wait_for_machine_status] at time 18:35:17.286847 duration_in_ms=2259281.45
2020-01-29 18:35:17,292 [salt.minion      :1711][INFO    ][3447] Returning information for job: 20200129175727411282
2020-01-29 18:35:28,524 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command pillar.get with jid 20200129183528508136
2020-01-29 18:35:28,552 [salt.minion      :1432][INFO    ][6019] Starting a new job with PID 6019
2020-01-29 18:35:28,568 [salt.minion      :1711][INFO    ][6019] Returning information for job: 20200129183528508136
2020-01-29 18:35:29,507 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command service.status with jid 20200129183529493254
2020-01-29 18:35:29,540 [salt.minion      :1432][INFO    ][6024] Starting a new job with PID 6024
2020-01-29 18:35:30,329 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][6024] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2020-01-29 18:35:30,381 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][6024] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2020-01-29 18:35:30,407 [salt.minion      :1711][INFO    ][6024] Returning information for job: 20200129183529493254
2020-01-29 18:35:31,343 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command state.apply with jid 20200129183531330287
2020-01-29 18:35:31,372 [salt.minion      :1432][INFO    ][6035] Starting a new job with PID 6035
2020-01-29 18:35:39,319 [salt.state       :915 ][INFO    ][6035] Loading fresh modules for state activity
2020-01-29 18:35:39,953 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6035] Executing command 'salt-minion --version' in directory '/root'
2020-01-29 18:35:40,331 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6035] Executing command 'salt-minion --version' in directory '/root'
2020-01-29 18:35:41,456 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6035] Executing command 'salt-minion --version' in directory '/root'
2020-01-29 18:35:41,857 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6035] Executing command 'salt-minion --version' in directory '/root'
2020-01-29 18:35:44,028 [salt.state       :1780][INFO    ][6035] Running state [salt-minion] at time 18:35:44.028215
2020-01-29 18:35:44,028 [salt.state       :1813][INFO    ][6035] Executing state pkg.installed for [salt-minion]
2020-01-29 18:35:44,029 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6035] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2020-01-29 18:35:44,146 [salt.state       :300 ][INFO    ][6035] All specified packages are already installed
2020-01-29 18:35:44,146 [salt.state       :1951][INFO    ][6035] Completed state [salt-minion] at time 18:35:44.146815 duration_in_ms=118.6
2020-01-29 18:35:44,147 [salt.state       :1780][INFO    ][6035] Running state [salt_minion_dependency_packages] at time 18:35:44.147161
2020-01-29 18:35:44,147 [salt.state       :1813][INFO    ][6035] Executing state pkg.installed for [salt_minion_dependency_packages]
2020-01-29 18:35:44,156 [salt.state       :300 ][INFO    ][6035] All specified packages are already installed
2020-01-29 18:35:44,157 [salt.state       :1951][INFO    ][6035] Completed state [salt_minion_dependency_packages] at time 18:35:44.157135 duration_in_ms=9.974
2020-01-29 18:35:44,160 [salt.state       :1780][INFO    ][6035] Running state [/etc/salt/minion.d/minion.conf] at time 18:35:44.160692
2020-01-29 18:35:44,161 [salt.state       :1813][INFO    ][6035] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2020-01-29 18:35:44,494 [salt.state       :300 ][INFO    ][6035] File /etc/salt/minion.d/minion.conf is in the correct state
2020-01-29 18:35:44,494 [salt.state       :1951][INFO    ][6035] Completed state [/etc/salt/minion.d/minion.conf] at time 18:35:44.494433 duration_in_ms=333.741
2020-01-29 18:35:44,498 [salt.state       :1780][INFO    ][6035] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 18:35:44.498422
2020-01-29 18:35:44,498 [salt.state       :1813][INFO    ][6035] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2020-01-29 18:35:44,512 [salt.state       :300 ][INFO    ][6035] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2020-01-29 18:35:44,513 [salt.state       :1951][INFO    ][6035] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 18:35:44.513029 duration_in_ms=14.607
2020-01-29 18:35:44,516 [salt.state       :1780][INFO    ][6035] Running state [salt-minion] at time 18:35:44.515971
2020-01-29 18:35:44,516 [salt.state       :1813][INFO    ][6035] Executing state service.running for [salt-minion]
2020-01-29 18:35:44,517 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6035] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2020-01-29 18:35:44,565 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6035] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2020-01-29 18:35:44,590 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6035] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2020-01-29 18:35:44,616 [salt.state       :300 ][INFO    ][6035] The service salt-minion is already running
2020-01-29 18:35:44,617 [salt.state       :1951][INFO    ][6035] Completed state [salt-minion] at time 18:35:44.616901 duration_in_ms=100.929
2020-01-29 18:35:44,620 [salt.state       :1780][INFO    ][6035] Running state [/etc/salt/grains.d] at time 18:35:44.620111
2020-01-29 18:35:44,620 [salt.state       :1813][INFO    ][6035] Executing state file.directory for [/etc/salt/grains.d]
2020-01-29 18:35:44,624 [salt.state       :300 ][INFO    ][6035] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2020-01-29 18:35:44,624 [salt.state       :1951][INFO    ][6035] Completed state [/etc/salt/grains.d] at time 18:35:44.624402 duration_in_ms=4.291
2020-01-29 18:35:44,625 [salt.state       :1780][INFO    ][6035] Running state [/etc/salt/grains] at time 18:35:44.625325
2020-01-29 18:35:44,626 [salt.state       :1813][INFO    ][6035] Executing state file.managed for [/etc/salt/grains]
2020-01-29 18:35:44,626 [salt.state       :300 ][INFO    ][6035] File /etc/salt/grains exists with proper permissions. No changes made.
2020-01-29 18:35:44,627 [salt.state       :1951][INFO    ][6035] Completed state [/etc/salt/grains] at time 18:35:44.627105 duration_in_ms=1.781
2020-01-29 18:35:44,627 [salt.state       :1780][INFO    ][6035] Running state [/etc/salt/grains.d/placeholder] at time 18:35:44.627773
2020-01-29 18:35:44,628 [salt.state       :1813][INFO    ][6035] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2020-01-29 18:35:44,628 [salt.state       :300 ][INFO    ][6035] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2020-01-29 18:35:44,629 [salt.state       :1951][INFO    ][6035] Completed state [/etc/salt/grains.d/placeholder] at time 18:35:44.629176 duration_in_ms=1.403
2020-01-29 18:35:44,630 [salt.state       :1780][INFO    ][6035] Running state [/etc/salt/grains.d/sphinx] at time 18:35:44.630202
2020-01-29 18:35:44,630 [salt.state       :1813][INFO    ][6035] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2020-01-29 18:35:44,632 [salt.state       :300 ][INFO    ][6035] File /etc/salt/grains.d/sphinx is in the correct state
2020-01-29 18:35:44,632 [salt.state       :1951][INFO    ][6035] Completed state [/etc/salt/grains.d/sphinx] at time 18:35:44.632542 duration_in_ms=2.34
2020-01-29 18:35:44,636 [salt.state       :1780][INFO    ][6035] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 18:35:44.636252
2020-01-29 18:35:44,636 [salt.state       :1813][INFO    ][6035] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2020-01-29 18:35:44,636 [salt.state       :300 ][INFO    ][6035] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2020-01-29 18:35:44,637 [salt.state       :1951][INFO    ][6035] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 18:35:44.637161 duration_in_ms=0.908
2020-01-29 18:35:44,638 [salt.state       :1780][INFO    ][6035] Running state [/etc/salt/grains.d/dns_records] at time 18:35:44.638458
2020-01-29 18:35:44,639 [salt.state       :1813][INFO    ][6035] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2020-01-29 18:35:44,641 [salt.state       :300 ][INFO    ][6035] File /etc/salt/grains.d/dns_records is in the correct state
2020-01-29 18:35:44,641 [salt.state       :1951][INFO    ][6035] Completed state [/etc/salt/grains.d/dns_records] at time 18:35:44.641389 duration_in_ms=2.932
2020-01-29 18:35:44,643 [salt.state       :1780][INFO    ][6035] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 18:35:44.642947
2020-01-29 18:35:44,643 [salt.state       :1813][INFO    ][6035] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2020-01-29 18:35:44,643 [salt.state       :300 ][INFO    ][6035] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2020-01-29 18:35:44,644 [salt.state       :1951][INFO    ][6035] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 18:35:44.644297 duration_in_ms=1.35
2020-01-29 18:35:44,645 [salt.state       :1780][INFO    ][6035] Running state [/etc/salt/grains.d/salt] at time 18:35:44.645023
2020-01-29 18:35:44,646 [salt.state       :1813][INFO    ][6035] Executing state file.managed for [/etc/salt/grains.d/salt]
2020-01-29 18:35:44,648 [salt.state       :300 ][INFO    ][6035] File /etc/salt/grains.d/salt is in the correct state
2020-01-29 18:35:44,648 [salt.state       :1951][INFO    ][6035] Completed state [/etc/salt/grains.d/salt] at time 18:35:44.648550 duration_in_ms=3.527
2020-01-29 18:35:44,650 [salt.state       :1780][INFO    ][6035] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 18:35:44.650734
2020-01-29 18:35:44,651 [salt.state       :1813][INFO    ][6035] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2020-01-29 18:35:44,651 [salt.state       :300 ][INFO    ][6035] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2020-01-29 18:35:44,651 [salt.state       :1951][INFO    ][6035] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 18:35:44.651611 duration_in_ms=0.877
2020-01-29 18:35:44,653 [salt.state       :1780][INFO    ][6035] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 18:35:44.653657
2020-01-29 18:35:44,653 [salt.state       :1813][INFO    ][6035] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2020-01-29 18:35:44,654 [salt.state       :300 ][INFO    ][6035] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2020-01-29 18:35:44,654 [salt.state       :1951][INFO    ][6035] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 18:35:44.654559 duration_in_ms=0.902
2020-01-29 18:35:44,655 [salt.state       :1780][INFO    ][6035] Running state [mine.update] at time 18:35:44.655261
2020-01-29 18:35:44,655 [salt.state       :1813][INFO    ][6035] Executing state module.wait for [mine.update]
2020-01-29 18:35:44,655 [salt.state       :300 ][INFO    ][6035] No changes made for mine.update
2020-01-29 18:35:44,656 [salt.state       :1951][INFO    ][6035] Completed state [mine.update] at time 18:35:44.656068 duration_in_ms=0.807
2020-01-29 18:35:44,656 [salt.state       :1780][INFO    ][6035] Running state [ca-certificates] at time 18:35:44.656370
2020-01-29 18:35:44,656 [salt.state       :1813][INFO    ][6035] Executing state pkg.installed for [ca-certificates]
2020-01-29 18:35:44,667 [salt.state       :300 ][INFO    ][6035] All specified packages are already installed
2020-01-29 18:35:44,667 [salt.state       :1951][INFO    ][6035] Completed state [ca-certificates] at time 18:35:44.667749 duration_in_ms=11.379
2020-01-29 18:35:44,668 [salt.state       :1780][INFO    ][6035] Running state [update-ca-certificates] at time 18:35:44.668729
2020-01-29 18:35:44,669 [salt.state       :1813][INFO    ][6035] Executing state cmd.wait for [update-ca-certificates]
2020-01-29 18:35:44,669 [salt.state       :300 ][INFO    ][6035] No changes made for update-ca-certificates
2020-01-29 18:35:44,670 [salt.state       :1951][INFO    ][6035] Completed state [update-ca-certificates] at time 18:35:44.670216 duration_in_ms=1.487
2020-01-29 18:35:44,670 [salt.state       :1780][INFO    ][6035] Running state [iptables] at time 18:35:44.670537
2020-01-29 18:35:44,670 [salt.state       :1813][INFO    ][6035] Executing state pkg.installed for [iptables]
2020-01-29 18:35:44,679 [salt.state       :300 ][INFO    ][6035] All specified packages are already installed
2020-01-29 18:35:44,680 [salt.state       :1951][INFO    ][6035] Completed state [iptables] at time 18:35:44.679938 duration_in_ms=9.401
2020-01-29 18:35:44,680 [salt.state       :1780][INFO    ][6035] Running state [iptables-persistent] at time 18:35:44.680252
2020-01-29 18:35:44,680 [salt.state       :1813][INFO    ][6035] Executing state pkg.installed for [iptables-persistent]
2020-01-29 18:35:44,690 [salt.state       :300 ][INFO    ][6035] All specified packages are already installed
2020-01-29 18:35:44,690 [salt.state       :1951][INFO    ][6035] Completed state [iptables-persistent] at time 18:35:44.690319 duration_in_ms=10.067
2020-01-29 18:35:44,691 [salt.state       :1780][INFO    ][6035] Running state [iptables_modules_v4_load] at time 18:35:44.691403
2020-01-29 18:35:44,691 [salt.state       :1813][INFO    ][6035] Executing state kmod.present for [iptables_modules_v4_load]
2020-01-29 18:35:44,692 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6035] Executing command 'lsmod' in directory '/root'
2020-01-29 18:35:44,715 [salt.state       :300 ][INFO    ][6035] Kernel modules iptable_filter, ip_tables are already present
2020-01-29 18:35:44,716 [salt.state       :1951][INFO    ][6035] Completed state [iptables_modules_v4_load] at time 18:35:44.715943 duration_in_ms=24.538
2020-01-29 18:35:44,717 [salt.state       :1780][INFO    ][6035] Running state [/etc/iptables/rules.v4] at time 18:35:44.717099
2020-01-29 18:35:44,719 [salt.state       :1813][INFO    ][6035] Executing state file.managed for [/etc/iptables/rules.v4]
2020-01-29 18:35:44,840 [salt.state       :300 ][INFO    ][6035] File /etc/iptables/rules.v4 is in the correct state
2020-01-29 18:35:44,840 [salt.state       :1951][INFO    ][6035] Completed state [/etc/iptables/rules.v4] at time 18:35:44.840422 duration_in_ms=123.324
2020-01-29 18:35:44,842 [salt.state       :1780][INFO    ][6035] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 18:35:44.842179
2020-01-29 18:35:44,842 [salt.state       :1813][INFO    ][6035] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2020-01-29 18:35:44,843 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6035] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2020-01-29 18:35:44,865 [salt.state       :300 ][INFO    ][6035] onlyif execution failed
2020-01-29 18:35:44,866 [salt.state       :1951][INFO    ][6035] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 18:35:44.866375 duration_in_ms=24.195
2020-01-29 18:35:44,867 [salt.state       :1780][INFO    ][6035] Running state [netfilter-persistent] at time 18:35:44.867882
2020-01-29 18:35:44,868 [salt.state       :1813][INFO    ][6035] Executing state service.running for [netfilter-persistent]
2020-01-29 18:35:44,869 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6035] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2020-01-29 18:35:44,902 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6035] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2020-01-29 18:35:44,929 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6035] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2020-01-29 18:35:44,954 [salt.state       :300 ][INFO    ][6035] The service netfilter-persistent is already running
2020-01-29 18:35:44,955 [salt.state       :1951][INFO    ][6035] Completed state [netfilter-persistent] at time 18:35:44.955132 duration_in_ms=87.248
2020-01-29 18:35:44,957 [salt.state       :1780][INFO    ][6035] Running state [iptables_extra.remove_stale_tables] at time 18:35:44.956981
2020-01-29 18:35:44,958 [salt.state       :1813][INFO    ][6035] Executing state module.wait for [iptables_extra.remove_stale_tables]
2020-01-29 18:35:44,958 [salt.state       :300 ][INFO    ][6035] No changes made for iptables_extra.remove_stale_tables
2020-01-29 18:35:44,959 [salt.state       :1951][INFO    ][6035] Completed state [iptables_extra.remove_stale_tables] at time 18:35:44.959267 duration_in_ms=2.286
2020-01-29 18:35:44,959 [salt.state       :1780][INFO    ][6035] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 18:35:44.959842
2020-01-29 18:35:44,960 [salt.state       :1813][INFO    ][6035] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2020-01-29 18:35:44,963 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6035] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2020-01-29 18:35:44,983 [salt.state       :300 ][INFO    ][6035] onlyif execution failed
2020-01-29 18:35:44,984 [salt.state       :1951][INFO    ][6035] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 18:35:44.984537 duration_in_ms=24.695
2020-01-29 18:35:44,987 [salt.state       :1780][INFO    ][6035] Running state [/etc/iptables/rules.v6] at time 18:35:44.986945
2020-01-29 18:35:44,987 [salt.state       :1813][INFO    ][6035] Executing state file.absent for [/etc/iptables/rules.v6]
2020-01-29 18:35:44,988 [salt.state       :300 ][INFO    ][6035] File /etc/iptables/rules.v6 is not present
2020-01-29 18:35:44,989 [salt.state       :1951][INFO    ][6035] Completed state [/etc/iptables/rules.v6] at time 18:35:44.989040 duration_in_ms=2.095
2020-01-29 18:35:44,990 [salt.state       :1780][INFO    ][6035] Running state [iptables_extra.flush_all] at time 18:35:44.990513
2020-01-29 18:35:44,991 [salt.state       :1813][INFO    ][6035] Executing state module.wait for [iptables_extra.flush_all]
2020-01-29 18:35:44,991 [salt.state       :300 ][INFO    ][6035] No changes made for iptables_extra.flush_all
2020-01-29 18:35:44,992 [salt.state       :1951][INFO    ][6035] Completed state [iptables_extra.flush_all] at time 18:35:44.992057 duration_in_ms=1.545
2020-01-29 18:35:44,997 [salt.minion      :1711][INFO    ][6035] Returning information for job: 20200129183531330287
2020-01-29 18:35:45,931 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command state.apply with jid 20200129183545916518
2020-01-29 18:35:45,957 [salt.minion      :1432][INFO    ][6117] Starting a new job with PID 6117
2020-01-29 18:35:46,982 [salt.state       :915 ][INFO    ][6117] Loading fresh modules for state activity
2020-01-29 18:35:48,241 [salt.state       :1780][INFO    ][6117] Running state [maas-rack-controller] at time 18:35:48.241747
2020-01-29 18:35:48,242 [salt.state       :1813][INFO    ][6117] Executing state pkg.installed for [maas-rack-controller]
2020-01-29 18:35:48,242 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6117] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2020-01-29 18:35:48,366 [salt.state       :300 ][INFO    ][6117] All specified packages are already installed
2020-01-29 18:35:48,366 [salt.state       :1951][INFO    ][6117] Completed state [maas-rack-controller] at time 18:35:48.366563 duration_in_ms=124.817
2020-01-29 18:35:48,367 [salt.state       :1780][INFO    ][6117] Running state [ipmitool] at time 18:35:48.367315
2020-01-29 18:35:48,368 [salt.state       :1813][INFO    ][6117] Executing state pkg.installed for [ipmitool]
2020-01-29 18:35:48,383 [salt.state       :300 ][INFO    ][6117] All specified packages are already installed
2020-01-29 18:35:48,383 [salt.state       :1951][INFO    ][6117] Completed state [ipmitool] at time 18:35:48.383880 duration_in_ms=16.567
2020-01-29 18:35:48,387 [salt.state       :1780][INFO    ][6117] Running state [/etc/maas/rackd.conf] at time 18:35:48.387882
2020-01-29 18:35:48,388 [salt.state       :1813][INFO    ][6117] Executing state file.line for [/etc/maas/rackd.conf]
2020-01-29 18:35:48,389 [salt.state       :300 ][INFO    ][6117] No changes needed to be made
2020-01-29 18:35:48,389 [salt.state       :1951][INFO    ][6117] Completed state [/etc/maas/rackd.conf] at time 18:35:48.389772 duration_in_ms=1.89
2020-01-29 18:35:48,390 [salt.state       :1780][INFO    ][6117] Running state [/etc/maas/rackd.conf] at time 18:35:48.390072
2020-01-29 18:35:48,390 [salt.state       :1813][INFO    ][6117] Executing state file.managed for [/etc/maas/rackd.conf]
2020-01-29 18:35:48,390 [salt.loaded.int.states.file:2298][WARNING ][6117] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2020-01-29 18:35:48,391 [salt.state       :300 ][INFO    ][6117] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2020-01-29 18:35:48,391 [salt.state       :1951][INFO    ][6117] Completed state [/etc/maas/rackd.conf] at time 18:35:48.391589 duration_in_ms=1.517
2020-01-29 18:35:48,392 [salt.state       :1780][INFO    ][6117] Running state [maas-rackd] at time 18:35:48.392817
2020-01-29 18:35:48,393 [salt.state       :1813][INFO    ][6117] Executing state service.running for [maas-rackd]
2020-01-29 18:35:48,395 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6117] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2020-01-29 18:35:48,441 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6117] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2020-01-29 18:35:48,464 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6117] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2020-01-29 18:35:48,491 [salt.state       :300 ][INFO    ][6117] The service maas-rackd is already running
2020-01-29 18:35:48,492 [salt.state       :1951][INFO    ][6117] Completed state [maas-rackd] at time 18:35:48.492469 duration_in_ms=99.651
2020-01-29 18:35:48,495 [salt.minion      :1711][INFO    ][6117] Returning information for job: 20200129183545916518
2020-01-29 18:35:49,427 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command state.apply with jid 20200129183549411898
2020-01-29 18:35:49,456 [salt.minion      :1432][INFO    ][6140] Starting a new job with PID 6140
2020-01-29 18:35:50,566 [salt.state       :915 ][INFO    ][6140] Loading fresh modules for state activity
2020-01-29 18:35:51,887 [salt.state       :1780][INFO    ][6140] Running state [maas-region-controller] at time 18:35:51.887335
2020-01-29 18:35:51,887 [salt.state       :1813][INFO    ][6140] Executing state pkg.installed for [maas-region-controller]
2020-01-29 18:35:51,888 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6140] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2020-01-29 18:35:52,014 [salt.state       :300 ][INFO    ][6140] All specified packages are already installed
2020-01-29 18:35:52,014 [salt.state       :1951][INFO    ][6140] Completed state [maas-region-controller] at time 18:35:52.014773 duration_in_ms=127.439
2020-01-29 18:35:52,015 [salt.state       :1780][INFO    ][6140] Running state [python-oauth] at time 18:35:52.015447
2020-01-29 18:35:52,015 [salt.state       :1813][INFO    ][6140] Executing state pkg.installed for [python-oauth]
2020-01-29 18:35:52,035 [salt.state       :300 ][INFO    ][6140] All specified packages are already installed
2020-01-29 18:35:52,035 [salt.state       :1951][INFO    ][6140] Completed state [python-oauth] at time 18:35:52.035501 duration_in_ms=20.053
2020-01-29 18:35:52,040 [salt.state       :1780][INFO    ][6140] Running state [/etc/maas/regiond.conf] at time 18:35:52.040205
2020-01-29 18:35:52,040 [salt.state       :1813][INFO    ][6140] Executing state file.replace for [/etc/maas/regiond.conf]
2020-01-29 18:35:52,048 [salt.state       :300 ][INFO    ][6140] No changes needed to be made
2020-01-29 18:35:52,048 [salt.state       :1951][INFO    ][6140] Completed state [/etc/maas/regiond.conf] at time 18:35:52.048314 duration_in_ms=8.109
2020-01-29 18:35:52,048 [salt.state       :1780][INFO    ][6140] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 18:35:52.048832
2020-01-29 18:35:52,049 [salt.state       :1813][INFO    ][6140] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2020-01-29 18:35:52,121 [salt.state       :300 ][INFO    ][6140] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2020-01-29 18:35:52,121 [salt.state       :1951][INFO    ][6140] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 18:35:52.121680 duration_in_ms=72.848
2020-01-29 18:35:52,122 [salt.state       :1780][INFO    ][6140] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 18:35:52.122209
2020-01-29 18:35:52,122 [salt.state       :1813][INFO    ][6140] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2020-01-29 18:35:52,127 [salt.state       :300 ][INFO    ][6140] No changes needed to be made
2020-01-29 18:35:52,127 [salt.state       :1951][INFO    ][6140] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 18:35:52.127495 duration_in_ms=5.286
2020-01-29 18:35:52,128 [salt.state       :1780][INFO    ][6140] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 18:35:52.128018
2020-01-29 18:35:52,128 [salt.state       :1813][INFO    ][6140] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2020-01-29 18:35:52,132 [salt.state       :300 ][INFO    ][6140] No changes needed to be made
2020-01-29 18:35:52,132 [salt.state       :1951][INFO    ][6140] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 18:35:52.132793 duration_in_ms=4.775
2020-01-29 18:35:52,134 [salt.state       :1780][INFO    ][6140] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 18:35:52.133944
2020-01-29 18:35:52,134 [salt.state       :1813][INFO    ][6140] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2020-01-29 18:35:52,164 [salt.state       :300 ][INFO    ][6140] No changes needed to be made
2020-01-29 18:35:52,165 [salt.state       :1951][INFO    ][6140] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 18:35:52.165169 duration_in_ms=31.224
2020-01-29 18:35:52,165 [salt.state       :1780][INFO    ][6140] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 18:35:52.165836
2020-01-29 18:35:52,166 [salt.state       :1813][INFO    ][6140] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2020-01-29 18:35:52,183 [salt.state       :300 ][INFO    ][6140] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2020-01-29 18:35:52,184 [salt.state       :1951][INFO    ][6140] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 18:35:52.184171 duration_in_ms=18.335
2020-01-29 18:35:52,185 [salt.state       :1780][INFO    ][6140] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 18:35:52.184952
2020-01-29 18:35:52,185 [salt.state       :1813][INFO    ][6140] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2020-01-29 18:35:52,240 [salt.state       :300 ][INFO    ][6140] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2020-01-29 18:35:52,240 [salt.state       :1951][INFO    ][6140] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 18:35:52.240576 duration_in_ms=55.624
2020-01-29 18:35:52,241 [salt.state       :1780][INFO    ][6140] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 18:35:52.241237
2020-01-29 18:35:52,241 [salt.state       :1813][INFO    ][6140] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2020-01-29 18:35:52,303 [salt.state       :300 ][INFO    ][6140] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2020-01-29 18:35:52,303 [salt.state       :1951][INFO    ][6140] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 18:35:52.303621 duration_in_ms=62.384
2020-01-29 18:35:52,304 [salt.state       :1780][INFO    ][6140] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 18:35:52.304744
2020-01-29 18:35:52,305 [salt.state       :1813][INFO    ][6140] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2020-01-29 18:35:52,388 [salt.state       :300 ][INFO    ][6140] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2020-01-29 18:35:52,389 [salt.state       :1951][INFO    ][6140] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 18:35:52.389194 duration_in_ms=84.451
2020-01-29 18:35:52,389 [salt.state       :1780][INFO    ][6140] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_bionic] at time 18:35:52.389770
2020-01-29 18:35:52,390 [salt.state       :1813][INFO    ][6140] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_bionic]
2020-01-29 18:35:52,469 [salt.state       :300 ][INFO    ][6140] File /etc/maas/preseeds/curtin_userdata_amd64_generic_bionic is in the correct state
2020-01-29 18:35:52,470 [salt.state       :1951][INFO    ][6140] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_bionic] at time 18:35:52.470077 duration_in_ms=80.307
2020-01-29 18:35:52,470 [salt.state       :1780][INFO    ][6140] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_bionic] at time 18:35:52.470625
2020-01-29 18:35:52,470 [salt.state       :1813][INFO    ][6140] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_bionic]
2020-01-29 18:35:52,538 [salt.state       :300 ][INFO    ][6140] File /etc/maas/preseeds/curtin_userdata_arm64_generic_bionic is in the correct state
2020-01-29 18:35:52,538 [salt.state       :1951][INFO    ][6140] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_bionic] at time 18:35:52.538890 duration_in_ms=68.264
2020-01-29 18:35:52,539 [salt.state       :1780][INFO    ][6140] Running state [/root/.pgpass] at time 18:35:52.539242
2020-01-29 18:35:52,539 [salt.state       :1813][INFO    ][6140] Executing state file.managed for [/root/.pgpass]
2020-01-29 18:35:52,602 [salt.state       :300 ][INFO    ][6140] File /root/.pgpass is in the correct state
2020-01-29 18:35:52,603 [salt.state       :1951][INFO    ][6140] Completed state [/root/.pgpass] at time 18:35:52.603033 duration_in_ms=63.79
2020-01-29 18:35:52,609 [salt.state       :1780][INFO    ][6140] Running state [maas-region migrate --noinput] at time 18:35:52.609600
2020-01-29 18:35:52,610 [salt.state       :1813][INFO    ][6140] Executing state cmd.run for [maas-region migrate --noinput]
2020-01-29 18:35:52,610 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6140] Executing command 'maas-region migrate --noinput' in directory '/root'
2020-01-29 18:35:55,449 [salt.state       :300 ][INFO    ][6140] {'pid': 6171, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Apply all migrations: auth, contenttypes, maasserver, metadataserver, piston3, sessions, sites\nRunning migrations:\n  No migrations to apply.'}
2020-01-29 18:35:55,450 [salt.state       :1951][INFO    ][6140] Completed state [maas-region migrate --noinput] at time 18:35:55.450258 duration_in_ms=2840.656
2020-01-29 18:35:55,450 [salt.state       :2022][WARNING ][6140] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2020-01-29 18:35:55,454 [salt.state       :1780][INFO    ][6140] Running state [maas-regiond] at time 18:35:55.454503
2020-01-29 18:35:55,455 [salt.state       :1813][INFO    ][6140] Executing state service.running for [maas-regiond]
2020-01-29 18:35:55,457 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6140] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2020-01-29 18:35:55,504 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6140] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2020-01-29 18:35:55,527 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6140] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2020-01-29 18:35:55,551 [salt.state       :300 ][INFO    ][6140] The service maas-regiond is already running
2020-01-29 18:35:55,552 [salt.state       :1951][INFO    ][6140] Completed state [maas-regiond] at time 18:35:55.552066 duration_in_ms=97.564
2020-01-29 18:35:55,555 [salt.state       :1780][INFO    ][6140] Running state [bind9] at time 18:35:55.555006
2020-01-29 18:35:55,555 [salt.state       :1813][INFO    ][6140] Executing state service.running for [bind9]
2020-01-29 18:35:55,556 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6140] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2020-01-29 18:35:55,584 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6140] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2020-01-29 18:35:55,604 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6140] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2020-01-29 18:35:55,630 [salt.state       :300 ][INFO    ][6140] The service bind9 is already running
2020-01-29 18:35:55,631 [salt.state       :1951][INFO    ][6140] Completed state [bind9] at time 18:35:55.631181 duration_in_ms=76.175
2020-01-29 18:35:55,632 [salt.state       :1780][INFO    ][6140] Running state [maasng.wait_for_http_code] at time 18:35:55.632455
2020-01-29 18:35:55,632 [salt.state       :1813][INFO    ][6140] Executing state module.run for [maasng.wait_for_http_code]
2020-01-29 18:35:55,633 [salt.utils.decorators:613 ][WARNING ][6140] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-29 18:35:55,782 [salt.state       :300 ][INFO    ][6140] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2020-01-29 18:35:55,783 [salt.state       :1951][INFO    ][6140] Completed state [maasng.wait_for_http_code] at time 18:35:55.783514 duration_in_ms=151.057
2020-01-29 18:35:55,785 [salt.state       :1780][INFO    ][6140] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 18:35:55.785047
2020-01-29 18:35:55,785 [salt.state       :1813][INFO    ][6140] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2020-01-29 18:35:55,786 [salt.state       :300 ][INFO    ][6140] /var/lib/maas/.setup_admin exists
2020-01-29 18:35:55,786 [salt.state       :1951][INFO    ][6140] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 18:35:55.786290 duration_in_ms=1.243
2020-01-29 18:35:55,787 [salt.state       :1780][INFO    ][6140] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 18:35:55.787028
2020-01-29 18:35:55,787 [salt.state       :1813][INFO    ][6140] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-01-29 18:35:55,788 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6140] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-01-29 18:35:57,884 [salt.state       :300 ][INFO    ][6140] {'pid': 6195, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-01-29 18:35:57,886 [salt.state       :1951][INFO    ][6140] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 18:35:57.886210 duration_in_ms=2099.181
2020-01-29 18:35:57,902 [salt.state       :1780][INFO    ][6140] Running state [maas_region_boot_source_resources_mirror] at time 18:35:57.902253
2020-01-29 18:35:57,903 [salt.state       :1813][INFO    ][6140] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2020-01-29 18:35:58,010 [salt.state       :300 ][INFO    ][6140] {'changes': {}}
2020-01-29 18:35:58,011 [salt.state       :1951][INFO    ][6140] Completed state [maas_region_boot_source_resources_mirror] at time 18:35:58.010890 duration_in_ms=108.64
2020-01-29 18:35:58,012 [salt.state       :1780][INFO    ][6140] Running state [maasng.boot_resources_import] at time 18:35:58.012098
2020-01-29 18:35:58,012 [salt.state       :1813][INFO    ][6140] Executing state module.run for [maasng.boot_resources_import]
2020-01-29 18:35:58,013 [salt.utils.decorators:613 ][WARNING ][6140] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-29 18:35:58,101 [salt.loaded.ext.module.maasng:1600][INFO    ][6140] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2020-01-29 18:36:03,189 [salt.state       :300 ][INFO    ][6140] {'ret': True}
2020-01-29 18:36:03,190 [salt.state       :1951][INFO    ][6140] Completed state [maasng.boot_resources_import] at time 18:36:03.190358 duration_in_ms=5178.257
2020-01-29 18:36:03,192 [salt.state       :1780][INFO    ][6140] Running state [maas_region_boot_sources_selection_bionic] at time 18:36:03.192607
2020-01-29 18:36:03,193 [salt.state       :1813][INFO    ][6140] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_bionic]
2020-01-29 18:36:03,360 [salt.state       :300 ][INFO    ][6140] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2020-01-29 18:36:03,361 [salt.state       :1951][INFO    ][6140] Completed state [maas_region_boot_sources_selection_bionic] at time 18:36:03.361012 duration_in_ms=168.404
2020-01-29 18:36:03,362 [salt.state       :1780][INFO    ][6140] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 18:36:03.362881
2020-01-29 18:36:03,363 [salt.state       :1813][INFO    ][6140] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2020-01-29 18:36:03,364 [salt.utils.decorators:613 ][WARNING ][6140] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-29 18:36:03,364 [salt.loaded.ext.module.maasng:1771][INFO    ][6140] boot-sources sync initiated for ALL Rack's
2020-01-29 18:36:04,137 [salt.state       :300 ][INFO    ][6140] {'ret': True}
2020-01-29 18:36:04,138 [salt.state       :1951][INFO    ][6140] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 18:36:04.138096 duration_in_ms=775.214
2020-01-29 18:36:04,139 [salt.state       :1780][INFO    ][6140] Running state [maas.process_maas_config] at time 18:36:04.139892
2020-01-29 18:36:04,140 [salt.state       :1813][INFO    ][6140] Executing state module.run for [maas.process_maas_config]
2020-01-29 18:36:04,140 [salt.utils.decorators:613 ][WARNING ][6140] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-29 18:36:04,141 [salt.loaded.ext.module.maas:92  ][INFO    ][6140] maasconfig name=enable_http_proxy value=True
2020-01-29 18:36:04,178 [salt.loaded.ext.module.maas:92  ][INFO    ][6140] maasconfig name=upstream_dns value=8.8.8.8
2020-01-29 18:36:04,224 [salt.loaded.ext.module.maas:92  ][INFO    ][6140] maasconfig name=commissioning_distro_series value=bionic
2020-01-29 18:36:04,280 [salt.loaded.ext.module.maas:92  ][INFO    ][6140] maasconfig name=default_osystem value=ubuntu
2020-01-29 18:36:04,331 [salt.loaded.ext.module.maas:92  ][INFO    ][6140] maasconfig name=active_discovery_interval value=600
2020-01-29 18:36:04,371 [salt.loaded.ext.module.maas:92  ][INFO    ][6140] maasconfig name=dnssec_validation value=no
2020-01-29 18:36:04,523 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129183604506966
2020-01-29 18:36:04,556 [salt.minion      :1432][INFO    ][6229] Starting a new job with PID 6229
2020-01-29 18:36:04,587 [salt.minion      :1711][INFO    ][6229] Returning information for job: 20200129183604506966
2020-01-29 18:36:05,571 [salt.loaded.ext.module.maas:92  ][INFO    ][6140] maasconfig name=kernel_opts value=spectre_v2=off nopti kpti=off nospec_store_bypass_disable noibrs noibpb
2020-01-29 18:36:05,631 [salt.loaded.ext.module.maas:92  ][INFO    ][6140] maasconfig name=network_discovery value=enabled
2020-01-29 18:36:05,711 [salt.loaded.ext.module.maas:92  ][INFO    ][6140] maasconfig name=enable_third_party_drivers value=True
2020-01-29 18:36:05,955 [salt.loaded.ext.module.maas:92  ][INFO    ][6140] maasconfig name=default_storage_layout value=lvm
2020-01-29 18:36:05,999 [salt.loaded.ext.module.maas:92  ][INFO    ][6140] maasconfig name=maas_name value=mas01
2020-01-29 18:36:06,195 [salt.loaded.ext.module.maas:92  ][INFO    ][6140] maasconfig name=disk_erase_with_secure_erase value=False
2020-01-29 18:36:06,234 [salt.loaded.ext.module.maas:92  ][INFO    ][6140] maasconfig name=default_distro_series value=bionic
2020-01-29 18:36:06,394 [salt.loaded.ext.module.maas:92  ][INFO    ][6140] maasconfig name=ntp_external_only value=True
2020-01-29 18:36:06,426 [salt.loaded.ext.module.maas:92  ][INFO    ][6140] maasconfig name=default_min_hwe_kernel value=ga-18.04
2020-01-29 18:36:06,484 [salt.state       :300 ][INFO    ][6140] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'kernel_opts', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'maas_name', 'disk_erase_with_secure_erase', 'default_distro_series', 'ntp_external_only', 'default_min_hwe_kernel']}}
2020-01-29 18:36:06,485 [salt.state       :1951][INFO    ][6140] Completed state [maas.process_maas_config] at time 18:36:06.485017 duration_in_ms=2345.125
2020-01-29 18:36:06,486 [salt.state       :1780][INFO    ][6140] Running state [pxe_admin] at time 18:36:06.486538
2020-01-29 18:36:06,487 [salt.state       :1813][INFO    ][6140] Executing state maasng.fabric_present for [pxe_admin]
2020-01-29 18:36:06,530 [salt.loaded.ext.module.maasng:945 ][INFO    ][6140] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'class_type': None, u'name': u'fabric-2', u'id': 2}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'cckwne', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': None, u'name': u'pxe_admin', u'id': 1}]
2020-01-29 18:36:06,577 [salt.loaded.ext.module.maasng:1008][WARNING ][6140] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2020-01-29 18:36:06,578 [salt.loaded.ext.module.maasng:1011][WARNING ][6140] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2020-01-29 18:36:06,620 [salt.state       :300 ][INFO    ][6140] {'new': 'Fabric  pxe_admin created', 'result': True}
2020-01-29 18:36:06,621 [salt.state       :1951][INFO    ][6140] Completed state [pxe_admin] at time 18:36:06.620958 duration_in_ms=134.421
2020-01-29 18:36:06,621 [salt.state       :1780][INFO    ][6140] Running state [vlan 0] at time 18:36:06.621600
2020-01-29 18:36:06,621 [salt.state       :1813][INFO    ][6140] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2020-01-29 18:36:06,670 [salt.loaded.ext.module.maasng:945 ][INFO    ][6140] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', 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'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-2', u'id': 2}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'cckwne', 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'class_type': None, u'name': u'pxe_admin', u'id': 1}]
2020-01-29 18:36:06,748 [salt.loaded.ext.module.maasng:945 ][INFO    ][6140] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', 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'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-2', u'id': 2}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'cckwne', 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'class_type': None, u'name': u'pxe_admin', u'id': 1}]
2020-01-29 18:36:06,982 [salt.loaded.ext.module.maasng:945 ][INFO    ][6140] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', 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'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-2', u'id': 2}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'cckwne', 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'class_type': None, u'name': u'pxe_admin', u'id': 1}]
2020-01-29 18:36:07,052 [salt.state       :300 ][INFO    ][6140] {'new': 'Vlan untagged was updated'}
2020-01-29 18:36:07,052 [salt.state       :1951][INFO    ][6140] Completed state [vlan 0] at time 18:36:07.052444 duration_in_ms=430.842
2020-01-29 18:36:07,054 [salt.state       :1780][INFO    ][6140] Running state [192.168.11.0/24] at time 18:36:07.054089
2020-01-29 18:36:07,054 [salt.state       :1813][INFO    ][6140] Executing state maasng.subnet_present for [192.168.11.0/24]
2020-01-29 18:36:07,203 [salt.loaded.ext.module.maasng:945 ][INFO    ][6140] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', 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'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-2', u'id': 2}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'cckwne', 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'class_type': None, u'name': u'pxe_admin', u'id': 1}]
2020-01-29 18:36:07,204 [salt.loaded.ext.module.maasng:1235][WARNING ][6140] Ignoring parameter vlan:0
2020-01-29 18:36:07,276 [salt.state       :300 ][INFO    ][6140] Subnet 192.168.11.0/24 has been updated for pxe_admin
2020-01-29 18:36:07,276 [salt.state       :1951][INFO    ][6140] Completed state [192.168.11.0/24] at time 18:36:07.276686 duration_in_ms=222.597
2020-01-29 18:36:07,278 [salt.state       :1780][INFO    ][6140] Running state [maas_create_iprange_1] at time 18:36:07.278360
2020-01-29 18:36:07,278 [salt.state       :1813][INFO    ][6140] Executing state maasng.iprange_present for [maas_create_iprange_1]
2020-01-29 18:36:07,323 [salt.state       :300 ][INFO    ][6140] Iprange maas_create_iprange_1 already exist.
2020-01-29 18:36:07,323 [salt.state       :1951][INFO    ][6140] Completed state [maas_create_iprange_1] at time 18:36:07.323747 duration_in_ms=45.387
2020-01-29 18:36:07,324 [salt.state       :1780][INFO    ][6140] Running state [vlan 0] at time 18:36:07.324122
2020-01-29 18:36:07,324 [salt.state       :1813][INFO    ][6140] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2020-01-29 18:36:07,367 [salt.loaded.ext.module.maasng:945 ][INFO    ][6140] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'class_type': None, u'name': u'fabric-2', u'id': 2}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'cckwne', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': None, u'name': u'pxe_admin', u'id': 1}]
2020-01-29 18:36:07,443 [salt.loaded.ext.module.maasng:945 ][INFO    ][6140] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', 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'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-2', u'id': 2}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'cckwne', 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'class_type': None, u'name': u'pxe_admin', u'id': 1}]
2020-01-29 18:36:07,642 [salt.loaded.ext.module.maasng:945 ][INFO    ][6140] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', 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'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-2', u'id': 2}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'cckwne', 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'class_type': None, u'name': u'pxe_admin', u'id': 1}]
2020-01-29 18:36:07,718 [salt.state       :300 ][INFO    ][6140] {'new': 'Vlan untagged was updated'}
2020-01-29 18:36:07,718 [salt.state       :1951][INFO    ][6140] Completed state [vlan 0] at time 18:36:07.718840 duration_in_ms=394.716
2020-01-29 18:36:07,719 [salt.state       :1780][INFO    ][6140] Running state [opnfv] at time 18:36:07.719804
2020-01-29 18:36:07,720 [salt.state       :1813][INFO    ][6140] Executing state maasng.sshkey_present for [opnfv]
2020-01-29 18:36:07,754 [salt.loaded.ext.module.maasng:1903][INFO    ][6140] [{u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b', u'keysource': u''}]
2020-01-29 18:36:07,755 [salt.state       :300 ][INFO    ][6140] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2020-01-29 18:36:07,755 [salt.state       :1951][INFO    ][6140] Completed state [opnfv] at time 18:36:07.755287 duration_in_ms=35.484
2020-01-29 18:36:07,756 [salt.state       :1780][INFO    ][6140] Running state [maas.process_tags] at time 18:36:07.755988
2020-01-29 18:36:07,756 [salt.state       :1813][INFO    ][6140] Executing state module.run for [maas.process_tags]
2020-01-29 18:36:07,756 [salt.utils.decorators:613 ][WARNING ][6140] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-29 18:36:07,787 [salt.loaded.ext.module.maas:92  ][INFO    ][6140] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"]|//capability[@id="cp15_barrier"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G kpti=off
2020-01-29 18:36:07,822 [salt.state       :300 ][INFO    ][6140] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2020-01-29 18:36:07,822 [salt.state       :1951][INFO    ][6140] Completed state [maas.process_tags] at time 18:36:07.822860 duration_in_ms=66.871
2020-01-29 18:36:07,828 [salt.minion      :1711][INFO    ][6140] Returning information for job: 20200129183549411898
2020-01-29 18:36:08,882 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command state.apply with jid 20200129183608869957
2020-01-29 18:36:08,907 [salt.minion      :1432][INFO    ][6271] Starting a new job with PID 6271
2020-01-29 18:36:16,869 [salt.state       :915 ][INFO    ][6271] Loading fresh modules for state activity
2020-01-29 18:36:16,967 [salt.state       :1780][INFO    ][6271] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 18:36:16.967589
2020-01-29 18:36:16,967 [salt.state       :1813][INFO    ][6271] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-01-29 18:36:16,969 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6271] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-01-29 18:36:18,969 [salt.state       :300 ][INFO    ][6271] {'pid': 6286, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-01-29 18:36:18,970 [salt.state       :1951][INFO    ][6271] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 18:36:18.970441 duration_in_ms=2002.851
2020-01-29 18:36:18,973 [salt.state       :1780][INFO    ][6271] Running state [maas.process_machines] at time 18:36:18.973715
2020-01-29 18:36:18,974 [salt.state       :1813][INFO    ][6271] Executing state module.run for [maas.process_machines]
2020-01-29 18:36:18,975 [salt.utils.decorators:613 ][WARNING ][6271] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-29 18:36:19,500 [salt.loaded.ext.module.maas:412 ][WARNING ][6271] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-01-29 18:36:19,501 [salt.loaded.ext.module.maas:92  ][INFO    ][6271] machine hostname=gtw01 power_type=ipmi mac_addresses=['14:58:d0:54:6a:60'] power_parameters_power_address=172.16.1.17 power_parameters_power_pass=Winter2017 system_id=dtaged architecture=amd64/generic power_parameters_power_user=opnfv
2020-01-29 18:36:20,231 [salt.loaded.ext.module.maas:412 ][WARNING ][6271] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-01-29 18:36:20,232 [salt.loaded.ext.module.maas:92  ][INFO    ][6271] machine hostname=cmp002 power_type=ipmi mac_addresses=['9c:b6:54:8a:10:18'] power_parameters_power_address=172.16.1.20 power_parameters_power_pass=Winter2017 system_id=mx7bck architecture=amd64/generic power_parameters_power_user=opnfv
2020-01-29 18:36:20,932 [salt.loaded.ext.module.maas:412 ][WARNING ][6271] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-01-29 18:36:20,932 [salt.loaded.ext.module.maas:92  ][INFO    ][6271] machine hostname=cmp001 power_type=ipmi mac_addresses=['9c:b6:54:8a:95:a0'] power_parameters_power_address=172.16.1.19 power_parameters_power_pass=Winter2017 system_id=pt7866 architecture=amd64/generic power_parameters_power_user=opnfv
2020-01-29 18:36:21,648 [salt.loaded.ext.module.maas:412 ][WARNING ][6271] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-01-29 18:36:21,649 [salt.loaded.ext.module.maas:92  ][INFO    ][6271] machine hostname=ctl01 power_type=ipmi mac_addresses=['14:58:d0:54:e7:88'] power_parameters_power_address=172.16.1.16 power_parameters_power_pass=Winter2017 system_id=xr7kpy architecture=amd64/generic power_parameters_power_user=opnfv
2020-01-29 18:36:22,373 [salt.state       :300 ][INFO    ][6271] {'ret': {'updated': ['gtw01', 'cmp002', 'cmp001', 'ctl01'], 'errors': {}, 'success': []}}
2020-01-29 18:36:22,374 [salt.state       :1951][INFO    ][6271] Completed state [maas.process_machines] at time 18:36:22.374215 duration_in_ms=3400.498
2020-01-29 18:36:22,379 [salt.minion      :1711][INFO    ][6271] Returning information for job: 20200129183608869957
2020-01-29 18:36:55,846 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command state.apply with jid 20200129183655828848
2020-01-29 18:36:55,870 [salt.minion      :1432][INFO    ][6329] Starting a new job with PID 6329
2020-01-29 18:37:04,090 [salt.state       :915 ][INFO    ][6329] Loading fresh modules for state activity
2020-01-29 18:37:04,201 [salt.state       :1780][INFO    ][6329] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 18:37:04.200934
2020-01-29 18:37:04,201 [salt.state       :1813][INFO    ][6329] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-01-29 18:37:04,203 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6329] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-01-29 18:37:06,401 [salt.state       :300 ][INFO    ][6329] {'pid': 6338, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-01-29 18:37:06,402 [salt.state       :1951][INFO    ][6329] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 18:37:06.402761 duration_in_ms=2201.826
2020-01-29 18:37:06,407 [salt.state       :1780][INFO    ][6329] Running state [maas.wait_for_machine_status] at time 18:37:06.407187
2020-01-29 18:37:06,407 [salt.state       :1813][INFO    ][6329] Executing state module.run for [maas.wait_for_machine_status]
2020-01-29 18:37:06,408 [salt.utils.decorators:613 ][WARNING ][6329] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-29 18:37:08,473 [salt.state       :300 ][INFO    ][6329] {'ret': True}
2020-01-29 18:37:08,473 [salt.state       :1951][INFO    ][6329] Completed state [maas.wait_for_machine_status] at time 18:37:08.473506 duration_in_ms=2066.319
2020-01-29 18:37:08,479 [salt.minion      :1711][INFO    ][6329] Returning information for job: 20200129183655828848
2020-01-29 18:37:09,420 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command state.apply with jid 20200129183709404841
2020-01-29 18:37:09,453 [salt.minion      :1432][INFO    ][6350] Starting a new job with PID 6350
2020-01-29 18:37:10,532 [salt.state       :915 ][INFO    ][6350] Loading fresh modules for state activity
2020-01-29 18:37:10,689 [salt.state       :1780][INFO    ][6350] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 18:37:10.688926
2020-01-29 18:37:10,689 [salt.state       :1813][INFO    ][6350] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-01-29 18:37:10,691 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6350] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-01-29 18:37:12,784 [salt.state       :300 ][INFO    ][6350] {'pid': 6357, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-01-29 18:37:12,786 [salt.state       :1951][INFO    ][6350] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 18:37:12.786070 duration_in_ms=2097.141
2020-01-29 18:37:12,790 [salt.state       :1780][INFO    ][6350] Running state [maas_machines_storage_cmp002_lvm] at time 18:37:12.790302
2020-01-29 18:37:12,790 [salt.state       :1813][INFO    ][6350] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2020-01-29 18:37:13,816 [salt.loaded.ext.module.maasng:610 ][INFO    ][6350] mx7bck
2020-01-29 18:37:13,816 [salt.loaded.ext.module.maasng:626 ][INFO    ][6350] sda
2020-01-29 18:37:14,322 [salt.loaded.ext.module.maasng:361 ][INFO    ][6350] mx7bck
2020-01-29 18:37:14,447 [salt.loaded.ext.module.maasng:367 ][INFO    ][6350] [{u'resource_uri': u'/MAAS/api/2.0/nodes/mx7bck/blockdevices/1/', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'de36ca08-e561-44ee-8e6f-eed405f21e00', u'resource_uri': u'/MAAS/api/2.0/nodes/mx7bck/blockdevices/1/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'mx7bck', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'd319c2bd-6052-4873-b17d-b45301bc2f6b', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 7, u'device_id': 1}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'mx7bck', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}, {u'resource_uri': u'/MAAS/api/2.0/nodes/mx7bck/blockdevices/11/', u'uuid': u'59065c04-25b2-4178-bccc-a902206547fa', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'03d8be4a-eb2c-4f24-be2c-9f5593f69b9a', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'mx7bck', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'used_size': 800097042432, u'id': 11, u'serial': None, u'size': 800097042432}]
2020-01-29 18:37:14,447 [salt.loaded.ext.module.maasng:632 ][INFO    ][6350] vgroot
2020-01-29 18:37:14,448 [salt.loaded.ext.module.maasng:635 ][INFO    ][6350] lvroot
2020-01-29 18:37:14,448 [salt.loaded.ext.module.maasng:639 ][INFO    ][6350] 107374182400
2020-01-29 18:37:15,079 [salt.loaded.ext.module.maasng:645 ][INFO    ][6350] {u'node_type_name': u'Machine', u'hwe_kernel': None, u'testing_status_name': u'Passed', u'ip_addresses': [], u'cpu_count': 40, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'is_default': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'boot_interface': {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'cckwne', 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'vendor': u'HP', u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'cckwne', 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'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 51, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:10:18', u'enabled': True, u'id': 5, u'discovered': [], u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'mx7bck', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/mx7bck/interfaces/5/'}, u'node_type': 0, u'tag_names': [], u'swap_size': None, u'cpu_speed': 2800, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'status_action': u'', u'blockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/mx7bck/blockdevices/1/', u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'8ad161e1-c50e-4d50-a795-041247871f4d', u'resource_uri': u'/MAAS/api/2.0/nodes/mx7bck/blockdevices/1/partition/9', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'mx7bck', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'77ffec2b-5beb-4a44-b315-393b0f03260a', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 9, u'device_id': 1}], u'id': 1, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'mx7bck', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}, {u'resource_uri': u'/MAAS/api/2.0/nodes/mx7bck/blockdevices/13/', u'available_size': 0, u'uuid': u'f0e57138-edc1-451b-be36-7e365faa1225', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'id': 13, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'mx7bck', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'51886d07-9432-4ff1-9124-eb9d7d130017', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'serial': None, u'size': 107374182400}], u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'description': u''}, u'resource_uri': u'/MAAS/api/2.0/machines/mx7bck/', u'current_commissioning_result_id': 16, u'hardware_info': {u'mainboard_firmware_version': u'I31', u'mainboard_product': u'Unknown', u'system_vendor': u'HP', u'cpu_model': u'Intel(R) Xeon(R) CPU E5-2680 v2', u'mainboard_firmware_date': u'12/20/2013', u'mainboard_vendor': u'Unknown', u'system_serial': u'USE410T2Y0', u'system_version': u'Unknown', u'system_product': u'ProLiant BL460c Gen8 (735151-B21)'}, u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'mx7bck', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'disable_ipv4': False, u'min_hwe_kernel': u'ga-18.04', u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'physicalblockdevice_set': [{u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/mx7bck/blockdevices/1/', u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 1, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'mx7bck', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'firmware_version': u'5.22', u'block_size': 4096, u'type': u'physical', u'model': u'LOGICAL VOLUME', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'8ad161e1-c50e-4d50-a795-041247871f4d', u'resource_uri': u'/MAAS/api/2.0/nodes/mx7bck/blockdevices/1/partition/9', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'mx7bck', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'77ffec2b-5beb-4a44-b315-393b0f03260a', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 9, u'device_id': 1}]}], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'mx7bck', u'id': 9}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/mx7bck/blockdevices/1/', u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 1, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'mx7bck', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'firmware_version': u'5.22', u'block_size': 4096, u'type': u'physical', u'model': u'LOGICAL VOLUME', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'8ad161e1-c50e-4d50-a795-041247871f4d', u'resource_uri': u'/MAAS/api/2.0/nodes/mx7bck/blockdevices/1/partition/9', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'mx7bck', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'77ffec2b-5beb-4a44-b315-393b0f03260a', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 9, u'device_id': 1}]}, u'current_testing_result_id': 17, u'cpu_test_status': -1, u'pool': {u'id': 0, u'resource_uri': u'/MAAS/api/2.0/resourcepool/0/', u'name': u'default', u'description': u'Default pool'}, u'storage_test_status': 2, u'locked': False, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/mx7bck/blockdevices/13/', u'available_size': 0, u'uuid': u'f0e57138-edc1-451b-be36-7e365faa1225', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'id': 13, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'mx7bck', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'51886d07-9432-4ff1-9124-eb9d7d130017', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'serial': None, u'partitions': []}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'interface_set': [{u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'cckwne', 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'vendor': u'HP', u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'cckwne', 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'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 51, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:10:18', u'enabled': True, u'id': 5, u'discovered': [], u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'mx7bck', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/mx7bck/interfaces/5/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': None, u'vendor': u'HP', u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:07:50', u'enabled': True, u'id': 10, u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'mx7bck', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/mx7bck/interfaces/10/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'cckwne', 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'vendor': u'HP', u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'cckwne', 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'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 52, u'mode': u'link_up'}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:10:1c', u'enabled': True, u'id': 11, u'discovered': [], u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'mx7bck', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/mx7bck/interfaces/11/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': None, u'vendor': u'HP', u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:07:51', u'enabled': True, u'id': 12, u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'mx7bck', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/mx7bck/interfaces/12/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {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'vendor': u'HP', u'name': u'ens2f0', u'links': [{u'id': 53, u'mode': u'link_up'}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:12:48', u'enabled': True, u'id': 13, u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'mx7bck', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/mx7bck/interfaces/13/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {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'vendor': u'HP', u'name': u'ens2f1', u'links': [{u'id': 54, u'mode': u'link_up'}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:12:49', u'enabled': True, u'id': 14, u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'mx7bck', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/mx7bck/interfaces/14/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2020-01-29 18:37:15,082 [salt.state       :300 ][INFO    ][6350] {'new': {'storage_layout': 'lvm'}}
2020-01-29 18:37:15,083 [salt.state       :1951][INFO    ][6350] Completed state [maas_machines_storage_cmp002_lvm] at time 18:37:15.083280 duration_in_ms=2292.977
2020-01-29 18:37:15,084 [salt.state       :1780][INFO    ][6350] Running state [maas_machines_storage_cmp001_lvm] at time 18:37:15.083988
2020-01-29 18:37:15,084 [salt.state       :1813][INFO    ][6350] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2020-01-29 18:37:16,109 [salt.loaded.ext.module.maasng:610 ][INFO    ][6350] pt7866
2020-01-29 18:37:16,110 [salt.loaded.ext.module.maasng:626 ][INFO    ][6350] sda
2020-01-29 18:37:16,617 [salt.loaded.ext.module.maasng:361 ][INFO    ][6350] pt7866
2020-01-29 18:37:16,691 [salt.loaded.ext.module.maasng:367 ][INFO    ][6350] [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/pt7866/blockdevices/2/', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'pt7866', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'type': u'physical', u'id': 2, u'partitions': [{u'uuid': u'71b4b5de-b230-44c4-b6e3-486bcafa14d7', u'resource_uri': u'/MAAS/api/2.0/nodes/pt7866/blockdevices/2/partition/8', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'pt7866', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'7f586990-4ff7-47c4-b0a0-9ed5c424af59', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 8, u'device_id': 2}]}, {u'size': 800097042432, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/pt7866/blockdevices/12/', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'uuid': u'a9f9e19f-e31b-4564-9749-b10613dd005b', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'pt7866', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'ce26692d-33c6-4fbb-9ced-0e8400c8bf08', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 12, u'partitions': []}]
2020-01-29 18:37:16,692 [salt.loaded.ext.module.maasng:632 ][INFO    ][6350] vgroot
2020-01-29 18:37:16,692 [salt.loaded.ext.module.maasng:635 ][INFO    ][6350] lvroot
2020-01-29 18:37:16,693 [salt.loaded.ext.module.maasng:639 ][INFO    ][6350] 107374182400
2020-01-29 18:37:17,311 [salt.loaded.ext.module.maasng:645 ][INFO    ][6350] {u'hwe_kernel': None, u'swap_size': None, u'memory_test_status': -1, u'ip_addresses': [], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'is_default': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'boot_interface': {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'cckwne', 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'vendor': u'HP', u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'cckwne', 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'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 58, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:95:a0', u'enabled': True, u'children': [], u'discovered': [], u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'pt7866', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/pt7866/interfaces/6/'}, u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'cpu_speed': 2800, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/pt7866/blockdevices/2/', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'id': 2, u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'pt7866', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'firmware_version': u'5.22', u'block_size': 4096, u'used_size': 800106479616, u'model': u'LOGICAL VOLUME', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'7b179aa2-41d6-43e1-8bcd-c131f5a30142', u'resource_uri': u'/MAAS/api/2.0/nodes/pt7866/blockdevices/2/partition/10', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'pt7866', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'2dca9b5a-bfc5-4401-a940-3cd5c9e7e62d', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 10, u'device_id': 2}]}, u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'description': u''}, u'resource_uri': u'/MAAS/api/2.0/machines/pt7866/', u'hardware_info': {u'mainboard_firmware_version': u'I31', u'mainboard_product': u'Unknown', u'system_vendor': u'HP', u'cpu_model': u'Intel(R) Xeon(R) CPU E5-2680 v2', u'mainboard_firmware_date': u'06/01/2015', u'mainboard_vendor': u'Unknown', u'system_serial': u'USE410T2XY', u'system_version': u'Unknown', u'system_product': u'ProLiant BL460c Gen8 (735151-B21)'}, u'hostname': u'cmp001', u'storage': 800109.715456, u'node_type': 0, u'testing_status': 2, u'system_id': u'pt7866', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/pt7866/blockdevices/14/', u'uuid': u'79a021df-2c23-4779-b538-e3e19ec6800f', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'ae35dac3-186a-41ec-9ed3-1b58e2a4cdeb', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'pt7866', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 14, u'serial': None, u'partitions': []}], u'blockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/pt7866/blockdevices/2/', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'7b179aa2-41d6-43e1-8bcd-c131f5a30142', u'resource_uri': u'/MAAS/api/2.0/nodes/pt7866/blockdevices/2/partition/10', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'pt7866', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'2dca9b5a-bfc5-4401-a940-3cd5c9e7e62d', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 10, u'device_id': 2}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'pt7866', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, {u'resource_uri': u'/MAAS/api/2.0/nodes/pt7866/blockdevices/14/', u'uuid': u'79a021df-2c23-4779-b538-e3e19ec6800f', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'ae35dac3-186a-41ec-9ed3-1b58e2a4cdeb', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'pt7866', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 14, u'serial': None, u'size': 107374182400}], u'status': 4, u'bcaches': [], u'cpu_count': 40, u'power_state': u'off', u'owner_data': {}, u'memory_test_status_name': u'Unknown', u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'pt7866', u'id': 10}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'node_type_name': u'Machine', u'interface_set': [{u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'cckwne', 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'vendor': u'HP', u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'cckwne', 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'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 58, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:95:a0', u'enabled': True, u'children': [], u'discovered': [], u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'pt7866', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/pt7866/interfaces/6/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': None, u'vendor': u'HP', u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:1f:d4', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'pt7866', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/pt7866/interfaces/15/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'cckwne', 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'vendor': u'HP', u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'cckwne', 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'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 59, u'mode': u'link_up'}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:95:a4', u'enabled': True, u'children': [], u'discovered': [], u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'pt7866', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/pt7866/interfaces/16/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': None, u'vendor': u'HP', u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:1f:d5', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'pt7866', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/pt7866/interfaces/17/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {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'vendor': u'HP', u'name': u'ens2f0', u'links': [{u'id': 60, u'mode': u'link_up'}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:52:cc', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'pt7866', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/pt7866/interfaces/18/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {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'vendor': u'HP', u'name': u'ens2f1', u'links': [{u'id': 61, u'mode': u'link_up'}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:52:cd', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'pt7866', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/pt7866/interfaces/19/'}], u'current_testing_result_id': 19, u'cpu_test_status': -1, u'pool': {u'id': 0, u'resource_uri': u'/MAAS/api/2.0/resourcepool/0/', u'name': u'default', u'description': u'Default pool'}, u'architecture': u'amd64/generic', u'storage_test_status': 2, u'locked': False, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/pt7866/blockdevices/2/', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'id': 2, u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'pt7866', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'firmware_version': u'5.22', u'block_size': 4096, u'used_size': 800106479616, u'model': u'LOGICAL VOLUME', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'7b179aa2-41d6-43e1-8bcd-c131f5a30142', u'resource_uri': u'/MAAS/api/2.0/nodes/pt7866/blockdevices/2/partition/10', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'pt7866', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'2dca9b5a-bfc5-4401-a940-3cd5c9e7e62d', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 10, u'device_id': 2}]}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'disable_ipv4': False, u'commissioning_status': 2, u'min_hwe_kernel': u'ga-18.04', u'current_commissioning_result_id': 18, u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2020-01-29 18:37:17,314 [salt.state       :300 ][INFO    ][6350] {'new': {'storage_layout': 'lvm'}}
2020-01-29 18:37:17,314 [salt.state       :1951][INFO    ][6350] Completed state [maas_machines_storage_cmp001_lvm] at time 18:37:17.314328 duration_in_ms=2230.34
2020-01-29 18:37:17,317 [salt.minion      :1711][INFO    ][6350] Returning information for job: 20200129183709404841
2020-01-29 18:37:18,251 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command state.apply with jid 20200129183718235125
2020-01-29 18:37:18,283 [salt.minion      :1432][INFO    ][6375] Starting a new job with PID 6375
2020-01-29 18:37:26,547 [salt.state       :915 ][INFO    ][6375] Loading fresh modules for state activity
2020-01-29 18:37:26,653 [salt.state       :1780][INFO    ][6375] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 18:37:26.653365
2020-01-29 18:37:26,653 [salt.state       :1813][INFO    ][6375] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-01-29 18:37:26,655 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6375] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-01-29 18:37:28,794 [salt.state       :300 ][INFO    ][6375] {'pid': 6396, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-01-29 18:37:28,795 [salt.state       :1951][INFO    ][6375] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 18:37:28.795001 duration_in_ms=2141.636
2020-01-29 18:37:28,797 [salt.state       :1780][INFO    ][6375] Running state [maas.deploy_machines] at time 18:37:28.797753
2020-01-29 18:37:28,798 [salt.state       :1813][INFO    ][6375] Executing state module.run for [maas.deploy_machines]
2020-01-29 18:37:28,799 [salt.utils.decorators:613 ][WARNING ][6375] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-29 18:37:29,320 [salt.loaded.ext.module.maas:684 ][INFO    ][6375] deploymachines hwe_kernel=ga-18.04 system_id=mx7bck distro_series=bionic
2020-01-29 18:37:31,232 [salt.loaded.ext.module.maas:684 ][INFO    ][6375] deploymachines hwe_kernel=ga-18.04 system_id=pt7866 distro_series=bionic
2020-01-29 18:37:33,154 [salt.state       :300 ][INFO    ][6375] {'ret': {'updated': ['gtw01', 'ctl01'], 'errors': {}, 'success': ['cmp002', 'cmp001']}}
2020-01-29 18:37:33,154 [salt.state       :1951][INFO    ][6375] Completed state [maas.deploy_machines] at time 18:37:33.154748 duration_in_ms=4356.995
2020-01-29 18:37:33,158 [salt.minion      :1711][INFO    ][6375] Returning information for job: 20200129183718235125
2020-01-29 18:37:34,136 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command state.apply with jid 20200129183734076751
2020-01-29 18:37:34,162 [salt.minion      :1432][INFO    ][6417] Starting a new job with PID 6417
2020-01-29 18:37:35,187 [salt.state       :915 ][INFO    ][6417] Loading fresh modules for state activity
2020-01-29 18:37:35,297 [salt.state       :1780][INFO    ][6417] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 18:37:35.297016
2020-01-29 18:37:35,298 [salt.state       :1813][INFO    ][6417] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-01-29 18:37:35,302 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6417] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-01-29 18:37:37,526 [salt.state       :300 ][INFO    ][6417] {'pid': 6424, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-01-29 18:37:37,527 [salt.state       :1951][INFO    ][6417] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 18:37:37.527404 duration_in_ms=2230.39
2020-01-29 18:37:37,529 [salt.state       :1780][INFO    ][6417] Running state [maas.wait_for_machine_status] at time 18:37:37.529124
2020-01-29 18:37:37,531 [salt.state       :1813][INFO    ][6417] Executing state module.run for [maas.wait_for_machine_status]
2020-01-29 18:37:37,531 [salt.utils.decorators:613 ][WARNING ][6417] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-29 18:37:39,659 [salt.loaded.ext.module.maas:1024][INFO    ][6417] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2247.88565302s left)
2020-01-29 18:37:49,136 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129183749121905
2020-01-29 18:37:49,165 [salt.minion      :1432][INFO    ][6444] Starting a new job with PID 6444
2020-01-29 18:37:49,198 [salt.minion      :1711][INFO    ][6444] Returning information for job: 20200129183749121905
2020-01-29 18:38:11,683 [salt.loaded.ext.module.maas:1024][INFO    ][6417] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2215.86143303s left)
2020-01-29 18:38:19,228 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129183819218500
2020-01-29 18:38:19,256 [salt.minion      :1432][INFO    ][6477] Starting a new job with PID 6477
2020-01-29 18:38:19,284 [salt.minion      :1711][INFO    ][6477] Returning information for job: 20200129183819218500
2020-01-29 18:38:43,838 [salt.loaded.ext.module.maas:1024][INFO    ][6417] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2183.70632696s left)
2020-01-29 18:38:49,316 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129183849301722
2020-01-29 18:38:49,340 [salt.minion      :1432][INFO    ][6515] Starting a new job with PID 6515
2020-01-29 18:38:49,366 [salt.minion      :1711][INFO    ][6515] Returning information for job: 20200129183849301722
2020-01-29 18:39:16,218 [salt.loaded.ext.module.maas:1024][INFO    ][6417] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2151.32589793s left)
2020-01-29 18:39:19,404 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129183919390520
2020-01-29 18:39:19,437 [salt.minion      :1432][INFO    ][6542] Starting a new job with PID 6542
2020-01-29 18:39:19,464 [salt.minion      :1711][INFO    ][6542] Returning information for job: 20200129183919390520
2020-01-29 18:39:48,518 [salt.loaded.ext.module.maas:1024][INFO    ][6417] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2119.02601194s left)
2020-01-29 18:39:49,489 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129183949474562
2020-01-29 18:39:49,519 [salt.minion      :1432][INFO    ][6571] Starting a new job with PID 6571
2020-01-29 18:39:49,545 [salt.minion      :1711][INFO    ][6571] Returning information for job: 20200129183949474562
2020-01-29 18:40:19,575 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129184019567125
2020-01-29 18:40:19,598 [salt.minion      :1432][INFO    ][6609] Starting a new job with PID 6609
2020-01-29 18:40:19,623 [salt.minion      :1711][INFO    ][6609] Returning information for job: 20200129184019567125
2020-01-29 18:40:20,566 [salt.loaded.ext.module.maas:1024][INFO    ][6417] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2086.978302s left)
2020-01-29 18:40:49,654 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129184049641103
2020-01-29 18:40:49,676 [salt.minion      :1432][INFO    ][6672] Starting a new job with PID 6672
2020-01-29 18:40:49,706 [salt.minion      :1711][INFO    ][6672] Returning information for job: 20200129184049641103
2020-01-29 18:40:52,803 [salt.loaded.ext.module.maas:1024][INFO    ][6417] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2054.74144292s left)
2020-01-29 18:41:19,762 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129184119747865
2020-01-29 18:41:19,794 [salt.minion      :1432][INFO    ][6727] Starting a new job with PID 6727
2020-01-29 18:41:19,831 [salt.minion      :1711][INFO    ][6727] Returning information for job: 20200129184119747865
2020-01-29 18:41:25,201 [salt.loaded.ext.module.maas:1024][INFO    ][6417] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2022.34348202s left)
2020-01-29 18:41:49,878 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129184149858904
2020-01-29 18:41:49,907 [salt.minion      :1432][INFO    ][6756] Starting a new job with PID 6756
2020-01-29 18:41:49,934 [salt.minion      :1711][INFO    ][6756] Returning information for job: 20200129184149858904
2020-01-29 18:41:57,491 [salt.loaded.ext.module.maas:1024][INFO    ][6417] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1990.05301404s left)
2020-01-29 18:42:19,994 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129184219975605
2020-01-29 18:42:20,024 [salt.minion      :1432][INFO    ][6782] Starting a new job with PID 6782
2020-01-29 18:42:20,052 [salt.minion      :1711][INFO    ][6782] Returning information for job: 20200129184219975605
2020-01-29 18:42:29,869 [salt.loaded.ext.module.maas:1024][INFO    ][6417] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1957.67515802s left)
2020-01-29 18:42:50,092 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129184250079270
2020-01-29 18:42:50,122 [salt.minion      :1432][INFO    ][6809] Starting a new job with PID 6809
2020-01-29 18:42:50,147 [salt.minion      :1711][INFO    ][6809] Returning information for job: 20200129184250079270
2020-01-29 18:43:02,131 [salt.loaded.ext.module.maas:1024][INFO    ][6417] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1925.4130919s left)
2020-01-29 18:43:20,220 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129184320204555
2020-01-29 18:43:20,255 [salt.minion      :1432][INFO    ][6834] Starting a new job with PID 6834
2020-01-29 18:43:20,280 [salt.minion      :1711][INFO    ][6834] Returning information for job: 20200129184320204555
2020-01-29 18:43:34,439 [salt.loaded.ext.module.maas:1024][INFO    ][6417] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1893.10525203s left)
2020-01-29 18:43:50,293 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129184350285876
2020-01-29 18:43:50,307 [salt.minion      :1432][INFO    ][6863] Starting a new job with PID 6863
2020-01-29 18:43:50,331 [salt.minion      :1711][INFO    ][6863] Returning information for job: 20200129184350285876
2020-01-29 18:44:06,789 [salt.loaded.ext.module.maas:1024][INFO    ][6417] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1860.75478506s left)
2020-01-29 18:44:20,403 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129184420394034
2020-01-29 18:44:20,426 [salt.minion      :1432][INFO    ][6888] Starting a new job with PID 6888
2020-01-29 18:44:20,459 [salt.minion      :1711][INFO    ][6888] Returning information for job: 20200129184420394034
2020-01-29 18:44:39,079 [salt.loaded.ext.module.maas:1024][INFO    ][6417] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1828.46542192s left)
2020-01-29 18:44:50,531 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129184450516993
2020-01-29 18:44:50,561 [salt.minion      :1432][INFO    ][6916] Starting a new job with PID 6916
2020-01-29 18:44:50,590 [salt.minion      :1711][INFO    ][6916] Returning information for job: 20200129184450516993
2020-01-29 18:45:11,575 [salt.loaded.ext.module.maas:1024][INFO    ][6417] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1795.96928596s left)
2020-01-29 18:45:20,683 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129184520667797
2020-01-29 18:45:20,710 [salt.minion      :1432][INFO    ][6945] Starting a new job with PID 6945
2020-01-29 18:45:20,736 [salt.minion      :1711][INFO    ][6945] Returning information for job: 20200129184520667797
2020-01-29 18:45:43,969 [salt.loaded.ext.module.maas:1024][INFO    ][6417] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1763.57573104s left)
2020-01-29 18:45:50,832 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129184550817511
2020-01-29 18:45:50,858 [salt.minion      :1432][INFO    ][6973] Starting a new job with PID 6973
2020-01-29 18:45:50,883 [salt.minion      :1711][INFO    ][6973] Returning information for job: 20200129184550817511
2020-01-29 18:46:16,288 [salt.loaded.ext.module.maas:1024][INFO    ][6417] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1731.25629711s left)
2020-01-29 18:46:20,990 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129184620971521
2020-01-29 18:46:21,014 [salt.minion      :1432][INFO    ][7000] Starting a new job with PID 7000
2020-01-29 18:46:21,042 [salt.minion      :1711][INFO    ][7000] Returning information for job: 20200129184620971521
2020-01-29 18:46:48,776 [salt.loaded.ext.module.maas:1024][INFO    ][6417] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1698.76778388s left)
2020-01-29 18:46:51,154 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129184651134007
2020-01-29 18:46:51,183 [salt.minion      :1432][INFO    ][7029] Starting a new job with PID 7029
2020-01-29 18:46:51,211 [salt.minion      :1711][INFO    ][7029] Returning information for job: 20200129184651134007
2020-01-29 18:47:21,075 [salt.loaded.ext.module.maas:1024][INFO    ][6417] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1666.46945906s left)
2020-01-29 18:47:21,301 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129184721290355
2020-01-29 18:47:21,327 [salt.minion      :1432][INFO    ][7054] Starting a new job with PID 7054
2020-01-29 18:47:21,359 [salt.minion      :1711][INFO    ][7054] Returning information for job: 20200129184721290355
2020-01-29 18:47:51,478 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129184751465385
2020-01-29 18:47:51,503 [salt.minion      :1432][INFO    ][7090] Starting a new job with PID 7090
2020-01-29 18:47:51,531 [salt.minion      :1711][INFO    ][7090] Returning information for job: 20200129184751465385
2020-01-29 18:47:53,506 [salt.loaded.ext.module.maas:1024][INFO    ][6417] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1634.03786302s left)
2020-01-29 18:48:21,602 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command saltutil.find_job with jid 20200129184821594022
2020-01-29 18:48:21,626 [salt.minion      :1432][INFO    ][7135] Starting a new job with PID 7135
2020-01-29 18:48:21,652 [salt.minion      :1711][INFO    ][7135] Returning information for job: 20200129184821594022
2020-01-29 18:48:23,128 [salt.utils.schedule:1377][INFO    ][2039] Running scheduled job: __mine_interval
2020-01-29 18:48:27,352 [salt.state       :300 ][INFO    ][6417] {'ret': True}
2020-01-29 18:48:27,355 [salt.state       :1951][INFO    ][6417] Completed state [maas.wait_for_machine_status] at time 18:48:27.353152 duration_in_ms=649824.026
2020-01-29 18:48:27,358 [salt.minion      :1711][INFO    ][6417] Returning information for job: 20200129183734076751
2020-01-29 19:39:00,982 [salt.minion      :1308][INFO    ][2039] User sudo_ubuntu Executing command cp.push_dir with jid 20200129193900969111
2020-01-29 19:39:01,012 [salt.minion      :1432][INFO    ][9310] Starting a new job with PID 9310
