2019-04-24 02:02:51,611 [salt.minion      :870 ][ERROR   ][387] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-24 02:03:41,661 [salt.minion      :870 ][ERROR   ][387] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-24 02:04:31,705 [salt.minion      :870 ][ERROR   ][387] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-24 02:05:21,753 [salt.minion      :870 ][ERROR   ][387] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-24 02:06:11,797 [salt.minion      :870 ][ERROR   ][387] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-24 02:07:01,844 [salt.minion      :870 ][ERROR   ][387] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-24 02:08:34,392 [salt.utils.decorators:613 ][WARNING ][2850] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 02:08:35,461 [salt.utils.decorators:613 ][WARNING ][2850] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 02:08:46,222 [salt.loaded.int.states.file:2298][WARNING ][2985] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-04-24 02:08:50,074 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3226] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-04-24 02:08:50,097 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3226] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-04-24 02:08:50,140 [salt.utils.parsers:1051][WARNING ][387] Minion received a SIGTERM. Exiting.
2019-04-24 02:08:51,318 [salt.cli.daemons :293 ][INFO    ][3384] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-04-24 02:08:51,489 [salt.cli.daemons :82  ][INFO    ][3384] Starting up the Salt Minion
2019-04-24 02:08:51,490 [salt.utils.event :1017][INFO    ][3384] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-04-24 02:08:52,648 [salt.minion      :976 ][INFO    ][3384] Creating minion process manager
2019-04-24 02:08:53,844 [salt.state       :2022][WARNING ][3231] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-04-24 02:08:55,169 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][3384] Executing command ['date', '+%z'] in directory '/root'
2019-04-24 02:08:55,196 [salt.utils.schedule:568 ][INFO    ][3384] Updating job settings for scheduled job: __mine_interval
2019-04-24 02:08:55,198 [salt.minion      :1108][INFO    ][3384] Added mine.update to scheduler
2019-04-24 02:08:55,206 [salt.minion      :1975][INFO    ][3384] Minion is starting as user 'root'
2019-04-24 02:08:55,222 [salt.minion      :2336][INFO    ][3384] Minion is ready to receive requests!
2019-04-24 02:08:55,915 [salt.utils.decorators:613 ][WARNING ][3231] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 02:09:02,481 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424020902469841
2019-04-24 02:09:02,495 [salt.minion      :1432][INFO    ][3895] Starting a new job with PID 3895
2019-04-24 02:09:02,522 [salt.minion      :1711][INFO    ][3895] Returning information for job: 20190424020902469841
2019-04-24 02:09:10,654 [salt.utils.decorators:613 ][WARNING ][3231] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 02:09:32,582 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424020932568046
2019-04-24 02:09:32,604 [salt.minion      :1432][INFO    ][4524] Starting a new job with PID 4524
2019-04-24 02:09:32,632 [salt.minion      :1711][INFO    ][4524] Returning information for job: 20190424020932568046
2019-04-24 02:09:32,722 [salt.utils.decorators:613 ][WARNING ][3231] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 02:09:33,609 [salt.utils.decorators:613 ][WARNING ][3231] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 02:09:35,662 [salt.loaded.ext.module.maasng:1008][WARNING ][3231] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-04-24 02:09:35,662 [salt.loaded.ext.module.maasng:1011][WARNING ][3231] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-04-24 02:09:36,319 [salt.loaded.ext.module.maasng:1235][WARNING ][3231] Ignoring parameter vlan:0
2019-04-24 02:09:37,915 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command state.apply with jid 20190424020937904836
2019-04-24 02:09:37,930 [salt.minion      :1432][INFO    ][4746] Starting a new job with PID 4746
2019-04-24 02:09:46,146 [salt.state       :915 ][INFO    ][4746] Loading fresh modules for state activity
2019-04-24 02:09:46,209 [salt.fileclient  :1219][INFO    ][4746] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-04-24 02:09:46,253 [salt.state       :1780][INFO    ][4746] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:09:46.253068
2019-04-24 02:09:46,253 [salt.state       :1813][INFO    ][4746] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-24 02:09:46,255 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4746] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-24 02:09:48,130 [salt.state       :300 ][INFO    ][4746] {'pid': 4776, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-24 02:09:48,131 [salt.state       :1951][INFO    ][4746] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:09:48.131532 duration_in_ms=1878.465
2019-04-24 02:09:48,133 [salt.state       :1780][INFO    ][4746] Running state [maas.process_machines] at time 02:09:48.133393
2019-04-24 02:09:48,133 [salt.state       :1813][INFO    ][4746] Executing state module.run for [maas.process_machines]
2019-04-24 02:09:48,134 [salt.utils.decorators:613 ][WARNING ][4746] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 02:09:48,225 [salt.loaded.ext.module.maas:412 ][WARNING ][4746] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-24 02:09:48,226 [salt.loaded.ext.module.maas:92  ][INFO    ][4746] 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
2019-04-24 02:09:49,676 [salt.loaded.ext.module.maas:412 ][WARNING ][4746] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-24 02:09:49,677 [salt.loaded.ext.module.maas:92  ][INFO    ][4746] 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
2019-04-24 02:09:51,035 [salt.loaded.ext.module.maas:412 ][WARNING ][4746] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-24 02:09:51,035 [salt.loaded.ext.module.maas:92  ][INFO    ][4746] machine hostname=kvm01 power_type=ipmi mac_addresses=14:58:d0:54:e7:88 power_parameters_power_address=172.16.1.16 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-24 02:09:52,321 [salt.loaded.ext.module.maas:412 ][WARNING ][4746] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-24 02:09:52,322 [salt.loaded.ext.module.maas:92  ][INFO    ][4746] machine hostname=kvm03 power_type=ipmi mac_addresses=14:58:d0:54:7a:28 power_parameters_power_address=172.16.1.18 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-24 02:09:53,016 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424020953007765
2019-04-24 02:09:53,034 [salt.minion      :1432][INFO    ][5009] Starting a new job with PID 5009
2019-04-24 02:09:53,060 [salt.minion      :1711][INFO    ][5009] Returning information for job: 20190424020953007765
2019-04-24 02:09:53,636 [salt.loaded.ext.module.maas:412 ][WARNING ][4746] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-24 02:09:53,636 [salt.loaded.ext.module.maas:92  ][INFO    ][4746] machine hostname=kvm02 power_type=ipmi mac_addresses=14:58:d0:54:6a:60 power_parameters_power_address=172.16.1.17 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-24 02:09:54,956 [salt.state       :300 ][INFO    ][4746] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-04-24 02:09:54,957 [salt.state       :1951][INFO    ][4746] Completed state [maas.process_machines] at time 02:09:54.957065 duration_in_ms=6823.67
2019-04-24 02:09:54,960 [salt.minion      :1711][INFO    ][4746] Returning information for job: 20190424020937904836
2019-04-24 02:10:26,298 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command state.apply with jid 20190424021026288042
2019-04-24 02:10:26,313 [salt.minion      :1432][INFO    ][5152] Starting a new job with PID 5152
2019-04-24 02:10:34,394 [salt.state       :915 ][INFO    ][5152] Loading fresh modules for state activity
2019-04-24 02:10:34,454 [salt.fileclient  :1219][INFO    ][5152] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-04-24 02:10:34,507 [salt.state       :1780][INFO    ][5152] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:10:34.507497
2019-04-24 02:10:34,507 [salt.state       :1813][INFO    ][5152] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-24 02:10:34,509 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5152] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-24 02:10:36,335 [salt.state       :300 ][INFO    ][5152] {'pid': 5161, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-24 02:10:36,336 [salt.state       :1951][INFO    ][5152] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:10:36.336174 duration_in_ms=1828.677
2019-04-24 02:10:36,340 [salt.state       :1780][INFO    ][5152] Running state [maas.wait_for_machine_status] at time 02:10:36.340515
2019-04-24 02:10:36,340 [salt.state       :1813][INFO    ][5152] Executing state module.run for [maas.wait_for_machine_status]
2019-04-24 02:10:36,341 [salt.utils.decorators:613 ][WARNING ][5152] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 02:10:37,133 [salt.loaded.ext.module.maas:1023][INFO    ][5152] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.21892905s left)
2019-04-24 02:10:41,377 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424021041359491
2019-04-24 02:10:41,398 [salt.minion      :1432][INFO    ][5173] Starting a new job with PID 5173
2019-04-24 02:10:41,426 [salt.minion      :1711][INFO    ][5173] Returning information for job: 20190424021041359491
2019-04-24 02:11:07,951 [salt.loaded.ext.module.maas:1023][INFO    ][5152] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.40098906s left)
2019-04-24 02:11:11,445 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424021111434102
2019-04-24 02:11:11,459 [salt.minion      :1432][INFO    ][5251] Starting a new job with PID 5251
2019-04-24 02:11:11,484 [salt.minion      :1711][INFO    ][5251] Returning information for job: 20190424021111434102
2019-04-24 02:11:38,724 [salt.loaded.ext.module.maas:1023][INFO    ][5152] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.62788105s left)
2019-04-24 02:11:41,514 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424021141500774
2019-04-24 02:11:41,530 [salt.minion      :1432][INFO    ][5271] Starting a new job with PID 5271
2019-04-24 02:11:41,561 [salt.minion      :1711][INFO    ][5271] Returning information for job: 20190424021141500774
2019-04-24 02:12:09,655 [salt.loaded.ext.module.maas:1023][INFO    ][5152] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.69672012s left)
2019-04-24 02:12:11,585 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424021211572025
2019-04-24 02:12:11,602 [salt.minion      :1432][INFO    ][5352] Starting a new job with PID 5352
2019-04-24 02:12:11,628 [salt.minion      :1711][INFO    ][5352] Returning information for job: 20190424021211572025
2019-04-24 02:12:40,686 [salt.loaded.ext.module.maas:1023][INFO    ][5152] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.66633701s left)
2019-04-24 02:12:41,626 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424021241614776
2019-04-24 02:12:41,648 [salt.minion      :1432][INFO    ][5399] Starting a new job with PID 5399
2019-04-24 02:12:41,675 [salt.minion      :1711][INFO    ][5399] Returning information for job: 20190424021241614776
2019-04-24 02:13:11,720 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424021311709335
2019-04-24 02:13:11,736 [salt.minion      :1432][INFO    ][5613] Starting a new job with PID 5613
2019-04-24 02:13:11,762 [salt.minion      :1711][INFO    ][5613] Returning information for job: 20190424021311709335
2019-04-24 02:13:11,781 [salt.loaded.ext.module.maas:1023][INFO    ][5152] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.57078505s left)
2019-04-24 02:13:41,802 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424021341786744
2019-04-24 02:13:41,821 [salt.minion      :1432][INFO    ][5709] Starting a new job with PID 5709
2019-04-24 02:13:41,846 [salt.minion      :1711][INFO    ][5709] Returning information for job: 20190424021341786744
2019-04-24 02:13:43,039 [salt.loaded.ext.module.maas:1023][INFO    ][5152] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1313.31305003s left)
2019-04-24 02:14:11,840 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424021411829978
2019-04-24 02:14:11,853 [salt.minion      :1432][INFO    ][6024] Starting a new job with PID 6024
2019-04-24 02:14:11,880 [salt.minion      :1711][INFO    ][6024] Returning information for job: 20190424021411829978
2019-04-24 02:14:14,213 [salt.loaded.ext.module.maas:1023][INFO    ][5152] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1282.13880706s left)
2019-04-24 02:14:41,917 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424021441904202
2019-04-24 02:14:41,937 [salt.minion      :1432][INFO    ][6142] Starting a new job with PID 6142
2019-04-24 02:14:41,971 [salt.minion      :1711][INFO    ][6142] Returning information for job: 20190424021441904202
2019-04-24 02:14:46,297 [salt.loaded.ext.module.maas:1023][INFO    ][5152] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1250.05481815s left)
2019-04-24 02:15:12,108 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424021512094467
2019-04-24 02:15:12,129 [salt.minion      :1432][INFO    ][6748] Starting a new job with PID 6748
2019-04-24 02:15:12,158 [salt.minion      :1711][INFO    ][6748] Returning information for job: 20190424021512094467
2019-04-24 02:15:18,644 [salt.loaded.ext.module.maas:1023][INFO    ][5152] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1217.70827603s left)
2019-04-24 02:15:42,201 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424021542188058
2019-04-24 02:15:42,224 [salt.minion      :1432][INFO    ][6787] Starting a new job with PID 6787
2019-04-24 02:15:42,251 [salt.minion      :1711][INFO    ][6787] Returning information for job: 20190424021542188058
2019-04-24 02:15:50,833 [salt.loaded.ext.module.maas:1023][INFO    ][5152] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1185.5188942s left)
2019-04-24 02:16:12,326 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424021612313241
2019-04-24 02:16:12,347 [salt.minion      :1432][INFO    ][6968] Starting a new job with PID 6968
2019-04-24 02:16:12,374 [salt.minion      :1711][INFO    ][6968] Returning information for job: 20190424021612313241
2019-04-24 02:16:23,807 [salt.state       :300 ][INFO    ][5152] {'ret': True}
2019-04-24 02:16:23,807 [salt.state       :1951][INFO    ][5152] Completed state [maas.wait_for_machine_status] at time 02:16:23.807444 duration_in_ms=347466.927
2019-04-24 02:16:23,813 [salt.minion      :1711][INFO    ][5152] Returning information for job: 20190424021026288042
2019-04-24 02:16:24,522 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command state.apply with jid 20190424021624508336
2019-04-24 02:16:24,537 [salt.minion      :1432][INFO    ][6989] Starting a new job with PID 6989
2019-04-24 02:16:32,737 [salt.state       :915 ][INFO    ][6989] Loading fresh modules for state activity
2019-04-24 02:16:32,797 [salt.fileclient  :1219][INFO    ][6989] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-04-24 02:16:32,894 [salt.state       :1780][INFO    ][6989] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:16:32.894533
2019-04-24 02:16:32,895 [salt.state       :1813][INFO    ][6989] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-24 02:16:32,897 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6989] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-24 02:16:34,718 [salt.state       :300 ][INFO    ][6989] {'pid': 7018, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-24 02:16:34,720 [salt.state       :1951][INFO    ][6989] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:16:34.719985 duration_in_ms=1825.449
2019-04-24 02:16:34,723 [salt.state       :1780][INFO    ][6989] Running state [maas_machines_storage_cmp002_lvm] at time 02:16:34.723628
2019-04-24 02:16:34,724 [salt.state       :1813][INFO    ][6989] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-04-24 02:16:35,819 [salt.loaded.ext.module.maasng:610 ][INFO    ][6989] d4qsmq
2019-04-24 02:16:35,819 [salt.loaded.ext.module.maasng:626 ][INFO    ][6989] sda
2019-04-24 02:16:36,373 [salt.loaded.ext.module.maasng:361 ][INFO    ][6989] d4qsmq
2019-04-24 02:16:36,464 [salt.loaded.ext.module.maasng:367 ][INFO    ][6989] [{u'block_size': 4096, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'partitions': [{u'uuid': u'765424fd-8f93-4077-bd51-06976403e7e2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'd4qsmq', u'device_id': 1, u'filesystem': {u'uuid': u'1c41404b-c89f-42cd-bffa-8ee1ad27a905', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/d4qsmq/blockdevices/1/partition/1', u'type': u'partition', u'id': 1, u'size': 800101236736}], u'filesystem': None, u'id': 1, u'name': u'sda', u'system_id': u'd4qsmq', 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'resource_uri': u'/MAAS/api/2.0/nodes/d4qsmq/blockdevices/1/', u'type': u'physical', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}, {u'block_size': 4096, u'uuid': u'173acc1a-72dc-40c5-bfce-bfaeb22490b4', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 800097042432, u'partitions': [], u'filesystem': {u'uuid': u'a406a336-07c8-49a2-933a-c9050bf1901b', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id': 3, u'name': u'vgroot-lvroot', u'system_id': u'd4qsmq', 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'resource_uri': u'/MAAS/api/2.0/nodes/d4qsmq/blockdevices/3/', u'type': u'virtual', u'serial': None, u'size': 800097042432}]
2019-04-24 02:16:36,465 [salt.loaded.ext.module.maasng:632 ][INFO    ][6989] vgroot
2019-04-24 02:16:36,465 [salt.loaded.ext.module.maasng:635 ][INFO    ][6989] lvroot
2019-04-24 02:16:36,465 [salt.loaded.ext.module.maasng:639 ][INFO    ][6989] 107374182400
2019-04-24 02:16:37,061 [salt.loaded.ext.module.maasng:645 ][INFO    ][6989] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'testing_status_name': u'Passed', u'boot_interface': {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wngwbt', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wngwbt', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'id': 18, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wngwbt', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'system_id': u'd4qsmq', u'mac_address': u'9c:b6:54:8a:10:18', u'id': 4, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/d4qsmq/interfaces/4/'}, u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'fqdn': u'cmp002.maas', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'testing_status': 2, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 800109715456, u'block_size': 4096, u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'0c387772-d63f-4dc1-b2bd-15421097d907', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'd4qsmq', u'filesystem': {u'uuid': u'a857cb7b-a15a-4812-965e-416dd67db624', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/d4qsmq/blockdevices/1/partition/6'}], u'id': 1, u'system_id': u'd4qsmq', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/d4qsmq/blockdevices/1/', u'used_size': 800106479616, u'model': u'LOGICAL VOLUME', u'name': u'sda'}, u'zone': {u'description': u'', u'id': 1, u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'hostname': u'cmp002', u'storage': 800109.715456, u'owner_data': {}, u'system_id': u'd4qsmq', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'block_size': 4096, u'available_size': 0, u'uuid': u'a9a6a909-1c37-4644-bea0-0e329177f676', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'partitions': [], u'id': 11, u'system_id': u'd4qsmq', u'partition_table_type': None, u'filesystem': {u'uuid': u'a30cfda2-a8a3-48d6-abed-8d2b8d24ea13', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/d4qsmq/blockdevices/11/', u'used_size': 107374182400, u'model': None, u'name': u'vgroot-lvroot'}], u'blockdevice_set': [{u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'0c387772-d63f-4dc1-b2bd-15421097d907', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'd4qsmq', u'filesystem': {u'uuid': u'a857cb7b-a15a-4812-965e-416dd67db624', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/d4qsmq/blockdevices/1/partition/6'}], u'uuid': None, u'id': 1, u'system_id': u'd4qsmq', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/d4qsmq/blockdevices/1/', u'used_size': 800106479616, u'model': u'LOGICAL VOLUME', u'size': 800109715456}, {u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'partitions': [], u'uuid': u'a9a6a909-1c37-4644-bea0-0e329177f676', u'id': 11, u'system_id': u'd4qsmq', u'partition_table_type': None, u'filesystem': {u'uuid': u'a30cfda2-a8a3-48d6-abed-8d2b8d24ea13', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/d4qsmq/blockdevices/11/', u'used_size': 107374182400, u'model': None, u'size': 107374182400}], u'status': 4, u'bcaches': [], u'cpu_count': 40, u'raids': [], u'physicalblockdevice_set': [{u'size': 800109715456, u'block_size': 4096, u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'0c387772-d63f-4dc1-b2bd-15421097d907', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'd4qsmq', u'filesystem': {u'uuid': u'a857cb7b-a15a-4812-965e-416dd67db624', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/d4qsmq/blockdevices/1/partition/6'}], u'id': 1, u'system_id': u'd4qsmq', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/d4qsmq/blockdevices/1/', u'used_size': 800106479616, u'model': u'LOGICAL VOLUME', u'name': u'sda'}], u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'd4qsmq', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'node_type_name': u'Machine', u'interface_set': [{u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wngwbt', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wngwbt', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'id': 18, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wngwbt', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'system_id': u'd4qsmq', u'mac_address': u'9c:b6:54:8a:10:18', u'id': 4, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/d4qsmq/interfaces/4/'}, {u'vlan': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'parents': [], u'discovered': None, u'system_id': u'd4qsmq', u'mac_address': u'38:ea:a7:8f:07:50', u'id': 14, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/d4qsmq/interfaces/14/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wngwbt', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wngwbt', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 19, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wngwbt', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.40'}], u'system_id': u'd4qsmq', u'mac_address': u'9c:b6:54:8a:10:1c', u'id': 13, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/d4qsmq/interfaces/13/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'name': u'ens2f1', u'links': [{u'id': 20, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'parents': [], u'discovered': None, u'system_id': u'd4qsmq', u'mac_address': u'38:ea:a7:8f:12:49', u'id': 15, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/d4qsmq/interfaces/15/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'name': u'ens2f0', u'links': [{u'id': 21, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'parents': [], u'discovered': None, u'system_id': u'd4qsmq', u'mac_address': u'38:ea:a7:8f:12:48', u'id': 11, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/d4qsmq/interfaces/11/'}, {u'vlan': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'parents': [], u'discovered': None, u'system_id': u'd4qsmq', u'mac_address': u'38:ea:a7:8f:07:51', u'id': 12, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/d4qsmq/interfaces/12/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'node_type': 0, u'commissioning_status': 2, u'min_hwe_kernel': u'ga-16.04', u'commissioning_status_name': u'Passed', u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/d4qsmq/', u'distro_series': u'', u'memory_test_status': -1}
2019-04-24 02:16:37,064 [salt.state       :300 ][INFO    ][6989] {'new': {'storage_layout': 'lvm'}}
2019-04-24 02:16:37,064 [salt.state       :1951][INFO    ][6989] Completed state [maas_machines_storage_cmp002_lvm] at time 02:16:37.064806 duration_in_ms=2341.177
2019-04-24 02:16:37,065 [salt.state       :1780][INFO    ][6989] Running state [maas_machines_storage_cmp001_lvm] at time 02:16:37.065477
2019-04-24 02:16:37,066 [salt.state       :1813][INFO    ][6989] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-04-24 02:16:38,226 [salt.loaded.ext.module.maasng:610 ][INFO    ][6989] nwsg4a
2019-04-24 02:16:38,227 [salt.loaded.ext.module.maasng:626 ][INFO    ][6989] sda
2019-04-24 02:16:38,774 [salt.loaded.ext.module.maasng:361 ][INFO    ][6989] nwsg4a
2019-04-24 02:16:38,862 [salt.loaded.ext.module.maasng:367 ][INFO    ][6989] [{u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'492e806d-6768-4789-8e82-5e56d59fd12b', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'nwsg4a', u'filesystem': {u'uuid': u'75028dae-177c-45dd-9b7c-3b78974f475d', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/nwsg4a/blockdevices/2/partition/2'}], u'uuid': None, u'id': 2, u'system_id': u'nwsg4a', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/nwsg4a/blockdevices/2/', u'used_size': 800106479616, u'model': u'LOGICAL VOLUME', u'size': 800109715456}, {u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'partitions': [], u'uuid': u'd08aa991-adf6-48f9-bc07-ae76e5dfc52d', u'id': 4, u'system_id': u'nwsg4a', u'partition_table_type': None, u'filesystem': {u'uuid': u'7f706e32-859f-4aa9-84a6-049539f3cd57', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/nwsg4a/blockdevices/4/', u'used_size': 800097042432, u'model': None, u'size': 800097042432}]
2019-04-24 02:16:38,863 [salt.loaded.ext.module.maasng:632 ][INFO    ][6989] vgroot
2019-04-24 02:16:38,863 [salt.loaded.ext.module.maasng:635 ][INFO    ][6989] lvroot
2019-04-24 02:16:38,864 [salt.loaded.ext.module.maasng:639 ][INFO    ][6989] 107374182400
2019-04-24 02:16:39,404 [salt.loaded.ext.module.maasng:645 ][INFO    ][6989] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'swap_size': None, u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.39', u'192.168.11.41'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'fqdn': u'cmp001.maas', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 800109715456, u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'nwsg4a', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'2ab92ea0-9fc0-402e-8931-88b604aaccd2', u'resource_uri': u'/MAAS/api/2.0/nodes/nwsg4a/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'nwsg4a', u'filesystem': {u'label': None, u'uuid': u'c584ae9e-4ac0-4fa3-8918-1ffd3712d105', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'size': 800101236736}], u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/nwsg4a/blockdevices/2/'}, u'zone': {u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default', u'id': 1}, u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'nwsg4a', 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'disable_ipv4': False, u'blockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/nwsg4a/blockdevices/2/', u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'nwsg4a', u'block_size': 4096, u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'size': 800109715456, u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'2ab92ea0-9fc0-402e-8931-88b604aaccd2', u'resource_uri': u'/MAAS/api/2.0/nodes/nwsg4a/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'nwsg4a', u'filesystem': {u'label': None, u'uuid': u'c584ae9e-4ac0-4fa3-8918-1ffd3712d105', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'size': 800101236736}]}, {u'resource_uri': u'/MAAS/api/2.0/nodes/nwsg4a/blockdevices/12/', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'uuid': u'38789ffc-1218-4e83-948d-03db7e1e203c', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'nwsg4a', u'block_size': 4096, u'partition_table_type': None, u'filesystem': {u'label': u'root', u'uuid': u'd8fb965b-ece9-49d4-91bf-77d09a9cb125', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'size': 107374182400, u'used_size': 107374182400, u'id': 12, u'serial': None, u'partitions': []}], u'status': 4, u'storage_test_status': 2, u'cpu_count': 40, u'power_state': u'off', u'physicalblockdevice_set': [{u'size': 800109715456, u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'nwsg4a', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'2ab92ea0-9fc0-402e-8931-88b604aaccd2', u'resource_uri': u'/MAAS/api/2.0/nodes/nwsg4a/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'nwsg4a', u'filesystem': {u'label': None, u'uuid': u'c584ae9e-4ac0-4fa3-8918-1ffd3712d105', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'size': 800101236736}], u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/nwsg4a/blockdevices/2/'}], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'nwsg4a', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'wngwbt', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'id': 24, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'wngwbt', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'wngwbt', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'system_id': u'nwsg4a', u'mac_address': u'9c:b6:54:8a:95:a0', u'params': u'', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/nwsg4a/interfaces/5/'}, u'current_testing_result_id': 5, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'node_type': 0, u'virtualblockdevice_set': [{u'size': 107374182400, u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'uuid': u'38789ffc-1218-4e83-948d-03db7e1e203c', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'nwsg4a', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'uuid': u'd8fb965b-ece9-49d4-91bf-77d09a9cb125', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'partitions': [], u'used_size': 107374182400, u'id': 12, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/nwsg4a/blockdevices/12/'}], u'commissioning_status': 2, u'min_hwe_kernel': u'ga-16.04', u'commissioning_status_name': u'Passed', u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'wngwbt', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'id': 24, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'wngwbt', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'wngwbt', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'system_id': u'nwsg4a', u'mac_address': u'9c:b6:54:8a:95:a0', u'params': u'', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/nwsg4a/interfaces/5/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'vlan': None, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'system_id': u'nwsg4a', u'mac_address': u'38:ea:a7:8f:1f:d5', u'params': u'', u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/nwsg4a/interfaces/17/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'vlan': None, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'system_id': u'nwsg4a', u'mac_address': u'38:ea:a7:8f:1f:d4', u'params': u'', u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/nwsg4a/interfaces/19/'}, {u'name': u'ens2f0', u'links': [{u'id': 25, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'system_id': u'nwsg4a', u'mac_address': u'38:ea:a7:8f:52:cc', u'params': u'', u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/nwsg4a/interfaces/16/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'wngwbt', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 26, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'wngwbt', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'wngwbt', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.41'}], u'parents': [], u'system_id': u'nwsg4a', u'mac_address': u'9c:b6:54:8a:95:a4', u'params': u'', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/nwsg4a/interfaces/18/'}, {u'name': u'ens2f1', u'links': [{u'id': 27, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 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'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'system_id': u'nwsg4a', u'mac_address': u'38:ea:a7:8f:52:cd', u'params': u'', u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/nwsg4a/interfaces/20/'}], u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/nwsg4a/', u'distro_series': u'', u'node_type_name': u'Machine'}
2019-04-24 02:16:39,405 [salt.state       :300 ][INFO    ][6989] {'new': {'storage_layout': 'lvm'}}
2019-04-24 02:16:39,406 [salt.state       :1951][INFO    ][6989] Completed state [maas_machines_storage_cmp001_lvm] at time 02:16:39.405984 duration_in_ms=2340.506
2019-04-24 02:16:39,409 [salt.minion      :1711][INFO    ][6989] Returning information for job: 20190424021624508336
2019-04-24 02:16:40,077 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command state.apply with jid 20190424021640069024
2019-04-24 02:16:40,092 [salt.minion      :1432][INFO    ][7038] Starting a new job with PID 7038
2019-04-24 02:16:41,198 [salt.state       :915 ][INFO    ][7038] Loading fresh modules for state activity
2019-04-24 02:16:41,245 [salt.fileclient  :1219][INFO    ][7038] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-04-24 02:16:41,289 [salt.state       :1780][INFO    ][7038] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:16:41.289561
2019-04-24 02:16:41,289 [salt.state       :1813][INFO    ][7038] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-24 02:16:41,291 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7038] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-24 02:16:43,140 [salt.state       :300 ][INFO    ][7038] {'pid': 7045, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-24 02:16:43,141 [salt.state       :1951][INFO    ][7038] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:16:43.141465 duration_in_ms=1851.904
2019-04-24 02:16:43,144 [salt.state       :1780][INFO    ][7038] Running state [maas.deploy_machines] at time 02:16:43.144121
2019-04-24 02:16:43,144 [salt.state       :1813][INFO    ][7038] Executing state module.run for [maas.deploy_machines]
2019-04-24 02:16:43,145 [salt.utils.decorators:613 ][WARNING ][7038] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 02:16:43,642 [salt.loaded.ext.module.maas:684 ][INFO    ][7038] deploymachines hwe_kernel=ga-16.04 system_id=d4qsmq distro_series=xenial
2019-04-24 02:16:45,768 [salt.loaded.ext.module.maas:684 ][INFO    ][7038] deploymachines hwe_kernel=ga-16.04 system_id=nwsg4a distro_series=xenial
2019-04-24 02:16:48,145 [salt.loaded.ext.module.maas:684 ][INFO    ][7038] deploymachines hwe_kernel=ga-16.04 system_id=bcrsg3 distro_series=xenial
2019-04-24 02:16:50,402 [salt.loaded.ext.module.maas:684 ][INFO    ][7038] deploymachines hwe_kernel=ga-16.04 system_id=bgdhph distro_series=xenial
2019-04-24 02:16:52,787 [salt.loaded.ext.module.maas:684 ][INFO    ][7038] deploymachines hwe_kernel=ga-16.04 system_id=rxdn43 distro_series=xenial
2019-04-24 02:16:55,159 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424021655148349
2019-04-24 02:16:55,176 [salt.minion      :1432][INFO    ][7341] Starting a new job with PID 7341
2019-04-24 02:16:55,197 [salt.state       :300 ][INFO    ][7038] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-04-24 02:16:55,197 [salt.state       :1951][INFO    ][7038] Completed state [maas.deploy_machines] at time 02:16:55.197475 duration_in_ms=12053.353
2019-04-24 02:16:55,200 [salt.minion      :1711][INFO    ][7038] Returning information for job: 20190424021640069024
2019-04-24 02:16:55,219 [salt.minion      :1711][INFO    ][7341] Returning information for job: 20190424021655148349
2019-04-24 02:16:55,820 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command state.apply with jid 20190424021655802817
2019-04-24 02:16:55,836 [salt.minion      :1432][INFO    ][7348] Starting a new job with PID 7348
2019-04-24 02:17:04,188 [salt.state       :915 ][INFO    ][7348] Loading fresh modules for state activity
2019-04-24 02:17:04,244 [salt.fileclient  :1219][INFO    ][7348] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-04-24 02:17:04,293 [salt.state       :1780][INFO    ][7348] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:17:04.293017
2019-04-24 02:17:04,293 [salt.state       :1813][INFO    ][7348] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-24 02:17:04,295 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7348] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-24 02:17:06,125 [salt.state       :300 ][INFO    ][7348] {'pid': 7404, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-24 02:17:06,126 [salt.state       :1951][INFO    ][7348] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:17:06.126578 duration_in_ms=1833.559
2019-04-24 02:17:06,130 [salt.state       :1780][INFO    ][7348] Running state [maas.wait_for_machine_status] at time 02:17:06.130723
2019-04-24 02:17:06,131 [salt.state       :1813][INFO    ][7348] Executing state module.run for [maas.wait_for_machine_status]
2019-04-24 02:17:06,131 [salt.utils.decorators:613 ][WARNING ][7348] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 02:17:09,014 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.12970114s left)
2019-04-24 02:17:10,943 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424021710926765
2019-04-24 02:17:10,964 [salt.minion      :1432][INFO    ][7415] Starting a new job with PID 7415
2019-04-24 02:17:10,992 [salt.minion      :1711][INFO    ][7415] Returning information for job: 20190424021710926765
2019-04-24 02:17:41,004 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424021740996002
2019-04-24 02:17:41,021 [salt.minion      :1432][INFO    ][7444] Starting a new job with PID 7444
2019-04-24 02:17:41,046 [salt.minion      :1711][INFO    ][7444] Returning information for job: 20190424021740996002
2019-04-24 02:17:41,785 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.35868406s left)
2019-04-24 02:18:11,087 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424021811071266
2019-04-24 02:18:11,107 [salt.minion      :1432][INFO    ][7524] Starting a new job with PID 7524
2019-04-24 02:18:11,134 [salt.minion      :1711][INFO    ][7524] Returning information for job: 20190424021811071266
2019-04-24 02:18:14,653 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.4905262s left)
2019-04-24 02:18:41,149 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424021841136433
2019-04-24 02:18:41,170 [salt.minion      :1432][INFO    ][7545] Starting a new job with PID 7545
2019-04-24 02:18:41,200 [salt.minion      :1711][INFO    ][7545] Returning information for job: 20190424021841136433
2019-04-24 02:18:47,541 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2148.60311317s left)
2019-04-24 02:19:11,221 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424021911207308
2019-04-24 02:19:11,242 [salt.minion      :1432][INFO    ][7644] Starting a new job with PID 7644
2019-04-24 02:19:11,268 [salt.minion      :1711][INFO    ][7644] Returning information for job: 20190424021911207308
2019-04-24 02:19:20,137 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2116.00717115s left)
2019-04-24 02:19:41,291 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424021941274439
2019-04-24 02:19:41,304 [salt.minion      :1432][INFO    ][7715] Starting a new job with PID 7715
2019-04-24 02:19:41,340 [salt.minion      :1711][INFO    ][7715] Returning information for job: 20190424021941274439
2019-04-24 02:19:52,916 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2083.227808s left)
2019-04-24 02:20:11,370 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424022011354243
2019-04-24 02:20:11,394 [salt.minion      :1432][INFO    ][7921] Starting a new job with PID 7921
2019-04-24 02:20:11,422 [salt.minion      :1711][INFO    ][7921] Returning information for job: 20190424022011354243
2019-04-24 02:20:25,845 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2050.29883218s left)
2019-04-24 02:20:41,455 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424022041445508
2019-04-24 02:20:41,471 [salt.minion      :1432][INFO    ][7948] Starting a new job with PID 7948
2019-04-24 02:20:41,501 [salt.minion      :1711][INFO    ][7948] Returning information for job: 20190424022041445508
2019-04-24 02:20:59,874 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2016.26984811s left)
2019-04-24 02:21:11,577 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424022111569822
2019-04-24 02:21:11,592 [salt.minion      :1432][INFO    ][8306] Starting a new job with PID 8306
2019-04-24 02:21:11,620 [salt.minion      :1711][INFO    ][8306] Returning information for job: 20190424022111569822
2019-04-24 02:21:32,525 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1983.6184442s left)
2019-04-24 02:21:41,649 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424022141637961
2019-04-24 02:21:41,673 [salt.minion      :1432][INFO    ][8385] Starting a new job with PID 8385
2019-04-24 02:21:41,702 [salt.minion      :1711][INFO    ][8385] Returning information for job: 20190424022141637961
2019-04-24 02:22:05,565 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1950.57827401s left)
2019-04-24 02:22:11,747 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424022211740319
2019-04-24 02:22:11,761 [salt.minion      :1432][INFO    ][8676] Starting a new job with PID 8676
2019-04-24 02:22:11,791 [salt.minion      :1711][INFO    ][8676] Returning information for job: 20190424022211740319
2019-04-24 02:22:38,381 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1917.76243901s left)
2019-04-24 02:22:41,841 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424022241833421
2019-04-24 02:22:41,861 [salt.minion      :1432][INFO    ][8721] Starting a new job with PID 8721
2019-04-24 02:22:41,889 [salt.minion      :1711][INFO    ][8721] Returning information for job: 20190424022241833421
2019-04-24 02:23:11,592 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1884.5519731s left)
2019-04-24 02:23:12,005 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424022311991683
2019-04-24 02:23:12,025 [salt.minion      :1432][INFO    ][9169] Starting a new job with PID 9169
2019-04-24 02:23:12,061 [salt.minion      :1711][INFO    ][9169] Returning information for job: 20190424022311991683
2019-04-24 02:23:42,113 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424022342102810
2019-04-24 02:23:42,128 [salt.minion      :1432][INFO    ][9193] Starting a new job with PID 9193
2019-04-24 02:23:42,156 [salt.minion      :1711][INFO    ][9193] Returning information for job: 20190424022342102810
2019-04-24 02:23:44,393 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1851.75086617s left)
2019-04-24 02:24:12,258 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424022412244230
2019-04-24 02:24:12,282 [salt.minion      :1432][INFO    ][9463] Starting a new job with PID 9463
2019-04-24 02:24:12,312 [salt.minion      :1711][INFO    ][9463] Returning information for job: 20190424022412244230
2019-04-24 02:24:17,195 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1818.9489851s left)
2019-04-24 02:24:42,382 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424022442368659
2019-04-24 02:24:42,406 [salt.minion      :1432][INFO    ][9484] Starting a new job with PID 9484
2019-04-24 02:24:42,439 [salt.minion      :1711][INFO    ][9484] Returning information for job: 20190424022442368659
2019-04-24 02:24:50,203 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1785.94037223s left)
2019-04-24 02:25:12,576 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424022512560593
2019-04-24 02:25:12,595 [salt.minion      :1432][INFO    ][9795] Starting a new job with PID 9795
2019-04-24 02:25:12,624 [salt.minion      :1711][INFO    ][9795] Returning information for job: 20190424022512560593
2019-04-24 02:25:23,100 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1753.043571s left)
2019-04-24 02:25:42,698 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424022542688883
2019-04-24 02:25:42,718 [salt.minion      :1432][INFO    ][9828] Starting a new job with PID 9828
2019-04-24 02:25:42,754 [salt.minion      :1711][INFO    ][9828] Returning information for job: 20190424022542688883
2019-04-24 02:25:55,832 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1720.31207108s left)
2019-04-24 02:26:12,889 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424022612875230
2019-04-24 02:26:12,911 [salt.minion      :1432][INFO    ][10017] Starting a new job with PID 10017
2019-04-24 02:26:12,938 [salt.minion      :1711][INFO    ][10017] Returning information for job: 20190424022612875230
2019-04-24 02:26:28,549 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1687.59497118s left)
2019-04-24 02:26:43,018 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424022643003307
2019-04-24 02:26:43,040 [salt.minion      :1432][INFO    ][10038] Starting a new job with PID 10038
2019-04-24 02:26:43,074 [salt.minion      :1711][INFO    ][10038] Returning information for job: 20190424022643003307
2019-04-24 02:27:01,273 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1654.87099814s left)
2019-04-24 02:27:13,209 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424022713195712
2019-04-24 02:27:13,232 [salt.minion      :1432][INFO    ][10200] Starting a new job with PID 10200
2019-04-24 02:27:13,261 [salt.minion      :1711][INFO    ][10200] Returning information for job: 20190424022713195712
2019-04-24 02:27:34,193 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1621.9508512s left)
2019-04-24 02:27:43,233 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424022743219226
2019-04-24 02:27:43,255 [salt.minion      :1432][INFO    ][10264] Starting a new job with PID 10264
2019-04-24 02:27:43,288 [salt.minion      :1711][INFO    ][10264] Returning information for job: 20190424022743219226
2019-04-24 02:28:07,001 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1589.14282012s left)
2019-04-24 02:28:13,368 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424022813355895
2019-04-24 02:28:13,388 [salt.minion      :1432][INFO    ][10616] Starting a new job with PID 10616
2019-04-24 02:28:13,417 [salt.minion      :1711][INFO    ][10616] Returning information for job: 20190424022813355895
2019-04-24 02:28:39,862 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1556.28189707s left)
2019-04-24 02:28:43,528 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424022843520225
2019-04-24 02:28:43,542 [salt.minion      :1432][INFO    ][10637] Starting a new job with PID 10637
2019-04-24 02:28:43,568 [salt.minion      :1711][INFO    ][10637] Returning information for job: 20190424022843520225
2019-04-24 02:29:12,618 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1523.52614903s left)
2019-04-24 02:29:13,713 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424022913702788
2019-04-24 02:29:13,735 [salt.minion      :1432][INFO    ][10940] Starting a new job with PID 10940
2019-04-24 02:29:13,769 [salt.minion      :1711][INFO    ][10940] Returning information for job: 20190424022913702788
2019-04-24 02:29:43,912 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424022943904746
2019-04-24 02:29:43,928 [salt.minion      :1432][INFO    ][10961] Starting a new job with PID 10961
2019-04-24 02:29:43,958 [salt.minion      :1711][INFO    ][10961] Returning information for job: 20190424022943904746
2019-04-24 02:29:45,398 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1490.74583602s left)
2019-04-24 02:30:14,137 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424023014121819
2019-04-24 02:30:14,155 [salt.minion      :1432][INFO    ][11078] Starting a new job with PID 11078
2019-04-24 02:30:14,184 [salt.minion      :1711][INFO    ][11078] Returning information for job: 20190424023014121819
2019-04-24 02:30:18,285 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1457.85846305s left)
2019-04-24 02:30:44,227 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424023044212946
2019-04-24 02:30:44,253 [salt.minion      :1432][INFO    ][11113] Starting a new job with PID 11113
2019-04-24 02:30:44,290 [salt.minion      :1711][INFO    ][11113] Returning information for job: 20190424023044212946
2019-04-24 02:30:51,169 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1424.97472501s left)
2019-04-24 02:31:14,340 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424023114328673
2019-04-24 02:31:14,359 [salt.minion      :1432][INFO    ][11312] Starting a new job with PID 11312
2019-04-24 02:31:14,385 [salt.minion      :1711][INFO    ][11312] Returning information for job: 20190424023114328673
2019-04-24 02:31:24,097 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1392.04641104s left)
2019-04-24 02:31:44,419 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424023144410773
2019-04-24 02:31:44,437 [salt.minion      :1432][INFO    ][11365] Starting a new job with PID 11365
2019-04-24 02:31:44,471 [salt.minion      :1711][INFO    ][11365] Returning information for job: 20190424023144410773
2019-04-24 02:31:56,830 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1359.31328607s left)
2019-04-24 02:32:14,613 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424023214600794
2019-04-24 02:32:14,635 [salt.minion      :1432][INFO    ][11559] Starting a new job with PID 11559
2019-04-24 02:32:14,662 [salt.minion      :1711][INFO    ][11559] Returning information for job: 20190424023214600794
2019-04-24 02:32:29,698 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1326.44572306s left)
2019-04-24 02:32:44,666 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424023244653683
2019-04-24 02:32:44,687 [salt.minion      :1432][INFO    ][11581] Starting a new job with PID 11581
2019-04-24 02:32:44,716 [salt.minion      :1711][INFO    ][11581] Returning information for job: 20190424023244653683
2019-04-24 02:33:02,389 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1293.75495005s left)
2019-04-24 02:33:14,700 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424023314692205
2019-04-24 02:33:14,720 [salt.minion      :1432][INFO    ][11722] Starting a new job with PID 11722
2019-04-24 02:33:14,748 [salt.minion      :1711][INFO    ][11722] Returning information for job: 20190424023314692205
2019-04-24 02:33:35,002 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1261.14219999s left)
2019-04-24 02:33:44,781 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424023344767163
2019-04-24 02:33:44,804 [salt.minion      :1432][INFO    ][11743] Starting a new job with PID 11743
2019-04-24 02:33:44,834 [salt.minion      :1711][INFO    ][11743] Returning information for job: 20190424023344767163
2019-04-24 02:34:07,718 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1228.42546105s left)
2019-04-24 02:34:14,832 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424023414820454
2019-04-24 02:34:14,849 [salt.minion      :1432][INFO    ][11827] Starting a new job with PID 11827
2019-04-24 02:34:14,877 [salt.minion      :1711][INFO    ][11827] Returning information for job: 20190424023414820454
2019-04-24 02:34:40,337 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1195.80688405s left)
2019-04-24 02:34:44,929 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424023444917003
2019-04-24 02:34:44,951 [salt.minion      :1432][INFO    ][11848] Starting a new job with PID 11848
2019-04-24 02:34:44,980 [salt.minion      :1711][INFO    ][11848] Returning information for job: 20190424023444917003
2019-04-24 02:35:13,375 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1162.7686522s left)
2019-04-24 02:35:15,030 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424023515015657
2019-04-24 02:35:15,047 [salt.minion      :1432][INFO    ][11929] Starting a new job with PID 11929
2019-04-24 02:35:15,081 [salt.minion      :1711][INFO    ][11929] Returning information for job: 20190424023515015657
2019-04-24 02:35:45,129 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424023545119708
2019-04-24 02:35:45,145 [salt.minion      :1432][INFO    ][11949] Starting a new job with PID 11949
2019-04-24 02:35:45,177 [salt.minion      :1711][INFO    ][11949] Returning information for job: 20190424023545119708
2019-04-24 02:35:46,172 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1129.97131705s left)
2019-04-24 02:36:15,271 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424023615259102
2019-04-24 02:36:15,296 [salt.minion      :1432][INFO    ][12028] Starting a new job with PID 12028
2019-04-24 02:36:15,325 [salt.minion      :1711][INFO    ][12028] Returning information for job: 20190424023615259102
2019-04-24 02:36:18,875 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1097.268677s left)
2019-04-24 02:36:45,389 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424023645372376
2019-04-24 02:36:45,406 [salt.minion      :1432][INFO    ][12060] Starting a new job with PID 12060
2019-04-24 02:36:45,437 [salt.minion      :1711][INFO    ][12060] Returning information for job: 20190424023645372376
2019-04-24 02:36:51,561 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1064.58287311s left)
2019-04-24 02:37:15,540 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424023715531345
2019-04-24 02:37:15,561 [salt.minion      :1432][INFO    ][12141] Starting a new job with PID 12141
2019-04-24 02:37:15,594 [salt.minion      :1711][INFO    ][12141] Returning information for job: 20190424023715531345
2019-04-24 02:37:24,345 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1031.79828501s left)
2019-04-24 02:37:45,671 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424023745656425
2019-04-24 02:37:45,691 [salt.minion      :1432][INFO    ][12162] Starting a new job with PID 12162
2019-04-24 02:37:45,721 [salt.minion      :1711][INFO    ][12162] Returning information for job: 20190424023745656425
2019-04-24 02:37:56,970 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (999.173400164s left)
2019-04-24 02:38:15,861 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424023815846206
2019-04-24 02:38:15,884 [salt.minion      :1432][INFO    ][12245] Starting a new job with PID 12245
2019-04-24 02:38:15,921 [salt.minion      :1711][INFO    ][12245] Returning information for job: 20190424023815846206
2019-04-24 02:38:29,608 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (966.535477161s left)
2019-04-24 02:38:46,041 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424023846025964
2019-04-24 02:38:46,062 [salt.minion      :1432][INFO    ][12266] Starting a new job with PID 12266
2019-04-24 02:38:46,093 [salt.minion      :1711][INFO    ][12266] Returning information for job: 20190424023846025964
2019-04-24 02:39:02,452 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (933.693332195s left)
2019-04-24 02:39:16,250 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424023916241953
2019-04-24 02:39:16,280 [salt.minion      :1432][INFO    ][12369] Starting a new job with PID 12369
2019-04-24 02:39:16,309 [salt.minion      :1711][INFO    ][12369] Returning information for job: 20190424023916241953
2019-04-24 02:39:35,251 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (900.893051147s left)
2019-04-24 02:39:46,436 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424023946422534
2019-04-24 02:39:46,459 [salt.minion      :1432][INFO    ][12392] Starting a new job with PID 12392
2019-04-24 02:39:46,488 [salt.minion      :1711][INFO    ][12392] Returning information for job: 20190424023946422534
2019-04-24 02:40:07,900 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (868.24379611s left)
2019-04-24 02:40:16,587 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424024016575646
2019-04-24 02:40:16,606 [salt.minion      :1432][INFO    ][12475] Starting a new job with PID 12475
2019-04-24 02:40:16,637 [salt.minion      :1711][INFO    ][12475] Returning information for job: 20190424024016575646
2019-04-24 02:40:40,493 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (835.650523186s left)
2019-04-24 02:40:46,750 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424024046743427
2019-04-24 02:40:46,764 [salt.minion      :1432][INFO    ][12500] Starting a new job with PID 12500
2019-04-24 02:40:46,794 [salt.minion      :1711][INFO    ][12500] Returning information for job: 20190424024046743427
2019-04-24 02:41:13,230 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (802.913868189s left)
2019-04-24 02:41:16,810 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424024116797429
2019-04-24 02:41:16,826 [salt.minion      :1432][INFO    ][12582] Starting a new job with PID 12582
2019-04-24 02:41:16,858 [salt.minion      :1711][INFO    ][12582] Returning information for job: 20190424024116797429
2019-04-24 02:41:45,954 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (770.189332008s left)
2019-04-24 02:41:46,896 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424024146882755
2019-04-24 02:41:46,919 [salt.minion      :1432][INFO    ][12603] Starting a new job with PID 12603
2019-04-24 02:41:46,953 [salt.minion      :1711][INFO    ][12603] Returning information for job: 20190424024146882755
2019-04-24 02:42:16,977 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424024216968971
2019-04-24 02:42:16,998 [salt.minion      :1432][INFO    ][12684] Starting a new job with PID 12684
2019-04-24 02:42:17,028 [salt.minion      :1711][INFO    ][12684] Returning information for job: 20190424024216968971
2019-04-24 02:42:18,617 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (737.526265144s left)
2019-04-24 02:42:47,117 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424024247104427
2019-04-24 02:42:47,138 [salt.minion      :1432][INFO    ][12703] Starting a new job with PID 12703
2019-04-24 02:42:47,172 [salt.minion      :1711][INFO    ][12703] Returning information for job: 20190424024247104427
2019-04-24 02:42:51,318 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (704.82588315s left)
2019-04-24 02:43:17,209 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424024317199578
2019-04-24 02:43:17,221 [salt.minion      :1432][INFO    ][12785] Starting a new job with PID 12785
2019-04-24 02:43:17,252 [salt.minion      :1711][INFO    ][12785] Returning information for job: 20190424024317199578
2019-04-24 02:43:24,067 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (672.076222181s left)
2019-04-24 02:43:47,375 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424024347360676
2019-04-24 02:43:47,398 [salt.minion      :1432][INFO    ][12805] Starting a new job with PID 12805
2019-04-24 02:43:47,430 [salt.minion      :1711][INFO    ][12805] Returning information for job: 20190424024347360676
2019-04-24 02:43:56,695 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (639.448954105s left)
2019-04-24 02:44:17,513 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424024417504403
2019-04-24 02:44:17,533 [salt.minion      :1432][INFO    ][12887] Starting a new job with PID 12887
2019-04-24 02:44:17,563 [salt.minion      :1711][INFO    ][12887] Returning information for job: 20190424024417504403
2019-04-24 02:44:29,458 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (606.68583703s left)
2019-04-24 02:44:47,704 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424024447695861
2019-04-24 02:44:47,724 [salt.minion      :1432][INFO    ][12911] Starting a new job with PID 12911
2019-04-24 02:44:47,753 [salt.minion      :1711][INFO    ][12911] Returning information for job: 20190424024447695861
2019-04-24 02:45:02,197 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (573.947046041s left)
2019-04-24 02:45:17,852 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424024517843550
2019-04-24 02:45:17,875 [salt.minion      :1432][INFO    ][12998] Starting a new job with PID 12998
2019-04-24 02:45:17,906 [salt.minion      :1711][INFO    ][12998] Returning information for job: 20190424024517843550
2019-04-24 02:45:34,958 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (541.185909033s left)
2019-04-24 02:45:47,900 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424024547886023
2019-04-24 02:45:47,921 [salt.minion      :1432][INFO    ][13020] Starting a new job with PID 13020
2019-04-24 02:45:47,952 [salt.minion      :1711][INFO    ][13020] Returning information for job: 20190424024547886023
2019-04-24 02:46:07,694 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (508.449550152s left)
2019-04-24 02:46:18,104 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424024618091303
2019-04-24 02:46:18,127 [salt.minion      :1432][INFO    ][13101] Starting a new job with PID 13101
2019-04-24 02:46:18,156 [salt.minion      :1711][INFO    ][13101] Returning information for job: 20190424024618091303
2019-04-24 02:46:40,351 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (475.792695045s left)
2019-04-24 02:46:48,164 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424024648153729
2019-04-24 02:46:48,189 [salt.minion      :1432][INFO    ][13133] Starting a new job with PID 13133
2019-04-24 02:46:48,230 [salt.minion      :1711][INFO    ][13133] Returning information for job: 20190424024648153729
2019-04-24 02:47:13,017 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (443.126726151s left)
2019-04-24 02:47:18,237 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424024718228147
2019-04-24 02:47:18,252 [salt.minion      :1432][INFO    ][13209] Starting a new job with PID 13209
2019-04-24 02:47:18,284 [salt.minion      :1711][INFO    ][13209] Returning information for job: 20190424024718228147
2019-04-24 02:47:45,781 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (410.362985134s left)
2019-04-24 02:47:48,284 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424024748272693
2019-04-24 02:47:48,301 [salt.minion      :1432][INFO    ][13242] Starting a new job with PID 13242
2019-04-24 02:47:48,337 [salt.minion      :1711][INFO    ][13242] Returning information for job: 20190424024748272693
2019-04-24 02:48:18,404 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424024818392862
2019-04-24 02:48:18,419 [salt.minion      :1432][INFO    ][13313] Starting a new job with PID 13313
2019-04-24 02:48:18,448 [salt.minion      :1711][INFO    ][13313] Returning information for job: 20190424024818392862
2019-04-24 02:48:18,534 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (377.609861135s left)
2019-04-24 02:48:48,475 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424024848464490
2019-04-24 02:48:48,490 [salt.minion      :1432][INFO    ][13354] Starting a new job with PID 13354
2019-04-24 02:48:48,526 [salt.minion      :1711][INFO    ][13354] Returning information for job: 20190424024848464490
2019-04-24 02:48:51,293 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (344.850974083s left)
2019-04-24 02:49:18,641 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424024918628588
2019-04-24 02:49:18,659 [salt.minion      :1432][INFO    ][13561] Starting a new job with PID 13561
2019-04-24 02:49:18,690 [salt.minion      :1711][INFO    ][13561] Returning information for job: 20190424024918628588
2019-04-24 02:49:24,035 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (312.108990192s left)
2019-04-24 02:49:48,673 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424024948666466
2019-04-24 02:49:48,690 [salt.minion      :1432][INFO    ][13598] Starting a new job with PID 13598
2019-04-24 02:49:48,720 [salt.minion      :1711][INFO    ][13598] Returning information for job: 20190424024948666466
2019-04-24 02:49:56,723 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (279.420791149s left)
2019-04-24 02:50:18,856 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424025018840880
2019-04-24 02:50:18,875 [salt.minion      :1432][INFO    ][13667] Starting a new job with PID 13667
2019-04-24 02:50:18,909 [salt.minion      :1711][INFO    ][13667] Returning information for job: 20190424025018840880
2019-04-24 02:50:29,396 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (246.747279167s left)
2019-04-24 02:50:48,992 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424025048983027
2019-04-24 02:50:49,005 [salt.minion      :1432][INFO    ][13702] Starting a new job with PID 13702
2019-04-24 02:50:49,035 [salt.minion      :1711][INFO    ][13702] Returning information for job: 20190424025048983027
2019-04-24 02:51:01,991 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (214.152286053s left)
2019-04-24 02:51:19,014 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424025119001154
2019-04-24 02:51:19,033 [salt.minion      :1432][INFO    ][13771] Starting a new job with PID 13771
2019-04-24 02:51:19,064 [salt.minion      :1711][INFO    ][13771] Returning information for job: 20190424025119001154
2019-04-24 02:51:34,726 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (181.41792202s left)
2019-04-24 02:51:49,202 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424025149195334
2019-04-24 02:51:49,220 [salt.minion      :1432][INFO    ][13812] Starting a new job with PID 13812
2019-04-24 02:51:49,249 [salt.minion      :1711][INFO    ][13812] Returning information for job: 20190424025149195334
2019-04-24 02:52:07,600 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (148.543334007s left)
2019-04-24 02:52:19,292 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424025219277966
2019-04-24 02:52:19,313 [salt.minion      :1432][INFO    ][13872] Starting a new job with PID 13872
2019-04-24 02:52:19,342 [salt.minion      :1711][INFO    ][13872] Returning information for job: 20190424025219277966
2019-04-24 02:52:40,289 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (115.855085135s left)
2019-04-24 02:52:49,518 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424025249505432
2019-04-24 02:52:49,542 [salt.minion      :1432][INFO    ][13913] Starting a new job with PID 13913
2019-04-24 02:52:49,571 [salt.minion      :1711][INFO    ][13913] Returning information for job: 20190424025249505432
2019-04-24 02:53:12,938 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (83.206084013s left)
2019-04-24 02:53:19,626 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424025319611839
2019-04-24 02:53:19,649 [salt.minion      :1432][INFO    ][13975] Starting a new job with PID 13975
2019-04-24 02:53:19,678 [salt.minion      :1711][INFO    ][13975] Returning information for job: 20190424025319611839
2019-04-24 02:53:45,602 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (50.5414299965s left)
2019-04-24 02:53:49,692 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424025349686074
2019-04-24 02:53:49,705 [salt.minion      :1432][INFO    ][14018] Starting a new job with PID 14018
2019-04-24 02:53:49,733 [salt.minion      :1711][INFO    ][14018] Returning information for job: 20190424025349686074
2019-04-24 02:54:18,254 [salt.loaded.ext.module.maas:1023][INFO    ][7348] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (17.8898611069s left)
2019-04-24 02:54:19,760 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424025419750552
2019-04-24 02:54:19,776 [salt.minion      :1432][INFO    ][14083] Starting a new job with PID 14083
2019-04-24 02:54:19,808 [salt.minion      :1711][INFO    ][14083] Returning information for job: 20190424025419750552
2019-04-24 02:54:49,949 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424025449939660
2019-04-24 02:54:49,964 [salt.minion      :1432][INFO    ][14125] Starting a new job with PID 14125
2019-04-24 02:54:49,998 [salt.minion      :1711][INFO    ][14125] Returning information for job: 20190424025449939660
2019-04-24 02:54:51,060 [salt.state       :302 ][ERROR   ][7348] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-04-24 02:54:51,061 [salt.state       :1951][INFO    ][7348] Completed state [maas.wait_for_machine_status] at time 02:54:51.061110 duration_in_ms=2264930.381
2019-04-24 02:54:51,067 [salt.minion      :1711][INFO    ][7348] Returning information for job: 20190424021655802817
2019-04-24 02:55:01,992 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command pillar.get with jid 20190424025501983846
2019-04-24 02:55:02,012 [salt.minion      :1432][INFO    ][14174] Starting a new job with PID 14174
2019-04-24 02:55:02,023 [salt.minion      :1711][INFO    ][14174] Returning information for job: 20190424025501983846
2019-04-24 02:55:02,709 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command service.status with jid 20190424025502700296
2019-04-24 02:55:02,733 [salt.minion      :1432][INFO    ][14179] Starting a new job with PID 14179
2019-04-24 02:55:03,486 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][14179] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-04-24 02:55:03,513 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][14179] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-04-24 02:55:03,529 [salt.minion      :1711][INFO    ][14179] Returning information for job: 20190424025502700296
2019-04-24 02:55:04,221 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command state.apply with jid 20190424025504207893
2019-04-24 02:55:04,244 [salt.minion      :1432][INFO    ][14191] Starting a new job with PID 14191
2019-04-24 02:55:12,382 [salt.state       :915 ][INFO    ][14191] Loading fresh modules for state activity
2019-04-24 02:55:12,944 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14191] Executing command 'salt-minion --version' in directory '/root'
2019-04-24 02:55:13,280 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14191] Executing command 'salt-minion --version' in directory '/root'
2019-04-24 02:55:14,306 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14191] Executing command 'salt-minion --version' in directory '/root'
2019-04-24 02:55:14,637 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14191] Executing command 'salt-minion --version' in directory '/root'
2019-04-24 02:55:16,753 [salt.state       :1780][INFO    ][14191] Running state [salt-minion] at time 02:55:16.753698
2019-04-24 02:55:16,754 [salt.state       :1813][INFO    ][14191] Executing state pkg.installed for [salt-minion]
2019-04-24 02:55:16,754 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14191] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-24 02:55:16,868 [salt.state       :300 ][INFO    ][14191] All specified packages are already installed
2019-04-24 02:55:16,868 [salt.state       :1951][INFO    ][14191] Completed state [salt-minion] at time 02:55:16.868481 duration_in_ms=114.784
2019-04-24 02:55:16,868 [salt.state       :1780][INFO    ][14191] Running state [salt_minion_dependency_packages] at time 02:55:16.868833
2019-04-24 02:55:16,869 [salt.state       :1813][INFO    ][14191] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-04-24 02:55:16,878 [salt.state       :300 ][INFO    ][14191] All specified packages are already installed
2019-04-24 02:55:16,879 [salt.state       :1951][INFO    ][14191] Completed state [salt_minion_dependency_packages] at time 02:55:16.879181 duration_in_ms=10.348
2019-04-24 02:55:16,882 [salt.state       :1780][INFO    ][14191] Running state [/etc/salt/minion.d/minion.conf] at time 02:55:16.882174
2019-04-24 02:55:16,882 [salt.state       :1813][INFO    ][14191] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-04-24 02:55:17,155 [salt.state       :300 ][INFO    ][14191] File /etc/salt/minion.d/minion.conf is in the correct state
2019-04-24 02:55:17,155 [salt.state       :1951][INFO    ][14191] Completed state [/etc/salt/minion.d/minion.conf] at time 02:55:17.155446 duration_in_ms=273.272
2019-04-24 02:55:17,155 [salt.state       :1780][INFO    ][14191] Running state [python-netaddr] at time 02:55:17.155742
2019-04-24 02:55:17,156 [salt.state       :1813][INFO    ][14191] Executing state pkg.installed for [python-netaddr]
2019-04-24 02:55:17,165 [salt.state       :300 ][INFO    ][14191] All specified packages are already installed
2019-04-24 02:55:17,165 [salt.state       :1951][INFO    ][14191] Completed state [python-netaddr] at time 02:55:17.165420 duration_in_ms=9.677
2019-04-24 02:55:17,169 [salt.state       :1780][INFO    ][14191] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:55:17.169197
2019-04-24 02:55:17,169 [salt.state       :1813][INFO    ][14191] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-04-24 02:55:17,180 [salt.state       :300 ][INFO    ][14191] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-04-24 02:55:17,181 [salt.state       :1951][INFO    ][14191] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:55:17.181235 duration_in_ms=12.037
2019-04-24 02:55:17,182 [salt.state       :1780][INFO    ][14191] Running state [salt-minion] at time 02:55:17.182448
2019-04-24 02:55:17,182 [salt.state       :1813][INFO    ][14191] Executing state service.running for [salt-minion]
2019-04-24 02:55:17,183 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14191] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-04-24 02:55:17,228 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14191] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-04-24 02:55:17,250 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14191] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-04-24 02:55:17,268 [salt.state       :300 ][INFO    ][14191] The service salt-minion is already running
2019-04-24 02:55:17,269 [salt.state       :1951][INFO    ][14191] Completed state [salt-minion] at time 02:55:17.268993 duration_in_ms=86.545
2019-04-24 02:55:17,273 [salt.state       :1780][INFO    ][14191] Running state [/etc/salt/grains.d] at time 02:55:17.273374
2019-04-24 02:55:17,273 [salt.state       :1813][INFO    ][14191] Executing state file.directory for [/etc/salt/grains.d]
2019-04-24 02:55:17,275 [salt.state       :300 ][INFO    ][14191] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-04-24 02:55:17,276 [salt.state       :1951][INFO    ][14191] Completed state [/etc/salt/grains.d] at time 02:55:17.275949 duration_in_ms=2.575
2019-04-24 02:55:17,277 [salt.state       :1780][INFO    ][14191] Running state [/etc/salt/grains] at time 02:55:17.277025
2019-04-24 02:55:17,277 [salt.state       :1813][INFO    ][14191] Executing state file.managed for [/etc/salt/grains]
2019-04-24 02:55:17,278 [salt.state       :300 ][INFO    ][14191] File /etc/salt/grains exists with proper permissions. No changes made.
2019-04-24 02:55:17,278 [salt.state       :1951][INFO    ][14191] Completed state [/etc/salt/grains] at time 02:55:17.278762 duration_in_ms=1.737
2019-04-24 02:55:17,279 [salt.state       :1780][INFO    ][14191] Running state [/etc/salt/grains.d/placeholder] at time 02:55:17.279595
2019-04-24 02:55:17,280 [salt.state       :1813][INFO    ][14191] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-04-24 02:55:17,280 [salt.state       :300 ][INFO    ][14191] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-04-24 02:55:17,281 [salt.state       :1951][INFO    ][14191] Completed state [/etc/salt/grains.d/placeholder] at time 02:55:17.281311 duration_in_ms=1.716
2019-04-24 02:55:17,282 [salt.state       :1780][INFO    ][14191] Running state [/etc/salt/grains.d/sphinx] at time 02:55:17.282111
2019-04-24 02:55:17,282 [salt.state       :1813][INFO    ][14191] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-04-24 02:55:17,286 [salt.state       :300 ][INFO    ][14191] File /etc/salt/grains.d/sphinx is in the correct state
2019-04-24 02:55:17,287 [salt.state       :1951][INFO    ][14191] Completed state [/etc/salt/grains.d/sphinx] at time 02:55:17.287316 duration_in_ms=5.205
2019-04-24 02:55:17,289 [salt.state       :1780][INFO    ][14191] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:55:17.289367
2019-04-24 02:55:17,289 [salt.state       :1813][INFO    ][14191] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-04-24 02:55:17,290 [salt.state       :300 ][INFO    ][14191] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-04-24 02:55:17,290 [salt.state       :1951][INFO    ][14191] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:55:17.290230 duration_in_ms=0.863
2019-04-24 02:55:17,290 [salt.state       :1780][INFO    ][14191] Running state [/etc/salt/grains.d/dns_records] at time 02:55:17.290745
2019-04-24 02:55:17,291 [salt.state       :1813][INFO    ][14191] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-04-24 02:55:17,292 [salt.state       :300 ][INFO    ][14191] File /etc/salt/grains.d/dns_records is in the correct state
2019-04-24 02:55:17,292 [salt.state       :1951][INFO    ][14191] Completed state [/etc/salt/grains.d/dns_records] at time 02:55:17.292603 duration_in_ms=1.858
2019-04-24 02:55:17,293 [salt.state       :1780][INFO    ][14191] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:55:17.293555
2019-04-24 02:55:17,293 [salt.state       :1813][INFO    ][14191] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-04-24 02:55:17,294 [salt.state       :300 ][INFO    ][14191] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-04-24 02:55:17,294 [salt.state       :1951][INFO    ][14191] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:55:17.294419 duration_in_ms=0.864
2019-04-24 02:55:17,297 [salt.state       :1780][INFO    ][14191] Running state [/etc/salt/grains.d/salt] at time 02:55:17.296874
2019-04-24 02:55:17,297 [salt.state       :1813][INFO    ][14191] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-04-24 02:55:17,298 [salt.state       :300 ][INFO    ][14191] File /etc/salt/grains.d/salt is in the correct state
2019-04-24 02:55:17,298 [salt.state       :1951][INFO    ][14191] Completed state [/etc/salt/grains.d/salt] at time 02:55:17.298745 duration_in_ms=1.871
2019-04-24 02:55:17,300 [salt.state       :1780][INFO    ][14191] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:55:17.299983
2019-04-24 02:55:17,300 [salt.state       :1813][INFO    ][14191] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-04-24 02:55:17,300 [salt.state       :300 ][INFO    ][14191] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-04-24 02:55:17,300 [salt.state       :1951][INFO    ][14191] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:55:17.300821 duration_in_ms=0.838
2019-04-24 02:55:17,302 [salt.state       :1780][INFO    ][14191] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:55:17.302757
2019-04-24 02:55:17,303 [salt.state       :1813][INFO    ][14191] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-04-24 02:55:17,303 [salt.state       :300 ][INFO    ][14191] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-04-24 02:55:17,303 [salt.state       :1951][INFO    ][14191] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:55:17.303626 duration_in_ms=0.869
2019-04-24 02:55:17,304 [salt.state       :1780][INFO    ][14191] Running state [mine.update] at time 02:55:17.304325
2019-04-24 02:55:17,304 [salt.state       :1813][INFO    ][14191] Executing state module.wait for [mine.update]
2019-04-24 02:55:17,304 [salt.state       :300 ][INFO    ][14191] No changes made for mine.update
2019-04-24 02:55:17,305 [salt.state       :1951][INFO    ][14191] Completed state [mine.update] at time 02:55:17.305121 duration_in_ms=0.796
2019-04-24 02:55:17,305 [salt.state       :1780][INFO    ][14191] Running state [ca-certificates] at time 02:55:17.305401
2019-04-24 02:55:17,305 [salt.state       :1813][INFO    ][14191] Executing state pkg.installed for [ca-certificates]
2019-04-24 02:55:17,316 [salt.state       :300 ][INFO    ][14191] All specified packages are already installed
2019-04-24 02:55:17,316 [salt.state       :1951][INFO    ][14191] Completed state [ca-certificates] at time 02:55:17.316673 duration_in_ms=11.272
2019-04-24 02:55:17,317 [salt.state       :1780][INFO    ][14191] Running state [update-ca-certificates] at time 02:55:17.317401
2019-04-24 02:55:17,317 [salt.state       :1813][INFO    ][14191] Executing state cmd.wait for [update-ca-certificates]
2019-04-24 02:55:17,318 [salt.state       :300 ][INFO    ][14191] No changes made for update-ca-certificates
2019-04-24 02:55:17,318 [salt.state       :1951][INFO    ][14191] Completed state [update-ca-certificates] at time 02:55:17.318216 duration_in_ms=0.815
2019-04-24 02:55:17,318 [salt.state       :1780][INFO    ][14191] Running state [iptables] at time 02:55:17.318499
2019-04-24 02:55:17,318 [salt.state       :1813][INFO    ][14191] Executing state pkg.installed for [iptables]
2019-04-24 02:55:17,329 [salt.state       :300 ][INFO    ][14191] All specified packages are already installed
2019-04-24 02:55:17,329 [salt.state       :1951][INFO    ][14191] Completed state [iptables] at time 02:55:17.329579 duration_in_ms=11.08
2019-04-24 02:55:17,329 [salt.state       :1780][INFO    ][14191] Running state [iptables-persistent] at time 02:55:17.329864
2019-04-24 02:55:17,330 [salt.state       :1813][INFO    ][14191] Executing state pkg.installed for [iptables-persistent]
2019-04-24 02:55:17,339 [salt.state       :300 ][INFO    ][14191] All specified packages are already installed
2019-04-24 02:55:17,340 [salt.state       :1951][INFO    ][14191] Completed state [iptables-persistent] at time 02:55:17.340047 duration_in_ms=10.183
2019-04-24 02:55:17,341 [salt.state       :1780][INFO    ][14191] Running state [iptables_modules_v4_load] at time 02:55:17.341084
2019-04-24 02:55:17,341 [salt.state       :1813][INFO    ][14191] Executing state kmod.present for [iptables_modules_v4_load]
2019-04-24 02:55:17,341 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14191] Executing command 'lsmod' in directory '/root'
2019-04-24 02:55:17,362 [salt.state       :300 ][INFO    ][14191] Kernel modules iptable_filter, ip_tables are already present
2019-04-24 02:55:17,362 [salt.state       :1951][INFO    ][14191] Completed state [iptables_modules_v4_load] at time 02:55:17.362422 duration_in_ms=21.337
2019-04-24 02:55:17,363 [salt.state       :1780][INFO    ][14191] Running state [/etc/iptables/rules.v4] at time 02:55:17.363283
2019-04-24 02:55:17,363 [salt.state       :1813][INFO    ][14191] Executing state file.managed for [/etc/iptables/rules.v4]
2019-04-24 02:55:17,465 [salt.state       :300 ][INFO    ][14191] File /etc/iptables/rules.v4 is in the correct state
2019-04-24 02:55:17,465 [salt.state       :1951][INFO    ][14191] Completed state [/etc/iptables/rules.v4] at time 02:55:17.465897 duration_in_ms=102.614
2019-04-24 02:55:17,467 [salt.state       :1780][INFO    ][14191] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:55:17.467672
2019-04-24 02:55:17,468 [salt.state       :1813][INFO    ][14191] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-04-24 02:55:17,468 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14191] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-04-24 02:55:17,484 [salt.state       :300 ][INFO    ][14191] onlyif execution failed
2019-04-24 02:55:17,485 [salt.state       :1951][INFO    ][14191] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:55:17.485261 duration_in_ms=17.588
2019-04-24 02:55:17,489 [salt.state       :1780][INFO    ][14191] Running state [netfilter-persistent] at time 02:55:17.489151
2019-04-24 02:55:17,489 [salt.state       :1813][INFO    ][14191] Executing state service.running for [netfilter-persistent]
2019-04-24 02:55:17,491 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14191] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-04-24 02:55:17,507 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14191] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-04-24 02:55:17,526 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14191] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-04-24 02:55:17,544 [salt.state       :300 ][INFO    ][14191] The service netfilter-persistent is already running
2019-04-24 02:55:17,545 [salt.state       :1951][INFO    ][14191] Completed state [netfilter-persistent] at time 02:55:17.545354 duration_in_ms=56.202
2019-04-24 02:55:17,546 [salt.state       :1780][INFO    ][14191] Running state [iptables_extra.remove_stale_tables] at time 02:55:17.546536
2019-04-24 02:55:17,549 [salt.state       :1813][INFO    ][14191] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-04-24 02:55:17,550 [salt.state       :300 ][INFO    ][14191] No changes made for iptables_extra.remove_stale_tables
2019-04-24 02:55:17,550 [salt.state       :1951][INFO    ][14191] Completed state [iptables_extra.remove_stale_tables] at time 02:55:17.550444 duration_in_ms=3.908
2019-04-24 02:55:17,551 [salt.state       :1780][INFO    ][14191] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:55:17.550860
2019-04-24 02:55:17,551 [salt.state       :1813][INFO    ][14191] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-04-24 02:55:17,552 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14191] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-04-24 02:55:17,566 [salt.state       :300 ][INFO    ][14191] onlyif execution failed
2019-04-24 02:55:17,566 [salt.state       :1951][INFO    ][14191] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:55:17.566840 duration_in_ms=15.981
2019-04-24 02:55:17,567 [salt.state       :1780][INFO    ][14191] Running state [/etc/iptables/rules.v6] at time 02:55:17.567925
2019-04-24 02:55:17,568 [salt.state       :1813][INFO    ][14191] Executing state file.absent for [/etc/iptables/rules.v6]
2019-04-24 02:55:17,568 [salt.state       :300 ][INFO    ][14191] File /etc/iptables/rules.v6 is not present
2019-04-24 02:55:17,569 [salt.state       :1951][INFO    ][14191] Completed state [/etc/iptables/rules.v6] at time 02:55:17.569058 duration_in_ms=1.133
2019-04-24 02:55:17,569 [salt.state       :1780][INFO    ][14191] Running state [iptables_extra.flush_all] at time 02:55:17.569851
2019-04-24 02:55:17,570 [salt.state       :1813][INFO    ][14191] Executing state module.wait for [iptables_extra.flush_all]
2019-04-24 02:55:17,570 [salt.state       :300 ][INFO    ][14191] No changes made for iptables_extra.flush_all
2019-04-24 02:55:17,573 [salt.state       :1951][INFO    ][14191] Completed state [iptables_extra.flush_all] at time 02:55:17.570794 duration_in_ms=0.943
2019-04-24 02:55:17,576 [salt.minion      :1711][INFO    ][14191] Returning information for job: 20190424025504207893
2019-04-24 02:55:18,295 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command state.apply with jid 20190424025518282110
2019-04-24 02:55:18,312 [salt.minion      :1432][INFO    ][14274] Starting a new job with PID 14274
2019-04-24 02:55:19,473 [salt.state       :915 ][INFO    ][14274] Loading fresh modules for state activity
2019-04-24 02:55:20,690 [salt.state       :1780][INFO    ][14274] Running state [maas-rack-controller] at time 02:55:20.690031
2019-04-24 02:55:20,690 [salt.state       :1813][INFO    ][14274] Executing state pkg.installed for [maas-rack-controller]
2019-04-24 02:55:20,691 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14274] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-24 02:55:20,801 [salt.state       :300 ][INFO    ][14274] All specified packages are already installed
2019-04-24 02:55:20,802 [salt.state       :1951][INFO    ][14274] Completed state [maas-rack-controller] at time 02:55:20.802194 duration_in_ms=112.164
2019-04-24 02:55:20,802 [salt.state       :1780][INFO    ][14274] Running state [ipmitool] at time 02:55:20.802570
2019-04-24 02:55:20,803 [salt.state       :1813][INFO    ][14274] Executing state pkg.installed for [ipmitool]
2019-04-24 02:55:20,812 [salt.state       :300 ][INFO    ][14274] All specified packages are already installed
2019-04-24 02:55:20,813 [salt.state       :1951][INFO    ][14274] Completed state [ipmitool] at time 02:55:20.812993 duration_in_ms=10.423
2019-04-24 02:55:20,816 [salt.state       :1780][INFO    ][14274] Running state [/etc/maas/rackd.conf] at time 02:55:20.816337
2019-04-24 02:55:20,816 [salt.state       :1813][INFO    ][14274] Executing state file.line for [/etc/maas/rackd.conf]
2019-04-24 02:55:20,817 [salt.state       :300 ][INFO    ][14274] No changes needed to be made
2019-04-24 02:55:20,817 [salt.state       :1951][INFO    ][14274] Completed state [/etc/maas/rackd.conf] at time 02:55:20.817849 duration_in_ms=1.512
2019-04-24 02:55:20,818 [salt.state       :1780][INFO    ][14274] Running state [/etc/maas/rackd.conf] at time 02:55:20.818136
2019-04-24 02:55:20,818 [salt.state       :1813][INFO    ][14274] Executing state file.managed for [/etc/maas/rackd.conf]
2019-04-24 02:55:20,818 [salt.loaded.int.states.file:2298][WARNING ][14274] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-04-24 02:55:20,819 [salt.state       :300 ][INFO    ][14274] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-04-24 02:55:20,819 [salt.state       :1951][INFO    ][14274] Completed state [/etc/maas/rackd.conf] at time 02:55:20.819797 duration_in_ms=1.661
2019-04-24 02:55:20,820 [salt.state       :1780][INFO    ][14274] Running state [maas-rackd] at time 02:55:20.820822
2019-04-24 02:55:20,821 [salt.state       :1813][INFO    ][14274] Executing state service.running for [maas-rackd]
2019-04-24 02:55:20,821 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14274] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-04-24 02:55:20,863 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14274] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-04-24 02:55:20,879 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14274] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-04-24 02:55:20,902 [salt.state       :300 ][INFO    ][14274] The service maas-rackd is already running
2019-04-24 02:55:20,902 [salt.state       :1951][INFO    ][14274] Completed state [maas-rackd] at time 02:55:20.902649 duration_in_ms=81.827
2019-04-24 02:55:20,905 [salt.minion      :1711][INFO    ][14274] Returning information for job: 20190424025518282110
2019-04-24 02:55:21,585 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command state.apply with jid 20190424025521575647
2019-04-24 02:55:21,605 [salt.minion      :1432][INFO    ][14297] Starting a new job with PID 14297
2019-04-24 02:55:22,697 [salt.state       :915 ][INFO    ][14297] Loading fresh modules for state activity
2019-04-24 02:55:24,030 [salt.state       :1780][INFO    ][14297] Running state [maas-region-controller] at time 02:55:24.030311
2019-04-24 02:55:24,030 [salt.state       :1813][INFO    ][14297] Executing state pkg.installed for [maas-region-controller]
2019-04-24 02:55:24,031 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14297] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-24 02:55:24,146 [salt.state       :300 ][INFO    ][14297] All specified packages are already installed
2019-04-24 02:55:24,146 [salt.state       :1951][INFO    ][14297] Completed state [maas-region-controller] at time 02:55:24.146640 duration_in_ms=116.329
2019-04-24 02:55:24,147 [salt.state       :1780][INFO    ][14297] Running state [python-oauth] at time 02:55:24.147578
2019-04-24 02:55:24,147 [salt.state       :1813][INFO    ][14297] Executing state pkg.installed for [python-oauth]
2019-04-24 02:55:24,156 [salt.state       :300 ][INFO    ][14297] All specified packages are already installed
2019-04-24 02:55:24,156 [salt.state       :1951][INFO    ][14297] Completed state [python-oauth] at time 02:55:24.156849 duration_in_ms=9.27
2019-04-24 02:55:24,160 [salt.state       :1780][INFO    ][14297] Running state [/etc/maas/regiond.conf] at time 02:55:24.160812
2019-04-24 02:55:24,161 [salt.state       :1813][INFO    ][14297] Executing state file.replace for [/etc/maas/regiond.conf]
2019-04-24 02:55:24,165 [salt.state       :300 ][INFO    ][14297] No changes needed to be made
2019-04-24 02:55:24,166 [salt.state       :1951][INFO    ][14297] Completed state [/etc/maas/regiond.conf] at time 02:55:24.166118 duration_in_ms=5.307
2019-04-24 02:55:24,166 [salt.state       :1780][INFO    ][14297] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:55:24.166634
2019-04-24 02:55:24,167 [salt.state       :1813][INFO    ][14297] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-04-24 02:55:24,226 [salt.state       :300 ][INFO    ][14297] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-04-24 02:55:24,227 [salt.state       :1951][INFO    ][14297] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:55:24.226822 duration_in_ms=60.187
2019-04-24 02:55:24,227 [salt.state       :1780][INFO    ][14297] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:55:24.227645
2019-04-24 02:55:24,227 [salt.state       :1813][INFO    ][14297] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-04-24 02:55:24,232 [salt.state       :300 ][INFO    ][14297] No changes needed to be made
2019-04-24 02:55:24,233 [salt.state       :1951][INFO    ][14297] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:55:24.233217 duration_in_ms=5.572
2019-04-24 02:55:24,233 [salt.state       :1780][INFO    ][14297] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:55:24.233737
2019-04-24 02:55:24,234 [salt.state       :1813][INFO    ][14297] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-04-24 02:55:24,238 [salt.state       :300 ][INFO    ][14297] No changes needed to be made
2019-04-24 02:55:24,238 [salt.state       :1951][INFO    ][14297] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:55:24.238379 duration_in_ms=4.642
2019-04-24 02:55:24,238 [salt.state       :1780][INFO    ][14297] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:55:24.238902
2019-04-24 02:55:24,239 [salt.state       :1813][INFO    ][14297] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-04-24 02:55:24,264 [salt.state       :300 ][INFO    ][14297] No changes needed to be made
2019-04-24 02:55:24,264 [salt.state       :1951][INFO    ][14297] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:55:24.264220 duration_in_ms=25.318
2019-04-24 02:55:24,264 [salt.state       :1780][INFO    ][14297] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:55:24.264739
2019-04-24 02:55:24,265 [salt.state       :1813][INFO    ][14297] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-04-24 02:55:24,281 [salt.state       :300 ][INFO    ][14297] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-04-24 02:55:24,282 [salt.state       :1951][INFO    ][14297] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:55:24.282002 duration_in_ms=17.262
2019-04-24 02:55:24,283 [salt.state       :1780][INFO    ][14297] Running state [a2enmod headers] at time 02:55:24.283525
2019-04-24 02:55:24,283 [salt.state       :1813][INFO    ][14297] Executing state cmd.run for [a2enmod headers]
2019-04-24 02:55:24,284 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14297] Executing command 'a2enmod headers' in directory '/root'
2019-04-24 02:55:24,353 [salt.state       :300 ][INFO    ][14297] {'pid': 14316, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-04-24 02:55:24,353 [salt.state       :1951][INFO    ][14297] Completed state [a2enmod headers] at time 02:55:24.353866 duration_in_ms=70.341
2019-04-24 02:55:24,354 [salt.state       :1780][INFO    ][14297] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:55:24.354457
2019-04-24 02:55:24,355 [salt.state       :1813][INFO    ][14297] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-04-24 02:55:24,378 [salt.state       :300 ][INFO    ][14297] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-04-24 02:55:24,378 [salt.state       :1951][INFO    ][14297] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:55:24.378393 duration_in_ms=23.936
2019-04-24 02:55:24,379 [salt.state       :1780][INFO    ][14297] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:55:24.379079
2019-04-24 02:55:24,379 [salt.state       :1813][INFO    ][14297] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-04-24 02:55:24,430 [salt.state       :300 ][INFO    ][14297] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-04-24 02:55:24,431 [salt.state       :1951][INFO    ][14297] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:55:24.431194 duration_in_ms=52.115
2019-04-24 02:55:24,431 [salt.state       :1780][INFO    ][14297] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:55:24.431782
2019-04-24 02:55:24,432 [salt.state       :1813][INFO    ][14297] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-04-24 02:55:24,489 [salt.state       :300 ][INFO    ][14297] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-04-24 02:55:24,490 [salt.state       :1951][INFO    ][14297] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:55:24.490140 duration_in_ms=58.358
2019-04-24 02:55:24,490 [salt.state       :1780][INFO    ][14297] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:55:24.490687
2019-04-24 02:55:24,491 [salt.state       :1813][INFO    ][14297] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-04-24 02:55:24,572 [salt.state       :300 ][INFO    ][14297] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-04-24 02:55:24,572 [salt.state       :1951][INFO    ][14297] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:55:24.572384 duration_in_ms=81.696
2019-04-24 02:55:24,572 [salt.state       :1780][INFO    ][14297] Running state [/root/.pgpass] at time 02:55:24.572692
2019-04-24 02:55:24,573 [salt.state       :1813][INFO    ][14297] Executing state file.managed for [/root/.pgpass]
2019-04-24 02:55:24,625 [salt.state       :300 ][INFO    ][14297] File /root/.pgpass is in the correct state
2019-04-24 02:55:24,625 [salt.state       :1951][INFO    ][14297] Completed state [/root/.pgpass] at time 02:55:24.625771 duration_in_ms=53.079
2019-04-24 02:55:24,632 [salt.state       :1780][INFO    ][14297] Running state [maas-region syncdb --noinput] at time 02:55:24.631944
2019-04-24 02:55:24,632 [salt.state       :1813][INFO    ][14297] Executing state cmd.run for [maas-region syncdb --noinput]
2019-04-24 02:55:24,632 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14297] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-04-24 02:55:27,256 [salt.state       :300 ][INFO    ][14297] {'pid': 14329, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: sites, maasserver, piston3, contenttypes, auth, metadataserver, sessions\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-04-24 02:55:27,257 [salt.state       :1951][INFO    ][14297] Completed state [maas-region syncdb --noinput] at time 02:55:27.256947 duration_in_ms=2625.002
2019-04-24 02:55:27,257 [salt.state       :2022][WARNING ][14297] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-04-24 02:55:27,261 [salt.state       :1780][INFO    ][14297] Running state [maas-regiond] at time 02:55:27.261558
2019-04-24 02:55:27,262 [salt.state       :1813][INFO    ][14297] Executing state service.running for [maas-regiond]
2019-04-24 02:55:27,264 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14297] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-04-24 02:55:27,299 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14297] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-04-24 02:55:27,317 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14297] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-04-24 02:55:27,335 [salt.state       :300 ][INFO    ][14297] The service maas-regiond is already running
2019-04-24 02:55:27,335 [salt.state       :1951][INFO    ][14297] Completed state [maas-regiond] at time 02:55:27.335622 duration_in_ms=74.064
2019-04-24 02:55:27,338 [salt.state       :1780][INFO    ][14297] Running state [bind9] at time 02:55:27.338015
2019-04-24 02:55:27,338 [salt.state       :1813][INFO    ][14297] Executing state service.running for [bind9]
2019-04-24 02:55:27,341 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14297] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-04-24 02:55:27,359 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14297] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-04-24 02:55:27,378 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14297] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-04-24 02:55:27,395 [salt.state       :300 ][INFO    ][14297] The service bind9 is already running
2019-04-24 02:55:27,396 [salt.state       :1951][INFO    ][14297] Completed state [bind9] at time 02:55:27.396060 duration_in_ms=58.045
2019-04-24 02:55:27,398 [salt.state       :1780][INFO    ][14297] Running state [apache2] at time 02:55:27.398536
2019-04-24 02:55:27,401 [salt.state       :1813][INFO    ][14297] Executing state service.running for [apache2]
2019-04-24 02:55:27,402 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14297] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-04-24 02:55:27,422 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14297] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-04-24 02:55:27,444 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14297] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-04-24 02:55:27,474 [salt.state       :300 ][INFO    ][14297] The service apache2 is already running
2019-04-24 02:55:27,475 [salt.state       :1951][INFO    ][14297] Completed state [apache2] at time 02:55:27.475497 duration_in_ms=76.96
2019-04-24 02:55:27,477 [salt.state       :1780][INFO    ][14297] Running state [maasng.wait_for_http_code] at time 02:55:27.477495
2019-04-24 02:55:27,478 [salt.state       :1813][INFO    ][14297] Executing state module.run for [maasng.wait_for_http_code]
2019-04-24 02:55:27,478 [salt.utils.decorators:613 ][WARNING ][14297] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 02:55:27,585 [salt.state       :300 ][INFO    ][14297] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-04-24 02:55:27,586 [salt.state       :1951][INFO    ][14297] Completed state [maasng.wait_for_http_code] at time 02:55:27.586306 duration_in_ms=108.811
2019-04-24 02:55:27,587 [salt.state       :1780][INFO    ][14297] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:55:27.587720
2019-04-24 02:55:27,588 [salt.state       :1813][INFO    ][14297] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-04-24 02:55:27,589 [salt.state       :300 ][INFO    ][14297] /var/lib/maas/.setup_admin exists
2019-04-24 02:55:27,589 [salt.state       :1951][INFO    ][14297] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:55:27.589387 duration_in_ms=1.667
2019-04-24 02:55:27,590 [salt.state       :1780][INFO    ][14297] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:55:27.590702
2019-04-24 02:55:27,593 [salt.state       :1813][INFO    ][14297] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-24 02:55:27,594 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14297] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-24 02:55:29,423 [salt.state       :300 ][INFO    ][14297] {'pid': 14351, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-24 02:55:29,423 [salt.state       :1951][INFO    ][14297] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:55:29.423854 duration_in_ms=1833.152
2019-04-24 02:55:29,432 [salt.state       :1780][INFO    ][14297] Running state [maas_region_boot_source_resources_mirror] at time 02:55:29.432849
2019-04-24 02:55:29,433 [salt.state       :1813][INFO    ][14297] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-04-24 02:55:29,516 [salt.state       :300 ][INFO    ][14297] {'changes': {}}
2019-04-24 02:55:29,517 [salt.state       :1951][INFO    ][14297] Completed state [maas_region_boot_source_resources_mirror] at time 02:55:29.517156 duration_in_ms=84.308
2019-04-24 02:55:29,518 [salt.state       :1780][INFO    ][14297] Running state [maasng.boot_resources_import] at time 02:55:29.518064
2019-04-24 02:55:29,518 [salt.state       :1813][INFO    ][14297] Executing state module.run for [maasng.boot_resources_import]
2019-04-24 02:55:29,519 [salt.utils.decorators:613 ][WARNING ][14297] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 02:55:29,598 [salt.loaded.ext.module.maasng:1600][INFO    ][14297] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-04-24 02:55:34,639 [salt.loaded.ext.module.maasng:1600][INFO    ][14297] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-04-24 02:55:36,620 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424025536606691
2019-04-24 02:55:36,638 [salt.minion      :1432][INFO    ][14385] Starting a new job with PID 14385
2019-04-24 02:55:36,678 [salt.minion      :1711][INFO    ][14385] Returning information for job: 20190424025536606691
2019-04-24 02:55:39,734 [salt.state       :300 ][INFO    ][14297] {'ret': True}
2019-04-24 02:55:39,734 [salt.state       :1951][INFO    ][14297] Completed state [maasng.boot_resources_import] at time 02:55:39.734677 duration_in_ms=10216.613
2019-04-24 02:55:39,737 [salt.state       :1780][INFO    ][14297] Running state [maas_region_boot_sources_selection_xenial] at time 02:55:39.737087
2019-04-24 02:55:39,737 [salt.state       :1813][INFO    ][14297] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-04-24 02:55:39,897 [salt.state       :300 ][INFO    ][14297] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-04-24 02:55:39,897 [salt.state       :1951][INFO    ][14297] Completed state [maas_region_boot_sources_selection_xenial] at time 02:55:39.897506 duration_in_ms=160.418
2019-04-24 02:55:39,898 [salt.state       :1780][INFO    ][14297] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 02:55:39.898674
2019-04-24 02:55:39,899 [salt.state       :1813][INFO    ][14297] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-04-24 02:55:39,899 [salt.utils.decorators:613 ][WARNING ][14297] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 02:55:39,900 [salt.loaded.ext.module.maasng:1771][INFO    ][14297] boot-sources sync initiated for ALL Rack's
2019-04-24 02:55:40,827 [salt.state       :300 ][INFO    ][14297] {'ret': True}
2019-04-24 02:55:40,828 [salt.state       :1951][INFO    ][14297] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 02:55:40.827890 duration_in_ms=929.215
2019-04-24 02:55:40,830 [salt.state       :1780][INFO    ][14297] Running state [maas.process_maas_config] at time 02:55:40.830208
2019-04-24 02:55:40,831 [salt.state       :1813][INFO    ][14297] Executing state module.run for [maas.process_maas_config]
2019-04-24 02:55:40,831 [salt.utils.decorators:613 ][WARNING ][14297] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 02:55:40,832 [salt.loaded.ext.module.maas:92  ][INFO    ][14297] maasconfig name=enable_http_proxy value=True
2019-04-24 02:55:40,878 [salt.loaded.ext.module.maas:92  ][INFO    ][14297] maasconfig name=upstream_dns value=8.8.8.8
2019-04-24 02:55:42,301 [salt.loaded.ext.module.maas:92  ][INFO    ][14297] maasconfig name=commissioning_distro_series value=xenial
2019-04-24 02:55:42,360 [salt.loaded.ext.module.maas:92  ][INFO    ][14297] maasconfig name=default_osystem value=ubuntu
2019-04-24 02:55:42,409 [salt.loaded.ext.module.maas:92  ][INFO    ][14297] maasconfig name=active_discovery_interval value=600
2019-04-24 02:55:42,464 [salt.loaded.ext.module.maas:92  ][INFO    ][14297] maasconfig name=dnssec_validation value=no
2019-04-24 02:55:42,506 [salt.loaded.ext.module.maas:92  ][INFO    ][14297] maasconfig name=maas_name value=mas01
2019-04-24 02:55:42,554 [salt.loaded.ext.module.maas:92  ][INFO    ][14297] maasconfig name=network_discovery value=enabled
2019-04-24 02:55:42,683 [salt.loaded.ext.module.maas:92  ][INFO    ][14297] maasconfig name=enable_third_party_drivers value=True
2019-04-24 02:55:42,728 [salt.loaded.ext.module.maas:92  ][INFO    ][14297] maasconfig name=default_storage_layout value=lvm
2019-04-24 02:55:42,780 [salt.loaded.ext.module.maas:92  ][INFO    ][14297] maasconfig name=ntp_external_only value=True
2019-04-24 02:55:42,824 [salt.loaded.ext.module.maas:92  ][INFO    ][14297] maasconfig name=disk_erase_with_secure_erase value=False
2019-04-24 02:55:42,864 [salt.loaded.ext.module.maas:92  ][INFO    ][14297] maasconfig name=default_distro_series value=xenial
2019-04-24 02:55:42,917 [salt.loaded.ext.module.maas:92  ][INFO    ][14297] maasconfig name=default_min_hwe_kernel value=ga-16.04
2019-04-24 02:55:43,053 [salt.state       :300 ][INFO    ][14297] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-04-24 02:55:43,053 [salt.state       :1951][INFO    ][14297] Completed state [maas.process_maas_config] at time 02:55:43.053683 duration_in_ms=2223.475
2019-04-24 02:55:43,054 [salt.state       :1780][INFO    ][14297] Running state [pxe_admin] at time 02:55:43.054415
2019-04-24 02:55:43,054 [salt.state       :1813][INFO    ][14297] Executing state maasng.fabric_present for [pxe_admin]
2019-04-24 02:55:43,115 [salt.loaded.ext.module.maasng:945 ][INFO    ][14297] [{u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None, u'name': u'fabric-2', u'id': 2}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'wngwbt', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u'', u'name': u'pxe_admin', u'id': 1}]
2019-04-24 02:55:43,171 [salt.loaded.ext.module.maasng:1008][WARNING ][14297] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-04-24 02:55:43,171 [salt.loaded.ext.module.maasng:1011][WARNING ][14297] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-04-24 02:55:43,226 [salt.state       :300 ][INFO    ][14297] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-04-24 02:55:43,229 [salt.state       :1951][INFO    ][14297] Completed state [pxe_admin] at time 02:55:43.228950 duration_in_ms=174.535
2019-04-24 02:55:43,229 [salt.state       :1780][INFO    ][14297] Running state [vlan 0] at time 02:55:43.229327
2019-04-24 02:55:43,229 [salt.state       :1813][INFO    ][14297] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-04-24 02:55:43,283 [salt.loaded.ext.module.maasng:945 ][INFO    ][14297] [{u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None, u'name': u'fabric-2', u'id': 2}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wngwbt', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u'', u'name': u'pxe_admin', u'id': 1}]
2019-04-24 02:55:43,381 [salt.loaded.ext.module.maasng:945 ][INFO    ][14297] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'wngwbt', 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': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-24 02:55:43,614 [salt.loaded.ext.module.maasng:945 ][INFO    ][14297] [{u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None, u'name': u'fabric-2', u'id': 2}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wngwbt', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u'', u'name': u'pxe_admin', u'id': 1}]
2019-04-24 02:55:43,706 [salt.state       :300 ][INFO    ][14297] {'new': 'Vlan untagged was updated'}
2019-04-24 02:55:43,706 [salt.state       :1951][INFO    ][14297] Completed state [vlan 0] at time 02:55:43.706574 duration_in_ms=477.245
2019-04-24 02:55:43,710 [salt.state       :1780][INFO    ][14297] Running state [192.168.11.0/24] at time 02:55:43.710231
2019-04-24 02:55:43,710 [salt.state       :1813][INFO    ][14297] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-04-24 02:55:43,897 [salt.loaded.ext.module.maasng:945 ][INFO    ][14297] [{u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None, u'name': u'fabric-2', u'id': 2}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'wngwbt', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u'', u'name': u'pxe_admin', u'id': 1}]
2019-04-24 02:55:43,898 [salt.loaded.ext.module.maasng:1235][WARNING ][14297] Ignoring parameter vlan:0
2019-04-24 02:55:43,963 [salt.state       :300 ][INFO    ][14297] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-04-24 02:55:43,963 [salt.state       :1951][INFO    ][14297] Completed state [192.168.11.0/24] at time 02:55:43.963603 duration_in_ms=253.372
2019-04-24 02:55:43,964 [salt.state       :1780][INFO    ][14297] Running state [maas_create_iprange_1] at time 02:55:43.964435
2019-04-24 02:55:43,964 [salt.state       :1813][INFO    ][14297] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-04-24 02:55:44,016 [salt.state       :300 ][INFO    ][14297] Iprange maas_create_iprange_1 already exist.
2019-04-24 02:55:44,016 [salt.state       :1951][INFO    ][14297] Completed state [maas_create_iprange_1] at time 02:55:44.016716 duration_in_ms=52.28
2019-04-24 02:55:44,017 [salt.state       :1780][INFO    ][14297] Running state [vlan 0] at time 02:55:44.017046
2019-04-24 02:55:44,017 [salt.state       :1813][INFO    ][14297] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-04-24 02:55:44,065 [salt.loaded.ext.module.maasng:945 ][INFO    ][14297] [{u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None, u'name': u'fabric-2', u'id': 2}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'wngwbt', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u'', u'name': u'pxe_admin', u'id': 1}]
2019-04-24 02:55:44,154 [salt.loaded.ext.module.maasng:945 ][INFO    ][14297] [{u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None, u'name': u'fabric-2', u'id': 2}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wngwbt', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u'', u'name': u'pxe_admin', u'id': 1}]
2019-04-24 02:55:44,359 [salt.loaded.ext.module.maasng:945 ][INFO    ][14297] [{u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None, u'name': u'fabric-2', u'id': 2}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'wngwbt', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u'', u'name': u'pxe_admin', u'id': 1}]
2019-04-24 02:55:44,436 [salt.state       :300 ][INFO    ][14297] {'new': 'Vlan untagged was updated'}
2019-04-24 02:55:44,436 [salt.state       :1951][INFO    ][14297] Completed state [vlan 0] at time 02:55:44.436297 duration_in_ms=419.249
2019-04-24 02:55:44,437 [salt.state       :1780][INFO    ][14297] Running state [opnfv] at time 02:55:44.437018
2019-04-24 02:55:44,437 [salt.state       :1813][INFO    ][14297] Executing state maasng.sshkey_present for [opnfv]
2019-04-24 02:55:44,496 [salt.loaded.ext.module.maasng:1903][INFO    ][14297] [{u'keysource': u'', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b', u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/'}]
2019-04-24 02:55:44,496 [salt.state       :300 ][INFO    ][14297] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-04-24 02:55:44,496 [salt.state       :1951][INFO    ][14297] Completed state [opnfv] at time 02:55:44.496861 duration_in_ms=59.842
2019-04-24 02:55:44,502 [salt.minion      :1711][INFO    ][14297] Returning information for job: 20190424025521575647
2019-04-24 02:55:45,265 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command state.apply with jid 20190424025545257918
2019-04-24 02:55:45,277 [salt.minion      :1432][INFO    ][14736] Starting a new job with PID 14736
2019-04-24 02:55:53,548 [salt.state       :915 ][INFO    ][14736] Loading fresh modules for state activity
2019-04-24 02:55:53,649 [salt.state       :1780][INFO    ][14736] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:55:53.649477
2019-04-24 02:55:53,649 [salt.state       :1813][INFO    ][14736] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-24 02:55:53,651 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14736] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-24 02:55:55,483 [salt.state       :300 ][INFO    ][14736] {'pid': 14787, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-24 02:55:55,484 [salt.state       :1951][INFO    ][14736] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:55:55.484551 duration_in_ms=1835.073
2019-04-24 02:55:55,488 [salt.state       :1780][INFO    ][14736] Running state [maas.process_machines] at time 02:55:55.488815
2019-04-24 02:55:55,489 [salt.state       :1813][INFO    ][14736] Executing state module.run for [maas.process_machines]
2019-04-24 02:55:55,490 [salt.utils.decorators:613 ][WARNING ][14736] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 02:55:56,006 [salt.loaded.ext.module.maas:412 ][WARNING ][14736] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-24 02:55:56,008 [salt.loaded.ext.module.maas:92  ][INFO    ][14736] 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=d4qsmq architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-24 02:55:57,140 [salt.loaded.ext.module.maas:412 ][WARNING ][14736] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-24 02:55:57,141 [salt.loaded.ext.module.maas:92  ][INFO    ][14736] 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=nwsg4a architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-24 02:55:58,289 [salt.loaded.ext.module.maas:412 ][WARNING ][14736] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-24 02:55:58,290 [salt.loaded.ext.module.maas:92  ][INFO    ][14736] machine hostname=kvm01 power_type=ipmi mac_addresses=['14:58:d0:54:e7:88'] power_parameters_power_address=172.16.1.16 power_parameters_power_pass=Winter2017 system_id=bcrsg3 architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-24 02:55:59,446 [salt.loaded.ext.module.maas:412 ][WARNING ][14736] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-24 02:55:59,446 [salt.loaded.ext.module.maas:92  ][INFO    ][14736] machine hostname=kvm03 power_type=ipmi mac_addresses=['14:58:d0:54:7a:28'] power_parameters_power_address=172.16.1.18 power_parameters_power_pass=Winter2017 system_id=bgdhph architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-24 02:56:00,290 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424025600282378
2019-04-24 02:56:00,307 [salt.minion      :1432][INFO    ][15000] Starting a new job with PID 15000
2019-04-24 02:56:00,335 [salt.minion      :1711][INFO    ][15000] Returning information for job: 20190424025600282378
2019-04-24 02:56:00,431 [salt.loaded.ext.module.maas:412 ][WARNING ][14736] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-24 02:56:00,431 [salt.loaded.ext.module.maas:92  ][INFO    ][14736] machine hostname=kvm02 power_type=ipmi mac_addresses=['14:58:d0:54:6a:60'] power_parameters_power_address=172.16.1.17 power_parameters_power_pass=Winter2017 system_id=rxdn43 architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-24 02:56:01,310 [salt.state       :300 ][INFO    ][14736] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-04-24 02:56:01,312 [salt.state       :1951][INFO    ][14736] Completed state [maas.process_machines] at time 02:56:01.312857 duration_in_ms=5824.042
2019-04-24 02:56:01,316 [salt.minion      :1711][INFO    ][14736] Returning information for job: 20190424025545257918
2019-04-24 02:56:35,147 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command state.apply with jid 20190424025635136279
2019-04-24 02:56:35,168 [salt.minion      :1432][INFO    ][15059] Starting a new job with PID 15059
2019-04-24 02:56:43,219 [salt.state       :915 ][INFO    ][15059] Loading fresh modules for state activity
2019-04-24 02:56:43,310 [salt.state       :1780][INFO    ][15059] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:56:43.310283
2019-04-24 02:56:43,310 [salt.state       :1813][INFO    ][15059] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-24 02:56:43,313 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15059] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-24 02:56:45,140 [salt.state       :300 ][INFO    ][15059] {'pid': 15067, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-24 02:56:45,141 [salt.state       :1951][INFO    ][15059] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:56:45.141786 duration_in_ms=1831.502
2019-04-24 02:56:45,145 [salt.state       :1780][INFO    ][15059] Running state [maas.wait_for_machine_status] at time 02:56:45.145141
2019-04-24 02:56:45,145 [salt.state       :1813][INFO    ][15059] Executing state module.run for [maas.wait_for_machine_status]
2019-04-24 02:56:45,146 [salt.utils.decorators:613 ][WARNING ][15059] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 02:56:47,901 [salt.state       :300 ][INFO    ][15059] {'ret': True}
2019-04-24 02:56:47,901 [salt.state       :1951][INFO    ][15059] Completed state [maas.wait_for_machine_status] at time 02:56:47.901695 duration_in_ms=2756.553
2019-04-24 02:56:47,905 [salt.minion      :1711][INFO    ][15059] Returning information for job: 20190424025635136279
2019-04-24 02:56:48,653 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command state.apply with jid 20190424025648642749
2019-04-24 02:56:48,667 [salt.minion      :1432][INFO    ][15102] Starting a new job with PID 15102
2019-04-24 02:56:49,860 [salt.state       :915 ][INFO    ][15102] Loading fresh modules for state activity
2019-04-24 02:56:50,011 [salt.state       :1780][INFO    ][15102] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:56:50.011658
2019-04-24 02:56:50,012 [salt.state       :1813][INFO    ][15102] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-24 02:56:50,013 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15102] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-24 02:56:51,858 [salt.state       :300 ][INFO    ][15102] {'pid': 15115, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-24 02:56:51,859 [salt.state       :1951][INFO    ][15102] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:56:51.859792 duration_in_ms=1848.135
2019-04-24 02:56:51,863 [salt.state       :1780][INFO    ][15102] Running state [maas_machines_storage_cmp002_lvm] at time 02:56:51.863369
2019-04-24 02:56:51,864 [salt.state       :1813][INFO    ][15102] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-04-24 02:56:52,400 [salt.state       :300 ][INFO    ][15102] Machine cmp002 is not in Ready state.
2019-04-24 02:56:52,401 [salt.state       :1951][INFO    ][15102] Completed state [maas_machines_storage_cmp002_lvm] at time 02:56:52.400969 duration_in_ms=537.597
2019-04-24 02:56:52,401 [salt.state       :1780][INFO    ][15102] Running state [maas_machines_storage_cmp001_lvm] at time 02:56:52.401783
2019-04-24 02:56:52,402 [salt.state       :1813][INFO    ][15102] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-04-24 02:56:52,932 [salt.state       :300 ][INFO    ][15102] Machine cmp001 is not in Ready state.
2019-04-24 02:56:52,933 [salt.state       :1951][INFO    ][15102] Completed state [maas_machines_storage_cmp001_lvm] at time 02:56:52.933185 duration_in_ms=531.402
2019-04-24 02:56:52,939 [salt.minion      :1711][INFO    ][15102] Returning information for job: 20190424025648642749
2019-04-24 02:56:53,617 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command state.apply with jid 20190424025653608150
2019-04-24 02:56:53,639 [salt.minion      :1432][INFO    ][15125] Starting a new job with PID 15125
2019-04-24 02:56:54,739 [salt.state       :915 ][INFO    ][15125] Loading fresh modules for state activity
2019-04-24 02:56:54,833 [salt.state       :1780][INFO    ][15125] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:56:54.833916
2019-04-24 02:56:54,834 [salt.state       :1813][INFO    ][15125] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-24 02:56:54,836 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15125] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-24 02:56:56,664 [salt.state       :300 ][INFO    ][15125] {'pid': 15132, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-24 02:56:56,665 [salt.state       :1951][INFO    ][15125] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:56:56.665564 duration_in_ms=1831.649
2019-04-24 02:56:56,669 [salt.state       :1780][INFO    ][15125] Running state [maas.deploy_machines] at time 02:56:56.669006
2019-04-24 02:56:56,669 [salt.state       :1813][INFO    ][15125] Executing state module.run for [maas.deploy_machines]
2019-04-24 02:56:56,670 [salt.utils.decorators:613 ][WARNING ][15125] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 02:56:57,181 [salt.loaded.ext.module.maas:684 ][INFO    ][15125] deploymachines hwe_kernel=ga-16.04 system_id=rxdn43 distro_series=xenial
2019-04-24 02:56:59,528 [salt.state       :300 ][INFO    ][15125] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-04-24 02:56:59,528 [salt.state       :1951][INFO    ][15125] Completed state [maas.deploy_machines] at time 02:56:59.528707 duration_in_ms=2859.701
2019-04-24 02:56:59,530 [salt.minion      :1711][INFO    ][15125] Returning information for job: 20190424025653608150
2019-04-24 02:57:00,316 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command state.apply with jid 20190424025700305000
2019-04-24 02:57:00,335 [salt.minion      :1432][INFO    ][15235] Starting a new job with PID 15235
2019-04-24 02:57:08,462 [salt.state       :915 ][INFO    ][15235] Loading fresh modules for state activity
2019-04-24 02:57:08,546 [salt.state       :1780][INFO    ][15235] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:57:08.546349
2019-04-24 02:57:08,546 [salt.state       :1813][INFO    ][15235] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-24 02:57:08,549 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15235] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-24 02:57:10,409 [salt.state       :300 ][INFO    ][15235] {'pid': 15249, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-24 02:57:10,410 [salt.state       :1951][INFO    ][15235] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:57:10.410109 duration_in_ms=1863.76
2019-04-24 02:57:10,413 [salt.state       :1780][INFO    ][15235] Running state [maas.wait_for_machine_status] at time 02:57:10.413484
2019-04-24 02:57:10,414 [salt.state       :1813][INFO    ][15235] Executing state module.run for [maas.wait_for_machine_status]
2019-04-24 02:57:10,414 [salt.utils.decorators:613 ][WARNING ][15235] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 02:57:13,148 [salt.loaded.ext.module.maas:1023][INFO    ][15235] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.28473806s left)
2019-04-24 02:57:15,439 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424025715392460
2019-04-24 02:57:15,455 [salt.minion      :1432][INFO    ][15262] Starting a new job with PID 15262
2019-04-24 02:57:15,488 [salt.minion      :1711][INFO    ][15262] Returning information for job: 20190424025715392460
2019-04-24 02:57:45,504 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424025745496531
2019-04-24 02:57:45,522 [salt.minion      :1432][INFO    ][15282] Starting a new job with PID 15282
2019-04-24 02:57:45,553 [salt.minion      :1711][INFO    ][15282] Returning information for job: 20190424025745496531
2019-04-24 02:57:45,837 [salt.loaded.ext.module.maas:1023][INFO    ][15235] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.59647703s left)
2019-04-24 02:58:15,577 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424025815570494
2019-04-24 02:58:15,590 [salt.minion      :1432][INFO    ][15357] Starting a new job with PID 15357
2019-04-24 02:58:15,623 [salt.minion      :1711][INFO    ][15357] Returning information for job: 20190424025815570494
2019-04-24 02:58:18,476 [salt.loaded.ext.module.maas:1023][INFO    ][15235] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2181.95743299s left)
2019-04-24 02:58:45,640 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424025845624563
2019-04-24 02:58:45,659 [salt.minion      :1432][INFO    ][15381] Starting a new job with PID 15381
2019-04-24 02:58:45,693 [salt.minion      :1711][INFO    ][15381] Returning information for job: 20190424025845624563
2019-04-24 02:58:51,342 [salt.loaded.ext.module.maas:1023][INFO    ][15235] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2149.09071612s left)
2019-04-24 02:59:15,722 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424025915707541
2019-04-24 02:59:15,742 [salt.minion      :1432][INFO    ][15480] Starting a new job with PID 15480
2019-04-24 02:59:15,777 [salt.minion      :1711][INFO    ][15480] Returning information for job: 20190424025915707541
2019-04-24 02:59:23,939 [salt.loaded.ext.module.maas:1023][INFO    ][15235] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2116.49391198s left)
2019-04-24 02:59:45,784 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424025945770066
2019-04-24 02:59:45,803 [salt.minion      :1432][INFO    ][15504] Starting a new job with PID 15504
2019-04-24 02:59:45,837 [salt.minion      :1711][INFO    ][15504] Returning information for job: 20190424025945770066
2019-04-24 02:59:56,709 [salt.loaded.ext.module.maas:1023][INFO    ][15235] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2083.72416496s left)
2019-04-24 03:00:15,871 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424030015856527
2019-04-24 03:00:15,894 [salt.minion      :1432][INFO    ][15586] Starting a new job with PID 15586
2019-04-24 03:00:15,925 [salt.minion      :1711][INFO    ][15586] Returning information for job: 20190424030015856527
2019-04-24 03:00:29,345 [salt.loaded.ext.module.maas:1023][INFO    ][15235] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2051.087713s left)
2019-04-24 03:00:45,944 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424030045928792
2019-04-24 03:00:45,968 [salt.minion      :1432][INFO    ][15607] Starting a new job with PID 15607
2019-04-24 03:00:45,997 [salt.minion      :1711][INFO    ][15607] Returning information for job: 20190424030045928792
2019-04-24 03:01:02,069 [salt.loaded.ext.module.maas:1023][INFO    ][15235] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2018.36432195s left)
2019-04-24 03:01:16,038 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424030116029562
2019-04-24 03:01:16,053 [salt.minion      :1432][INFO    ][15711] Starting a new job with PID 15711
2019-04-24 03:01:16,080 [salt.minion      :1711][INFO    ][15711] Returning information for job: 20190424030116029562
2019-04-24 03:01:34,740 [salt.loaded.ext.module.maas:1023][INFO    ][15235] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1985.6927619s left)
2019-04-24 03:01:46,096 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424030146080879
2019-04-24 03:01:46,112 [salt.minion      :1432][INFO    ][15761] Starting a new job with PID 15761
2019-04-24 03:01:46,141 [salt.minion      :1711][INFO    ][15761] Returning information for job: 20190424030146080879
2019-04-24 03:02:07,495 [salt.loaded.ext.module.maas:1023][INFO    ][15235] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1952.93767905s left)
2019-04-24 03:02:16,201 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424030216187768
2019-04-24 03:02:16,225 [salt.minion      :1432][INFO    ][15917] Starting a new job with PID 15917
2019-04-24 03:02:16,256 [salt.minion      :1711][INFO    ][15917] Returning information for job: 20190424030216187768
2019-04-24 03:02:40,412 [salt.loaded.ext.module.maas:1023][INFO    ][15235] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1920.02135491s left)
2019-04-24 03:02:46,291 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424030246276329
2019-04-24 03:02:46,306 [salt.minion      :1432][INFO    ][15946] Starting a new job with PID 15946
2019-04-24 03:02:46,333 [salt.minion      :1711][INFO    ][15946] Returning information for job: 20190424030246276329
2019-04-24 03:03:13,195 [salt.loaded.ext.module.maas:1023][INFO    ][15235] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1887.238096s left)
2019-04-24 03:03:16,398 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424030316389580
2019-04-24 03:03:16,421 [salt.minion      :1432][INFO    ][16121] Starting a new job with PID 16121
2019-04-24 03:03:16,447 [salt.minion      :1711][INFO    ][16121] Returning information for job: 20190424030316389580
2019-04-24 03:03:46,029 [salt.loaded.ext.module.maas:1023][INFO    ][15235] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1854.40399694s left)
2019-04-24 03:03:46,485 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424030346474727
2019-04-24 03:03:46,510 [salt.minion      :1432][INFO    ][16142] Starting a new job with PID 16142
2019-04-24 03:03:46,541 [salt.minion      :1711][INFO    ][16142] Returning information for job: 20190424030346474727
2019-04-24 03:04:16,609 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424030416597202
2019-04-24 03:04:16,629 [salt.minion      :1432][INFO    ][16226] Starting a new job with PID 16226
2019-04-24 03:04:16,662 [salt.minion      :1711][INFO    ][16226] Returning information for job: 20190424030416597202
2019-04-24 03:04:18,739 [salt.loaded.ext.module.maas:1023][INFO    ][15235] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1821.69384503s left)
2019-04-24 03:04:46,721 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424030446712383
2019-04-24 03:04:46,734 [salt.minion      :1432][INFO    ][16245] Starting a new job with PID 16245
2019-04-24 03:04:46,762 [salt.minion      :1711][INFO    ][16245] Returning information for job: 20190424030446712383
2019-04-24 03:04:51,511 [salt.loaded.ext.module.maas:1023][INFO    ][15235] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1788.92256808s left)
2019-04-24 03:05:16,857 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424030516842979
2019-04-24 03:05:16,881 [salt.minion      :1432][INFO    ][16407] Starting a new job with PID 16407
2019-04-24 03:05:16,914 [salt.minion      :1711][INFO    ][16407] Returning information for job: 20190424030516842979
2019-04-24 03:05:24,240 [salt.loaded.ext.module.maas:1023][INFO    ][15235] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1756.19253111s left)
2019-04-24 03:05:47,002 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424030546987500
2019-04-24 03:05:47,024 [salt.minion      :1432][INFO    ][16436] Starting a new job with PID 16436
2019-04-24 03:05:47,052 [salt.minion      :1711][INFO    ][16436] Returning information for job: 20190424030546987500
2019-04-24 03:05:56,803 [salt.loaded.ext.module.maas:1023][INFO    ][15235] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1723.63017011s left)
2019-04-24 03:06:17,153 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424030617139903
2019-04-24 03:06:17,171 [salt.minion      :1432][INFO    ][16618] Starting a new job with PID 16618
2019-04-24 03:06:17,205 [salt.minion      :1711][INFO    ][16618] Returning information for job: 20190424030617139903
2019-04-24 03:06:29,564 [salt.loaded.ext.module.maas:1023][INFO    ][15235] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1690.86923695s left)
2019-04-24 03:06:47,306 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424030647294334
2019-04-24 03:06:47,327 [salt.minion      :1432][INFO    ][16641] Starting a new job with PID 16641
2019-04-24 03:06:47,358 [salt.minion      :1711][INFO    ][16641] Returning information for job: 20190424030647294334
2019-04-24 03:07:02,371 [salt.loaded.ext.module.maas:1023][INFO    ][15235] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1658.06196809s left)
2019-04-24 03:07:17,465 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424030717451935
2019-04-24 03:07:17,485 [salt.minion      :1432][INFO    ][16723] Starting a new job with PID 16723
2019-04-24 03:07:17,518 [salt.minion      :1711][INFO    ][16723] Returning information for job: 20190424030717451935
2019-04-24 03:07:35,097 [salt.loaded.ext.module.maas:1023][INFO    ][15235] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1625.33629513s left)
2019-04-24 03:07:47,633 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424030747615979
2019-04-24 03:07:47,654 [salt.minion      :1432][INFO    ][16744] Starting a new job with PID 16744
2019-04-24 03:07:47,688 [salt.minion      :1711][INFO    ][16744] Returning information for job: 20190424030747615979
2019-04-24 03:08:07,711 [salt.loaded.ext.module.maas:1023][INFO    ][15235] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1592.72213197s left)
2019-04-24 03:08:17,808 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424030817792111
2019-04-24 03:08:17,830 [salt.minion      :1432][INFO    ][16827] Starting a new job with PID 16827
2019-04-24 03:08:17,862 [salt.minion      :1711][INFO    ][16827] Returning information for job: 20190424030817792111
2019-04-24 03:08:40,433 [salt.loaded.ext.module.maas:1023][INFO    ][15235] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1559.99984193s left)
2019-04-24 03:08:47,993 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424030847977763
2019-04-24 03:08:48,019 [salt.minion      :1432][INFO    ][16847] Starting a new job with PID 16847
2019-04-24 03:08:48,049 [salt.minion      :1711][INFO    ][16847] Returning information for job: 20190424030847977763
2019-04-24 03:08:56,227 [salt.utils.schedule:1377][INFO    ][3384] Running scheduled job: __mine_interval
2019-04-24 03:09:12,856 [salt.loaded.ext.module.maas:1023][INFO    ][15235] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1527.57864213s left)
2019-04-24 03:09:18,168 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424030918157137
2019-04-24 03:09:18,188 [salt.minion      :1432][INFO    ][17112] Starting a new job with PID 17112
2019-04-24 03:09:18,218 [salt.minion      :1711][INFO    ][17112] Returning information for job: 20190424030918157137
2019-04-24 03:09:45,666 [salt.loaded.ext.module.maas:1023][INFO    ][15235] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1494.76720095s left)
2019-04-24 03:09:48,360 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424030948350405
2019-04-24 03:09:48,373 [salt.minion      :1432][INFO    ][17169] Starting a new job with PID 17169
2019-04-24 03:09:48,402 [salt.minion      :1711][INFO    ][17169] Returning information for job: 20190424030948350405
2019-04-24 03:10:18,340 [salt.state       :300 ][INFO    ][15235] {'ret': True}
2019-04-24 03:10:18,340 [salt.state       :1951][INFO    ][15235] Completed state [maas.wait_for_machine_status] at time 03:10:18.340344 duration_in_ms=787926.857
2019-04-24 03:10:18,343 [salt.minion      :1711][INFO    ][15235] Returning information for job: 20190424025700305000
2019-04-24 04:08:56,226 [salt.utils.schedule:1377][INFO    ][3384] Running scheduled job: __mine_interval
2019-04-24 04:58:12,093 [salt.minion      :1308][INFO    ][3384] User sudo_ubuntu Executing command cp.push_dir with jid 20190424045812084133
2019-04-24 04:58:12,116 [salt.minion      :1432][INFO    ][28249] Starting a new job with PID 28249
