2020-02-25 02:36:14,443 [salt.utils.decorators:613 ][WARNING ][1551] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-25 02:36:15,631 [salt.utils.decorators:613 ][WARNING ][1551] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-25 02:36:27,654 [salt.loaded.int.states.file:2298][WARNING ][1695] 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-02-25 02:36:32,547 [salt.loaded.int.module.cmdmod:395 ][INFO    ][1949] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2020-02-25 02:36:32,591 [salt.loaded.int.module.cmdmod:395 ][INFO    ][1949] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2020-02-25 02:36:32,646 [salt.utils.parsers:1051][WARNING ][154] Minion received a SIGTERM. Exiting.
2020-02-25 02:36:33,880 [salt.cli.daemons :293 ][INFO    ][2059] Setting up the Salt Minion "mas01.mcp-fdio-noha.local"
2020-02-25 02:36:34,135 [salt.cli.daemons :82  ][INFO    ][2059] Starting up the Salt Minion
2020-02-25 02:36:34,137 [salt.utils.event :1017][INFO    ][2059] Starting pull socket on /var/run/salt/minion/minion_event_38d774b16c_pull.ipc
2020-02-25 02:36:35,602 [salt.minion      :976 ][INFO    ][2059] Creating minion process manager
2020-02-25 02:36:36,193 [salt.state       :2022][WARNING ][1940] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2020-02-25 02:36:36,955 [salt.utils.decorators:613 ][WARNING ][1940] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-25 02:36:37,951 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][2059] Executing command ['date', '+%z'] in directory '/root'
2020-02-25 02:36:37,983 [salt.utils.schedule:568 ][INFO    ][2059] Updating job settings for scheduled job: __mine_interval
2020-02-25 02:36:37,985 [salt.minion      :1108][INFO    ][2059] Added mine.update to scheduler
2020-02-25 02:36:37,990 [salt.minion      :1975][INFO    ][2059] Minion is starting as user 'root'
2020-02-25 02:36:38,010 [salt.minion      :2336][INFO    ][2059] Minion is ready to receive requests!
2020-02-25 02:36:44,297 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225023644285093
2020-02-25 02:36:44,322 [salt.minion      :1432][INFO    ][2452] Starting a new job with PID 2452
2020-02-25 02:36:44,350 [salt.minion      :1711][INFO    ][2452] Returning information for job: 20200225023644285093
2020-02-25 02:36:53,519 [salt.utils.decorators:613 ][WARNING ][1940] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-25 02:37:10,492 [salt.utils.decorators:613 ][WARNING ][1940] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-25 02:37:11,417 [salt.utils.decorators:613 ][WARNING ][1940] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-25 02:37:14,128 [salt.loaded.ext.module.maasng:1008][WARNING ][1940] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2020-02-25 02:37:14,129 [salt.loaded.ext.module.maasng:1011][WARNING ][1940] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2020-02-25 02:37:14,464 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225023714451428
2020-02-25 02:37:14,489 [salt.minion      :1432][INFO    ][2607] Starting a new job with PID 2607
2020-02-25 02:37:14,526 [salt.minion      :1711][INFO    ][2607] Returning information for job: 20200225023714451428
2020-02-25 02:37:14,709 [salt.loaded.ext.module.maasng:1235][WARNING ][1940] Ignoring parameter vlan:0
2020-02-25 02:37:15,476 [salt.utils.decorators:613 ][WARNING ][1940] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-25 02:37:16,785 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command state.apply with jid 20200225023716771057
2020-02-25 02:37:16,806 [salt.minion      :1432][INFO    ][2642] Starting a new job with PID 2642
2020-02-25 02:37:25,194 [salt.state       :915 ][INFO    ][2642] Loading fresh modules for state activity
2020-02-25 02:37:25,269 [salt.fileclient  :1219][INFO    ][2642] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2020-02-25 02:37:25,325 [salt.state       :1780][INFO    ][2642] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:37:25.325446
2020-02-25 02:37:25,325 [salt.state       :1813][INFO    ][2642] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-25 02:37:25,327 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2642] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-25 02:37:27,447 [salt.state       :300 ][INFO    ][2642] {'pid': 2649, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-25 02:37:27,449 [salt.state       :1951][INFO    ][2642] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:37:27.449469 duration_in_ms=2124.023
2020-02-25 02:37:27,452 [salt.state       :1780][INFO    ][2642] Running state [maas.process_machines] at time 02:37:27.452398
2020-02-25 02:37:27,453 [salt.state       :1813][INFO    ][2642] Executing state module.run for [maas.process_machines]
2020-02-25 02:37:27,453 [salt.utils.decorators:613 ][WARNING ][2642] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-25 02:37:27,505 [salt.loaded.ext.module.maas:412 ][WARNING ][2642] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-25 02:37:27,506 [salt.loaded.ext.module.maas:92  ][INFO    ][2642] 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-02-25 02:37:28,424 [salt.loaded.ext.module.maas:412 ][WARNING ][2642] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-25 02:37:28,425 [salt.loaded.ext.module.maas:92  ][INFO    ][2642] 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-02-25 02:37:29,264 [salt.loaded.ext.module.maas:412 ][WARNING ][2642] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-25 02:37:29,265 [salt.loaded.ext.module.maas:92  ][INFO    ][2642] 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-02-25 02:37:30,225 [salt.loaded.ext.module.maas:412 ][WARNING ][2642] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-25 02:37:30,226 [salt.loaded.ext.module.maas:92  ][INFO    ][2642] 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-02-25 02:37:31,026 [salt.state       :300 ][INFO    ][2642] {'ret': {'updated': [], 'errors': {}, 'success': ['gtw01', 'cmp002', 'cmp001', 'ctl01']}}
2020-02-25 02:37:31,027 [salt.state       :1951][INFO    ][2642] Completed state [maas.process_machines] at time 02:37:31.027243 duration_in_ms=3574.844
2020-02-25 02:37:31,031 [salt.minion      :1711][INFO    ][2642] Returning information for job: 20200225023716771057
2020-02-25 02:38:02,997 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command state.apply with jid 20200225023802985379
2020-02-25 02:38:03,020 [salt.minion      :1432][INFO    ][2724] Starting a new job with PID 2724
2020-02-25 02:38:11,469 [salt.state       :915 ][INFO    ][2724] Loading fresh modules for state activity
2020-02-25 02:38:11,535 [salt.fileclient  :1219][INFO    ][2724] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2020-02-25 02:38:11,587 [salt.state       :1780][INFO    ][2724] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:38:11.586972
2020-02-25 02:38:11,587 [salt.state       :1813][INFO    ][2724] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-25 02:38:11,589 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2724] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-25 02:38:13,862 [salt.state       :300 ][INFO    ][2724] {'pid': 2740, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-25 02:38:13,863 [salt.state       :1951][INFO    ][2724] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:38:13.863496 duration_in_ms=2276.522
2020-02-25 02:38:13,867 [salt.state       :1780][INFO    ][2724] Running state [maas.wait_for_machine_status] at time 02:38:13.867308
2020-02-25 02:38:13,868 [salt.state       :1813][INFO    ][2724] Executing state module.run for [maas.wait_for_machine_status]
2020-02-25 02:38:13,868 [salt.utils.decorators:613 ][WARNING ][2724] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-25 02:38:14,537 [salt.loaded.ext.module.maas:1024][INFO    ][2724] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1499.34612298s left)
2020-02-25 02:38:18,114 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225023818097563
2020-02-25 02:38:18,142 [salt.minion      :1432][INFO    ][2749] Starting a new job with PID 2749
2020-02-25 02:38:18,167 [salt.minion      :1711][INFO    ][2749] Returning information for job: 20200225023818097563
2020-02-25 02:38:45,234 [salt.loaded.ext.module.maas:1024][INFO    ][2724] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1468.64858699s left)
2020-02-25 02:38:48,203 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225023848189182
2020-02-25 02:38:48,225 [salt.minion      :1432][INFO    ][2782] Starting a new job with PID 2782
2020-02-25 02:38:48,251 [salt.minion      :1711][INFO    ][2782] Returning information for job: 20200225023848189182
2020-02-25 02:39:15,855 [salt.loaded.ext.module.maas:1024][INFO    ][2724] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1438.02793908s left)
2020-02-25 02:39:18,270 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225023918258603
2020-02-25 02:39:18,292 [salt.minion      :1432][INFO    ][2800] Starting a new job with PID 2800
2020-02-25 02:39:18,318 [salt.minion      :1711][INFO    ][2800] Returning information for job: 20200225023918258603
2020-02-25 02:39:46,482 [salt.loaded.ext.module.maas:1024][INFO    ][2724] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1407.40098095s left)
2020-02-25 02:39:48,346 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225023948337527
2020-02-25 02:39:48,367 [salt.minion      :1432][INFO    ][2834] Starting a new job with PID 2834
2020-02-25 02:39:48,391 [salt.minion      :1711][INFO    ][2834] Returning information for job: 20200225023948337527
2020-02-25 02:40:17,349 [salt.loaded.ext.module.maas:1024][INFO    ][2724] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1376.53321195s left)
2020-02-25 02:40:18,411 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225024018393710
2020-02-25 02:40:18,431 [salt.minion      :1432][INFO    ][2864] Starting a new job with PID 2864
2020-02-25 02:40:18,460 [salt.minion      :1711][INFO    ][2864] Returning information for job: 20200225024018393710
2020-02-25 02:40:48,302 [salt.loaded.ext.module.maas:1024][INFO    ][2724] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1345.58070302s left)
2020-02-25 02:40:48,475 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225024048465612
2020-02-25 02:40:48,502 [salt.minion      :1432][INFO    ][2930] Starting a new job with PID 2930
2020-02-25 02:40:48,526 [salt.minion      :1711][INFO    ][2930] Returning information for job: 20200225024048465612
2020-02-25 02:41:18,585 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225024118571086
2020-02-25 02:41:18,610 [salt.minion      :1432][INFO    ][2961] Starting a new job with PID 2961
2020-02-25 02:41:18,637 [salt.minion      :1711][INFO    ][2961] Returning information for job: 20200225024118571086
2020-02-25 02:41:19,408 [salt.loaded.ext.module.maas:1024][INFO    ][2724] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1314.47506094s left)
2020-02-25 02:41:48,675 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225024148665069
2020-02-25 02:41:48,693 [salt.minion      :1432][INFO    ][3025] Starting a new job with PID 3025
2020-02-25 02:41:48,724 [salt.minion      :1711][INFO    ][3025] Returning information for job: 20200225024148665069
2020-02-25 02:41:50,534 [salt.loaded.ext.module.maas:1024][INFO    ][2724] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1283.34819698s left)
2020-02-25 02:42:18,811 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225024218794970
2020-02-25 02:42:18,833 [salt.minion      :1432][INFO    ][3078] Starting a new job with PID 3078
2020-02-25 02:42:18,860 [salt.minion      :1711][INFO    ][3078] Returning information for job: 20200225024218794970
2020-02-25 02:42:21,990 [salt.loaded.ext.module.maas:1024][INFO    ][2724] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1251.89257193s left)
2020-02-25 02:42:48,922 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225024248906814
2020-02-25 02:42:48,948 [salt.minion      :1432][INFO    ][3128] Starting a new job with PID 3128
2020-02-25 02:42:48,975 [salt.minion      :1711][INFO    ][3128] Returning information for job: 20200225024248906814
2020-02-25 02:42:53,805 [salt.loaded.ext.module.maas:1024][INFO    ][2724] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1220.07739592s left)
2020-02-25 02:43:19,040 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225024319025207
2020-02-25 02:43:19,071 [salt.minion      :1432][INFO    ][3175] Starting a new job with PID 3175
2020-02-25 02:43:19,101 [salt.minion      :1711][INFO    ][3175] Returning information for job: 20200225024319025207
2020-02-25 02:43:25,681 [salt.loaded.ext.module.maas:1024][INFO    ][2724] Waiting status:Ready|Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:1500s (1188.20118093s left)
2020-02-25 02:43:49,156 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225024349142257
2020-02-25 02:43:49,185 [salt.minion      :1432][INFO    ][3211] Starting a new job with PID 3211
2020-02-25 02:43:49,219 [salt.minion      :1711][INFO    ][3211] Returning information for job: 20200225024349142257
2020-02-25 02:43:57,486 [salt.loaded.ext.module.maas:1024][INFO    ][2724] Waiting status:Ready|Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:1500s (1156.39646101s left)
2020-02-25 02:44:19,287 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225024419280201
2020-02-25 02:44:19,307 [salt.minion      :1432][INFO    ][3275] Starting a new job with PID 3275
2020-02-25 02:44:19,335 [salt.minion      :1711][INFO    ][3275] Returning information for job: 20200225024419280201
2020-02-25 02:44:29,754 [salt.loaded.ext.module.maas:1024][INFO    ][2724] Waiting status:Ready|Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:1500s (1124.12854695s left)
2020-02-25 02:44:49,389 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225024449376136
2020-02-25 02:44:49,410 [salt.minion      :1432][INFO    ][3308] Starting a new job with PID 3308
2020-02-25 02:44:49,438 [salt.minion      :1711][INFO    ][3308] Returning information for job: 20200225024449376136
2020-02-25 02:45:02,027 [salt.state       :300 ][INFO    ][2724] {'ret': True}
2020-02-25 02:45:02,028 [salt.state       :1951][INFO    ][2724] Completed state [maas.wait_for_machine_status] at time 02:45:02.028123 duration_in_ms=408160.816
2020-02-25 02:45:02,034 [salt.minion      :1711][INFO    ][2724] Returning information for job: 20200225023802985379
2020-02-25 02:45:03,016 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command state.apply with jid 20200225024503004119
2020-02-25 02:45:03,041 [salt.minion      :1432][INFO    ][3325] Starting a new job with PID 3325
2020-02-25 02:45:11,433 [salt.state       :915 ][INFO    ][3325] Loading fresh modules for state activity
2020-02-25 02:45:11,505 [salt.fileclient  :1219][INFO    ][3325] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2020-02-25 02:45:11,620 [salt.state       :1780][INFO    ][3325] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:45:11.620254
2020-02-25 02:45:11,621 [salt.state       :1813][INFO    ][3325] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-25 02:45:11,623 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3325] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-25 02:45:13,863 [salt.state       :300 ][INFO    ][3325] {'pid': 3341, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-25 02:45:13,865 [salt.state       :1951][INFO    ][3325] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:45:13.865225 duration_in_ms=2244.986
2020-02-25 02:45:13,868 [salt.state       :1780][INFO    ][3325] Running state [maas_machines_storage_cmp002_lvm] at time 02:45:13.868131
2020-02-25 02:45:13,869 [salt.state       :1813][INFO    ][3325] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2020-02-25 02:45:14,973 [salt.loaded.ext.module.maasng:610 ][INFO    ][3325] cwqman
2020-02-25 02:45:14,973 [salt.loaded.ext.module.maasng:626 ][INFO    ][3325] sda
2020-02-25 02:45:15,525 [salt.loaded.ext.module.maasng:361 ][INFO    ][3325] cwqman
2020-02-25 02:45:15,609 [salt.loaded.ext.module.maasng:367 ][INFO    ][3325] [{u'resource_uri': u'/MAAS/api/2.0/nodes/cwqman/blockdevices/1/', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'c6f171c0-326c-4184-a8c0-6f8047a4cf83', u'resource_uri': u'/MAAS/api/2.0/nodes/cwqman/blockdevices/1/partition/1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'cwqman', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'9257e218-bdf2-4c06-aa9f-81b13bbdf4be', 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'filesystem': None, u'uuid': None, u'id': 1, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'cwqman', 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'type': u'physical', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}, {u'resource_uri': u'/MAAS/api/2.0/nodes/cwqman/blockdevices/3/', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'0fe9e172-9b0a-4ec5-87cb-beb628484271', u'fstype': u'ext4'}, u'uuid': u'6f951e10-ccd2-4c3a-8561-3a0b47e86413', u'id': 3, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'cwqman', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'serial': None, u'size': 800097042432}]
2020-02-25 02:45:15,609 [salt.loaded.ext.module.maasng:632 ][INFO    ][3325] vgroot
2020-02-25 02:45:15,610 [salt.loaded.ext.module.maasng:635 ][INFO    ][3325] lvroot
2020-02-25 02:45:15,610 [salt.loaded.ext.module.maasng:639 ][INFO    ][3325] 107374182400
2020-02-25 02:45:16,266 [salt.loaded.ext.module.maasng:645 ][INFO    ][3325] {u'node_type_name': u'Machine', u'hwe_kernel': None, u'swap_size': None, u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'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': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w7e46d', 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'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': 4, u'resource_uri': u'/MAAS/api/2.0/subnets/4/'}, u'ip_address': u'192.168.11.38', u'id': 18, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w7e46d', 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'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': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w7e46d', 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'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': 4, u'resource_uri': u'/MAAS/api/2.0/subnets/4/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'firmware_version': u'bc 7.8.24', u'system_id': u'cwqman', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/cwqman/interfaces/5/'}, u'min_hwe_kernel': u'ga-18.04', u'node_type': 0, 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'status_action': u'', 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/cwqman/', 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'cwqman', 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'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/cwqman/blockdevices/9/', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'uuid': u'5c70ccb2-d0ed-4576-b6c0-ca6be9b432e3', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'cwqman', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'770e1ec8-3a2d-41d9-870c-42058ab1ba9d', 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'size': 107374182400}], u'blockdevice_set': [{u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/cwqman/blockdevices/1/', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'2e616a92-33e4-40f2-9581-b36ee397d290', u'resource_uri': u'/MAAS/api/2.0/nodes/cwqman/blockdevices/1/partition/5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'cwqman', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'44e8daf9-7aad-4f8b-a910-7f6c23cdc69f', 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'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'cwqman', 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'size': 800109715456}, {u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/cwqman/blockdevices/9/', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'uuid': u'5c70ccb2-d0ed-4576-b6c0-ca6be9b432e3', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'cwqman', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'770e1ec8-3a2d-41d9-870c-42058ab1ba9d', 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'size': 107374182400}], u'status': 4, u'bcaches': [], u'cpu_count': 40, u'power_state': u'off', u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'cwqman', u'id': 5}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'commissioning_status_name': u'Passed', 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'storage_test_status': 2, u'locked': False, u'current_commissioning_result_id': 4, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/cwqman/blockdevices/1/', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'2e616a92-33e4-40f2-9581-b36ee397d290', u'resource_uri': u'/MAAS/api/2.0/nodes/cwqman/blockdevices/1/partition/5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'cwqman', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'44e8daf9-7aad-4f8b-a910-7f6c23cdc69f', 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'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'cwqman', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'firmware_version': u'5.22', u'block_size': 4096, u'type': u'physical', u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'memory_test_status_name': u'Unknown', u'commissioning_status': 2, u'architecture': u'amd64/generic', 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': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w7e46d', 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'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': 4, u'resource_uri': u'/MAAS/api/2.0/subnets/4/'}, u'ip_address': u'192.168.11.38', u'id': 18, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w7e46d', 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'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': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w7e46d', 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'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': 4, u'resource_uri': u'/MAAS/api/2.0/subnets/4/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'firmware_version': u'bc 7.8.24', u'system_id': u'cwqman', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/cwqman/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'vlan': None, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:50', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'firmware_version': u'bc 7.8.24', u'system_id': u'cwqman', u'type': u'physical', u'id': 10, u'resource_uri': u'/MAAS/api/2.0/nodes/cwqman/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': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w7e46d', 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'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': 4, u'resource_uri': u'/MAAS/api/2.0/subnets/4/'}, u'id': 19, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w7e46d', 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'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': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w7e46d', 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'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': 4, u'resource_uri': u'/MAAS/api/2.0/subnets/4/'}, u'ip_address': u'192.168.11.40'}], u'mac_address': u'9c:b6:54:8a:10:1c', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'firmware_version': u'bc 7.8.24', u'system_id': u'cwqman', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/cwqman/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'vlan': None, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:51', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'firmware_version': u'bc 7.8.24', u'system_id': u'cwqman', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/cwqman/interfaces/12/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens2f0', u'links': [{u'id': 20, u'mode': u'link_up'}], u'tags': [u'sriov'], 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'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:48', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'firmware_version': u'bc 7.8.24', u'system_id': u'cwqman', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/cwqman/interfaces/13/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens2f1', u'links': [{u'id': 21, u'mode': u'link_up'}], u'tags': [u'sriov'], 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'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:49', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'firmware_version': u'bc 7.8.24', u'system_id': u'cwqman', u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/cwqman/interfaces/14/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'boot_disk': {u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/cwqman/blockdevices/1/', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'2e616a92-33e4-40f2-9581-b36ee397d290', u'resource_uri': u'/MAAS/api/2.0/nodes/cwqman/blockdevices/1/partition/5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'cwqman', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'44e8daf9-7aad-4f8b-a910-7f6c23cdc69f', 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'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'cwqman', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'firmware_version': u'5.22', u'block_size': 4096, u'type': u'physical', u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}}
2020-02-25 02:45:16,268 [salt.state       :300 ][INFO    ][3325] {'new': {'storage_layout': 'lvm'}}
2020-02-25 02:45:16,269 [salt.state       :1951][INFO    ][3325] Completed state [maas_machines_storage_cmp002_lvm] at time 02:45:16.268958 duration_in_ms=2400.824
2020-02-25 02:45:16,269 [salt.state       :1780][INFO    ][3325] Running state [maas_machines_storage_cmp001_lvm] at time 02:45:16.269396
2020-02-25 02:45:16,269 [salt.state       :1813][INFO    ][3325] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2020-02-25 02:45:17,475 [salt.loaded.ext.module.maasng:610 ][INFO    ][3325] xkpar3
2020-02-25 02:45:17,475 [salt.loaded.ext.module.maasng:626 ][INFO    ][3325] sda
2020-02-25 02:45:18,016 [salt.loaded.ext.module.maasng:361 ][INFO    ][3325] xkpar3
2020-02-25 02:45:18,069 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225024518060210
2020-02-25 02:45:18,094 [salt.minion      :1432][INFO    ][3350] Starting a new job with PID 3350
2020-02-25 02:45:18,100 [salt.loaded.ext.module.maasng:367 ][INFO    ][3325] [{u'resource_uri': u'/MAAS/api/2.0/nodes/xkpar3/blockdevices/2/', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'41492507-20a7-4123-b428-db19ba543c7c', u'resource_uri': u'/MAAS/api/2.0/nodes/xkpar3/blockdevices/2/partition/2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xkpar3', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'f7089426-af0a-47b0-b6c3-790b6c868513', 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'filesystem': None, u'uuid': None, u'id': 2, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'xkpar3', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, {u'resource_uri': u'/MAAS/api/2.0/nodes/xkpar3/blockdevices/4/', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'c27877a2-fc23-44e4-9c64-ddcbb40302b0', u'fstype': u'ext4'}, u'uuid': u'f54cfa4d-8db9-4322-ab9c-34c780950e78', u'id': 4, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'xkpar3', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'serial': None, u'size': 800097042432}]
2020-02-25 02:45:18,101 [salt.loaded.ext.module.maasng:632 ][INFO    ][3325] vgroot
2020-02-25 02:45:18,101 [salt.loaded.ext.module.maasng:635 ][INFO    ][3325] lvroot
2020-02-25 02:45:18,102 [salt.loaded.ext.module.maasng:639 ][INFO    ][3325] 107374182400
2020-02-25 02:45:18,133 [salt.minion      :1711][INFO    ][3350] Returning information for job: 20200225024518060210
2020-02-25 02:45:18,795 [salt.loaded.ext.module.maasng:645 ][INFO    ][3325] {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.43'], 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'memory_test_status_name': u'Unknown', 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'resource_uri': u'/MAAS/api/2.0/nodes/xkpar3/blockdevices/2/', u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'dbe95158-58e7-49f2-a46c-493ad2946a36', u'resource_uri': u'/MAAS/api/2.0/nodes/xkpar3/blockdevices/2/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xkpar3', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'f26877ed-29a3-4afc-809f-dcf6f5d71c58', 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'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'system_id': u'xkpar3', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id': 2, u'available_size': 0, u'firmware_version': u'5.22', u'block_size': 4096, u'used_size': 800106479616, u'model': u'LOGICAL VOLUME', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, 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/xkpar3/', u'current_commissioning_result_id': 6, 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'xkpar3', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/xkpar3/blockdevices/10/', 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'ba393cb9-3901-4b5a-a597-593c9130aeab', u'fstype': u'ext4'}, u'uuid': u'4efe03df-d479-4be3-bf60-6e28d261e16e', u'used_for': u'ext4 formatted filesystem mounted at /', u'id_path': None, u'system_id': u'xkpar3', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id': 10, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'serial': None, u'size': 107374182400}], u'blockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/xkpar3/blockdevices/2/', u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'id': 2, u'partitions': [{u'uuid': u'dbe95158-58e7-49f2-a46c-493ad2946a36', u'resource_uri': u'/MAAS/api/2.0/nodes/xkpar3/blockdevices/2/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xkpar3', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'f26877ed-29a3-4afc-809f-dcf6f5d71c58', 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'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'xkpar3', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, {u'resource_uri': u'/MAAS/api/2.0/nodes/xkpar3/blockdevices/10/', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'id': 10, u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'ba393cb9-3901-4b5a-a597-593c9130aeab', u'fstype': u'ext4'}, u'uuid': u'4efe03df-d479-4be3-bf60-6e28d261e16e', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'xkpar3', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'serial': None, u'size': 107374182400}], u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'xkpar3', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'commissioning_status_name': u'Passed', 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'bcaches': [], u'locked': False, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/xkpar3/blockdevices/2/', u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'dbe95158-58e7-49f2-a46c-493ad2946a36', u'resource_uri': u'/MAAS/api/2.0/nodes/xkpar3/blockdevices/2/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xkpar3', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'f26877ed-29a3-4afc-809f-dcf6f5d71c58', 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'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'system_id': u'xkpar3', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id': 2, u'available_size': 0, u'firmware_version': u'5.22', u'block_size': 4096, u'used_size': 800106479616, u'model': u'LOGICAL VOLUME', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'disable_ipv4': False, u'commissioning_status': 2, u'min_hwe_kernel': u'ga-18.04', u'boot_interface': {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w7e46d', 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'pxe_admin'}, 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w7e46d', 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'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/subnets/4/'}, u'ip_address': u'192.168.11.39', u'id': 26, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 6, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w7e46d', 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'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/subnets/4/'}, u'ip_address': u'192.168.11.39'}], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'firmware_version': u'bc 7.8.24', u'system_id': u'xkpar3', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/xkpar3/interfaces/6/'}, u'interface_set': [{u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w7e46d', 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'pxe_admin'}, 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w7e46d', 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'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/subnets/4/'}, u'ip_address': u'192.168.11.39', u'id': 26, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 6, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w7e46d', 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'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/subnets/4/'}, u'ip_address': u'192.168.11.39'}], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'firmware_version': u'bc 7.8.24', u'system_id': u'xkpar3', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/xkpar3/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'effective_mtu': 1500, u'enabled': True, u'id': 15, u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d4', u'parents': [], u'firmware_version': u'bc 7.8.24', u'system_id': u'xkpar3', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/xkpar3/interfaces/15/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w7e46d', 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'pxe_admin'}, 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w7e46d', 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'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/subnets/4/'}, u'id': 27, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 16, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w7e46d', 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'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/subnets/4/'}, u'ip_address': u'192.168.11.43'}], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a4', u'parents': [], u'firmware_version': u'bc 7.8.24', u'system_id': u'xkpar3', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/xkpar3/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'effective_mtu': 1500, u'enabled': True, u'id': 17, u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d5', u'parents': [], u'firmware_version': u'bc 7.8.24', u'system_id': u'xkpar3', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/xkpar3/interfaces/17/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'vendor': u'HP', u'name': u'ens2f0', u'links': [{u'id': 28, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 18, u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cc', u'parents': [], u'firmware_version': u'bc 7.8.24', u'system_id': u'xkpar3', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/xkpar3/interfaces/18/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'vendor': u'HP', u'name': u'ens2f1', u'links': [{u'id': 29, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 19, u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cd', u'parents': [], u'firmware_version': u'bc 7.8.24', u'system_id': u'xkpar3', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/xkpar3/interfaces/19/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'node_type_name': u'Machine'}
2020-02-25 02:45:18,798 [salt.state       :300 ][INFO    ][3325] {'new': {'storage_layout': 'lvm'}}
2020-02-25 02:45:18,799 [salt.state       :1951][INFO    ][3325] Completed state [maas_machines_storage_cmp001_lvm] at time 02:45:18.799164 duration_in_ms=2529.766
2020-02-25 02:45:18,805 [salt.minion      :1711][INFO    ][3325] Returning information for job: 20200225024503004119
2020-02-25 02:45:19,758 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command state.apply with jid 20200225024519742939
2020-02-25 02:45:19,784 [salt.minion      :1432][INFO    ][3357] Starting a new job with PID 3357
2020-02-25 02:45:20,923 [salt.state       :915 ][INFO    ][3357] Loading fresh modules for state activity
2020-02-25 02:45:20,986 [salt.fileclient  :1219][INFO    ][3357] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2020-02-25 02:45:21,035 [salt.state       :1780][INFO    ][3357] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:45:21.034902
2020-02-25 02:45:21,035 [salt.state       :1813][INFO    ][3357] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-25 02:45:21,037 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3357] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-25 02:45:23,295 [salt.state       :300 ][INFO    ][3357] {'pid': 3364, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-25 02:45:23,297 [salt.state       :1951][INFO    ][3357] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:45:23.297098 duration_in_ms=2262.197
2020-02-25 02:45:23,298 [salt.state       :1780][INFO    ][3357] Running state [maas.deploy_machines] at time 02:45:23.298934
2020-02-25 02:45:23,299 [salt.state       :1813][INFO    ][3357] Executing state module.run for [maas.deploy_machines]
2020-02-25 02:45:23,299 [salt.utils.decorators:613 ][WARNING ][3357] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-25 02:45:23,871 [salt.loaded.ext.module.maas:684 ][INFO    ][3357] deploymachines hwe_kernel=ga-18.04 system_id=gct4rh distro_series=bionic
2020-02-25 02:45:25,787 [salt.loaded.ext.module.maas:684 ][INFO    ][3357] deploymachines hwe_kernel=ga-18.04 system_id=cwqman distro_series=bionic
2020-02-25 02:45:27,706 [salt.loaded.ext.module.maas:684 ][INFO    ][3357] deploymachines hwe_kernel=ga-18.04 system_id=xkpar3 distro_series=bionic
2020-02-25 02:45:29,710 [salt.loaded.ext.module.maas:684 ][INFO    ][3357] deploymachines hwe_kernel=ga-18.04 system_id=87fdg8 distro_series=bionic
2020-02-25 02:45:31,831 [salt.state       :300 ][INFO    ][3357] {'ret': {'updated': [], 'errors': {}, 'success': ['gtw01', 'cmp002', 'cmp001', 'ctl01']}}
2020-02-25 02:45:31,831 [salt.state       :1951][INFO    ][3357] Completed state [maas.deploy_machines] at time 02:45:31.831596 duration_in_ms=8532.661
2020-02-25 02:45:31,835 [salt.minion      :1711][INFO    ][3357] Returning information for job: 20200225024519742939
2020-02-25 02:45:32,782 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command state.apply with jid 20200225024532770438
2020-02-25 02:45:32,807 [salt.minion      :1432][INFO    ][3402] Starting a new job with PID 3402
2020-02-25 02:45:41,297 [salt.state       :915 ][INFO    ][3402] Loading fresh modules for state activity
2020-02-25 02:45:41,372 [salt.fileclient  :1219][INFO    ][3402] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2020-02-25 02:45:41,428 [salt.state       :1780][INFO    ][3402] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:45:41.428111
2020-02-25 02:45:41,429 [salt.state       :1813][INFO    ][3402] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-25 02:45:41,431 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3402] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-25 02:45:43,715 [salt.state       :300 ][INFO    ][3402] {'pid': 3430, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-25 02:45:43,717 [salt.state       :1951][INFO    ][3402] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:45:43.716177 duration_in_ms=2288.067
2020-02-25 02:45:43,719 [salt.state       :1780][INFO    ][3402] Running state [maas.wait_for_machine_status] at time 02:45:43.719291
2020-02-25 02:45:43,719 [salt.state       :1813][INFO    ][3402] Executing state module.run for [maas.wait_for_machine_status]
2020-02-25 02:45:43,720 [salt.utils.decorators:613 ][WARNING ][3402] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-25 02:45:45,843 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2247.88841319s left)
2020-02-25 02:45:47,824 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225024547812858
2020-02-25 02:45:47,854 [salt.minion      :1432][INFO    ][3446] Starting a new job with PID 3446
2020-02-25 02:45:47,884 [salt.minion      :1711][INFO    ][3446] Returning information for job: 20200225024547812858
2020-02-25 02:46:17,896 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225024617884634
2020-02-25 02:46:17,919 [salt.minion      :1432][INFO    ][3464] Starting a new job with PID 3464
2020-02-25 02:46:17,947 [salt.minion      :1711][INFO    ][3464] Returning information for job: 20200225024617884634
2020-02-25 02:46:18,035 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2215.69705105s left)
2020-02-25 02:46:47,958 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225024647948090
2020-02-25 02:46:47,983 [salt.minion      :1432][INFO    ][3508] Starting a new job with PID 3508
2020-02-25 02:46:48,009 [salt.minion      :1711][INFO    ][3508] Returning information for job: 20200225024647948090
2020-02-25 02:46:50,294 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2183.43791699s left)
2020-02-25 02:47:18,035 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225024718022179
2020-02-25 02:47:18,060 [salt.minion      :1432][INFO    ][3535] Starting a new job with PID 3535
2020-02-25 02:47:18,102 [salt.minion      :1711][INFO    ][3535] Returning information for job: 20200225024718022179
2020-02-25 02:47:22,610 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2151.12175798s left)
2020-02-25 02:47:48,126 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225024748115544
2020-02-25 02:47:48,154 [salt.minion      :1432][INFO    ][3572] Starting a new job with PID 3572
2020-02-25 02:47:48,185 [salt.minion      :1711][INFO    ][3572] Returning information for job: 20200225024748115544
2020-02-25 02:47:54,986 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2118.74598002s left)
2020-02-25 02:48:18,206 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225024818191228
2020-02-25 02:48:18,231 [salt.minion      :1432][INFO    ][3599] Starting a new job with PID 3599
2020-02-25 02:48:18,261 [salt.minion      :1711][INFO    ][3599] Returning information for job: 20200225024818191228
2020-02-25 02:48:27,304 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2086.42813206s left)
2020-02-25 02:48:48,293 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225024848278626
2020-02-25 02:48:48,318 [salt.minion      :1432][INFO    ][3665] Starting a new job with PID 3665
2020-02-25 02:48:48,343 [salt.minion      :1711][INFO    ][3665] Returning information for job: 20200225024848278626
2020-02-25 02:48:59,657 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2054.07440209s left)
2020-02-25 02:49:18,374 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225024918362807
2020-02-25 02:49:18,395 [salt.minion      :1432][INFO    ][3703] Starting a new job with PID 3703
2020-02-25 02:49:18,424 [salt.minion      :1711][INFO    ][3703] Returning information for job: 20200225024918362807
2020-02-25 02:49:30,871 [salt.loaded.ext.module.maas:993 ][INFO    ][3402] Machine cwqman mark broken
2020-02-25 02:49:48,456 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225024948446800
2020-02-25 02:49:48,474 [salt.minion      :1432][INFO    ][3766] Starting a new job with PID 3766
2020-02-25 02:49:48,502 [salt.minion      :1711][INFO    ][3766] Returning information for job: 20200225024948446800
2020-02-25 02:50:01,521 [salt.loaded.ext.module.maas:997 ][INFO    ][3402] Machine cwqman mark fixed
2020-02-25 02:50:02,466 [salt.loaded.ext.module.maas:684 ][INFO    ][3402] deploymachines hwe_kernel=ga-18.04 system_id=cwqman distro_series=bionic
2020-02-25 02:50:04,705 [salt.loaded.ext.module.maas:993 ][INFO    ][3402] Machine xkpar3 mark broken
2020-02-25 02:50:18,532 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225025018515942
2020-02-25 02:50:18,555 [salt.minion      :1432][INFO    ][3810] Starting a new job with PID 3810
2020-02-25 02:50:18,583 [salt.minion      :1711][INFO    ][3810] Returning information for job: 20200225025018515942
2020-02-25 02:50:35,283 [salt.loaded.ext.module.maas:997 ][INFO    ][3402] Machine xkpar3 mark fixed
2020-02-25 02:50:36,357 [salt.loaded.ext.module.maas:684 ][INFO    ][3402] deploymachines hwe_kernel=ga-18.04 system_id=xkpar3 distro_series=bionic
2020-02-25 02:50:38,994 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1954.73745203s left)
2020-02-25 02:50:48,658 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225025048649925
2020-02-25 02:50:48,682 [salt.minion      :1432][INFO    ][3887] Starting a new job with PID 3887
2020-02-25 02:50:48,713 [salt.minion      :1711][INFO    ][3887] Returning information for job: 20200225025048649925
2020-02-25 02:51:11,306 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1922.42569208s left)
2020-02-25 02:51:18,756 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225025118746004
2020-02-25 02:51:18,783 [salt.minion      :1432][INFO    ][3909] Starting a new job with PID 3909
2020-02-25 02:51:18,815 [salt.minion      :1711][INFO    ][3909] Returning information for job: 20200225025118746004
2020-02-25 02:51:43,852 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1889.87987399s left)
2020-02-25 02:51:48,867 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225025148855273
2020-02-25 02:51:48,886 [salt.minion      :1432][INFO    ][3942] Starting a new job with PID 3942
2020-02-25 02:51:48,910 [salt.minion      :1711][INFO    ][3942] Returning information for job: 20200225025148855273
2020-02-25 02:52:16,124 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1857.6073451s left)
2020-02-25 02:52:18,995 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225025218981980
2020-02-25 02:52:19,022 [salt.minion      :1432][INFO    ][3966] Starting a new job with PID 3966
2020-02-25 02:52:19,049 [salt.minion      :1711][INFO    ][3966] Returning information for job: 20200225025218981980
2020-02-25 02:52:48,499 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1825.23294806s left)
2020-02-25 02:52:49,112 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225025249099299
2020-02-25 02:52:49,133 [salt.minion      :1432][INFO    ][4010] Starting a new job with PID 4010
2020-02-25 02:52:49,159 [salt.minion      :1711][INFO    ][4010] Returning information for job: 20200225025249099299
2020-02-25 02:53:19,239 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225025319224047
2020-02-25 02:53:19,258 [salt.minion      :1432][INFO    ][4044] Starting a new job with PID 4044
2020-02-25 02:53:19,293 [salt.minion      :1711][INFO    ][4044] Returning information for job: 20200225025319224047
2020-02-25 02:53:20,858 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1792.87402821s left)
2020-02-25 02:53:49,375 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225025349362938
2020-02-25 02:53:49,398 [salt.minion      :1432][INFO    ][4101] Starting a new job with PID 4101
2020-02-25 02:53:49,433 [salt.minion      :1711][INFO    ][4101] Returning information for job: 20200225025349362938
2020-02-25 02:53:53,402 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1760.32956815s left)
2020-02-25 02:54:19,520 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225025419507271
2020-02-25 02:54:19,546 [salt.minion      :1432][INFO    ][4144] Starting a new job with PID 4144
2020-02-25 02:54:19,579 [salt.minion      :1711][INFO    ][4144] Returning information for job: 20200225025419507271
2020-02-25 02:54:24,577 [salt.loaded.ext.module.maas:993 ][INFO    ][3402] Machine cwqman mark broken
2020-02-25 02:54:49,666 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225025449655672
2020-02-25 02:54:49,692 [salt.minion      :1432][INFO    ][4185] Starting a new job with PID 4185
2020-02-25 02:54:49,719 [salt.minion      :1711][INFO    ][4185] Returning information for job: 20200225025449655672
2020-02-25 02:54:55,186 [salt.loaded.ext.module.maas:997 ][INFO    ][3402] Machine cwqman mark fixed
2020-02-25 02:54:55,672 [salt.loaded.ext.module.maas:1001][INFO    ][3402] Machine cwqman fio test
2020-02-25 02:54:57,680 [salt.loaded.ext.module.maas:993 ][INFO    ][3402] Machine xkpar3 mark broken
2020-02-25 02:55:19,818 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225025519801292
2020-02-25 02:55:19,842 [salt.minion      :1432][INFO    ][4221] Starting a new job with PID 4221
2020-02-25 02:55:19,868 [salt.minion      :1711][INFO    ][4221] Returning information for job: 20200225025519801292
2020-02-25 02:55:28,277 [salt.loaded.ext.module.maas:997 ][INFO    ][3402] Machine xkpar3 mark fixed
2020-02-25 02:55:28,755 [salt.loaded.ext.module.maas:1001][INFO    ][3402] Machine xkpar3 fio test
2020-02-25 02:55:30,414 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1663.3179121s left)
2020-02-25 02:55:49,961 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225025549948429
2020-02-25 02:55:49,977 [salt.minion      :1432][INFO    ][4265] Starting a new job with PID 4265
2020-02-25 02:55:50,004 [salt.minion      :1711][INFO    ][4265] Returning information for job: 20200225025549948429
2020-02-25 02:56:02,325 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1631.40731812s left)
2020-02-25 02:56:20,128 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225025620119007
2020-02-25 02:56:20,146 [salt.minion      :1432][INFO    ][4285] Starting a new job with PID 4285
2020-02-25 02:56:20,175 [salt.minion      :1711][INFO    ][4285] Returning information for job: 20200225025620119007
2020-02-25 02:56:34,186 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1599.54572105s left)
2020-02-25 02:56:50,290 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225025650281868
2020-02-25 02:56:50,315 [salt.minion      :1432][INFO    ][4333] Starting a new job with PID 4333
2020-02-25 02:56:50,342 [salt.minion      :1711][INFO    ][4333] Returning information for job: 20200225025650281868
2020-02-25 02:57:06,063 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1567.66845012s left)
2020-02-25 02:57:20,470 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225025720457345
2020-02-25 02:57:20,494 [salt.minion      :1432][INFO    ][4355] Starting a new job with PID 4355
2020-02-25 02:57:20,528 [salt.minion      :1711][INFO    ][4355] Returning information for job: 20200225025720457345
2020-02-25 02:57:37,895 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1535.83635998s left)
2020-02-25 02:57:50,634 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225025750624007
2020-02-25 02:57:50,660 [salt.minion      :1432][INFO    ][4397] Starting a new job with PID 4397
2020-02-25 02:57:50,688 [salt.minion      :1711][INFO    ][4397] Returning information for job: 20200225025750624007
2020-02-25 02:58:09,833 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1503.89914608s left)
2020-02-25 02:58:20,823 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225025820804970
2020-02-25 02:58:20,849 [salt.minion      :1432][INFO    ][4423] Starting a new job with PID 4423
2020-02-25 02:58:20,874 [salt.minion      :1711][INFO    ][4423] Returning information for job: 20200225025820804970
2020-02-25 02:58:41,804 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1471.92801905s left)
2020-02-25 02:58:50,995 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225025850983501
2020-02-25 02:58:51,021 [salt.minion      :1432][INFO    ][4480] Starting a new job with PID 4480
2020-02-25 02:58:51,047 [salt.minion      :1711][INFO    ][4480] Returning information for job: 20200225025850983501
2020-02-25 02:59:13,633 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1440.09888411s left)
2020-02-25 02:59:21,163 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225025921154580
2020-02-25 02:59:21,188 [salt.minion      :1432][INFO    ][4525] Starting a new job with PID 4525
2020-02-25 02:59:21,213 [salt.minion      :1711][INFO    ][4525] Returning information for job: 20200225025921154580
2020-02-25 02:59:45,829 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1407.90270209s left)
2020-02-25 02:59:51,351 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225025951335995
2020-02-25 02:59:51,371 [salt.minion      :1432][INFO    ][4590] Starting a new job with PID 4590
2020-02-25 02:59:51,398 [salt.minion      :1711][INFO    ][4590] Returning information for job: 20200225025951335995
2020-02-25 03:00:17,894 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1375.83752704s left)
2020-02-25 03:00:21,562 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225030021534860
2020-02-25 03:00:21,593 [salt.minion      :1432][INFO    ][4609] Starting a new job with PID 4609
2020-02-25 03:00:21,620 [salt.minion      :1711][INFO    ][4609] Returning information for job: 20200225030021534860
2020-02-25 03:00:50,246 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1343.48592806s left)
2020-02-25 03:00:51,779 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225030051771782
2020-02-25 03:00:51,801 [salt.minion      :1432][INFO    ][4666] Starting a new job with PID 4666
2020-02-25 03:00:51,827 [salt.minion      :1711][INFO    ][4666] Returning information for job: 20200225030051771782
2020-02-25 03:01:22,003 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225030121990757
2020-02-25 03:01:22,026 [salt.minion      :1432][INFO    ][4687] Starting a new job with PID 4687
2020-02-25 03:01:22,052 [salt.minion      :1711][INFO    ][4687] Returning information for job: 20200225030121990757
2020-02-25 03:01:22,509 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1311.22316217s left)
2020-02-25 03:01:52,026 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225030152013890
2020-02-25 03:01:52,052 [salt.minion      :1432][INFO    ][4720] Starting a new job with PID 4720
2020-02-25 03:01:52,083 [salt.minion      :1711][INFO    ][4720] Returning information for job: 20200225030152013890
2020-02-25 03:01:54,803 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1278.92867708s left)
2020-02-25 03:02:22,074 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225030222062369
2020-02-25 03:02:22,102 [salt.minion      :1432][INFO    ][4742] Starting a new job with PID 4742
2020-02-25 03:02:22,125 [salt.minion      :1711][INFO    ][4742] Returning information for job: 20200225030222062369
2020-02-25 03:02:27,206 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1246.52550602s left)
2020-02-25 03:02:52,117 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225030252102777
2020-02-25 03:02:52,142 [salt.minion      :1432][INFO    ][4777] Starting a new job with PID 4777
2020-02-25 03:02:52,173 [salt.minion      :1711][INFO    ][4777] Returning information for job: 20200225030252102777
2020-02-25 03:02:59,539 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1214.19239497s left)
2020-02-25 03:03:22,156 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225030322141742
2020-02-25 03:03:22,183 [salt.minion      :1432][INFO    ][4796] Starting a new job with PID 4796
2020-02-25 03:03:22,210 [salt.minion      :1711][INFO    ][4796] Returning information for job: 20200225030322141742
2020-02-25 03:03:31,931 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1181.80099201s left)
2020-02-25 03:03:52,242 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225030352231435
2020-02-25 03:03:52,269 [salt.minion      :1432][INFO    ][4833] Starting a new job with PID 4833
2020-02-25 03:03:52,290 [salt.minion      :1711][INFO    ][4833] Returning information for job: 20200225030352231435
2020-02-25 03:04:04,181 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1149.55032897s left)
2020-02-25 03:04:22,302 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225030422287142
2020-02-25 03:04:22,333 [salt.minion      :1432][INFO    ][4857] Starting a new job with PID 4857
2020-02-25 03:04:22,362 [salt.minion      :1711][INFO    ][4857] Returning information for job: 20200225030422287142
2020-02-25 03:04:36,495 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1117.23638916s left)
2020-02-25 03:04:52,415 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225030452401630
2020-02-25 03:04:52,441 [salt.minion      :1432][INFO    ][4891] Starting a new job with PID 4891
2020-02-25 03:04:52,469 [salt.minion      :1711][INFO    ][4891] Returning information for job: 20200225030452401630
2020-02-25 03:05:08,840 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1084.89205909s left)
2020-02-25 03:05:22,545 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225030522528193
2020-02-25 03:05:22,571 [salt.minion      :1432][INFO    ][4911] Starting a new job with PID 4911
2020-02-25 03:05:22,600 [salt.minion      :1711][INFO    ][4911] Returning information for job: 20200225030522528193
2020-02-25 03:05:41,274 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1052.45746899s left)
2020-02-25 03:05:52,647 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225030552634300
2020-02-25 03:05:52,672 [salt.minion      :1432][INFO    ][4948] Starting a new job with PID 4948
2020-02-25 03:05:52,697 [salt.minion      :1711][INFO    ][4948] Returning information for job: 20200225030552634300
2020-02-25 03:06:13,587 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1020.14469218s left)
2020-02-25 03:06:22,804 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225030622789767
2020-02-25 03:06:22,828 [salt.minion      :1432][INFO    ][4966] Starting a new job with PID 4966
2020-02-25 03:06:22,852 [salt.minion      :1711][INFO    ][4966] Returning information for job: 20200225030622789767
2020-02-25 03:06:45,859 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (987.872407198s left)
2020-02-25 03:06:52,961 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225030652946896
2020-02-25 03:06:52,988 [salt.minion      :1432][INFO    ][5014] Starting a new job with PID 5014
2020-02-25 03:06:53,015 [salt.minion      :1711][INFO    ][5014] Returning information for job: 20200225030652946896
2020-02-25 03:07:18,118 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (955.614303112s left)
2020-02-25 03:07:23,123 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225030723107489
2020-02-25 03:07:23,153 [salt.minion      :1432][INFO    ][5036] Starting a new job with PID 5036
2020-02-25 03:07:23,177 [salt.minion      :1711][INFO    ][5036] Returning information for job: 20200225030723107489
2020-02-25 03:07:50,475 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (923.256783009s left)
2020-02-25 03:07:53,309 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225030753295753
2020-02-25 03:07:53,335 [salt.minion      :1432][INFO    ][5071] Starting a new job with PID 5071
2020-02-25 03:07:53,361 [salt.minion      :1711][INFO    ][5071] Returning information for job: 20200225030753295753
2020-02-25 03:08:22,826 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (890.906013012s left)
2020-02-25 03:08:23,489 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225030823475229
2020-02-25 03:08:23,512 [salt.minion      :1432][INFO    ][5096] Starting a new job with PID 5096
2020-02-25 03:08:23,535 [salt.minion      :1711][INFO    ][5096] Returning information for job: 20200225030823475229
2020-02-25 03:08:53,667 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225030853656931
2020-02-25 03:08:53,686 [salt.minion      :1432][INFO    ][5133] Starting a new job with PID 5133
2020-02-25 03:08:53,710 [salt.minion      :1711][INFO    ][5133] Returning information for job: 20200225030853656931
2020-02-25 03:08:55,016 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (858.716153145s left)
2020-02-25 03:09:23,859 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225030923845446
2020-02-25 03:09:23,885 [salt.minion      :1432][INFO    ][5158] Starting a new job with PID 5158
2020-02-25 03:09:23,909 [salt.minion      :1711][INFO    ][5158] Returning information for job: 20200225030923845446
2020-02-25 03:09:27,083 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (826.648406029s left)
2020-02-25 03:09:54,075 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225030954064085
2020-02-25 03:09:54,092 [salt.minion      :1432][INFO    ][5197] Starting a new job with PID 5197
2020-02-25 03:09:54,115 [salt.minion      :1711][INFO    ][5197] Returning information for job: 20200225030954064085
2020-02-25 03:09:59,105 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (794.626919031s left)
2020-02-25 03:10:24,273 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225031024261296
2020-02-25 03:10:24,296 [salt.minion      :1432][INFO    ][5216] Starting a new job with PID 5216
2020-02-25 03:10:24,322 [salt.minion      :1711][INFO    ][5216] Returning information for job: 20200225031024261296
2020-02-25 03:10:31,383 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (762.348894119s left)
2020-02-25 03:10:54,304 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225031054293530
2020-02-25 03:10:54,332 [salt.minion      :1432][INFO    ][5249] Starting a new job with PID 5249
2020-02-25 03:10:54,358 [salt.minion      :1711][INFO    ][5249] Returning information for job: 20200225031054293530
2020-02-25 03:11:03,532 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (730.199805975s left)
2020-02-25 03:11:24,378 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225031124368983
2020-02-25 03:11:24,403 [salt.minion      :1432][INFO    ][5270] Starting a new job with PID 5270
2020-02-25 03:11:24,426 [salt.minion      :1711][INFO    ][5270] Returning information for job: 20200225031124368983
2020-02-25 03:11:35,633 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (698.099166155s left)
2020-02-25 03:11:54,466 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225031154451352
2020-02-25 03:11:54,491 [salt.minion      :1432][INFO    ][5305] Starting a new job with PID 5305
2020-02-25 03:11:54,517 [salt.minion      :1711][INFO    ][5305] Returning information for job: 20200225031154451352
2020-02-25 03:12:08,070 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (665.661345005s left)
2020-02-25 03:12:24,572 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225031224559952
2020-02-25 03:12:24,599 [salt.minion      :1432][INFO    ][5325] Starting a new job with PID 5325
2020-02-25 03:12:24,628 [salt.minion      :1711][INFO    ][5325] Returning information for job: 20200225031224559952
2020-02-25 03:12:40,157 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (633.575282097s left)
2020-02-25 03:12:54,693 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225031254681717
2020-02-25 03:12:54,719 [salt.minion      :1432][INFO    ][5360] Starting a new job with PID 5360
2020-02-25 03:12:54,746 [salt.minion      :1711][INFO    ][5360] Returning information for job: 20200225031254681717
2020-02-25 03:13:12,231 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (601.500936985s left)
2020-02-25 03:13:24,813 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225031324801070
2020-02-25 03:13:24,837 [salt.minion      :1432][INFO    ][5380] Starting a new job with PID 5380
2020-02-25 03:13:24,861 [salt.minion      :1711][INFO    ][5380] Returning information for job: 20200225031324801070
2020-02-25 03:13:44,358 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (569.373862982s left)
2020-02-25 03:13:54,964 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225031354954296
2020-02-25 03:13:54,992 [salt.minion      :1432][INFO    ][5415] Starting a new job with PID 5415
2020-02-25 03:13:55,017 [salt.minion      :1711][INFO    ][5415] Returning information for job: 20200225031354954296
2020-02-25 03:14:16,474 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (537.257348061s left)
2020-02-25 03:14:25,145 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225031425133549
2020-02-25 03:14:25,171 [salt.minion      :1432][INFO    ][5434] Starting a new job with PID 5434
2020-02-25 03:14:25,197 [salt.minion      :1711][INFO    ][5434] Returning information for job: 20200225031425133549
2020-02-25 03:14:48,514 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (505.217839003s left)
2020-02-25 03:14:55,322 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225031455306904
2020-02-25 03:14:55,342 [salt.minion      :1432][INFO    ][5467] Starting a new job with PID 5467
2020-02-25 03:14:55,374 [salt.minion      :1711][INFO    ][5467] Returning information for job: 20200225031455306904
2020-02-25 03:15:20,646 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (473.086112976s left)
2020-02-25 03:15:25,538 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225031525522255
2020-02-25 03:15:25,563 [salt.minion      :1432][INFO    ][5485] Starting a new job with PID 5485
2020-02-25 03:15:25,588 [salt.minion      :1711][INFO    ][5485] Returning information for job: 20200225031525522255
2020-02-25 03:15:52,838 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (440.893952131s left)
2020-02-25 03:15:55,565 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225031555553315
2020-02-25 03:15:55,587 [salt.minion      :1432][INFO    ][5520] Starting a new job with PID 5520
2020-02-25 03:15:55,610 [salt.minion      :1711][INFO    ][5520] Returning information for job: 20200225031555553315
2020-02-25 03:16:25,130 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (408.601988077s left)
2020-02-25 03:16:25,786 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225031625779419
2020-02-25 03:16:25,810 [salt.minion      :1432][INFO    ][5541] Starting a new job with PID 5541
2020-02-25 03:16:25,842 [salt.minion      :1711][INFO    ][5541] Returning information for job: 20200225031625779419
2020-02-25 03:16:55,846 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225031655834593
2020-02-25 03:16:55,864 [salt.minion      :1432][INFO    ][5591] Starting a new job with PID 5591
2020-02-25 03:16:55,890 [salt.minion      :1711][INFO    ][5591] Returning information for job: 20200225031655834593
2020-02-25 03:16:57,305 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (376.427009106s left)
2020-02-25 03:17:25,916 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225031725901753
2020-02-25 03:17:25,938 [salt.minion      :1432][INFO    ][5615] Starting a new job with PID 5615
2020-02-25 03:17:25,963 [salt.minion      :1711][INFO    ][5615] Returning information for job: 20200225031725901753
2020-02-25 03:17:29,432 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (344.299341202s left)
2020-02-25 03:17:55,991 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225031755979405
2020-02-25 03:17:56,022 [salt.minion      :1432][INFO    ][5652] Starting a new job with PID 5652
2020-02-25 03:17:56,053 [salt.minion      :1711][INFO    ][5652] Returning information for job: 20200225031755979405
2020-02-25 03:18:01,723 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (312.009117126s left)
2020-02-25 03:18:26,063 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225031826050211
2020-02-25 03:18:26,091 [salt.minion      :1432][INFO    ][5673] Starting a new job with PID 5673
2020-02-25 03:18:26,119 [salt.minion      :1711][INFO    ][5673] Returning information for job: 20200225031826050211
2020-02-25 03:18:33,894 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (279.837460041s left)
2020-02-25 03:18:56,194 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225031856182715
2020-02-25 03:18:56,219 [salt.minion      :1432][INFO    ][5709] Starting a new job with PID 5709
2020-02-25 03:18:56,248 [salt.minion      :1711][INFO    ][5709] Returning information for job: 20200225031856182715
2020-02-25 03:19:06,055 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (247.676439047s left)
2020-02-25 03:19:26,318 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225031926302789
2020-02-25 03:19:26,346 [salt.minion      :1432][INFO    ][5729] Starting a new job with PID 5729
2020-02-25 03:19:26,373 [salt.minion      :1711][INFO    ][5729] Returning information for job: 20200225031926302789
2020-02-25 03:19:38,135 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (215.596366167s left)
2020-02-25 03:19:56,482 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225031956471087
2020-02-25 03:19:56,504 [salt.minion      :1432][INFO    ][5762] Starting a new job with PID 5762
2020-02-25 03:19:56,531 [salt.minion      :1711][INFO    ][5762] Returning information for job: 20200225031956471087
2020-02-25 03:20:10,379 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (183.353289127s left)
2020-02-25 03:20:26,616 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225032026607184
2020-02-25 03:20:26,643 [salt.minion      :1432][INFO    ][5780] Starting a new job with PID 5780
2020-02-25 03:20:26,677 [salt.minion      :1711][INFO    ][5780] Returning information for job: 20200225032026607184
2020-02-25 03:20:42,606 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (151.125478983s left)
2020-02-25 03:20:56,814 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225032056801055
2020-02-25 03:20:56,842 [salt.minion      :1432][INFO    ][5817] Starting a new job with PID 5817
2020-02-25 03:20:56,874 [salt.minion      :1711][INFO    ][5817] Returning information for job: 20200225032056801055
2020-02-25 03:21:14,599 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (119.132369041s left)
2020-02-25 03:21:27,025 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225032127013080
2020-02-25 03:21:27,049 [salt.minion      :1432][INFO    ][5838] Starting a new job with PID 5838
2020-02-25 03:21:27,081 [salt.minion      :1711][INFO    ][5838] Returning information for job: 20200225032127013080
2020-02-25 03:21:46,719 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (87.0132541656s left)
2020-02-25 03:21:57,245 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225032157234326
2020-02-25 03:21:57,271 [salt.minion      :1432][INFO    ][5872] Starting a new job with PID 5872
2020-02-25 03:21:57,313 [salt.minion      :1711][INFO    ][5872] Returning information for job: 20200225032157234326
2020-02-25 03:22:18,751 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (54.9811291695s left)
2020-02-25 03:22:27,459 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225032227445636
2020-02-25 03:22:27,477 [salt.minion      :1432][INFO    ][5892] Starting a new job with PID 5892
2020-02-25 03:22:27,502 [salt.minion      :1711][INFO    ][5892] Returning information for job: 20200225032227445636
2020-02-25 03:22:50,851 [salt.loaded.ext.module.maas:1024][INFO    ][3402] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (22.8807301521s left)
2020-02-25 03:22:57,478 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225032257467811
2020-02-25 03:22:57,503 [salt.minion      :1432][INFO    ][5926] Starting a new job with PID 5926
2020-02-25 03:22:57,531 [salt.minion      :1711][INFO    ][5926] Returning information for job: 20200225032257467811
2020-02-25 03:23:23,121 [salt.state       :302 ][ERROR   ][3402] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['cmp002', 'cmp001']not in Deployed state
2020-02-25 03:23:23,121 [salt.state       :1951][INFO    ][3402] Completed state [maas.wait_for_machine_status] at time 03:23:23.121524 duration_in_ms=2259402.231
2020-02-25 03:23:23,126 [salt.minion      :1711][INFO    ][3402] Returning information for job: 20200225024532770438
2020-02-25 03:23:34,445 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command pillar.get with jid 20200225032334424177
2020-02-25 03:23:34,472 [salt.minion      :1432][INFO    ][5953] Starting a new job with PID 5953
2020-02-25 03:23:34,482 [salt.minion      :1711][INFO    ][5953] Returning information for job: 20200225032334424177
2020-02-25 03:23:35,421 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command service.status with jid 20200225032335411573
2020-02-25 03:23:35,446 [salt.minion      :1432][INFO    ][5958] Starting a new job with PID 5958
2020-02-25 03:23:36,217 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][5958] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2020-02-25 03:23:36,267 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][5958] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2020-02-25 03:23:36,287 [salt.minion      :1711][INFO    ][5958] Returning information for job: 20200225032335411573
2020-02-25 03:23:37,232 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command state.apply with jid 20200225032337219647
2020-02-25 03:23:37,262 [salt.minion      :1432][INFO    ][5969] Starting a new job with PID 5969
2020-02-25 03:23:45,651 [salt.state       :915 ][INFO    ][5969] Loading fresh modules for state activity
2020-02-25 03:23:46,242 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5969] Executing command 'salt-minion --version' in directory '/root'
2020-02-25 03:23:46,670 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5969] Executing command 'salt-minion --version' in directory '/root'
2020-02-25 03:23:47,781 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5969] Executing command 'salt-minion --version' in directory '/root'
2020-02-25 03:23:48,187 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5969] Executing command 'salt-minion --version' in directory '/root'
2020-02-25 03:23:50,480 [salt.state       :1780][INFO    ][5969] Running state [salt-minion] at time 03:23:50.480767
2020-02-25 03:23:50,481 [salt.state       :1813][INFO    ][5969] Executing state pkg.installed for [salt-minion]
2020-02-25 03:23:50,482 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5969] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2020-02-25 03:23:50,594 [salt.state       :300 ][INFO    ][5969] All specified packages are already installed
2020-02-25 03:23:50,594 [salt.state       :1951][INFO    ][5969] Completed state [salt-minion] at time 03:23:50.594854 duration_in_ms=114.087
2020-02-25 03:23:50,595 [salt.state       :1780][INFO    ][5969] Running state [salt_minion_dependency_packages] at time 03:23:50.595239
2020-02-25 03:23:50,595 [salt.state       :1813][INFO    ][5969] Executing state pkg.installed for [salt_minion_dependency_packages]
2020-02-25 03:23:50,608 [salt.state       :300 ][INFO    ][5969] All specified packages are already installed
2020-02-25 03:23:50,609 [salt.state       :1951][INFO    ][5969] Completed state [salt_minion_dependency_packages] at time 03:23:50.609256 duration_in_ms=14.017
2020-02-25 03:23:50,614 [salt.state       :1780][INFO    ][5969] Running state [/etc/salt/minion.d/minion.conf] at time 03:23:50.614276
2020-02-25 03:23:50,614 [salt.state       :1813][INFO    ][5969] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2020-02-25 03:23:50,905 [salt.state       :300 ][INFO    ][5969] File /etc/salt/minion.d/minion.conf is in the correct state
2020-02-25 03:23:50,905 [salt.state       :1951][INFO    ][5969] Completed state [/etc/salt/minion.d/minion.conf] at time 03:23:50.905636 duration_in_ms=291.359
2020-02-25 03:23:50,908 [salt.state       :1780][INFO    ][5969] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 03:23:50.908761
2020-02-25 03:23:50,909 [salt.state       :1813][INFO    ][5969] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2020-02-25 03:23:50,920 [salt.state       :300 ][INFO    ][5969] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2020-02-25 03:23:50,920 [salt.state       :1951][INFO    ][5969] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 03:23:50.920533 duration_in_ms=11.772
2020-02-25 03:23:50,921 [salt.state       :1780][INFO    ][5969] Running state [salt-minion] at time 03:23:50.921798
2020-02-25 03:23:50,922 [salt.state       :1813][INFO    ][5969] Executing state service.running for [salt-minion]
2020-02-25 03:23:50,923 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5969] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2020-02-25 03:23:50,979 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5969] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2020-02-25 03:23:51,006 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5969] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2020-02-25 03:23:51,031 [salt.state       :300 ][INFO    ][5969] The service salt-minion is already running
2020-02-25 03:23:51,032 [salt.state       :1951][INFO    ][5969] Completed state [salt-minion] at time 03:23:51.032044 duration_in_ms=110.244
2020-02-25 03:23:51,034 [salt.state       :1780][INFO    ][5969] Running state [/etc/salt/grains.d] at time 03:23:51.034803
2020-02-25 03:23:51,035 [salt.state       :1813][INFO    ][5969] Executing state file.directory for [/etc/salt/grains.d]
2020-02-25 03:23:51,039 [salt.state       :300 ][INFO    ][5969] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2020-02-25 03:23:51,039 [salt.state       :1951][INFO    ][5969] Completed state [/etc/salt/grains.d] at time 03:23:51.039489 duration_in_ms=4.686
2020-02-25 03:23:51,040 [salt.state       :1780][INFO    ][5969] Running state [/etc/salt/grains] at time 03:23:51.040384
2020-02-25 03:23:51,040 [salt.state       :1813][INFO    ][5969] Executing state file.managed for [/etc/salt/grains]
2020-02-25 03:23:51,041 [salt.state       :300 ][INFO    ][5969] File /etc/salt/grains exists with proper permissions. No changes made.
2020-02-25 03:23:51,041 [salt.state       :1951][INFO    ][5969] Completed state [/etc/salt/grains] at time 03:23:51.041741 duration_in_ms=1.357
2020-02-25 03:23:51,042 [salt.state       :1780][INFO    ][5969] Running state [/etc/salt/grains.d/placeholder] at time 03:23:51.042357
2020-02-25 03:23:51,042 [salt.state       :1813][INFO    ][5969] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2020-02-25 03:23:51,043 [salt.state       :300 ][INFO    ][5969] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2020-02-25 03:23:51,043 [salt.state       :1951][INFO    ][5969] Completed state [/etc/salt/grains.d/placeholder] at time 03:23:51.043676 duration_in_ms=1.319
2020-02-25 03:23:51,044 [salt.state       :1780][INFO    ][5969] Running state [/etc/salt/grains.d/sphinx] at time 03:23:51.044327
2020-02-25 03:23:51,044 [salt.state       :1813][INFO    ][5969] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2020-02-25 03:23:51,046 [salt.state       :300 ][INFO    ][5969] File /etc/salt/grains.d/sphinx is in the correct state
2020-02-25 03:23:51,046 [salt.state       :1951][INFO    ][5969] Completed state [/etc/salt/grains.d/sphinx] at time 03:23:51.046577 duration_in_ms=2.25
2020-02-25 03:23:51,051 [salt.state       :1780][INFO    ][5969] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 03:23:51.051243
2020-02-25 03:23:51,051 [salt.state       :1813][INFO    ][5969] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2020-02-25 03:23:51,051 [salt.state       :300 ][INFO    ][5969] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2020-02-25 03:23:51,052 [salt.state       :1951][INFO    ][5969] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 03:23:51.052127 duration_in_ms=0.884
2020-02-25 03:23:51,052 [salt.state       :1780][INFO    ][5969] Running state [/etc/salt/grains.d/dns_records] at time 03:23:51.052654
2020-02-25 03:23:51,052 [salt.state       :1813][INFO    ][5969] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2020-02-25 03:23:51,053 [salt.state       :300 ][INFO    ][5969] File /etc/salt/grains.d/dns_records is in the correct state
2020-02-25 03:23:51,054 [salt.state       :1951][INFO    ][5969] Completed state [/etc/salt/grains.d/dns_records] at time 03:23:51.054186 duration_in_ms=1.532
2020-02-25 03:23:51,055 [salt.state       :1780][INFO    ][5969] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 03:23:51.055122
2020-02-25 03:23:51,055 [salt.state       :1813][INFO    ][5969] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2020-02-25 03:23:51,055 [salt.state       :300 ][INFO    ][5969] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2020-02-25 03:23:51,056 [salt.state       :1951][INFO    ][5969] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 03:23:51.055975 duration_in_ms=0.853
2020-02-25 03:23:51,056 [salt.state       :1780][INFO    ][5969] Running state [/etc/salt/grains.d/salt] at time 03:23:51.056809
2020-02-25 03:23:51,057 [salt.state       :1813][INFO    ][5969] Executing state file.managed for [/etc/salt/grains.d/salt]
2020-02-25 03:23:51,058 [salt.state       :300 ][INFO    ][5969] File /etc/salt/grains.d/salt is in the correct state
2020-02-25 03:23:51,058 [salt.state       :1951][INFO    ][5969] Completed state [/etc/salt/grains.d/salt] at time 03:23:51.058375 duration_in_ms=1.566
2020-02-25 03:23:51,059 [salt.state       :1780][INFO    ][5969] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 03:23:51.059278
2020-02-25 03:23:51,059 [salt.state       :1813][INFO    ][5969] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2020-02-25 03:23:51,059 [salt.state       :300 ][INFO    ][5969] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2020-02-25 03:23:51,060 [salt.state       :1951][INFO    ][5969] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 03:23:51.060097 duration_in_ms=0.819
2020-02-25 03:23:51,064 [salt.state       :1780][INFO    ][5969] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 03:23:51.064788
2020-02-25 03:23:51,065 [salt.state       :1813][INFO    ][5969] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2020-02-25 03:23:51,065 [salt.state       :300 ][INFO    ][5969] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2020-02-25 03:23:51,066 [salt.state       :1951][INFO    ][5969] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 03:23:51.066152 duration_in_ms=1.365
2020-02-25 03:23:51,067 [salt.state       :1780][INFO    ][5969] Running state [mine.update] at time 03:23:51.067241
2020-02-25 03:23:51,067 [salt.state       :1813][INFO    ][5969] Executing state module.wait for [mine.update]
2020-02-25 03:23:51,068 [salt.state       :300 ][INFO    ][5969] No changes made for mine.update
2020-02-25 03:23:51,068 [salt.state       :1951][INFO    ][5969] Completed state [mine.update] at time 03:23:51.068677 duration_in_ms=1.437
2020-02-25 03:23:51,069 [salt.state       :1780][INFO    ][5969] Running state [ca-certificates] at time 03:23:51.068976
2020-02-25 03:23:51,069 [salt.state       :1813][INFO    ][5969] Executing state pkg.installed for [ca-certificates]
2020-02-25 03:23:51,083 [salt.state       :300 ][INFO    ][5969] All specified packages are already installed
2020-02-25 03:23:51,083 [salt.state       :1951][INFO    ][5969] Completed state [ca-certificates] at time 03:23:51.083625 duration_in_ms=14.649
2020-02-25 03:23:51,086 [salt.state       :1780][INFO    ][5969] Running state [update-ca-certificates] at time 03:23:51.086032
2020-02-25 03:23:51,086 [salt.state       :1813][INFO    ][5969] Executing state cmd.wait for [update-ca-certificates]
2020-02-25 03:23:51,086 [salt.state       :300 ][INFO    ][5969] No changes made for update-ca-certificates
2020-02-25 03:23:51,086 [salt.state       :1951][INFO    ][5969] Completed state [update-ca-certificates] at time 03:23:51.086843 duration_in_ms=0.811
2020-02-25 03:23:51,087 [salt.state       :1780][INFO    ][5969] Running state [iptables] at time 03:23:51.087122
2020-02-25 03:23:51,087 [salt.state       :1813][INFO    ][5969] Executing state pkg.installed for [iptables]
2020-02-25 03:23:51,095 [salt.state       :300 ][INFO    ][5969] All specified packages are already installed
2020-02-25 03:23:51,096 [salt.state       :1951][INFO    ][5969] Completed state [iptables] at time 03:23:51.096189 duration_in_ms=9.067
2020-02-25 03:23:51,097 [salt.state       :1780][INFO    ][5969] Running state [iptables-persistent] at time 03:23:51.097342
2020-02-25 03:23:51,097 [salt.state       :1813][INFO    ][5969] Executing state pkg.installed for [iptables-persistent]
2020-02-25 03:23:51,106 [salt.state       :300 ][INFO    ][5969] All specified packages are already installed
2020-02-25 03:23:51,106 [salt.state       :1951][INFO    ][5969] Completed state [iptables-persistent] at time 03:23:51.106308 duration_in_ms=8.966
2020-02-25 03:23:51,107 [salt.state       :1780][INFO    ][5969] Running state [iptables_modules_v4_load] at time 03:23:51.107355
2020-02-25 03:23:51,107 [salt.state       :1813][INFO    ][5969] Executing state kmod.present for [iptables_modules_v4_load]
2020-02-25 03:23:51,108 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5969] Executing command 'lsmod' in directory '/root'
2020-02-25 03:23:51,136 [salt.state       :300 ][INFO    ][5969] Kernel modules iptable_filter, ip_tables are already present
2020-02-25 03:23:51,137 [salt.state       :1951][INFO    ][5969] Completed state [iptables_modules_v4_load] at time 03:23:51.137063 duration_in_ms=29.707
2020-02-25 03:23:51,138 [salt.state       :1780][INFO    ][5969] Running state [/etc/iptables/rules.v4] at time 03:23:51.138281
2020-02-25 03:23:51,138 [salt.state       :1813][INFO    ][5969] Executing state file.managed for [/etc/iptables/rules.v4]
2020-02-25 03:23:51,243 [salt.state       :300 ][INFO    ][5969] File /etc/iptables/rules.v4 is in the correct state
2020-02-25 03:23:51,243 [salt.state       :1951][INFO    ][5969] Completed state [/etc/iptables/rules.v4] at time 03:23:51.243903 duration_in_ms=105.623
2020-02-25 03:23:51,245 [salt.state       :1780][INFO    ][5969] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 03:23:51.245648
2020-02-25 03:23:51,245 [salt.state       :1813][INFO    ][5969] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2020-02-25 03:23:51,246 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5969] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2020-02-25 03:23:51,269 [salt.state       :300 ][INFO    ][5969] onlyif execution failed
2020-02-25 03:23:51,270 [salt.state       :1951][INFO    ][5969] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 03:23:51.270475 duration_in_ms=24.825
2020-02-25 03:23:51,273 [salt.state       :1780][INFO    ][5969] Running state [netfilter-persistent] at time 03:23:51.273035
2020-02-25 03:23:51,273 [salt.state       :1813][INFO    ][5969] Executing state service.running for [netfilter-persistent]
2020-02-25 03:23:51,274 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5969] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2020-02-25 03:23:51,309 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5969] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2020-02-25 03:23:51,334 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5969] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2020-02-25 03:23:51,358 [salt.state       :300 ][INFO    ][5969] The service netfilter-persistent is already running
2020-02-25 03:23:51,359 [salt.state       :1951][INFO    ][5969] Completed state [netfilter-persistent] at time 03:23:51.359439 duration_in_ms=86.403
2020-02-25 03:23:51,362 [salt.state       :1780][INFO    ][5969] Running state [iptables_extra.remove_stale_tables] at time 03:23:51.362848
2020-02-25 03:23:51,363 [salt.state       :1813][INFO    ][5969] Executing state module.wait for [iptables_extra.remove_stale_tables]
2020-02-25 03:23:51,363 [salt.state       :300 ][INFO    ][5969] No changes made for iptables_extra.remove_stale_tables
2020-02-25 03:23:51,364 [salt.state       :1951][INFO    ][5969] Completed state [iptables_extra.remove_stale_tables] at time 03:23:51.364219 duration_in_ms=1.372
2020-02-25 03:23:51,364 [salt.state       :1780][INFO    ][5969] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 03:23:51.364611
2020-02-25 03:23:51,365 [salt.state       :1813][INFO    ][5969] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2020-02-25 03:23:51,366 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5969] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2020-02-25 03:23:51,383 [salt.state       :300 ][INFO    ][5969] onlyif execution failed
2020-02-25 03:23:51,386 [salt.state       :1951][INFO    ][5969] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 03:23:51.385960 duration_in_ms=21.349
2020-02-25 03:23:51,387 [salt.state       :1780][INFO    ][5969] Running state [/etc/iptables/rules.v6] at time 03:23:51.387491
2020-02-25 03:23:51,388 [salt.state       :1813][INFO    ][5969] Executing state file.absent for [/etc/iptables/rules.v6]
2020-02-25 03:23:51,389 [salt.state       :300 ][INFO    ][5969] File /etc/iptables/rules.v6 is not present
2020-02-25 03:23:51,389 [salt.state       :1951][INFO    ][5969] Completed state [/etc/iptables/rules.v6] at time 03:23:51.389405 duration_in_ms=1.913
2020-02-25 03:23:51,390 [salt.state       :1780][INFO    ][5969] Running state [iptables_extra.flush_all] at time 03:23:51.390527
2020-02-25 03:23:51,391 [salt.state       :1813][INFO    ][5969] Executing state module.wait for [iptables_extra.flush_all]
2020-02-25 03:23:51,391 [salt.state       :300 ][INFO    ][5969] No changes made for iptables_extra.flush_all
2020-02-25 03:23:51,391 [salt.state       :1951][INFO    ][5969] Completed state [iptables_extra.flush_all] at time 03:23:51.391754 duration_in_ms=1.227
2020-02-25 03:23:51,395 [salt.minion      :1711][INFO    ][5969] Returning information for job: 20200225032337219647
2020-02-25 03:23:52,328 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command state.apply with jid 20200225032352313807
2020-02-25 03:23:52,354 [salt.minion      :1432][INFO    ][6068] Starting a new job with PID 6068
2020-02-25 03:23:53,400 [salt.state       :915 ][INFO    ][6068] Loading fresh modules for state activity
2020-02-25 03:23:54,683 [salt.state       :1780][INFO    ][6068] Running state [maas-rack-controller] at time 03:23:54.683363
2020-02-25 03:23:54,683 [salt.state       :1813][INFO    ][6068] Executing state pkg.installed for [maas-rack-controller]
2020-02-25 03:23:54,685 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6068] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2020-02-25 03:23:54,814 [salt.state       :300 ][INFO    ][6068] All specified packages are already installed
2020-02-25 03:23:54,815 [salt.state       :1951][INFO    ][6068] Completed state [maas-rack-controller] at time 03:23:54.815219 duration_in_ms=131.857
2020-02-25 03:23:54,815 [salt.state       :1780][INFO    ][6068] Running state [ipmitool] at time 03:23:54.815680
2020-02-25 03:23:54,816 [salt.state       :1813][INFO    ][6068] Executing state pkg.installed for [ipmitool]
2020-02-25 03:23:54,829 [salt.state       :300 ][INFO    ][6068] All specified packages are already installed
2020-02-25 03:23:54,830 [salt.state       :1951][INFO    ][6068] Completed state [ipmitool] at time 03:23:54.830004 duration_in_ms=14.324
2020-02-25 03:23:54,834 [salt.state       :1780][INFO    ][6068] Running state [/etc/maas/rackd.conf] at time 03:23:54.834676
2020-02-25 03:23:54,835 [salt.state       :1813][INFO    ][6068] Executing state file.line for [/etc/maas/rackd.conf]
2020-02-25 03:23:54,836 [salt.state       :300 ][INFO    ][6068] No changes needed to be made
2020-02-25 03:23:54,837 [salt.state       :1951][INFO    ][6068] Completed state [/etc/maas/rackd.conf] at time 03:23:54.837145 duration_in_ms=2.468
2020-02-25 03:23:54,837 [salt.state       :1780][INFO    ][6068] Running state [/etc/maas/rackd.conf] at time 03:23:54.837522
2020-02-25 03:23:54,837 [salt.state       :1813][INFO    ][6068] Executing state file.managed for [/etc/maas/rackd.conf]
2020-02-25 03:23:54,838 [salt.loaded.int.states.file:2298][WARNING ][6068] 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-02-25 03:23:54,839 [salt.state       :300 ][INFO    ][6068] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2020-02-25 03:23:54,839 [salt.state       :1951][INFO    ][6068] Completed state [/etc/maas/rackd.conf] at time 03:23:54.839372 duration_in_ms=1.85
2020-02-25 03:23:54,842 [salt.state       :1780][INFO    ][6068] Running state [maas-rackd] at time 03:23:54.842677
2020-02-25 03:23:54,843 [salt.state       :1813][INFO    ][6068] Executing state service.running for [maas-rackd]
2020-02-25 03:23:54,843 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6068] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2020-02-25 03:23:54,890 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6068] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2020-02-25 03:23:54,915 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6068] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2020-02-25 03:23:54,939 [salt.state       :300 ][INFO    ][6068] The service maas-rackd is already running
2020-02-25 03:23:54,941 [salt.state       :1951][INFO    ][6068] Completed state [maas-rackd] at time 03:23:54.940243 duration_in_ms=97.564
2020-02-25 03:23:54,943 [salt.minion      :1711][INFO    ][6068] Returning information for job: 20200225032352313807
2020-02-25 03:23:55,883 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command state.apply with jid 20200225032355874290
2020-02-25 03:23:55,903 [salt.minion      :1432][INFO    ][6091] Starting a new job with PID 6091
2020-02-25 03:23:57,005 [salt.state       :915 ][INFO    ][6091] Loading fresh modules for state activity
2020-02-25 03:23:58,455 [salt.state       :1780][INFO    ][6091] Running state [maas-region-controller] at time 03:23:58.455087
2020-02-25 03:23:58,455 [salt.state       :1813][INFO    ][6091] Executing state pkg.installed for [maas-region-controller]
2020-02-25 03:23:58,456 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6091] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2020-02-25 03:23:58,578 [salt.state       :300 ][INFO    ][6091] All specified packages are already installed
2020-02-25 03:23:58,578 [salt.state       :1951][INFO    ][6091] Completed state [maas-region-controller] at time 03:23:58.578453 duration_in_ms=123.367
2020-02-25 03:23:58,578 [salt.state       :1780][INFO    ][6091] Running state [python-oauth] at time 03:23:58.578806
2020-02-25 03:23:58,579 [salt.state       :1813][INFO    ][6091] Executing state pkg.installed for [python-oauth]
2020-02-25 03:23:58,589 [salt.state       :300 ][INFO    ][6091] All specified packages are already installed
2020-02-25 03:23:58,590 [salt.state       :1951][INFO    ][6091] Completed state [python-oauth] at time 03:23:58.590029 duration_in_ms=11.223
2020-02-25 03:23:58,593 [salt.state       :1780][INFO    ][6091] Running state [/etc/maas/regiond.conf] at time 03:23:58.593636
2020-02-25 03:23:58,593 [salt.state       :1813][INFO    ][6091] Executing state file.replace for [/etc/maas/regiond.conf]
2020-02-25 03:23:58,600 [salt.state       :300 ][INFO    ][6091] No changes needed to be made
2020-02-25 03:23:58,600 [salt.state       :1951][INFO    ][6091] Completed state [/etc/maas/regiond.conf] at time 03:23:58.600678 duration_in_ms=7.043
2020-02-25 03:23:58,601 [salt.state       :1780][INFO    ][6091] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 03:23:58.601198
2020-02-25 03:23:58,601 [salt.state       :1813][INFO    ][6091] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2020-02-25 03:23:58,665 [salt.state       :300 ][INFO    ][6091] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2020-02-25 03:23:58,666 [salt.state       :1951][INFO    ][6091] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 03:23:58.666191 duration_in_ms=64.993
2020-02-25 03:23:58,667 [salt.state       :1780][INFO    ][6091] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 03:23:58.667177
2020-02-25 03:23:58,667 [salt.state       :1813][INFO    ][6091] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2020-02-25 03:23:58,677 [salt.state       :300 ][INFO    ][6091] No changes needed to be made
2020-02-25 03:23:58,677 [salt.state       :1951][INFO    ][6091] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 03:23:58.677484 duration_in_ms=10.308
2020-02-25 03:23:58,678 [salt.state       :1780][INFO    ][6091] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 03:23:58.678089
2020-02-25 03:23:58,678 [salt.state       :1813][INFO    ][6091] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2020-02-25 03:23:58,685 [salt.state       :300 ][INFO    ][6091] No changes needed to be made
2020-02-25 03:23:58,685 [salt.state       :1951][INFO    ][6091] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 03:23:58.685810 duration_in_ms=7.721
2020-02-25 03:23:58,686 [salt.state       :1780][INFO    ][6091] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 03:23:58.686316
2020-02-25 03:23:58,686 [salt.state       :1813][INFO    ][6091] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2020-02-25 03:23:58,715 [salt.state       :300 ][INFO    ][6091] No changes needed to be made
2020-02-25 03:23:58,715 [salt.state       :1951][INFO    ][6091] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 03:23:58.715776 duration_in_ms=29.46
2020-02-25 03:23:58,716 [salt.state       :1780][INFO    ][6091] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 03:23:58.716112
2020-02-25 03:23:58,717 [salt.state       :1813][INFO    ][6091] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2020-02-25 03:23:58,735 [salt.state       :300 ][INFO    ][6091] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2020-02-25 03:23:58,736 [salt.state       :1951][INFO    ][6091] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 03:23:58.736614 duration_in_ms=20.502
2020-02-25 03:23:58,737 [salt.state       :1780][INFO    ][6091] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 03:23:58.737305
2020-02-25 03:23:58,737 [salt.state       :1813][INFO    ][6091] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2020-02-25 03:23:58,794 [salt.state       :300 ][INFO    ][6091] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2020-02-25 03:23:58,794 [salt.state       :1951][INFO    ][6091] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 03:23:58.794703 duration_in_ms=57.398
2020-02-25 03:23:58,795 [salt.state       :1780][INFO    ][6091] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 03:23:58.795304
2020-02-25 03:23:58,795 [salt.state       :1813][INFO    ][6091] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2020-02-25 03:23:58,861 [salt.state       :300 ][INFO    ][6091] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2020-02-25 03:23:58,861 [salt.state       :1951][INFO    ][6091] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 03:23:58.861890 duration_in_ms=66.586
2020-02-25 03:23:58,862 [salt.state       :1780][INFO    ][6091] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 03:23:58.862461
2020-02-25 03:23:58,862 [salt.state       :1813][INFO    ][6091] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2020-02-25 03:23:58,940 [salt.state       :300 ][INFO    ][6091] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2020-02-25 03:23:58,941 [salt.state       :1951][INFO    ][6091] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 03:23:58.941048 duration_in_ms=78.587
2020-02-25 03:23:58,941 [salt.state       :1780][INFO    ][6091] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_bionic] at time 03:23:58.941569
2020-02-25 03:23:58,941 [salt.state       :1813][INFO    ][6091] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_bionic]
2020-02-25 03:23:59,014 [salt.state       :300 ][INFO    ][6091] File /etc/maas/preseeds/curtin_userdata_amd64_generic_bionic is in the correct state
2020-02-25 03:23:59,014 [salt.state       :1951][INFO    ][6091] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_bionic] at time 03:23:59.014817 duration_in_ms=73.248
2020-02-25 03:23:59,015 [salt.state       :1780][INFO    ][6091] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_bionic] at time 03:23:59.015393
2020-02-25 03:23:59,015 [salt.state       :1813][INFO    ][6091] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_bionic]
2020-02-25 03:23:59,085 [salt.state       :300 ][INFO    ][6091] File /etc/maas/preseeds/curtin_userdata_arm64_generic_bionic is in the correct state
2020-02-25 03:23:59,085 [salt.state       :1951][INFO    ][6091] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_bionic] at time 03:23:59.085441 duration_in_ms=70.047
2020-02-25 03:23:59,085 [salt.state       :1780][INFO    ][6091] Running state [/root/.pgpass] at time 03:23:59.085894
2020-02-25 03:23:59,086 [salt.state       :1813][INFO    ][6091] Executing state file.managed for [/root/.pgpass]
2020-02-25 03:23:59,143 [salt.state       :300 ][INFO    ][6091] File /root/.pgpass is in the correct state
2020-02-25 03:23:59,144 [salt.state       :1951][INFO    ][6091] Completed state [/root/.pgpass] at time 03:23:59.143965 duration_in_ms=58.065
2020-02-25 03:23:59,151 [salt.state       :1780][INFO    ][6091] Running state [maas-region migrate --noinput] at time 03:23:59.150979
2020-02-25 03:23:59,151 [salt.state       :1813][INFO    ][6091] Executing state cmd.run for [maas-region migrate --noinput]
2020-02-25 03:23:59,152 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6091] Executing command 'maas-region migrate --noinput' in directory '/root'
2020-02-25 03:24:02,474 [salt.state       :300 ][INFO    ][6091] {'pid': 6122, '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-02-25 03:24:02,475 [salt.state       :1951][INFO    ][6091] Completed state [maas-region migrate --noinput] at time 03:24:02.474940 duration_in_ms=3323.959
2020-02-25 03:24:02,475 [salt.state       :2022][WARNING ][6091] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2020-02-25 03:24:02,478 [salt.state       :1780][INFO    ][6091] Running state [maas-regiond] at time 03:24:02.478411
2020-02-25 03:24:02,478 [salt.state       :1813][INFO    ][6091] Executing state service.running for [maas-regiond]
2020-02-25 03:24:02,481 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6091] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2020-02-25 03:24:02,540 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6091] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2020-02-25 03:24:02,564 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6091] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2020-02-25 03:24:02,589 [salt.state       :300 ][INFO    ][6091] The service maas-regiond is already running
2020-02-25 03:24:02,590 [salt.state       :1951][INFO    ][6091] Completed state [maas-regiond] at time 03:24:02.590201 duration_in_ms=111.787
2020-02-25 03:24:02,594 [salt.state       :1780][INFO    ][6091] Running state [bind9] at time 03:24:02.594751
2020-02-25 03:24:02,595 [salt.state       :1813][INFO    ][6091] Executing state service.running for [bind9]
2020-02-25 03:24:02,596 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6091] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2020-02-25 03:24:02,619 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6091] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2020-02-25 03:24:02,647 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6091] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2020-02-25 03:24:02,672 [salt.state       :300 ][INFO    ][6091] The service bind9 is already running
2020-02-25 03:24:02,672 [salt.state       :1951][INFO    ][6091] Completed state [bind9] at time 03:24:02.672528 duration_in_ms=77.775
2020-02-25 03:24:02,675 [salt.state       :1780][INFO    ][6091] Running state [maasng.wait_for_http_code] at time 03:24:02.675209
2020-02-25 03:24:02,675 [salt.state       :1813][INFO    ][6091] Executing state module.run for [maasng.wait_for_http_code]
2020-02-25 03:24:02,678 [salt.utils.decorators:613 ][WARNING ][6091] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-25 03:24:02,740 [salt.state       :300 ][INFO    ][6091] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2020-02-25 03:24:02,741 [salt.state       :1951][INFO    ][6091] Completed state [maasng.wait_for_http_code] at time 03:24:02.741615 duration_in_ms=66.407
2020-02-25 03:24:02,743 [salt.state       :1780][INFO    ][6091] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 03:24:02.742921
2020-02-25 03:24:02,743 [salt.state       :1813][INFO    ][6091] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2020-02-25 03:24:02,744 [salt.state       :300 ][INFO    ][6091] /var/lib/maas/.setup_admin exists
2020-02-25 03:24:02,744 [salt.state       :1951][INFO    ][6091] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 03:24:02.744852 duration_in_ms=1.932
2020-02-25 03:24:02,746 [salt.state       :1780][INFO    ][6091] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:24:02.746086
2020-02-25 03:24:02,746 [salt.state       :1813][INFO    ][6091] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-25 03:24:02,747 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6091] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-25 03:24:04,943 [salt.state       :300 ][INFO    ][6091] {'pid': 6140, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-25 03:24:04,944 [salt.state       :1951][INFO    ][6091] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:24:04.944109 duration_in_ms=2198.023
2020-02-25 03:24:04,951 [salt.state       :1780][INFO    ][6091] Running state [maas_region_boot_source_resources_mirror] at time 03:24:04.950955
2020-02-25 03:24:04,951 [salt.state       :1813][INFO    ][6091] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2020-02-25 03:24:05,015 [salt.state       :300 ][INFO    ][6091] {'changes': {}}
2020-02-25 03:24:05,016 [salt.state       :1951][INFO    ][6091] Completed state [maas_region_boot_source_resources_mirror] at time 03:24:05.016112 duration_in_ms=65.156
2020-02-25 03:24:05,019 [salt.state       :1780][INFO    ][6091] Running state [maasng.boot_resources_import] at time 03:24:05.019757
2020-02-25 03:24:05,020 [salt.state       :1813][INFO    ][6091] Executing state module.run for [maasng.boot_resources_import]
2020-02-25 03:24:05,020 [salt.utils.decorators:613 ][WARNING ][6091] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-25 03:24:05,098 [salt.loaded.ext.module.maasng:1600][INFO    ][6091] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2020-02-25 03:24:10,127 [salt.loaded.ext.module.maasng:1600][INFO    ][6091] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2020-02-25 03:24:10,970 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225032410957841
2020-02-25 03:24:10,995 [salt.minion      :1432][INFO    ][6168] Starting a new job with PID 6168
2020-02-25 03:24:11,018 [salt.minion      :1711][INFO    ][6168] Returning information for job: 20200225032410957841
2020-02-25 03:24:15,247 [salt.state       :300 ][INFO    ][6091] {'ret': True}
2020-02-25 03:24:15,248 [salt.state       :1951][INFO    ][6091] Completed state [maasng.boot_resources_import] at time 03:24:15.248348 duration_in_ms=10228.591
2020-02-25 03:24:15,249 [salt.state       :1780][INFO    ][6091] Running state [maas_region_boot_sources_selection_bionic] at time 03:24:15.249610
2020-02-25 03:24:15,250 [salt.state       :1813][INFO    ][6091] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_bionic]
2020-02-25 03:24:15,410 [salt.state       :300 ][INFO    ][6091] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2020-02-25 03:24:15,411 [salt.state       :1951][INFO    ][6091] Completed state [maas_region_boot_sources_selection_bionic] at time 03:24:15.410886 duration_in_ms=161.275
2020-02-25 03:24:15,413 [salt.state       :1780][INFO    ][6091] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 03:24:15.412182
2020-02-25 03:24:15,413 [salt.state       :1813][INFO    ][6091] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2020-02-25 03:24:15,414 [salt.utils.decorators:613 ][WARNING ][6091] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-25 03:24:15,414 [salt.loaded.ext.module.maasng:1771][INFO    ][6091] boot-sources sync initiated for ALL Rack's
2020-02-25 03:24:16,187 [salt.state       :300 ][INFO    ][6091] {'ret': True}
2020-02-25 03:24:16,187 [salt.state       :1951][INFO    ][6091] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 03:24:16.187579 duration_in_ms=775.398
2020-02-25 03:24:16,189 [salt.state       :1780][INFO    ][6091] Running state [maas.process_maas_config] at time 03:24:16.189892
2020-02-25 03:24:16,190 [salt.state       :1813][INFO    ][6091] Executing state module.run for [maas.process_maas_config]
2020-02-25 03:24:16,190 [salt.utils.decorators:613 ][WARNING ][6091] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-25 03:24:16,191 [salt.loaded.ext.module.maas:92  ][INFO    ][6091] maasconfig name=enable_http_proxy value=True
2020-02-25 03:24:16,239 [salt.loaded.ext.module.maas:92  ][INFO    ][6091] maasconfig name=upstream_dns value=8.8.8.8
2020-02-25 03:24:16,285 [salt.loaded.ext.module.maas:92  ][INFO    ][6091] maasconfig name=commissioning_distro_series value=bionic
2020-02-25 03:24:17,866 [salt.loaded.ext.module.maas:92  ][INFO    ][6091] maasconfig name=default_osystem value=ubuntu
2020-02-25 03:24:18,223 [salt.loaded.ext.module.maas:92  ][INFO    ][6091] maasconfig name=active_discovery_interval value=600
2020-02-25 03:24:18,261 [salt.loaded.ext.module.maas:92  ][INFO    ][6091] maasconfig name=dnssec_validation value=no
2020-02-25 03:24:18,481 [salt.loaded.ext.module.maas:92  ][INFO    ][6091] maasconfig name=kernel_opts value=spectre_v2=off nopti kpti=off nospec_store_bypass_disable noibrs noibpb
2020-02-25 03:24:18,514 [salt.loaded.ext.module.maas:92  ][INFO    ][6091] maasconfig name=network_discovery value=enabled
2020-02-25 03:24:18,595 [salt.loaded.ext.module.maas:92  ][INFO    ][6091] maasconfig name=enable_third_party_drivers value=True
2020-02-25 03:24:18,637 [salt.loaded.ext.module.maas:92  ][INFO    ][6091] maasconfig name=default_storage_layout value=lvm
2020-02-25 03:24:18,702 [salt.loaded.ext.module.maas:92  ][INFO    ][6091] maasconfig name=maas_name value=mas01
2020-02-25 03:24:18,741 [salt.loaded.ext.module.maas:92  ][INFO    ][6091] maasconfig name=disk_erase_with_secure_erase value=False
2020-02-25 03:24:18,780 [salt.loaded.ext.module.maas:92  ][INFO    ][6091] maasconfig name=default_distro_series value=bionic
2020-02-25 03:24:18,826 [salt.loaded.ext.module.maas:92  ][INFO    ][6091] maasconfig name=ntp_external_only value=True
2020-02-25 03:24:18,875 [salt.loaded.ext.module.maas:92  ][INFO    ][6091] maasconfig name=default_min_hwe_kernel value=ga-18.04
2020-02-25 03:24:18,939 [salt.state       :300 ][INFO    ][6091] {'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-02-25 03:24:18,939 [salt.state       :1951][INFO    ][6091] Completed state [maas.process_maas_config] at time 03:24:18.939673 duration_in_ms=2749.78
2020-02-25 03:24:18,941 [salt.state       :1780][INFO    ][6091] Running state [pxe_admin] at time 03:24:18.941287
2020-02-25 03:24:18,941 [salt.state       :1813][INFO    ][6091] Executing state maasng.fabric_present for [pxe_admin]
2020-02-25 03:24:18,989 [salt.loaded.ext.module.maasng:945 ][INFO    ][6091] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'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'fabric-1'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w7e46d', 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'pxe_admin'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2020-02-25 03:24:19,040 [salt.loaded.ext.module.maasng:1008][WARNING ][6091] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2020-02-25 03:24:19,040 [salt.loaded.ext.module.maasng:1011][WARNING ][6091] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2020-02-25 03:24:19,086 [salt.state       :300 ][INFO    ][6091] {'new': 'Fabric  pxe_admin created', 'result': True}
2020-02-25 03:24:19,086 [salt.state       :1951][INFO    ][6091] Completed state [pxe_admin] at time 03:24:19.086809 duration_in_ms=145.521
2020-02-25 03:24:19,087 [salt.state       :1780][INFO    ][6091] Running state [vlan 0] at time 03:24:19.087388
2020-02-25 03:24:19,088 [salt.state       :1813][INFO    ][6091] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2020-02-25 03:24:19,135 [salt.loaded.ext.module.maasng:945 ][INFO    ][6091] [{u'id': 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'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w7e46d', 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'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2020-02-25 03:24:19,222 [salt.loaded.ext.module.maasng:945 ][INFO    ][6091] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'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'fabric-1'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w7e46d', 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'pxe_admin'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2020-02-25 03:24:19,439 [salt.loaded.ext.module.maasng:945 ][INFO    ][6091] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'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'fabric-1'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w7e46d', 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'pxe_admin'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2020-02-25 03:24:19,499 [salt.state       :300 ][INFO    ][6091] {'new': 'Vlan untagged was updated'}
2020-02-25 03:24:19,501 [salt.state       :1951][INFO    ][6091] Completed state [vlan 0] at time 03:24:19.500179 duration_in_ms=412.792
2020-02-25 03:24:19,502 [salt.state       :1780][INFO    ][6091] Running state [192.168.11.0/24] at time 03:24:19.502342
2020-02-25 03:24:19,502 [salt.state       :1813][INFO    ][6091] Executing state maasng.subnet_present for [192.168.11.0/24]
2020-02-25 03:24:19,654 [salt.loaded.ext.module.maasng:945 ][INFO    ][6091] [{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/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'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'fabric-1'}], u'class_type': None, u'name': u'fabric-1', u'id': 1}, {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': u'w7e46d', 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'pxe_admin'}], u'class_type': None, u'name': u'pxe_admin', u'id': 2}]
2020-02-25 03:24:19,654 [salt.loaded.ext.module.maasng:1235][WARNING ][6091] Ignoring parameter vlan:0
2020-02-25 03:24:19,699 [salt.state       :300 ][INFO    ][6091] Subnet 192.168.11.0/24 has been updated for pxe_admin
2020-02-25 03:24:19,699 [salt.state       :1951][INFO    ][6091] Completed state [192.168.11.0/24] at time 03:24:19.699492 duration_in_ms=197.147
2020-02-25 03:24:19,701 [salt.state       :1780][INFO    ][6091] Running state [maas_create_iprange_1] at time 03:24:19.701449
2020-02-25 03:24:19,701 [salt.state       :1813][INFO    ][6091] Executing state maasng.iprange_present for [maas_create_iprange_1]
2020-02-25 03:24:19,737 [salt.state       :300 ][INFO    ][6091] Iprange maas_create_iprange_1 already exist.
2020-02-25 03:24:19,737 [salt.state       :1951][INFO    ][6091] Completed state [maas_create_iprange_1] at time 03:24:19.737286 duration_in_ms=35.837
2020-02-25 03:24:19,737 [salt.state       :1780][INFO    ][6091] Running state [vlan 0] at time 03:24:19.737620
2020-02-25 03:24:19,737 [salt.state       :1813][INFO    ][6091] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2020-02-25 03:24:19,775 [salt.loaded.ext.module.maasng:945 ][INFO    ][6091] [{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/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'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'fabric-1'}], u'class_type': None, u'name': u'fabric-1', u'id': 1}, {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': u'w7e46d', 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'pxe_admin'}], u'class_type': None, u'name': u'pxe_admin', u'id': 2}]
2020-02-25 03:24:19,847 [salt.loaded.ext.module.maasng:945 ][INFO    ][6091] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'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'fabric-1'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'w7e46d', 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'pxe_admin'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2020-02-25 03:24:20,014 [salt.loaded.ext.module.maasng:945 ][INFO    ][6091] [{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/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'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'fabric-1'}], u'class_type': None, u'name': u'fabric-1', u'id': 1}, {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': u'w7e46d', 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'pxe_admin'}], u'class_type': None, u'name': u'pxe_admin', u'id': 2}]
2020-02-25 03:24:20,074 [salt.state       :300 ][INFO    ][6091] {'new': 'Vlan untagged was updated'}
2020-02-25 03:24:20,075 [salt.state       :1951][INFO    ][6091] Completed state [vlan 0] at time 03:24:20.075251 duration_in_ms=337.63
2020-02-25 03:24:20,076 [salt.state       :1780][INFO    ][6091] Running state [opnfv] at time 03:24:20.075953
2020-02-25 03:24:20,078 [salt.state       :1813][INFO    ][6091] Executing state maasng.sshkey_present for [opnfv]
2020-02-25 03:24:20,104 [salt.loaded.ext.module.maasng:1903][INFO    ][6091] [{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-02-25 03:24:20,105 [salt.state       :300 ][INFO    ][6091] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2020-02-25 03:24:20,105 [salt.state       :1951][INFO    ][6091] Completed state [opnfv] at time 03:24:20.105630 duration_in_ms=29.677
2020-02-25 03:24:20,106 [salt.state       :1780][INFO    ][6091] Running state [maas.process_tags] at time 03:24:20.106449
2020-02-25 03:24:20,106 [salt.state       :1813][INFO    ][6091] Executing state module.run for [maas.process_tags]
2020-02-25 03:24:20,107 [salt.utils.decorators:613 ][WARNING ][6091] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-25 03:24:20,135 [salt.loaded.ext.module.maas:92  ][INFO    ][6091] 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-02-25 03:24:20,176 [salt.state       :300 ][INFO    ][6091] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2020-02-25 03:24:20,176 [salt.state       :1951][INFO    ][6091] Completed state [maas.process_tags] at time 03:24:20.176755 duration_in_ms=70.306
2020-02-25 03:24:20,179 [salt.minion      :1711][INFO    ][6091] Returning information for job: 20200225032355874290
2020-02-25 03:24:21,251 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command state.apply with jid 20200225032421238008
2020-02-25 03:24:21,286 [salt.minion      :1432][INFO    ][6223] Starting a new job with PID 6223
2020-02-25 03:24:29,576 [salt.state       :915 ][INFO    ][6223] Loading fresh modules for state activity
2020-02-25 03:24:29,689 [salt.state       :1780][INFO    ][6223] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:24:29.689648
2020-02-25 03:24:29,690 [salt.state       :1813][INFO    ][6223] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-25 03:24:29,692 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6223] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-25 03:24:31,972 [salt.state       :300 ][INFO    ][6223] {'pid': 6231, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-25 03:24:31,973 [salt.state       :1951][INFO    ][6223] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:24:31.973230 duration_in_ms=2283.584
2020-02-25 03:24:31,977 [salt.state       :1780][INFO    ][6223] Running state [maas.process_machines] at time 03:24:31.977102
2020-02-25 03:24:31,977 [salt.state       :1813][INFO    ][6223] Executing state module.run for [maas.process_machines]
2020-02-25 03:24:31,978 [salt.utils.decorators:613 ][WARNING ][6223] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-25 03:24:32,489 [salt.loaded.ext.module.maas:412 ][WARNING ][6223] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-25 03:24:32,489 [salt.loaded.ext.module.maas:92  ][INFO    ][6223] 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=gct4rh architecture=amd64/generic power_parameters_power_user=opnfv
2020-02-25 03:24:33,213 [salt.loaded.ext.module.maas:412 ][WARNING ][6223] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-25 03:24:33,214 [salt.loaded.ext.module.maas:92  ][INFO    ][6223] 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=cwqman architecture=amd64/generic power_parameters_power_user=opnfv
2020-02-25 03:24:33,889 [salt.loaded.ext.module.maas:412 ][WARNING ][6223] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-25 03:24:33,890 [salt.loaded.ext.module.maas:92  ][INFO    ][6223] 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=xkpar3 architecture=amd64/generic power_parameters_power_user=opnfv
2020-02-25 03:24:34,556 [salt.loaded.ext.module.maas:412 ][WARNING ][6223] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-25 03:24:34,557 [salt.loaded.ext.module.maas:92  ][INFO    ][6223] 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=87fdg8 architecture=amd64/generic power_parameters_power_user=opnfv
2020-02-25 03:24:35,236 [salt.state       :300 ][INFO    ][6223] {'ret': {'updated': ['gtw01', 'cmp002', 'cmp001', 'ctl01'], 'errors': {}, 'success': []}}
2020-02-25 03:24:35,237 [salt.state       :1951][INFO    ][6223] Completed state [maas.process_machines] at time 03:24:35.237272 duration_in_ms=3260.169
2020-02-25 03:24:35,240 [salt.minion      :1711][INFO    ][6223] Returning information for job: 20200225032421238008
2020-02-25 03:25:08,871 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command state.apply with jid 20200225032508857193
2020-02-25 03:25:08,894 [salt.minion      :1432][INFO    ][6282] Starting a new job with PID 6282
2020-02-25 03:25:17,229 [salt.state       :915 ][INFO    ][6282] Loading fresh modules for state activity
2020-02-25 03:25:17,341 [salt.state       :1780][INFO    ][6282] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:25:17.341158
2020-02-25 03:25:17,341 [salt.state       :1813][INFO    ][6282] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-25 03:25:17,343 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6282] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-25 03:25:19,566 [salt.state       :300 ][INFO    ][6282] {'pid': 6290, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-25 03:25:19,567 [salt.state       :1951][INFO    ][6282] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:25:19.567088 duration_in_ms=2225.932
2020-02-25 03:25:19,569 [salt.state       :1780][INFO    ][6282] Running state [maas.wait_for_machine_status] at time 03:25:19.569333
2020-02-25 03:25:19,569 [salt.state       :1813][INFO    ][6282] Executing state module.run for [maas.wait_for_machine_status]
2020-02-25 03:25:19,570 [salt.utils.decorators:613 ][WARNING ][6282] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-25 03:25:21,688 [salt.state       :300 ][INFO    ][6282] {'ret': True}
2020-02-25 03:25:21,689 [salt.state       :1951][INFO    ][6282] Completed state [maas.wait_for_machine_status] at time 03:25:21.689028 duration_in_ms=2119.695
2020-02-25 03:25:21,692 [salt.minion      :1711][INFO    ][6282] Returning information for job: 20200225032508857193
2020-02-25 03:25:22,642 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command state.apply with jid 20200225032522628504
2020-02-25 03:25:22,672 [salt.minion      :1432][INFO    ][6301] Starting a new job with PID 6301
2020-02-25 03:25:23,705 [salt.state       :915 ][INFO    ][6301] Loading fresh modules for state activity
2020-02-25 03:25:23,850 [salt.state       :1780][INFO    ][6301] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:25:23.850841
2020-02-25 03:25:23,851 [salt.state       :1813][INFO    ][6301] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-25 03:25:23,853 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6301] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-25 03:25:26,066 [salt.state       :300 ][INFO    ][6301] {'pid': 6308, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-25 03:25:26,067 [salt.state       :1951][INFO    ][6301] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:25:26.067431 duration_in_ms=2216.589
2020-02-25 03:25:26,071 [salt.state       :1780][INFO    ][6301] Running state [maas_machines_storage_cmp002_lvm] at time 03:25:26.070937
2020-02-25 03:25:26,071 [salt.state       :1813][INFO    ][6301] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2020-02-25 03:25:27,115 [salt.loaded.ext.module.maasng:610 ][INFO    ][6301] cwqman
2020-02-25 03:25:27,115 [salt.loaded.ext.module.maasng:626 ][INFO    ][6301] sda
2020-02-25 03:25:27,635 [salt.loaded.ext.module.maasng:361 ][INFO    ][6301] cwqman
2020-02-25 03:25:27,725 [salt.loaded.ext.module.maasng:367 ][INFO    ][6301] [{u'resource_uri': u'/MAAS/api/2.0/nodes/cwqman/blockdevices/1/', u'name': u'sda', u'tags': [u'ssd'], u'system_id': u'cwqman', u'used_size': 800106479616, u'partitions': [{u'uuid': u'bc61e6ca-3b1b-4d5b-ac12-e40ce2c39b76', u'resource_uri': u'/MAAS/api/2.0/nodes/cwqman/blockdevices/1/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'cwqman', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'549a9c5c-084c-4f0a-bba2-14d85d166be6', 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'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'id': 1, u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, 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/cwqman/blockdevices/11/', u'name': u'vgroot-lvroot', u'tags': [], u'system_id': u'cwqman', u'used_size': 800097042432, u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'82058f67-a58a-42c0-9da6-d5d4b6ae6050', u'fstype': u'ext4'}, u'uuid': u'eef52676-4251-4ed9-8171-8b0ef6383049', u'used_for': u'ext4 formatted filesystem mounted at /', u'id': 11, u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'type': u'virtual', u'serial': None, u'size': 800097042432}]
2020-02-25 03:25:27,726 [salt.loaded.ext.module.maasng:632 ][INFO    ][6301] vgroot
2020-02-25 03:25:27,726 [salt.loaded.ext.module.maasng:635 ][INFO    ][6301] lvroot
2020-02-25 03:25:27,726 [salt.loaded.ext.module.maasng:639 ][INFO    ][6301] 107374182400
2020-02-25 03:25:28,363 [salt.loaded.ext.module.maasng:645 ][INFO    ][6301] {u'node_type_name': u'Machine', 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'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': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w7e46d', 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'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': 4, u'resource_uri': u'/MAAS/api/2.0/subnets/4/'}, u'id': 50, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w7e46d', 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'enabled': True, u'children': [], u'discovered': [], u'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'firmware_version': u'bc 7.8.24', u'system_id': u'cwqman', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/cwqman/interfaces/5/'}, u'min_hwe_kernel': u'ga-18.04', u'node_type': 0, 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'status_action': u'', 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/cwqman/', 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'cwqman', 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'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/cwqman/blockdevices/13/', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'uuid': u'1ee09365-7f57-4534-ad9b-490f5133fa08', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'cwqman', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'38c5fd1f-9631-4d49-9245-884b68ac6ec0', 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': 13, u'size': 107374182400}], u'blockdevice_set': [{u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/cwqman/blockdevices/1/', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'4e2ca02e-547b-45a9-8f49-a861cf36731c', u'resource_uri': u'/MAAS/api/2.0/nodes/cwqman/blockdevices/1/partition/9', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'cwqman', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'8db1ac9c-6bb4-4e72-8a41-fa3ee080f119', 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'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'cwqman', 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'size': 800109715456}, {u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/cwqman/blockdevices/13/', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'uuid': u'1ee09365-7f57-4534-ad9b-490f5133fa08', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'cwqman', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'38c5fd1f-9631-4d49-9245-884b68ac6ec0', 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': 13, u'size': 107374182400}], u'status': 4, u'bcaches': [], u'cpu_count': 40, u'power_state': u'off', u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'cwqman', u'id': 9}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'commissioning_status_name': u'Passed', 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'current_commissioning_result_id': 16, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/cwqman/blockdevices/1/', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'4e2ca02e-547b-45a9-8f49-a861cf36731c', u'resource_uri': u'/MAAS/api/2.0/nodes/cwqman/blockdevices/1/partition/9', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'cwqman', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'8db1ac9c-6bb4-4e72-8a41-fa3ee080f119', 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'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'cwqman', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'firmware_version': u'5.22', u'block_size': 4096, u'type': u'physical', u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'memory_test_status_name': u'Unknown', u'commissioning_status': 2, u'architecture': u'amd64/generic', 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': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w7e46d', 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'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': 4, u'resource_uri': u'/MAAS/api/2.0/subnets/4/'}, u'id': 50, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w7e46d', 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'enabled': True, u'children': [], u'discovered': [], u'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'firmware_version': u'bc 7.8.24', u'system_id': u'cwqman', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/cwqman/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'vlan': None, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:50', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'firmware_version': u'bc 7.8.24', u'system_id': u'cwqman', u'type': u'physical', u'id': 10, u'resource_uri': u'/MAAS/api/2.0/nodes/cwqman/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': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w7e46d', 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'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': 4, u'resource_uri': u'/MAAS/api/2.0/subnets/4/'}, u'id': 51, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w7e46d', 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'enabled': True, u'children': [], u'discovered': [], u'mac_address': u'9c:b6:54:8a:10:1c', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'firmware_version': u'bc 7.8.24', u'system_id': u'cwqman', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/cwqman/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'vlan': None, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:51', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'firmware_version': u'bc 7.8.24', u'system_id': u'cwqman', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/cwqman/interfaces/12/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens2f0', u'links': [{u'id': 52, u'mode': u'link_up'}], u'tags': [u'sriov'], 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'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:48', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'firmware_version': u'bc 7.8.24', u'system_id': u'cwqman', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/cwqman/interfaces/13/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens2f1', u'links': [{u'id': 53, u'mode': u'link_up'}], u'tags': [u'sriov'], 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'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:49', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'firmware_version': u'bc 7.8.24', u'system_id': u'cwqman', u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/cwqman/interfaces/14/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'boot_disk': {u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/cwqman/blockdevices/1/', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'4e2ca02e-547b-45a9-8f49-a861cf36731c', u'resource_uri': u'/MAAS/api/2.0/nodes/cwqman/blockdevices/1/partition/9', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'cwqman', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'8db1ac9c-6bb4-4e72-8a41-fa3ee080f119', 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'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'cwqman', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'firmware_version': u'5.22', u'block_size': 4096, u'type': u'physical', u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}}
2020-02-25 03:25:28,367 [salt.state       :300 ][INFO    ][6301] {'new': {'storage_layout': 'lvm'}}
2020-02-25 03:25:28,367 [salt.state       :1951][INFO    ][6301] Completed state [maas_machines_storage_cmp002_lvm] at time 03:25:28.367795 duration_in_ms=2296.856
2020-02-25 03:25:28,370 [salt.state       :1780][INFO    ][6301] Running state [maas_machines_storage_cmp001_lvm] at time 03:25:28.370415
2020-02-25 03:25:28,370 [salt.state       :1813][INFO    ][6301] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2020-02-25 03:25:29,370 [salt.loaded.ext.module.maasng:610 ][INFO    ][6301] xkpar3
2020-02-25 03:25:29,370 [salt.loaded.ext.module.maasng:626 ][INFO    ][6301] sda
2020-02-25 03:25:30,045 [salt.loaded.ext.module.maasng:361 ][INFO    ][6301] xkpar3
2020-02-25 03:25:30,123 [salt.loaded.ext.module.maasng:367 ][INFO    ][6301] [{u'resource_uri': u'/MAAS/api/2.0/nodes/xkpar3/blockdevices/2/', u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'id': 2, u'partitions': [{u'uuid': u'd23e0731-8f78-4b8a-8bf6-ef120361587d', u'resource_uri': u'/MAAS/api/2.0/nodes/xkpar3/blockdevices/2/partition/8', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xkpar3', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'927a3ad2-52d1-49c1-a578-f5342eb3b4a8', 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'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'xkpar3', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, {u'resource_uri': u'/MAAS/api/2.0/nodes/xkpar3/blockdevices/12/', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'id': 12, u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'6abbb2ab-6ef0-49e1-aa76-bf38cdf02d79', u'fstype': u'ext4'}, u'uuid': u'753213a7-e6bb-47bf-bf3d-54457d5168ed', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'xkpar3', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 800097042432, u'serial': None, u'size': 800097042432}]
2020-02-25 03:25:30,124 [salt.loaded.ext.module.maasng:632 ][INFO    ][6301] vgroot
2020-02-25 03:25:30,125 [salt.loaded.ext.module.maasng:635 ][INFO    ][6301] lvroot
2020-02-25 03:25:30,125 [salt.loaded.ext.module.maasng:639 ][INFO    ][6301] 107374182400
2020-02-25 03:25:30,683 [salt.loaded.ext.module.maasng:645 ][INFO    ][6301] {u'hwe_kernel': None, u'swap_size': None, u'memory_test_status': -1, 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'memory_test_status_name': u'Unknown', 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'resource_uri': u'/MAAS/api/2.0/nodes/xkpar3/blockdevices/2/', u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'bd4fad80-6e8f-4ba4-b2de-1b92dcf6f87f', u'resource_uri': u'/MAAS/api/2.0/nodes/xkpar3/blockdevices/2/partition/10', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xkpar3', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'5cf598f6-8166-452a-9940-13b0e216c6db', 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'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'system_id': u'xkpar3', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id': 2, u'available_size': 0, u'firmware_version': u'5.22', u'block_size': 4096, u'used_size': 800106479616, u'model': u'LOGICAL VOLUME', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, 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/xkpar3/', u'current_commissioning_result_id': 18, 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'xkpar3', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/xkpar3/blockdevices/14/', 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'4160d741-55d8-4fcd-bb19-f485199886b3', u'fstype': u'ext4'}, u'uuid': u'6091101c-0cf8-4606-9e28-2237cd406aa3', u'used_for': u'ext4 formatted filesystem mounted at /', u'id_path': None, u'system_id': u'xkpar3', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id': 14, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'serial': None, u'size': 107374182400}], u'blockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/xkpar3/blockdevices/2/', u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'id': 2, u'partitions': [{u'uuid': u'bd4fad80-6e8f-4ba4-b2de-1b92dcf6f87f', u'resource_uri': u'/MAAS/api/2.0/nodes/xkpar3/blockdevices/2/partition/10', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xkpar3', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'5cf598f6-8166-452a-9940-13b0e216c6db', 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'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'xkpar3', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, {u'resource_uri': u'/MAAS/api/2.0/nodes/xkpar3/blockdevices/14/', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'id': 14, u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'4160d741-55d8-4fcd-bb19-f485199886b3', u'fstype': u'ext4'}, u'uuid': u'6091101c-0cf8-4606-9e28-2237cd406aa3', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'xkpar3', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'serial': None, u'size': 107374182400}], u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'xkpar3', u'id': 10}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'commissioning_status_name': u'Passed', 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'bcaches': [], u'locked': False, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/xkpar3/blockdevices/2/', u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'bd4fad80-6e8f-4ba4-b2de-1b92dcf6f87f', u'resource_uri': u'/MAAS/api/2.0/nodes/xkpar3/blockdevices/2/partition/10', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xkpar3', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'5cf598f6-8166-452a-9940-13b0e216c6db', 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'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'system_id': u'xkpar3', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id': 2, u'available_size': 0, u'firmware_version': u'5.22', u'block_size': 4096, u'used_size': 800106479616, u'model': u'LOGICAL VOLUME', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'disable_ipv4': False, u'commissioning_status': 2, u'min_hwe_kernel': u'ga-18.04', u'boot_interface': {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w7e46d', 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'pxe_admin'}, 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w7e46d', 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'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/subnets/4/'}, u'id': 57, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 6, u'discovered': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'firmware_version': u'bc 7.8.24', u'system_id': u'xkpar3', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/xkpar3/interfaces/6/'}, u'interface_set': [{u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w7e46d', 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'pxe_admin'}, 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w7e46d', 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'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/subnets/4/'}, u'id': 57, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 6, u'discovered': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'firmware_version': u'bc 7.8.24', u'system_id': u'xkpar3', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/xkpar3/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'effective_mtu': 1500, u'enabled': True, u'id': 15, u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d4', u'parents': [], u'firmware_version': u'bc 7.8.24', u'system_id': u'xkpar3', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/xkpar3/interfaces/15/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w7e46d', 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'pxe_admin'}, 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w7e46d', 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'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/subnets/4/'}, u'id': 58, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 16, u'discovered': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a4', u'parents': [], u'firmware_version': u'bc 7.8.24', u'system_id': u'xkpar3', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/xkpar3/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'effective_mtu': 1500, u'enabled': True, u'id': 17, u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d5', u'parents': [], u'firmware_version': u'bc 7.8.24', u'system_id': u'xkpar3', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/xkpar3/interfaces/17/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'vendor': u'HP', u'name': u'ens2f0', u'links': [{u'id': 59, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 18, u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cc', u'parents': [], u'firmware_version': u'bc 7.8.24', u'system_id': u'xkpar3', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/xkpar3/interfaces/18/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'vendor': u'HP', u'name': u'ens2f1', u'links': [{u'id': 60, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 19, u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cd', u'parents': [], u'firmware_version': u'bc 7.8.24', u'system_id': u'xkpar3', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/xkpar3/interfaces/19/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'node_type_name': u'Machine'}
2020-02-25 03:25:30,686 [salt.state       :300 ][INFO    ][6301] {'new': {'storage_layout': 'lvm'}}
2020-02-25 03:25:30,686 [salt.state       :1951][INFO    ][6301] Completed state [maas_machines_storage_cmp001_lvm] at time 03:25:30.686848 duration_in_ms=2316.432
2020-02-25 03:25:30,690 [salt.minion      :1711][INFO    ][6301] Returning information for job: 20200225032522628504
2020-02-25 03:25:31,622 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command state.apply with jid 20200225032531614268
2020-02-25 03:25:31,649 [salt.minion      :1432][INFO    ][6326] Starting a new job with PID 6326
2020-02-25 03:25:39,994 [salt.state       :915 ][INFO    ][6326] Loading fresh modules for state activity
2020-02-25 03:25:40,095 [salt.state       :1780][INFO    ][6326] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:25:40.095000
2020-02-25 03:25:40,095 [salt.state       :1813][INFO    ][6326] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-25 03:25:40,097 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6326] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-25 03:25:42,167 [salt.state       :300 ][INFO    ][6326] {'pid': 6350, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-25 03:25:42,168 [salt.state       :1951][INFO    ][6326] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:25:42.168025 duration_in_ms=2073.027
2020-02-25 03:25:42,171 [salt.state       :1780][INFO    ][6326] Running state [maas.deploy_machines] at time 03:25:42.171679
2020-02-25 03:25:42,172 [salt.state       :1813][INFO    ][6326] Executing state module.run for [maas.deploy_machines]
2020-02-25 03:25:42,173 [salt.utils.decorators:613 ][WARNING ][6326] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-25 03:25:42,674 [salt.loaded.ext.module.maas:684 ][INFO    ][6326] deploymachines hwe_kernel=ga-18.04 system_id=cwqman distro_series=bionic
2020-02-25 03:25:44,586 [salt.loaded.ext.module.maas:684 ][INFO    ][6326] deploymachines hwe_kernel=ga-18.04 system_id=xkpar3 distro_series=bionic
2020-02-25 03:25:46,438 [salt.state       :300 ][INFO    ][6326] {'ret': {'updated': ['gtw01', 'ctl01'], 'errors': {}, 'success': ['cmp002', 'cmp001']}}
2020-02-25 03:25:46,439 [salt.state       :1951][INFO    ][6326] Completed state [maas.deploy_machines] at time 03:25:46.439229 duration_in_ms=4267.549
2020-02-25 03:25:46,445 [salt.minion      :1711][INFO    ][6326] Returning information for job: 20200225032531614268
2020-02-25 03:25:47,428 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command state.apply with jid 20200225032547375680
2020-02-25 03:25:47,453 [salt.minion      :1432][INFO    ][6378] Starting a new job with PID 6378
2020-02-25 03:25:48,477 [salt.state       :915 ][INFO    ][6378] Loading fresh modules for state activity
2020-02-25 03:25:48,589 [salt.state       :1780][INFO    ][6378] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:25:48.588992
2020-02-25 03:25:48,589 [salt.state       :1813][INFO    ][6378] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-25 03:25:48,591 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6378] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-25 03:25:50,913 [salt.state       :300 ][INFO    ][6378] {'pid': 6385, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-25 03:25:50,914 [salt.state       :1951][INFO    ][6378] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:25:50.914214 duration_in_ms=2325.229
2020-02-25 03:25:50,918 [salt.state       :1780][INFO    ][6378] Running state [maas.wait_for_machine_status] at time 03:25:50.918067
2020-02-25 03:25:50,918 [salt.state       :1813][INFO    ][6378] Executing state module.run for [maas.wait_for_machine_status]
2020-02-25 03:25:50,918 [salt.utils.decorators:613 ][WARNING ][6378] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-25 03:25:53,043 [salt.loaded.ext.module.maas:1024][INFO    ][6378] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2247.88712001s left)
2020-02-25 03:26:02,423 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225032602410060
2020-02-25 03:26:02,448 [salt.minion      :1432][INFO    ][6403] Starting a new job with PID 6403
2020-02-25 03:26:02,471 [salt.minion      :1711][INFO    ][6403] Returning information for job: 20200225032602410060
2020-02-25 03:26:25,107 [salt.loaded.ext.module.maas:1024][INFO    ][6378] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2215.82376385s left)
2020-02-25 03:26:32,490 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225032632481348
2020-02-25 03:26:32,517 [salt.minion      :1432][INFO    ][6429] Starting a new job with PID 6429
2020-02-25 03:26:32,544 [salt.minion      :1711][INFO    ][6429] Returning information for job: 20200225032632481348
2020-02-25 03:26:57,231 [salt.loaded.ext.module.maas:1024][INFO    ][6378] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2183.69915605s left)
2020-02-25 03:27:02,558 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225032702548672
2020-02-25 03:27:02,586 [salt.minion      :1432][INFO    ][6473] Starting a new job with PID 6473
2020-02-25 03:27:02,613 [salt.minion      :1711][INFO    ][6473] Returning information for job: 20200225032702548672
2020-02-25 03:27:29,435 [salt.loaded.ext.module.maas:1024][INFO    ][6378] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2151.49493194s left)
2020-02-25 03:27:32,642 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225032732633414
2020-02-25 03:27:32,669 [salt.minion      :1432][INFO    ][6493] Starting a new job with PID 6493
2020-02-25 03:27:32,697 [salt.minion      :1711][INFO    ][6493] Returning information for job: 20200225032732633414
2020-02-25 03:28:01,525 [salt.loaded.ext.module.maas:1024][INFO    ][6378] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2119.40553784s left)
2020-02-25 03:28:02,709 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225032802698672
2020-02-25 03:28:02,733 [salt.minion      :1432][INFO    ][6528] Starting a new job with PID 6528
2020-02-25 03:28:02,763 [salt.minion      :1711][INFO    ][6528] Returning information for job: 20200225032802698672
2020-02-25 03:28:32,770 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225032832757558
2020-02-25 03:28:32,799 [salt.minion      :1432][INFO    ][6559] Starting a new job with PID 6559
2020-02-25 03:28:32,821 [salt.minion      :1711][INFO    ][6559] Returning information for job: 20200225032832757558
2020-02-25 03:28:33,598 [salt.loaded.ext.module.maas:1024][INFO    ][6378] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2087.33229899s left)
2020-02-25 03:29:02,852 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225032902841912
2020-02-25 03:29:02,880 [salt.minion      :1432][INFO    ][6630] Starting a new job with PID 6630
2020-02-25 03:29:02,906 [salt.minion      :1711][INFO    ][6630] Returning information for job: 20200225032902841912
2020-02-25 03:29:05,973 [salt.loaded.ext.module.maas:1024][INFO    ][6378] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2054.95694304s left)
2020-02-25 03:29:32,958 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225032932947523
2020-02-25 03:29:32,984 [salt.minion      :1432][INFO    ][6680] Starting a new job with PID 6680
2020-02-25 03:29:33,010 [salt.minion      :1711][INFO    ][6680] Returning information for job: 20200225032932947523
2020-02-25 03:29:38,359 [salt.loaded.ext.module.maas:1024][INFO    ][6378] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2022.57096004s left)
2020-02-25 03:30:03,051 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225033003030255
2020-02-25 03:30:03,082 [salt.minion      :1432][INFO    ][6714] Starting a new job with PID 6714
2020-02-25 03:30:03,110 [salt.minion      :1711][INFO    ][6714] Returning information for job: 20200225033003030255
2020-02-25 03:30:11,001 [salt.loaded.ext.module.maas:1024][INFO    ][6378] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1989.9290359s left)
2020-02-25 03:30:33,154 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225033033138095
2020-02-25 03:30:33,185 [salt.minion      :1432][INFO    ][6736] Starting a new job with PID 6736
2020-02-25 03:30:33,209 [salt.minion      :1711][INFO    ][6736] Returning information for job: 20200225033033138095
2020-02-25 03:30:43,410 [salt.loaded.ext.module.maas:1024][INFO    ][6378] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1957.52032185s left)
2020-02-25 03:31:03,258 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225033103247966
2020-02-25 03:31:03,282 [salt.minion      :1432][INFO    ][6770] Starting a new job with PID 6770
2020-02-25 03:31:03,310 [salt.minion      :1711][INFO    ][6770] Returning information for job: 20200225033103247966
2020-02-25 03:31:15,842 [salt.loaded.ext.module.maas:1024][INFO    ][6378] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1925.08828783s left)
2020-02-25 03:31:33,359 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225033133348130
2020-02-25 03:31:33,387 [salt.minion      :1432][INFO    ][6792] Starting a new job with PID 6792
2020-02-25 03:31:33,413 [salt.minion      :1711][INFO    ][6792] Returning information for job: 20200225033133348130
2020-02-25 03:31:48,230 [salt.loaded.ext.module.maas:1024][INFO    ][6378] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1892.70018196s left)
2020-02-25 03:32:03,481 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225033203469251
2020-02-25 03:32:03,509 [salt.minion      :1432][INFO    ][6826] Starting a new job with PID 6826
2020-02-25 03:32:03,535 [salt.minion      :1711][INFO    ][6826] Returning information for job: 20200225033203469251
2020-02-25 03:32:20,674 [salt.loaded.ext.module.maas:1024][INFO    ][6378] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1860.25595784s left)
2020-02-25 03:32:33,585 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225033233576038
2020-02-25 03:32:33,611 [salt.minion      :1432][INFO    ][6845] Starting a new job with PID 6845
2020-02-25 03:32:33,643 [salt.minion      :1711][INFO    ][6845] Returning information for job: 20200225033233576038
2020-02-25 03:32:53,116 [salt.loaded.ext.module.maas:1024][INFO    ][6378] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1827.81442904s left)
2020-02-25 03:33:03,707 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225033303694866
2020-02-25 03:33:03,734 [salt.minion      :1432][INFO    ][6877] Starting a new job with PID 6877
2020-02-25 03:33:03,758 [salt.minion      :1711][INFO    ][6877] Returning information for job: 20200225033303694866
2020-02-25 03:33:25,558 [salt.loaded.ext.module.maas:1024][INFO    ][6378] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1795.37250996s left)
2020-02-25 03:33:33,821 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225033333809914
2020-02-25 03:33:33,845 [salt.minion      :1432][INFO    ][6898] Starting a new job with PID 6898
2020-02-25 03:33:33,870 [salt.minion      :1711][INFO    ][6898] Returning information for job: 20200225033333809914
2020-02-25 03:33:57,845 [salt.loaded.ext.module.maas:1024][INFO    ][6378] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1763.08542395s left)
2020-02-25 03:34:03,955 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225033403940626
2020-02-25 03:34:03,982 [salt.minion      :1432][INFO    ][6931] Starting a new job with PID 6931
2020-02-25 03:34:04,015 [salt.minion      :1711][INFO    ][6931] Returning information for job: 20200225033403940626
2020-02-25 03:34:30,194 [salt.loaded.ext.module.maas:1024][INFO    ][6378] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1730.73615694s left)
2020-02-25 03:34:34,083 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225033434072107
2020-02-25 03:34:34,110 [salt.minion      :1432][INFO    ][6954] Starting a new job with PID 6954
2020-02-25 03:34:34,139 [salt.minion      :1711][INFO    ][6954] Returning information for job: 20200225033434072107
2020-02-25 03:35:02,561 [salt.loaded.ext.module.maas:1024][INFO    ][6378] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1698.36965489s left)
2020-02-25 03:35:04,222 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225033504213804
2020-02-25 03:35:04,242 [salt.minion      :1432][INFO    ][6987] Starting a new job with PID 6987
2020-02-25 03:35:04,268 [salt.minion      :1711][INFO    ][6987] Returning information for job: 20200225033504213804
2020-02-25 03:35:34,363 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225033534350789
2020-02-25 03:35:34,386 [salt.minion      :1432][INFO    ][7008] Starting a new job with PID 7008
2020-02-25 03:35:34,410 [salt.minion      :1711][INFO    ][7008] Returning information for job: 20200225033534350789
2020-02-25 03:35:35,032 [salt.loaded.ext.module.maas:1024][INFO    ][6378] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1665.89795494s left)
2020-02-25 03:36:04,518 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225033604506033
2020-02-25 03:36:04,542 [salt.minion      :1432][INFO    ][7056] Starting a new job with PID 7056
2020-02-25 03:36:04,570 [salt.minion      :1711][INFO    ][7056] Returning information for job: 20200225033604506033
2020-02-25 03:36:07,371 [salt.loaded.ext.module.maas:1024][INFO    ][6378] Waiting status:Deployed for machines:['cmp001']
sleep for:30s Timeout:2250s (1633.55913806s left)
2020-02-25 03:36:34,603 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command saltutil.find_job with jid 20200225033634592364
2020-02-25 03:36:34,635 [salt.minion      :1432][INFO    ][7092] Starting a new job with PID 7092
2020-02-25 03:36:34,666 [salt.minion      :1711][INFO    ][7092] Returning information for job: 20200225033634592364
2020-02-25 03:36:39,014 [salt.utils.schedule:1377][INFO    ][2059] Running scheduled job: __mine_interval
2020-02-25 03:36:39,858 [salt.state       :300 ][INFO    ][6378] {'ret': True}
2020-02-25 03:36:39,858 [salt.state       :1951][INFO    ][6378] Completed state [maas.wait_for_machine_status] at time 03:36:39.858370 duration_in_ms=648940.302
2020-02-25 03:36:39,861 [salt.minion      :1711][INFO    ][6378] Returning information for job: 20200225032547375680
2020-02-25 04:27:21,542 [salt.minion      :1308][INFO    ][2059] User sudo_ubuntu Executing command cp.push_dir with jid 20200225042721530991
2020-02-25 04:27:21,564 [salt.minion      :1432][INFO    ][9475] Starting a new job with PID 9475
