2019-06-03 02:04:33,641 [salt.utils.decorators:613 ][WARNING ][2053] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-03 02:04:34,820 [salt.utils.decorators:613 ][WARNING ][2053] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-03 02:04:38,781 [salt.loaded.int.states.file:2298][WARNING ][2465] 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-06-03 02:04:57,007 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2869] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-06-03 02:04:57,035 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2869] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-06-03 02:04:57,088 [salt.utils.parsers:1051][WARNING ][383] Minion received a SIGTERM. Exiting.
2019-06-03 02:04:58,241 [salt.cli.daemons :293 ][INFO    ][2983] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-06-03 02:04:58,418 [salt.cli.daemons :82  ][INFO    ][2983] Starting up the Salt Minion
2019-06-03 02:04:58,419 [salt.utils.event :1017][INFO    ][2983] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-06-03 02:04:59,644 [salt.minion      :976 ][INFO    ][2983] Creating minion process manager
2019-06-03 02:05:01,727 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][2983] Executing command ['date', '+%z'] in directory '/root'
2019-06-03 02:05:01,748 [salt.utils.schedule:568 ][INFO    ][2983] Updating job settings for scheduled job: __mine_interval
2019-06-03 02:05:01,750 [salt.minion      :1108][INFO    ][2983] Added mine.update to scheduler
2019-06-03 02:05:01,756 [salt.minion      :1975][INFO    ][2983] Minion is starting as user 'root'
2019-06-03 02:05:01,771 [salt.minion      :2336][INFO    ][2983] Minion is ready to receive requests!
2019-06-03 02:05:08,023 [salt.state       :2022][WARNING ][2872] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-06-03 02:05:09,211 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603020509200267
2019-06-03 02:05:09,227 [salt.minion      :1432][INFO    ][3294] Starting a new job with PID 3294
2019-06-03 02:05:09,259 [salt.minion      :1711][INFO    ][3294] Returning information for job: 20190603020509200267
2019-06-03 02:05:10,181 [salt.utils.decorators:613 ][WARNING ][2872] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-03 02:05:25,897 [salt.utils.decorators:613 ][WARNING ][2872] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-03 02:05:39,380 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603020539363868
2019-06-03 02:05:39,408 [salt.minion      :1432][INFO    ][4014] Starting a new job with PID 4014
2019-06-03 02:05:39,441 [salt.minion      :1711][INFO    ][4014] Returning information for job: 20190603020539363868
2019-06-03 02:05:51,724 [salt.utils.decorators:613 ][WARNING ][2872] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-03 02:05:52,907 [salt.utils.decorators:613 ][WARNING ][2872] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-03 02:05:55,451 [salt.loaded.ext.module.maasng:1008][WARNING ][2872] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-06-03 02:05:55,451 [salt.loaded.ext.module.maasng:1011][WARNING ][2872] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-06-03 02:05:56,315 [salt.loaded.ext.module.maasng:1235][WARNING ][2872] Ignoring parameter vlan:0
2019-06-03 02:05:58,142 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command state.apply with jid 20190603020558133680
2019-06-03 02:05:58,160 [salt.minion      :1432][INFO    ][4407] Starting a new job with PID 4407
2019-06-03 02:06:06,008 [salt.state       :915 ][INFO    ][4407] Loading fresh modules for state activity
2019-06-03 02:06:06,068 [salt.fileclient  :1219][INFO    ][4407] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-06-03 02:06:06,114 [salt.state       :1780][INFO    ][4407] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:06:06.114259
2019-06-03 02:06:06,114 [salt.state       :1813][INFO    ][4407] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-03 02:06:06,116 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4407] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-03 02:06:07,914 [salt.state       :300 ][INFO    ][4407] {'pid': 4432, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-03 02:06:07,915 [salt.state       :1951][INFO    ][4407] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:06:07.915498 duration_in_ms=1801.238
2019-06-03 02:06:07,918 [salt.state       :1780][INFO    ][4407] Running state [maas.process_machines] at time 02:06:07.918697
2019-06-03 02:06:07,919 [salt.state       :1813][INFO    ][4407] Executing state module.run for [maas.process_machines]
2019-06-03 02:06:07,920 [salt.utils.decorators:613 ][WARNING ][4407] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-03 02:06:07,980 [salt.loaded.ext.module.maas:412 ][WARNING ][4407] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-03 02:06:07,981 [salt.loaded.ext.module.maas:92  ][INFO    ][4407] 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-06-03 02:06:09,552 [salt.loaded.ext.module.maas:412 ][WARNING ][4407] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-03 02:06:09,553 [salt.loaded.ext.module.maas:92  ][INFO    ][4407] 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-06-03 02:06:11,107 [salt.loaded.ext.module.maas:412 ][WARNING ][4407] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-03 02:06:11,107 [salt.loaded.ext.module.maas:92  ][INFO    ][4407] 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-06-03 02:06:12,623 [salt.loaded.ext.module.maas:412 ][WARNING ][4407] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-03 02:06:12,624 [salt.loaded.ext.module.maas:92  ][INFO    ][4407] 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-06-03 02:06:13,221 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603020613208950
2019-06-03 02:06:13,242 [salt.minion      :1432][INFO    ][4649] Starting a new job with PID 4649
2019-06-03 02:06:13,265 [salt.minion      :1711][INFO    ][4649] Returning information for job: 20190603020613208950
2019-06-03 02:06:14,172 [salt.loaded.ext.module.maas:412 ][WARNING ][4407] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-03 02:06:14,172 [salt.loaded.ext.module.maas:92  ][INFO    ][4407] 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-06-03 02:06:15,670 [salt.state       :300 ][INFO    ][4407] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-06-03 02:06:15,670 [salt.state       :1951][INFO    ][4407] Completed state [maas.process_machines] at time 02:06:15.670859 duration_in_ms=7752.162
2019-06-03 02:06:15,679 [salt.minion      :1711][INFO    ][4407] Returning information for job: 20190603020558133680
2019-06-03 02:06:46,781 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command state.apply with jid 20190603020646767071
2019-06-03 02:06:46,804 [salt.minion      :1432][INFO    ][4761] Starting a new job with PID 4761
2019-06-03 02:06:54,796 [salt.state       :915 ][INFO    ][4761] Loading fresh modules for state activity
2019-06-03 02:06:54,863 [salt.fileclient  :1219][INFO    ][4761] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-06-03 02:06:54,925 [salt.state       :1780][INFO    ][4761] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:06:54.925086
2019-06-03 02:06:54,925 [salt.state       :1813][INFO    ][4761] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-03 02:06:54,932 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4761] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-03 02:06:56,643 [salt.state       :300 ][INFO    ][4761] {'pid': 4778, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-03 02:06:56,645 [salt.state       :1951][INFO    ][4761] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:06:56.644815 duration_in_ms=1719.729
2019-06-03 02:06:56,648 [salt.state       :1780][INFO    ][4761] Running state [maas.wait_for_machine_status] at time 02:06:56.648258
2019-06-03 02:06:56,648 [salt.state       :1813][INFO    ][4761] Executing state module.run for [maas.wait_for_machine_status]
2019-06-03 02:06:56,648 [salt.utils.decorators:613 ][WARNING ][4761] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-03 02:06:57,485 [salt.loaded.ext.module.maas:1023][INFO    ][4761] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.17346406s left)
2019-06-03 02:07:01,879 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603020701863290
2019-06-03 02:07:01,908 [salt.minion      :1432][INFO    ][4792] Starting a new job with PID 4792
2019-06-03 02:07:01,930 [salt.minion      :1711][INFO    ][4792] Returning information for job: 20190603020701863290
2019-06-03 02:07:28,387 [salt.loaded.ext.module.maas:1023][INFO    ][4761] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.27106619s left)
2019-06-03 02:07:31,949 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603020731936500
2019-06-03 02:07:31,976 [salt.minion      :1432][INFO    ][4833] Starting a new job with PID 4833
2019-06-03 02:07:31,998 [salt.minion      :1711][INFO    ][4833] Returning information for job: 20190603020731936500
2019-06-03 02:07:59,267 [salt.loaded.ext.module.maas:1023][INFO    ][4761] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.39118314s left)
2019-06-03 02:08:02,059 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603020802044395
2019-06-03 02:08:02,087 [salt.minion      :1432][INFO    ][4862] Starting a new job with PID 4862
2019-06-03 02:08:02,107 [salt.minion      :1711][INFO    ][4862] Returning information for job: 20190603020802044395
2019-06-03 02:08:30,131 [salt.loaded.ext.module.maas:1023][INFO    ][4761] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.52714014s left)
2019-06-03 02:08:32,140 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603020832124771
2019-06-03 02:08:32,165 [salt.minion      :1432][INFO    ][4908] Starting a new job with PID 4908
2019-06-03 02:08:32,185 [salt.minion      :1711][INFO    ][4908] Returning information for job: 20190603020832124771
2019-06-03 02:09:01,208 [salt.loaded.ext.module.maas:1023][INFO    ][4761] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.44996023s left)
2019-06-03 02:09:02,225 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603020902207723
2019-06-03 02:09:02,254 [salt.minion      :1432][INFO    ][4983] Starting a new job with PID 4983
2019-06-03 02:09:02,277 [salt.minion      :1711][INFO    ][4983] Returning information for job: 20190603020902207723
2019-06-03 02:09:32,340 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603020932327204
2019-06-03 02:09:32,365 [salt.minion      :1432][INFO    ][5180] Starting a new job with PID 5180
2019-06-03 02:09:32,391 [salt.minion      :1711][INFO    ][5180] Returning information for job: 20190603020932327204
2019-06-03 02:09:32,397 [salt.loaded.ext.module.maas:1023][INFO    ][4761] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.2614572s left)
2019-06-03 02:10:02,466 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603021002447643
2019-06-03 02:10:02,493 [salt.minion      :1432][INFO    ][5253] Starting a new job with PID 5253
2019-06-03 02:10:02,523 [salt.minion      :1711][INFO    ][5253] Returning information for job: 20190603021002447643
2019-06-03 02:10:03,745 [salt.loaded.ext.module.maas:1023][INFO    ][4761] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1312.9129281s left)
2019-06-03 02:10:32,591 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603021032579894
2019-06-03 02:10:32,613 [salt.minion      :1432][INFO    ][5587] Starting a new job with PID 5587
2019-06-03 02:10:32,635 [salt.minion      :1711][INFO    ][5587] Returning information for job: 20190603021032579894
2019-06-03 02:10:35,467 [salt.loaded.ext.module.maas:1023][INFO    ][4761] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1281.19073915s left)
2019-06-03 02:11:02,695 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603021102676995
2019-06-03 02:11:02,723 [salt.minion      :1432][INFO    ][5679] Starting a new job with PID 5679
2019-06-03 02:11:02,748 [salt.minion      :1711][INFO    ][5679] Returning information for job: 20190603021102676995
2019-06-03 02:11:07,465 [salt.loaded.ext.module.maas:1023][INFO    ][4761] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1249.1935761s left)
2019-06-03 02:11:32,856 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603021132845393
2019-06-03 02:11:32,876 [salt.minion      :1432][INFO    ][6050] Starting a new job with PID 6050
2019-06-03 02:11:32,902 [salt.minion      :1711][INFO    ][6050] Returning information for job: 20190603021132845393
2019-06-03 02:11:39,956 [salt.loaded.ext.module.maas:1023][INFO    ][4761] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1216.70201206s left)
2019-06-03 02:12:02,979 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603021202961769
2019-06-03 02:12:03,006 [salt.minion      :1432][INFO    ][6152] Starting a new job with PID 6152
2019-06-03 02:12:03,028 [salt.minion      :1711][INFO    ][6152] Returning information for job: 20190603021202961769
2019-06-03 02:12:12,875 [salt.loaded.ext.module.maas:1023][INFO    ][4761] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1183.78310704s left)
2019-06-03 02:12:33,117 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603021233101046
2019-06-03 02:12:33,142 [salt.minion      :1432][INFO    ][6337] Starting a new job with PID 6337
2019-06-03 02:12:33,167 [salt.minion      :1711][INFO    ][6337] Returning information for job: 20190603021233101046
2019-06-03 02:12:45,816 [salt.state       :300 ][INFO    ][4761] {'ret': True}
2019-06-03 02:12:45,816 [salt.state       :1951][INFO    ][4761] Completed state [maas.wait_for_machine_status] at time 02:12:45.816793 duration_in_ms=349168.533
2019-06-03 02:12:45,822 [salt.minion      :1711][INFO    ][4761] Returning information for job: 20190603020646767071
2019-06-03 02:12:46,402 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command state.apply with jid 20190603021246385535
2019-06-03 02:12:46,433 [salt.minion      :1432][INFO    ][6447] Starting a new job with PID 6447
2019-06-03 02:12:54,399 [salt.state       :915 ][INFO    ][6447] Loading fresh modules for state activity
2019-06-03 02:12:54,463 [salt.fileclient  :1219][INFO    ][6447] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-06-03 02:12:54,570 [salt.state       :1780][INFO    ][6447] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:12:54.570379
2019-06-03 02:12:54,570 [salt.state       :1813][INFO    ][6447] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-03 02:12:54,572 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6447] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-03 02:12:56,404 [salt.state       :300 ][INFO    ][6447] {'pid': 6454, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-03 02:12:56,406 [salt.state       :1951][INFO    ][6447] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:12:56.405566 duration_in_ms=1835.186
2019-06-03 02:12:56,410 [salt.state       :1780][INFO    ][6447] Running state [maas_machines_storage_cmp002_lvm] at time 02:12:56.409638
2019-06-03 02:12:56,410 [salt.state       :1813][INFO    ][6447] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-06-03 02:12:57,685 [salt.loaded.ext.module.maasng:610 ][INFO    ][6447] ncmrka
2019-06-03 02:12:57,685 [salt.loaded.ext.module.maasng:626 ][INFO    ][6447] sda
2019-06-03 02:12:58,246 [salt.loaded.ext.module.maasng:361 ][INFO    ][6447] ncmrka
2019-06-03 02:12:58,350 [salt.loaded.ext.module.maasng:367 ][INFO    ][6447] [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'061b7333-502b-4cad-87c8-e2fc5466bf67', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ncmrka', u'device_id': 1, u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'56c3f505-5226-4dad-834c-b0794324b759', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/ncmrka/blockdevices/1/partition/1'}], u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/ncmrka/blockdevices/1/', u'system_id': u'ncmrka', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'type': u'physical', u'id': 1, u'size': 800109715456}, {u'model': None, u'block_size': 4096, u'uuid': u'13de63ef-469f-4b61-b1ae-ec1d27f60e63', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'resource_uri': u'/MAAS/api/2.0/nodes/ncmrka/blockdevices/3/', u'system_id': u'ncmrka', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'uuid': u'f2b7d81f-9fab-4c53-b4f7-8d3308bd4c3e', u'fstype': u'ext4', u'mount_options': None}, u'id_path': None, u'available_size': 0, u'serial': None, u'path': u'/dev/disk/by-dname/lvroot', u'type': u'virtual', u'id': 3, u'size': 800097042432}]
2019-06-03 02:12:58,351 [salt.loaded.ext.module.maasng:632 ][INFO    ][6447] vgroot
2019-06-03 02:12:58,351 [salt.loaded.ext.module.maasng:635 ][INFO    ][6447] lvroot
2019-06-03 02:12:58,351 [salt.loaded.ext.module.maasng:639 ][INFO    ][6447] 107374182400
2019-06-03 02:12:58,960 [salt.loaded.ext.module.maasng:645 ][INFO    ][6447] {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.38', u'192.168.11.40'], u'cpu_count': 40, u'power_type': u'ipmi', u'hwe_kernel': u'', 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'rtkfss', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'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'mode': u'dhcp', u'id': 17}], u'tags': [u'sriov'], 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'rtkfss', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'mac_address': u'9c:b6:54:8a:10:18', 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'primary_rack': u'rtkfss', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'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'children': [], u'system_id': u'ncmrka', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/ncmrka/interfaces/4/'}, u'min_hwe_kernel': u'ga-16.04', u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'status_action': u'', u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'description': u''}, u'disable_ipv4': False, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'ncmrka', 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'physicalblockdevice_set': [{u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/ncmrka/blockdevices/1/', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'ed94d4be-e7e7-46a3-8b48-5f049b76417d', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ncmrka', u'device_id': 1, u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'e414aa3e-6a22-481b-a27e-f9f637353832', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/ncmrka/blockdevices/1/partition/6'}], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'ncmrka', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'type': u'physical', u'id': 1, u'size': 800109715456}], u'blockdevice_set': [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'ed94d4be-e7e7-46a3-8b48-5f049b76417d', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ncmrka', u'device_id': 1, u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'e414aa3e-6a22-481b-a27e-f9f637353832', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/ncmrka/blockdevices/1/partition/6'}], u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/ncmrka/blockdevices/1/', u'system_id': u'ncmrka', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'type': u'physical', u'id': 1, u'size': 800109715456}, {u'model': None, u'block_size': 4096, u'uuid': u'f80349ac-cb57-4b51-97f1-d896d16569cd', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'resource_uri': u'/MAAS/api/2.0/nodes/ncmrka/blockdevices/11/', u'system_id': u'ncmrka', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'uuid': u'117c4a60-ccc8-4d63-a3e1-7d6ea9fa892a', u'fstype': u'ext4', u'mount_options': None}, u'id_path': None, u'available_size': 0, u'serial': None, u'path': u'/dev/disk/by-dname/lvroot', u'type': u'virtual', u'id': 11, u'size': 107374182400}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'ncmrka', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'boot_disk': {u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/ncmrka/blockdevices/1/', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'ed94d4be-e7e7-46a3-8b48-5f049b76417d', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ncmrka', u'device_id': 1, u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'e414aa3e-6a22-481b-a27e-f9f637353832', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/ncmrka/blockdevices/1/partition/6'}], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'ncmrka', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'type': u'physical', u'id': 1, u'size': 800109715456}, u'current_testing_result_id': 3, u'cpu_test_status': -1, u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/ncmrka/blockdevices/11/', u'uuid': u'f80349ac-cb57-4b51-97f1-d896d16569cd', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'ncmrka', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'uuid': u'117c4a60-ccc8-4d63-a3e1-7d6ea9fa892a', u'fstype': u'ext4', u'mount_options': None}, u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 11, u'size': 107374182400}], u'commissioning_status': 2, u'architecture': u'amd64/generic', 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'rtkfss', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'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'mode': u'dhcp', u'id': 17}], u'tags': [u'sriov'], 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'rtkfss', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'mac_address': u'9c:b6:54:8a:10:18', 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'primary_rack': u'rtkfss', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'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'children': [], u'system_id': u'ncmrka', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/ncmrka/interfaces/4/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'mac_address': u'38:ea:a7:8f:07:50', u'parents': [], u'discovered': None, u'children': [], u'system_id': u'ncmrka', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/ncmrka/interfaces/15/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'mac_address': u'38:ea:a7:8f:07:51', u'parents': [], u'discovered': None, u'children': [], u'system_id': u'ncmrka', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/ncmrka/interfaces/14/'}, {u'name': u'ens2f1', u'links': [{u'id': 18, u'mode': u'link_up'}], u'tags': [u'sriov'], 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'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'mac_address': u'38:ea:a7:8f:12:49', u'parents': [], u'discovered': None, u'children': [], u'system_id': u'ncmrka', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/ncmrka/interfaces/11/'}, {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'rtkfss', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'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'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'rtkfss', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'mac_address': u'9c:b6:54:8a:10:1c', 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'primary_rack': u'rtkfss', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'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'children': [], u'system_id': u'ncmrka', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/ncmrka/interfaces/12/'}, {u'name': u'ens2f0', u'links': [{u'id': 20, u'mode': u'link_up'}], u'tags': [u'sriov'], 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'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'mac_address': u'38:ea:a7:8f:12:48', u'parents': [], u'discovered': None, u'children': [], u'system_id': u'ncmrka', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/ncmrka/interfaces/13/'}], u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/ncmrka/', u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2019-06-03 02:12:58,964 [salt.state       :300 ][INFO    ][6447] {'new': {'storage_layout': 'lvm'}}
2019-06-03 02:12:58,964 [salt.state       :1951][INFO    ][6447] Completed state [maas_machines_storage_cmp002_lvm] at time 02:12:58.964735 duration_in_ms=2555.098
2019-06-03 02:12:58,965 [salt.state       :1780][INFO    ][6447] Running state [maas_machines_storage_cmp001_lvm] at time 02:12:58.965097
2019-06-03 02:12:58,965 [salt.state       :1813][INFO    ][6447] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-06-03 02:13:00,197 [salt.loaded.ext.module.maasng:610 ][INFO    ][6447] mgbdqp
2019-06-03 02:13:00,198 [salt.loaded.ext.module.maasng:626 ][INFO    ][6447] sda
2019-06-03 02:13:00,786 [salt.loaded.ext.module.maasng:361 ][INFO    ][6447] mgbdqp
2019-06-03 02:13:00,888 [salt.loaded.ext.module.maasng:367 ][INFO    ][6447] [{u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/mgbdqp/blockdevices/2/', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'f39f8afb-fb12-4f9c-b0a7-d1d4fde2e381', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'mgbdqp', u'device_id': 2, u'filesystem': {u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'uuid': u'775129ed-11d2-45d2-9759-775ce21dc4e0'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/mgbdqp/blockdevices/2/partition/2', u'type': u'partition', u'id': 2, u'size': 800101236736}], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'mgbdqp', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'type': u'physical', u'id': 2, u'size': 800109715456}, {u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/mgbdqp/blockdevices/4/', u'uuid': u'f487b5ea-941d-488a-8ea7-59c2a13d6cc6', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'mgbdqp', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'uuid': u'dd7f9f06-11ae-4e6f-9530-347f3915ae74'}, u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 4, u'size': 800097042432}]
2019-06-03 02:13:00,889 [salt.loaded.ext.module.maasng:632 ][INFO    ][6447] vgroot
2019-06-03 02:13:00,889 [salt.loaded.ext.module.maasng:635 ][INFO    ][6447] lvroot
2019-06-03 02:13:00,890 [salt.loaded.ext.module.maasng:639 ][INFO    ][6447] 107374182400
2019-06-03 02:13:01,483 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603021301470485
2019-06-03 02:13:01,507 [salt.loaded.ext.module.maasng:645 ][INFO    ][6447] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'swap_size': None, u'memory_test_status': -1, u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'boot_interface': {u'vlan': {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'rtkfss', 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'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'rtkfss', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'id': 24, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:95:a0', u'enabled': True, u'effective_mtu': 1500, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'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'rtkfss', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'system_id': u'mgbdqp', u'params': u'', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/mgbdqp/interfaces/5/'}, 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'zone': {u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default', u'description': u''}, u'resource_uri': u'/MAAS/api/2.0/machines/mgbdqp/', u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'node_type': 0, u'testing_status': 2, u'system_id': u'mgbdqp', 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'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/mgbdqp/blockdevices/12/', u'uuid': u'af997bab-0815-49f5-b610-452d3f602925', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'mgbdqp', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'uuid': u'3fe12061-4bcf-4cd6-be15-1351f224e13e'}, u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 12, u'size': 107374182400}], u'blockdevice_set': [{u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/mgbdqp/blockdevices/2/', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'382f30c9-4541-4907-b286-f47c29ca0e06', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'mgbdqp', u'device_id': 2, u'filesystem': {u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'uuid': u'73ace9b8-1591-461f-afae-55d9cae51659'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/mgbdqp/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'mgbdqp', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'type': u'physical', u'id': 2, u'size': 800109715456}, {u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/mgbdqp/blockdevices/12/', u'uuid': u'af997bab-0815-49f5-b610-452d3f602925', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'mgbdqp', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'uuid': u'3fe12061-4bcf-4cd6-be15-1351f224e13e'}, u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 12, u'size': 107374182400}], u'status': 4, u'bcaches': [], u'cpu_count': 40, u'raids': [], u'commissioning_status': 2, u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'mgbdqp', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'commissioning_status_name': u'Passed', u'current_testing_result_id': 5, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'storage_test_status': 2, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/mgbdqp/blockdevices/2/', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'382f30c9-4541-4907-b286-f47c29ca0e06', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'mgbdqp', u'device_id': 2, u'filesystem': {u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'uuid': u'73ace9b8-1591-461f-afae-55d9cae51659'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/mgbdqp/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'mgbdqp', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'type': u'physical', u'id': 2, u'size': 800109715456}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'memory_test_status_name': u'Unknown', u'ip_addresses': [u'192.168.11.39', u'192.168.11.41'], u'min_hwe_kernel': u'ga-16.04', u'interface_set': [{u'vlan': {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'rtkfss', 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'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'rtkfss', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'id': 24, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:95:a0', u'enabled': True, u'effective_mtu': 1500, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'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'rtkfss', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'system_id': u'mgbdqp', u'params': u'', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/mgbdqp/interfaces/5/'}, {u'vlan': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:1f:d5', u'enabled': True, u'effective_mtu': 1500, u'id': 17, u'discovered': None, u'system_id': u'mgbdqp', u'params': u'', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/mgbdqp/interfaces/17/'}, {u'vlan': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:1f:d4', u'enabled': True, u'effective_mtu': 1500, u'id': 18, u'discovered': None, u'system_id': u'mgbdqp', u'params': u'', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/mgbdqp/interfaces/18/'}, {u'vlan': {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'name': u'ens2f1', u'links': [{u'id': 25, u'mode': u'link_up'}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:52:cd', u'enabled': True, u'effective_mtu': 1500, u'id': 16, u'discovered': None, u'system_id': u'mgbdqp', u'params': u'', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/mgbdqp/interfaces/16/'}, {u'vlan': {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'rtkfss', 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'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'rtkfss', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 26, u'mode': u'link_up'}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:95:a4', u'enabled': True, u'effective_mtu': 1500, u'id': 19, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'rtkfss', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.41'}], u'system_id': u'mgbdqp', u'params': u'', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/mgbdqp/interfaces/19/'}, {u'vlan': {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'name': u'ens2f0', u'links': [{u'id': 27, u'mode': u'link_up'}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:52:cc', u'enabled': True, u'effective_mtu': 1500, u'id': 20, u'discovered': None, u'system_id': u'mgbdqp', u'params': u'', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/mgbdqp/interfaces/20/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'boot_disk': {u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/mgbdqp/blockdevices/2/', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'382f30c9-4541-4907-b286-f47c29ca0e06', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'mgbdqp', u'device_id': 2, u'filesystem': {u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'uuid': u'73ace9b8-1591-461f-afae-55d9cae51659'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/mgbdqp/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'mgbdqp', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'type': u'physical', u'id': 2, u'size': 800109715456}}
2019-06-03 02:13:01,509 [salt.state       :300 ][INFO    ][6447] {'new': {'storage_layout': 'lvm'}}
2019-06-03 02:13:01,510 [salt.state       :1951][INFO    ][6447] Completed state [maas_machines_storage_cmp001_lvm] at time 02:13:01.510654 duration_in_ms=2545.557
2019-06-03 02:13:01,510 [salt.minion      :1432][INFO    ][6502] Starting a new job with PID 6502
2019-06-03 02:13:01,517 [salt.minion      :1711][INFO    ][6447] Returning information for job: 20190603021246385535
2019-06-03 02:13:01,536 [salt.minion      :1711][INFO    ][6502] Returning information for job: 20190603021301470485
2019-06-03 02:13:02,007 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command state.apply with jid 20190603021301994459
2019-06-03 02:13:02,031 [salt.minion      :1432][INFO    ][6509] Starting a new job with PID 6509
2019-06-03 02:13:03,136 [salt.state       :915 ][INFO    ][6509] Loading fresh modules for state activity
2019-06-03 02:13:03,195 [salt.fileclient  :1219][INFO    ][6509] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-06-03 02:13:03,243 [salt.state       :1780][INFO    ][6509] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:13:03.243028
2019-06-03 02:13:03,243 [salt.state       :1813][INFO    ][6509] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-03 02:13:03,245 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6509] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-03 02:13:04,972 [salt.state       :300 ][INFO    ][6509] {'pid': 6516, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-03 02:13:04,974 [salt.state       :1951][INFO    ][6509] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:13:04.974321 duration_in_ms=1731.291
2019-06-03 02:13:04,977 [salt.state       :1780][INFO    ][6509] Running state [maas.deploy_machines] at time 02:13:04.977537
2019-06-03 02:13:04,978 [salt.state       :1813][INFO    ][6509] Executing state module.run for [maas.deploy_machines]
2019-06-03 02:13:04,979 [salt.utils.decorators:613 ][WARNING ][6509] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-03 02:13:05,573 [salt.loaded.ext.module.maas:684 ][INFO    ][6509] deploymachines hwe_kernel=ga-16.04 system_id=ncmrka distro_series=xenial
2019-06-03 02:13:08,075 [salt.loaded.ext.module.maas:684 ][INFO    ][6509] deploymachines hwe_kernel=ga-16.04 system_id=mgbdqp distro_series=xenial
2019-06-03 02:13:10,542 [salt.loaded.ext.module.maas:684 ][INFO    ][6509] deploymachines hwe_kernel=ga-16.04 system_id=et6k6w distro_series=xenial
2019-06-03 02:13:13,236 [salt.loaded.ext.module.maas:684 ][INFO    ][6509] deploymachines hwe_kernel=ga-16.04 system_id=kc3tnh distro_series=xenial
2019-06-03 02:13:15,751 [salt.loaded.ext.module.maas:684 ][INFO    ][6509] deploymachines hwe_kernel=ga-16.04 system_id=yhhd6x distro_series=xenial
2019-06-03 02:13:17,118 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603021317098997
2019-06-03 02:13:17,142 [salt.minion      :1432][INFO    ][6804] Starting a new job with PID 6804
2019-06-03 02:13:17,165 [salt.minion      :1711][INFO    ][6804] Returning information for job: 20190603021317098997
2019-06-03 02:13:18,332 [salt.state       :300 ][INFO    ][6509] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-06-03 02:13:18,333 [salt.state       :1951][INFO    ][6509] Completed state [maas.deploy_machines] at time 02:13:18.333341 duration_in_ms=13355.804
2019-06-03 02:13:18,340 [salt.minion      :1711][INFO    ][6509] Returning information for job: 20190603021301994459
2019-06-03 02:13:18,901 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command state.apply with jid 20190603021318886459
2019-06-03 02:13:18,928 [salt.minion      :1432][INFO    ][6820] Starting a new job with PID 6820
2019-06-03 02:13:26,876 [salt.state       :915 ][INFO    ][6820] Loading fresh modules for state activity
2019-06-03 02:13:26,931 [salt.fileclient  :1219][INFO    ][6820] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-06-03 02:13:26,990 [salt.state       :1780][INFO    ][6820] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:13:26.990227
2019-06-03 02:13:26,990 [salt.state       :1813][INFO    ][6820] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-03 02:13:26,992 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6820] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-03 02:13:28,773 [salt.state       :300 ][INFO    ][6820] {'pid': 6838, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-03 02:13:28,774 [salt.state       :1951][INFO    ][6820] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:13:28.774610 duration_in_ms=1784.382
2019-06-03 02:13:28,779 [salt.state       :1780][INFO    ][6820] Running state [maas.wait_for_machine_status] at time 02:13:28.779549
2019-06-03 02:13:28,780 [salt.state       :1813][INFO    ][6820] Executing state module.run for [maas.wait_for_machine_status]
2019-06-03 02:13:28,781 [salt.utils.decorators:613 ][WARNING ][6820] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-03 02:13:31,591 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.20012808s left)
2019-06-03 02:13:33,966 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603021333949050
2019-06-03 02:13:33,994 [salt.minion      :1432][INFO    ][6850] Starting a new job with PID 6850
2019-06-03 02:13:34,017 [salt.minion      :1711][INFO    ][6850] Returning information for job: 20190603021333949050
2019-06-03 02:14:04,042 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603021404031831
2019-06-03 02:14:04,064 [salt.minion      :1432][INFO    ][6887] Starting a new job with PID 6887
2019-06-03 02:14:04,085 [salt.minion      :1711][INFO    ][6887] Returning information for job: 20190603021404031831
2019-06-03 02:14:04,684 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.10745311s left)
2019-06-03 02:14:34,152 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603021434133650
2019-06-03 02:14:34,178 [salt.minion      :1432][INFO    ][6932] Starting a new job with PID 6932
2019-06-03 02:14:34,200 [salt.minion      :1711][INFO    ][6932] Returning information for job: 20190603021434133650
2019-06-03 02:14:37,624 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.16733718s left)
2019-06-03 02:15:04,219 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603021504201282
2019-06-03 02:15:04,248 [salt.minion      :1432][INFO    ][6969] Starting a new job with PID 6969
2019-06-03 02:15:04,272 [salt.minion      :1711][INFO    ][6969] Returning information for job: 20190603021504201282
2019-06-03 02:15:10,612 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2148.17916012s left)
2019-06-03 02:15:34,304 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603021534294440
2019-06-03 02:15:34,333 [salt.minion      :1432][INFO    ][7030] Starting a new job with PID 7030
2019-06-03 02:15:34,356 [salt.minion      :1711][INFO    ][7030] Returning information for job: 20190603021534294440
2019-06-03 02:15:43,542 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2115.24932408s left)
2019-06-03 02:16:04,390 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603021604380268
2019-06-03 02:16:04,419 [salt.minion      :1432][INFO    ][7107] Starting a new job with PID 7107
2019-06-03 02:16:04,439 [salt.minion      :1711][INFO    ][7107] Returning information for job: 20190603021604380268
2019-06-03 02:16:16,605 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2082.18608809s left)
2019-06-03 02:16:34,507 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603021634491766
2019-06-03 02:16:34,536 [salt.minion      :1432][INFO    ][7281] Starting a new job with PID 7281
2019-06-03 02:16:34,557 [salt.minion      :1711][INFO    ][7281] Returning information for job: 20190603021634491766
2019-06-03 02:16:49,461 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2049.33013105s left)
2019-06-03 02:17:04,600 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603021704586896
2019-06-03 02:17:04,619 [salt.minion      :1432][INFO    ][7321] Starting a new job with PID 7321
2019-06-03 02:17:04,643 [salt.minion      :1711][INFO    ][7321] Returning information for job: 20190603021704586896
2019-06-03 02:17:22,552 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2016.23949099s left)
2019-06-03 02:17:34,747 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603021734727954
2019-06-03 02:17:34,776 [salt.minion      :1432][INFO    ][7638] Starting a new job with PID 7638
2019-06-03 02:17:34,809 [salt.minion      :1711][INFO    ][7638] Returning information for job: 20190603021734727954
2019-06-03 02:17:55,611 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1983.18048596s left)
2019-06-03 02:18:04,895 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603021804887101
2019-06-03 02:18:04,916 [salt.minion      :1432][INFO    ][7746] Starting a new job with PID 7746
2019-06-03 02:18:04,944 [salt.minion      :1711][INFO    ][7746] Returning information for job: 20190603021804887101
2019-06-03 02:18:28,782 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1950.0100491s left)
2019-06-03 02:18:35,047 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603021835025704
2019-06-03 02:18:35,073 [salt.minion      :1432][INFO    ][8052] Starting a new job with PID 8052
2019-06-03 02:18:35,096 [salt.minion      :1711][INFO    ][8052] Returning information for job: 20190603021835025704
2019-06-03 02:19:01,829 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1916.96222401s left)
2019-06-03 02:19:05,172 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603021905157050
2019-06-03 02:19:05,200 [salt.minion      :1432][INFO    ][8088] Starting a new job with PID 8088
2019-06-03 02:19:05,224 [salt.minion      :1711][INFO    ][8088] Returning information for job: 20190603021905157050
2019-06-03 02:19:34,852 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1883.94201016s left)
2019-06-03 02:19:35,360 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603021935342515
2019-06-03 02:19:35,385 [salt.minion      :1432][INFO    ][8529] Starting a new job with PID 8529
2019-06-03 02:19:35,417 [salt.minion      :1711][INFO    ][8529] Returning information for job: 20190603021935342515
2019-06-03 02:20:05,515 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603022005499738
2019-06-03 02:20:05,539 [salt.minion      :1432][INFO    ][8571] Starting a new job with PID 8571
2019-06-03 02:20:05,567 [salt.minion      :1711][INFO    ][8571] Returning information for job: 20190603022005499738
2019-06-03 02:20:07,875 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1850.91642213s left)
2019-06-03 02:20:35,725 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603022035703325
2019-06-03 02:20:35,754 [salt.minion      :1432][INFO    ][8939] Starting a new job with PID 8939
2019-06-03 02:20:35,775 [salt.minion      :1711][INFO    ][8939] Returning information for job: 20190603022035703325
2019-06-03 02:20:40,822 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1817.96904016s left)
2019-06-03 02:21:05,883 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603022105865030
2019-06-03 02:21:05,911 [salt.minion      :1432][INFO    ][8970] Starting a new job with PID 8970
2019-06-03 02:21:05,933 [salt.minion      :1711][INFO    ][8970] Returning information for job: 20190603022105865030
2019-06-03 02:21:14,873 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1783.91832709s left)
2019-06-03 02:21:36,055 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603022136039817
2019-06-03 02:21:36,084 [salt.minion      :1432][INFO    ][9196] Starting a new job with PID 9196
2019-06-03 02:21:36,108 [salt.minion      :1711][INFO    ][9196] Returning information for job: 20190603022136039817
2019-06-03 02:21:47,719 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1751.07249713s left)
2019-06-03 02:22:06,234 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603022206219881
2019-06-03 02:22:06,261 [salt.minion      :1432][INFO    ][9242] Starting a new job with PID 9242
2019-06-03 02:22:06,284 [salt.minion      :1711][INFO    ][9242] Returning information for job: 20190603022206219881
2019-06-03 02:22:20,627 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1718.16432405s left)
2019-06-03 02:22:36,412 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603022236396146
2019-06-03 02:22:36,429 [salt.minion      :1432][INFO    ][9368] Starting a new job with PID 9368
2019-06-03 02:22:36,452 [salt.minion      :1711][INFO    ][9368] Returning information for job: 20190603022236396146
2019-06-03 02:22:53,696 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1685.09551001s left)
2019-06-03 02:23:06,488 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603022306475198
2019-06-03 02:23:06,516 [salt.minion      :1432][INFO    ][9434] Starting a new job with PID 9434
2019-06-03 02:23:06,536 [salt.minion      :1711][INFO    ][9434] Returning information for job: 20190603022306475198
2019-06-03 02:23:26,636 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1652.15578198s left)
2019-06-03 02:23:36,555 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603022336539797
2019-06-03 02:23:36,586 [salt.minion      :1432][INFO    ][9633] Starting a new job with PID 9633
2019-06-03 02:23:36,609 [salt.minion      :1711][INFO    ][9633] Returning information for job: 20190603022336539797
2019-06-03 02:23:59,602 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1619.18940997s left)
2019-06-03 02:24:06,744 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603022406726758
2019-06-03 02:24:06,774 [salt.minion      :1432][INFO    ][9661] Starting a new job with PID 9661
2019-06-03 02:24:06,796 [salt.minion      :1711][INFO    ][9661] Returning information for job: 20190603022406726758
2019-06-03 02:24:32,574 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1586.21792006s left)
2019-06-03 02:24:36,941 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603022436924848
2019-06-03 02:24:36,970 [salt.minion      :1432][INFO    ][9786] Starting a new job with PID 9786
2019-06-03 02:24:36,991 [salt.minion      :1711][INFO    ][9786] Returning information for job: 20190603022436924848
2019-06-03 02:25:05,560 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1553.23120403s left)
2019-06-03 02:25:07,115 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603022507100374
2019-06-03 02:25:07,146 [salt.minion      :1432][INFO    ][9951] Starting a new job with PID 9951
2019-06-03 02:25:07,167 [salt.minion      :1711][INFO    ][9951] Returning information for job: 20190603022507100374
2019-06-03 02:25:37,337 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603022537324660
2019-06-03 02:25:37,364 [salt.minion      :1432][INFO    ][10042] Starting a new job with PID 10042
2019-06-03 02:25:37,389 [salt.minion      :1711][INFO    ][10042] Returning information for job: 20190603022537324660
2019-06-03 02:25:38,536 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1520.25480008s left)
2019-06-03 02:26:07,536 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603022607519914
2019-06-03 02:26:07,567 [salt.minion      :1432][INFO    ][10123] Starting a new job with PID 10123
2019-06-03 02:26:07,590 [salt.minion      :1711][INFO    ][10123] Returning information for job: 20190603022607519914
2019-06-03 02:26:11,587 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1487.20409918s left)
2019-06-03 02:26:37,579 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603022637563452
2019-06-03 02:26:37,609 [salt.minion      :1432][INFO    ][10323] Starting a new job with PID 10323
2019-06-03 02:26:37,632 [salt.minion      :1711][INFO    ][10323] Returning information for job: 20190603022637563452
2019-06-03 02:26:44,472 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1454.31964707s left)
2019-06-03 02:27:07,619 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603022707602552
2019-06-03 02:27:07,648 [salt.minion      :1432][INFO    ][10354] Starting a new job with PID 10354
2019-06-03 02:27:07,668 [salt.minion      :1711][INFO    ][10354] Returning information for job: 20190603022707602552
2019-06-03 02:27:17,554 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1421.23720312s left)
2019-06-03 02:27:37,668 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603022737651950
2019-06-03 02:27:37,702 [salt.minion      :1432][INFO    ][10453] Starting a new job with PID 10453
2019-06-03 02:27:37,729 [salt.minion      :1711][INFO    ][10453] Returning information for job: 20190603022737651950
2019-06-03 02:27:50,579 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1388.21269011s left)
2019-06-03 02:28:07,725 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603022807710912
2019-06-03 02:28:07,755 [salt.minion      :1432][INFO    ][10484] Starting a new job with PID 10484
2019-06-03 02:28:07,779 [salt.minion      :1711][INFO    ][10484] Returning information for job: 20190603022807710912
2019-06-03 02:28:23,512 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1355.27898717s left)
2019-06-03 02:28:37,796 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603022837780476
2019-06-03 02:28:37,824 [salt.minion      :1432][INFO    ][10540] Starting a new job with PID 10540
2019-06-03 02:28:37,844 [salt.minion      :1711][INFO    ][10540] Returning information for job: 20190603022837780476
2019-06-03 02:28:56,428 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1322.36302614s left)
2019-06-03 02:29:07,869 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603022907860918
2019-06-03 02:29:07,893 [salt.minion      :1432][INFO    ][10569] Starting a new job with PID 10569
2019-06-03 02:29:07,913 [salt.minion      :1711][INFO    ][10569] Returning information for job: 20190603022907860918
2019-06-03 02:29:29,328 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1289.46377802s left)
2019-06-03 02:29:37,949 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603022937931725
2019-06-03 02:29:37,975 [salt.minion      :1432][INFO    ][10623] Starting a new job with PID 10623
2019-06-03 02:29:37,997 [salt.minion      :1711][INFO    ][10623] Returning information for job: 20190603022937931725
2019-06-03 02:30:02,167 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1256.62382817s left)
2019-06-03 02:30:08,035 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603023008020593
2019-06-03 02:30:08,061 [salt.minion      :1432][INFO    ][10660] Starting a new job with PID 10660
2019-06-03 02:30:08,085 [salt.minion      :1711][INFO    ][10660] Returning information for job: 20190603023008020593
2019-06-03 02:30:34,861 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1223.930583s left)
2019-06-03 02:30:38,131 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603023038117621
2019-06-03 02:30:38,154 [salt.minion      :1432][INFO    ][10708] Starting a new job with PID 10708
2019-06-03 02:30:38,179 [salt.minion      :1711][INFO    ][10708] Returning information for job: 20190603023038117621
2019-06-03 02:31:07,699 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1191.09243202s left)
2019-06-03 02:31:08,253 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603023108240864
2019-06-03 02:31:08,284 [salt.minion      :1432][INFO    ][10736] Starting a new job with PID 10736
2019-06-03 02:31:08,308 [salt.minion      :1711][INFO    ][10736] Returning information for job: 20190603023108240864
2019-06-03 02:31:38,368 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603023138355493
2019-06-03 02:31:38,391 [salt.minion      :1432][INFO    ][10782] Starting a new job with PID 10782
2019-06-03 02:31:38,415 [salt.minion      :1711][INFO    ][10782] Returning information for job: 20190603023138355493
2019-06-03 02:31:40,473 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1158.31818008s left)
2019-06-03 02:32:08,518 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603023208502101
2019-06-03 02:32:08,549 [salt.minion      :1432][INFO    ][10813] Starting a new job with PID 10813
2019-06-03 02:32:08,573 [salt.minion      :1711][INFO    ][10813] Returning information for job: 20190603023208502101
2019-06-03 02:32:13,609 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1125.18261504s left)
2019-06-03 02:32:38,660 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603023238649202
2019-06-03 02:32:38,689 [salt.minion      :1432][INFO    ][10862] Starting a new job with PID 10862
2019-06-03 02:32:38,711 [salt.minion      :1711][INFO    ][10862] Returning information for job: 20190603023238649202
2019-06-03 02:32:46,340 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1092.45162106s left)
2019-06-03 02:33:08,826 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603023308810541
2019-06-03 02:33:08,854 [salt.minion      :1432][INFO    ][10890] Starting a new job with PID 10890
2019-06-03 02:33:08,877 [salt.minion      :1711][INFO    ][10890] Returning information for job: 20190603023308810541
2019-06-03 02:33:19,211 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1059.58009005s left)
2019-06-03 02:33:39,028 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603023339010873
2019-06-03 02:33:39,055 [salt.minion      :1432][INFO    ][10938] Starting a new job with PID 10938
2019-06-03 02:33:39,079 [salt.minion      :1711][INFO    ][10938] Returning information for job: 20190603023339010873
2019-06-03 02:33:52,137 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1026.65437317s left)
2019-06-03 02:34:09,234 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603023409219706
2019-06-03 02:34:09,264 [salt.minion      :1432][INFO    ][10969] Starting a new job with PID 10969
2019-06-03 02:34:09,293 [salt.minion      :1711][INFO    ][10969] Returning information for job: 20190603023409219706
2019-06-03 02:34:24,908 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (993.883116007s left)
2019-06-03 02:34:39,258 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603023439244199
2019-06-03 02:34:39,277 [salt.minion      :1432][INFO    ][11015] Starting a new job with PID 11015
2019-06-03 02:34:39,302 [salt.minion      :1711][INFO    ][11015] Returning information for job: 20190603023439244199
2019-06-03 02:34:57,775 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (961.016138077s left)
2019-06-03 02:35:09,481 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603023509467675
2019-06-03 02:35:09,513 [salt.minion      :1432][INFO    ][11054] Starting a new job with PID 11054
2019-06-03 02:35:09,549 [salt.minion      :1711][INFO    ][11054] Returning information for job: 20190603023509467675
2019-06-03 02:35:30,483 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (928.308607101s left)
2019-06-03 02:35:39,555 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603023539539228
2019-06-03 02:35:39,584 [salt.minion      :1432][INFO    ][11112] Starting a new job with PID 11112
2019-06-03 02:35:39,616 [salt.minion      :1711][INFO    ][11112] Returning information for job: 20190603023539539228
2019-06-03 02:36:03,323 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (895.467914104s left)
2019-06-03 02:36:09,599 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603023609585795
2019-06-03 02:36:09,630 [salt.minion      :1432][INFO    ][11140] Starting a new job with PID 11140
2019-06-03 02:36:09,655 [salt.minion      :1711][INFO    ][11140] Returning information for job: 20190603023609585795
2019-06-03 02:36:36,176 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (862.615071058s left)
2019-06-03 02:36:39,694 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603023639677925
2019-06-03 02:36:39,723 [salt.minion      :1432][INFO    ][11184] Starting a new job with PID 11184
2019-06-03 02:36:39,747 [salt.minion      :1711][INFO    ][11184] Returning information for job: 20190603023639677925
2019-06-03 02:37:08,938 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (829.853044033s left)
2019-06-03 02:37:09,773 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603023709755657
2019-06-03 02:37:09,803 [salt.minion      :1432][INFO    ][11218] Starting a new job with PID 11218
2019-06-03 02:37:09,826 [salt.minion      :1711][INFO    ][11218] Returning information for job: 20190603023709755657
2019-06-03 02:37:39,895 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603023739881067
2019-06-03 02:37:39,920 [salt.minion      :1432][INFO    ][11259] Starting a new job with PID 11259
2019-06-03 02:37:39,945 [salt.minion      :1711][INFO    ][11259] Returning information for job: 20190603023739881067
2019-06-03 02:37:41,860 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (796.930835962s left)
2019-06-03 02:38:10,013 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603023810001143
2019-06-03 02:38:10,041 [salt.minion      :1432][INFO    ][11284] Starting a new job with PID 11284
2019-06-03 02:38:10,064 [salt.minion      :1711][INFO    ][11284] Returning information for job: 20190603023810001143
2019-06-03 02:38:14,922 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (763.869008064s left)
2019-06-03 02:38:40,180 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603023840163552
2019-06-03 02:38:40,208 [salt.minion      :1432][INFO    ][11328] Starting a new job with PID 11328
2019-06-03 02:38:40,232 [salt.minion      :1711][INFO    ][11328] Returning information for job: 20190603023840163552
2019-06-03 02:38:47,827 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (730.964274168s left)
2019-06-03 02:39:10,318 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603023910302139
2019-06-03 02:39:10,348 [salt.minion      :1432][INFO    ][11356] Starting a new job with PID 11356
2019-06-03 02:39:10,373 [salt.minion      :1711][INFO    ][11356] Returning information for job: 20190603023910302139
2019-06-03 02:39:20,585 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (698.206287146s left)
2019-06-03 02:39:40,534 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603023940517647
2019-06-03 02:39:40,563 [salt.minion      :1432][INFO    ][11403] Starting a new job with PID 11403
2019-06-03 02:39:40,588 [salt.minion      :1711][INFO    ][11403] Returning information for job: 20190603023940517647
2019-06-03 02:39:53,344 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (665.447030067s left)
2019-06-03 02:40:10,687 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603024010672681
2019-06-03 02:40:10,716 [salt.minion      :1432][INFO    ][11432] Starting a new job with PID 11432
2019-06-03 02:40:10,739 [salt.minion      :1711][INFO    ][11432] Returning information for job: 20190603024010672681
2019-06-03 02:40:26,219 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (632.572458029s left)
2019-06-03 02:40:40,732 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603024040715308
2019-06-03 02:40:40,762 [salt.minion      :1432][INFO    ][11477] Starting a new job with PID 11477
2019-06-03 02:40:40,786 [salt.minion      :1711][INFO    ][11477] Returning information for job: 20190603024040715308
2019-06-03 02:40:59,239 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (599.5526371s left)
2019-06-03 02:41:10,774 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603024110766256
2019-06-03 02:41:10,797 [salt.minion      :1432][INFO    ][11508] Starting a new job with PID 11508
2019-06-03 02:41:10,819 [salt.minion      :1711][INFO    ][11508] Returning information for job: 20190603024110766256
2019-06-03 02:41:31,971 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (566.820250988s left)
2019-06-03 02:41:40,995 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603024140982153
2019-06-03 02:41:41,020 [salt.minion      :1432][INFO    ][11555] Starting a new job with PID 11555
2019-06-03 02:41:41,043 [salt.minion      :1711][INFO    ][11555] Returning information for job: 20190603024140982153
2019-06-03 02:42:04,736 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (534.055055141s left)
2019-06-03 02:42:11,086 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603024211072606
2019-06-03 02:42:11,110 [salt.minion      :1432][INFO    ][11585] Starting a new job with PID 11585
2019-06-03 02:42:11,135 [salt.minion      :1711][INFO    ][11585] Returning information for job: 20190603024211072606
2019-06-03 02:42:37,528 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (501.262943029s left)
2019-06-03 02:42:41,156 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603024241144786
2019-06-03 02:42:41,182 [salt.minion      :1432][INFO    ][11632] Starting a new job with PID 11632
2019-06-03 02:42:41,205 [salt.minion      :1711][INFO    ][11632] Returning information for job: 20190603024241144786
2019-06-03 02:43:10,415 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (468.376712084s left)
2019-06-03 02:43:11,277 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603024311262969
2019-06-03 02:43:11,310 [salt.minion      :1432][INFO    ][11658] Starting a new job with PID 11658
2019-06-03 02:43:11,338 [salt.minion      :1711][INFO    ][11658] Returning information for job: 20190603024311262969
2019-06-03 02:43:41,372 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603024341362913
2019-06-03 02:43:41,395 [salt.minion      :1432][INFO    ][11705] Starting a new job with PID 11705
2019-06-03 02:43:41,418 [salt.minion      :1711][INFO    ][11705] Returning information for job: 20190603024341362913
2019-06-03 02:43:43,427 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (435.364418983s left)
2019-06-03 02:44:11,529 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603024411516100
2019-06-03 02:44:11,555 [salt.minion      :1432][INFO    ][11745] Starting a new job with PID 11745
2019-06-03 02:44:11,579 [salt.minion      :1711][INFO    ][11745] Returning information for job: 20190603024411516100
2019-06-03 02:44:16,308 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (402.482874155s left)
2019-06-03 02:44:41,682 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603024441668338
2019-06-03 02:44:41,710 [salt.minion      :1432][INFO    ][11781] Starting a new job with PID 11781
2019-06-03 02:44:41,732 [salt.minion      :1711][INFO    ][11781] Returning information for job: 20190603024441668338
2019-06-03 02:44:49,039 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (369.75257802s left)
2019-06-03 02:45:11,912 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603024511899886
2019-06-03 02:45:11,939 [salt.minion      :1432][INFO    ][11957] Starting a new job with PID 11957
2019-06-03 02:45:11,964 [salt.minion      :1711][INFO    ][11957] Returning information for job: 20190603024511899886
2019-06-03 02:45:21,750 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (337.04096508s left)
2019-06-03 02:45:42,080 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603024542063615
2019-06-03 02:45:42,112 [salt.minion      :1432][INFO    ][11998] Starting a new job with PID 11998
2019-06-03 02:45:42,146 [salt.minion      :1711][INFO    ][11998] Returning information for job: 20190603024542063615
2019-06-03 02:45:54,599 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (304.191836119s left)
2019-06-03 02:46:12,128 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603024612117456
2019-06-03 02:46:12,156 [salt.minion      :1432][INFO    ][12039] Starting a new job with PID 12039
2019-06-03 02:46:12,183 [salt.minion      :1711][INFO    ][12039] Returning information for job: 20190603024612117456
2019-06-03 02:46:27,385 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (271.406486988s left)
2019-06-03 02:46:42,142 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603024642133026
2019-06-03 02:46:42,160 [salt.minion      :1432][INFO    ][12073] Starting a new job with PID 12073
2019-06-03 02:46:42,188 [salt.minion      :1711][INFO    ][12073] Returning information for job: 20190603024642133026
2019-06-03 02:47:00,177 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (238.614653111s left)
2019-06-03 02:47:12,197 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603024712180305
2019-06-03 02:47:12,228 [salt.minion      :1432][INFO    ][12116] Starting a new job with PID 12116
2019-06-03 02:47:12,250 [salt.minion      :1711][INFO    ][12116] Returning information for job: 20190603024712180305
2019-06-03 02:47:33,058 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (205.733025074s left)
2019-06-03 02:47:42,299 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603024742287396
2019-06-03 02:47:42,331 [salt.minion      :1432][INFO    ][12154] Starting a new job with PID 12154
2019-06-03 02:47:42,353 [salt.minion      :1711][INFO    ][12154] Returning information for job: 20190603024742287396
2019-06-03 02:48:05,856 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (172.935297966s left)
2019-06-03 02:48:12,427 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603024812412916
2019-06-03 02:48:12,456 [salt.minion      :1432][INFO    ][12196] Starting a new job with PID 12196
2019-06-03 02:48:12,480 [salt.minion      :1711][INFO    ][12196] Returning information for job: 20190603024812412916
2019-06-03 02:48:38,736 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (140.055044174s left)
2019-06-03 02:48:42,524 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603024842509299
2019-06-03 02:48:42,548 [salt.minion      :1432][INFO    ][12228] Starting a new job with PID 12228
2019-06-03 02:48:42,570 [salt.minion      :1711][INFO    ][12228] Returning information for job: 20190603024842509299
2019-06-03 02:49:11,860 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (106.93111515s left)
2019-06-03 02:49:12,631 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603024912616745
2019-06-03 02:49:12,658 [salt.minion      :1432][INFO    ][12269] Starting a new job with PID 12269
2019-06-03 02:49:12,682 [salt.minion      :1711][INFO    ][12269] Returning information for job: 20190603024912616745
2019-06-03 02:49:42,803 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603024942792598
2019-06-03 02:49:42,827 [salt.minion      :1432][INFO    ][12300] Starting a new job with PID 12300
2019-06-03 02:49:42,850 [salt.minion      :1711][INFO    ][12300] Returning information for job: 20190603024942792598
2019-06-03 02:49:44,579 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (74.2121491432s left)
2019-06-03 02:50:12,972 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603025012959637
2019-06-03 02:50:13,000 [salt.minion      :1432][INFO    ][12353] Starting a new job with PID 12353
2019-06-03 02:50:13,023 [salt.minion      :1711][INFO    ][12353] Returning information for job: 20190603025012959637
2019-06-03 02:50:17,322 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (41.4692320824s left)
2019-06-03 02:50:43,170 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603025043154978
2019-06-03 02:50:43,197 [salt.minion      :1432][INFO    ][12378] Starting a new job with PID 12378
2019-06-03 02:50:43,220 [salt.minion      :1711][INFO    ][12378] Returning information for job: 20190603025043154978
2019-06-03 02:50:50,317 [salt.loaded.ext.module.maas:1023][INFO    ][6820] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (8.47434401512s left)
2019-06-03 02:51:13,339 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603025113325178
2019-06-03 02:51:13,368 [salt.minion      :1432][INFO    ][12428] Starting a new job with PID 12428
2019-06-03 02:51:13,389 [salt.minion      :1711][INFO    ][12428] Returning information for job: 20190603025113325178
2019-06-03 02:51:23,299 [salt.state       :302 ][ERROR   ][6820] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-06-03 02:51:23,299 [salt.state       :1951][INFO    ][6820] Completed state [maas.wait_for_machine_status] at time 02:51:23.299880 duration_in_ms=2274520.327
2019-06-03 02:51:23,307 [salt.minion      :1711][INFO    ][6820] Returning information for job: 20190603021318886459
2019-06-03 02:51:34,121 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command pillar.get with jid 20190603025134106960
2019-06-03 02:51:34,155 [salt.minion      :1432][INFO    ][12447] Starting a new job with PID 12447
2019-06-03 02:51:34,164 [salt.minion      :1711][INFO    ][12447] Returning information for job: 20190603025134106960
2019-06-03 02:51:34,724 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command service.status with jid 20190603025134710775
2019-06-03 02:51:34,752 [salt.minion      :1432][INFO    ][12452] Starting a new job with PID 12452
2019-06-03 02:51:35,410 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12452] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-06-03 02:51:35,455 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12452] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-06-03 02:51:35,478 [salt.minion      :1711][INFO    ][12452] Returning information for job: 20190603025134710775
2019-06-03 02:51:36,056 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command state.apply with jid 20190603025136040375
2019-06-03 02:51:36,083 [salt.minion      :1432][INFO    ][12463] Starting a new job with PID 12463
2019-06-03 02:51:44,058 [salt.state       :915 ][INFO    ][12463] Loading fresh modules for state activity
2019-06-03 02:51:44,675 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12463] Executing command 'salt-minion --version' in directory '/root'
2019-06-03 02:51:44,960 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12463] Executing command 'salt-minion --version' in directory '/root'
2019-06-03 02:51:45,977 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12463] Executing command 'salt-minion --version' in directory '/root'
2019-06-03 02:51:46,281 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12463] Executing command 'salt-minion --version' in directory '/root'
2019-06-03 02:51:48,118 [salt.state       :1780][INFO    ][12463] Running state [salt-minion] at time 02:51:48.117925
2019-06-03 02:51:48,118 [salt.state       :1813][INFO    ][12463] Executing state pkg.installed for [salt-minion]
2019-06-03 02:51:48,119 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12463] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-03 02:51:48,245 [salt.state       :300 ][INFO    ][12463] All specified packages are already installed
2019-06-03 02:51:48,245 [salt.state       :1951][INFO    ][12463] Completed state [salt-minion] at time 02:51:48.245429 duration_in_ms=127.505
2019-06-03 02:51:48,246 [salt.state       :1780][INFO    ][12463] Running state [salt_minion_dependency_packages] at time 02:51:48.246512
2019-06-03 02:51:48,247 [salt.state       :1813][INFO    ][12463] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-06-03 02:51:48,262 [salt.state       :300 ][INFO    ][12463] All specified packages are already installed
2019-06-03 02:51:48,262 [salt.state       :1951][INFO    ][12463] Completed state [salt_minion_dependency_packages] at time 02:51:48.262505 duration_in_ms=15.995
2019-06-03 02:51:48,265 [salt.state       :1780][INFO    ][12463] Running state [/etc/salt/minion.d/minion.conf] at time 02:51:48.265518
2019-06-03 02:51:48,267 [salt.state       :1813][INFO    ][12463] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-06-03 02:51:48,554 [salt.state       :300 ][INFO    ][12463] File /etc/salt/minion.d/minion.conf is in the correct state
2019-06-03 02:51:48,554 [salt.state       :1951][INFO    ][12463] Completed state [/etc/salt/minion.d/minion.conf] at time 02:51:48.554722 duration_in_ms=289.204
2019-06-03 02:51:48,555 [salt.state       :1780][INFO    ][12463] Running state [python-netaddr] at time 02:51:48.554987
2019-06-03 02:51:48,555 [salt.state       :1813][INFO    ][12463] Executing state pkg.installed for [python-netaddr]
2019-06-03 02:51:48,564 [salt.state       :300 ][INFO    ][12463] All specified packages are already installed
2019-06-03 02:51:48,564 [salt.state       :1951][INFO    ][12463] Completed state [python-netaddr] at time 02:51:48.564664 duration_in_ms=9.677
2019-06-03 02:51:48,568 [salt.state       :1780][INFO    ][12463] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:51:48.568356
2019-06-03 02:51:48,568 [salt.state       :1813][INFO    ][12463] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-06-03 02:51:48,580 [salt.state       :300 ][INFO    ][12463] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-06-03 02:51:48,580 [salt.state       :1951][INFO    ][12463] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:51:48.580615 duration_in_ms=12.258
2019-06-03 02:51:48,582 [salt.state       :1780][INFO    ][12463] Running state [salt-minion] at time 02:51:48.582042
2019-06-03 02:51:48,582 [salt.state       :1813][INFO    ][12463] Executing state service.running for [salt-minion]
2019-06-03 02:51:48,583 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12463] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-06-03 02:51:48,631 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12463] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-06-03 02:51:48,655 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12463] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-06-03 02:51:48,678 [salt.state       :300 ][INFO    ][12463] The service salt-minion is already running
2019-06-03 02:51:48,679 [salt.state       :1951][INFO    ][12463] Completed state [salt-minion] at time 02:51:48.679180 duration_in_ms=97.136
2019-06-03 02:51:48,682 [salt.state       :1780][INFO    ][12463] Running state [/etc/salt/grains.d] at time 02:51:48.682130
2019-06-03 02:51:48,682 [salt.state       :1813][INFO    ][12463] Executing state file.directory for [/etc/salt/grains.d]
2019-06-03 02:51:48,684 [salt.state       :300 ][INFO    ][12463] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-06-03 02:51:48,685 [salt.state       :1951][INFO    ][12463] Completed state [/etc/salt/grains.d] at time 02:51:48.684952 duration_in_ms=2.822
2019-06-03 02:51:48,688 [salt.state       :1780][INFO    ][12463] Running state [/etc/salt/grains] at time 02:51:48.687992
2019-06-03 02:51:48,688 [salt.state       :1813][INFO    ][12463] Executing state file.managed for [/etc/salt/grains]
2019-06-03 02:51:48,688 [salt.state       :300 ][INFO    ][12463] File /etc/salt/grains exists with proper permissions. No changes made.
2019-06-03 02:51:48,689 [salt.state       :1951][INFO    ][12463] Completed state [/etc/salt/grains] at time 02:51:48.689101 duration_in_ms=1.109
2019-06-03 02:51:48,689 [salt.state       :1780][INFO    ][12463] Running state [/etc/salt/grains.d/placeholder] at time 02:51:48.689620
2019-06-03 02:51:48,689 [salt.state       :1813][INFO    ][12463] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-06-03 02:51:48,690 [salt.state       :300 ][INFO    ][12463] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-06-03 02:51:48,690 [salt.state       :1951][INFO    ][12463] Completed state [/etc/salt/grains.d/placeholder] at time 02:51:48.690697 duration_in_ms=1.077
2019-06-03 02:51:48,691 [salt.state       :1780][INFO    ][12463] Running state [/etc/salt/grains.d/sphinx] at time 02:51:48.691211
2019-06-03 02:51:48,691 [salt.state       :1813][INFO    ][12463] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-06-03 02:51:48,693 [salt.state       :300 ][INFO    ][12463] File /etc/salt/grains.d/sphinx is in the correct state
2019-06-03 02:51:48,693 [salt.state       :1951][INFO    ][12463] Completed state [/etc/salt/grains.d/sphinx] at time 02:51:48.693532 duration_in_ms=2.321
2019-06-03 02:51:48,695 [salt.state       :1780][INFO    ][12463] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:51:48.695691
2019-06-03 02:51:48,695 [salt.state       :1813][INFO    ][12463] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-06-03 02:51:48,696 [salt.state       :300 ][INFO    ][12463] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-06-03 02:51:48,696 [salt.state       :1951][INFO    ][12463] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:51:48.696461 duration_in_ms=0.77
2019-06-03 02:51:48,696 [salt.state       :1780][INFO    ][12463] Running state [/etc/salt/grains.d/dns_records] at time 02:51:48.696933
2019-06-03 02:51:48,697 [salt.state       :1813][INFO    ][12463] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-06-03 02:51:48,700 [salt.state       :300 ][INFO    ][12463] File /etc/salt/grains.d/dns_records is in the correct state
2019-06-03 02:51:48,700 [salt.state       :1951][INFO    ][12463] Completed state [/etc/salt/grains.d/dns_records] at time 02:51:48.700657 duration_in_ms=3.722
2019-06-03 02:51:48,702 [salt.state       :1780][INFO    ][12463] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:51:48.702279
2019-06-03 02:51:48,702 [salt.state       :1813][INFO    ][12463] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-06-03 02:51:48,703 [salt.state       :300 ][INFO    ][12463] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-06-03 02:51:48,703 [salt.state       :1951][INFO    ][12463] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:51:48.703870 duration_in_ms=1.59
2019-06-03 02:51:48,704 [salt.state       :1780][INFO    ][12463] Running state [/etc/salt/grains.d/salt] at time 02:51:48.704836
2019-06-03 02:51:48,705 [salt.state       :1813][INFO    ][12463] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-06-03 02:51:48,707 [salt.state       :300 ][INFO    ][12463] File /etc/salt/grains.d/salt is in the correct state
2019-06-03 02:51:48,707 [salt.state       :1951][INFO    ][12463] Completed state [/etc/salt/grains.d/salt] at time 02:51:48.707389 duration_in_ms=2.552
2019-06-03 02:51:48,709 [salt.state       :1780][INFO    ][12463] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:51:48.709166
2019-06-03 02:51:48,711 [salt.state       :1813][INFO    ][12463] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-06-03 02:51:48,712 [salt.state       :300 ][INFO    ][12463] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-06-03 02:51:48,712 [salt.state       :1951][INFO    ][12463] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:51:48.712455 duration_in_ms=3.29
2019-06-03 02:51:48,714 [salt.state       :1780][INFO    ][12463] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:51:48.714674
2019-06-03 02:51:48,715 [salt.state       :1813][INFO    ][12463] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-06-03 02:51:48,715 [salt.state       :300 ][INFO    ][12463] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-06-03 02:51:48,715 [salt.state       :1951][INFO    ][12463] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:51:48.715468 duration_in_ms=0.795
2019-06-03 02:51:48,716 [salt.state       :1780][INFO    ][12463] Running state [mine.update] at time 02:51:48.716129
2019-06-03 02:51:48,716 [salt.state       :1813][INFO    ][12463] Executing state module.wait for [mine.update]
2019-06-03 02:51:48,716 [salt.state       :300 ][INFO    ][12463] No changes made for mine.update
2019-06-03 02:51:48,716 [salt.state       :1951][INFO    ][12463] Completed state [mine.update] at time 02:51:48.716831 duration_in_ms=0.702
2019-06-03 02:51:48,717 [salt.state       :1780][INFO    ][12463] Running state [ca-certificates] at time 02:51:48.717066
2019-06-03 02:51:48,717 [salt.state       :1813][INFO    ][12463] Executing state pkg.installed for [ca-certificates]
2019-06-03 02:51:48,727 [salt.state       :300 ][INFO    ][12463] All specified packages are already installed
2019-06-03 02:51:48,727 [salt.state       :1951][INFO    ][12463] Completed state [ca-certificates] at time 02:51:48.727386 duration_in_ms=10.32
2019-06-03 02:51:48,728 [salt.state       :1780][INFO    ][12463] Running state [update-ca-certificates] at time 02:51:48.728064
2019-06-03 02:51:48,728 [salt.state       :1813][INFO    ][12463] Executing state cmd.wait for [update-ca-certificates]
2019-06-03 02:51:48,728 [salt.state       :300 ][INFO    ][12463] No changes made for update-ca-certificates
2019-06-03 02:51:48,728 [salt.state       :1951][INFO    ][12463] Completed state [update-ca-certificates] at time 02:51:48.728777 duration_in_ms=0.713
2019-06-03 02:51:48,729 [salt.state       :1780][INFO    ][12463] Running state [iptables] at time 02:51:48.729010
2019-06-03 02:51:48,729 [salt.state       :1813][INFO    ][12463] Executing state pkg.installed for [iptables]
2019-06-03 02:51:48,739 [salt.state       :300 ][INFO    ][12463] All specified packages are already installed
2019-06-03 02:51:48,739 [salt.state       :1951][INFO    ][12463] Completed state [iptables] at time 02:51:48.739595 duration_in_ms=10.585
2019-06-03 02:51:48,739 [salt.state       :1780][INFO    ][12463] Running state [iptables-persistent] at time 02:51:48.739833
2019-06-03 02:51:48,740 [salt.state       :1813][INFO    ][12463] Executing state pkg.installed for [iptables-persistent]
2019-06-03 02:51:48,749 [salt.state       :300 ][INFO    ][12463] All specified packages are already installed
2019-06-03 02:51:48,750 [salt.state       :1951][INFO    ][12463] Completed state [iptables-persistent] at time 02:51:48.749704 duration_in_ms=9.871
2019-06-03 02:51:48,751 [salt.state       :1780][INFO    ][12463] Running state [iptables_modules_v4_load] at time 02:51:48.751266
2019-06-03 02:51:48,751 [salt.state       :1813][INFO    ][12463] Executing state kmod.present for [iptables_modules_v4_load]
2019-06-03 02:51:48,752 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12463] Executing command 'lsmod' in directory '/root'
2019-06-03 02:51:48,775 [salt.state       :300 ][INFO    ][12463] Kernel modules iptable_filter, ip_tables are already present
2019-06-03 02:51:48,775 [salt.state       :1951][INFO    ][12463] Completed state [iptables_modules_v4_load] at time 02:51:48.775674 duration_in_ms=24.407
2019-06-03 02:51:48,776 [salt.state       :1780][INFO    ][12463] Running state [/etc/iptables/rules.v4] at time 02:51:48.776844
2019-06-03 02:51:48,777 [salt.state       :1813][INFO    ][12463] Executing state file.managed for [/etc/iptables/rules.v4]
2019-06-03 02:51:48,882 [salt.state       :300 ][INFO    ][12463] File /etc/iptables/rules.v4 is in the correct state
2019-06-03 02:51:48,882 [salt.state       :1951][INFO    ][12463] Completed state [/etc/iptables/rules.v4] at time 02:51:48.882456 duration_in_ms=105.612
2019-06-03 02:51:48,883 [salt.state       :1780][INFO    ][12463] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:51:48.883572
2019-06-03 02:51:48,883 [salt.state       :1813][INFO    ][12463] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-06-03 02:51:48,884 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12463] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-06-03 02:51:48,905 [salt.state       :300 ][INFO    ][12463] onlyif execution failed
2019-06-03 02:51:48,906 [salt.state       :1951][INFO    ][12463] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:51:48.906062 duration_in_ms=22.157
2019-06-03 02:51:48,908 [salt.state       :1780][INFO    ][12463] Running state [netfilter-persistent] at time 02:51:48.907986
2019-06-03 02:51:48,908 [salt.state       :1813][INFO    ][12463] Executing state service.running for [netfilter-persistent]
2019-06-03 02:51:48,909 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12463] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-06-03 02:51:48,935 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12463] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-06-03 02:51:48,959 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12463] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-06-03 02:51:48,978 [salt.state       :300 ][INFO    ][12463] The service netfilter-persistent is already running
2019-06-03 02:51:48,978 [salt.state       :1951][INFO    ][12463] Completed state [netfilter-persistent] at time 02:51:48.978709 duration_in_ms=70.723
2019-06-03 02:51:48,979 [salt.state       :1780][INFO    ][12463] Running state [iptables_extra.remove_stale_tables] at time 02:51:48.979809
2019-06-03 02:51:48,980 [salt.state       :1813][INFO    ][12463] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-06-03 02:51:48,980 [salt.state       :300 ][INFO    ][12463] No changes made for iptables_extra.remove_stale_tables
2019-06-03 02:51:48,980 [salt.state       :1951][INFO    ][12463] Completed state [iptables_extra.remove_stale_tables] at time 02:51:48.980865 duration_in_ms=1.056
2019-06-03 02:51:48,981 [salt.state       :1780][INFO    ][12463] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:51:48.981188
2019-06-03 02:51:48,981 [salt.state       :1813][INFO    ][12463] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-06-03 02:51:48,982 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12463] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-06-03 02:51:49,000 [salt.state       :300 ][INFO    ][12463] onlyif execution failed
2019-06-03 02:51:49,001 [salt.state       :1951][INFO    ][12463] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:51:49.001332 duration_in_ms=20.144
2019-06-03 02:51:49,002 [salt.state       :1780][INFO    ][12463] Running state [/etc/iptables/rules.v6] at time 02:51:49.002547
2019-06-03 02:51:49,002 [salt.state       :1813][INFO    ][12463] Executing state file.absent for [/etc/iptables/rules.v6]
2019-06-03 02:51:49,003 [salt.state       :300 ][INFO    ][12463] File /etc/iptables/rules.v6 is not present
2019-06-03 02:51:49,003 [salt.state       :1951][INFO    ][12463] Completed state [/etc/iptables/rules.v6] at time 02:51:49.003729 duration_in_ms=1.182
2019-06-03 02:51:49,004 [salt.state       :1780][INFO    ][12463] Running state [iptables_extra.flush_all] at time 02:51:49.004593
2019-06-03 02:51:49,004 [salt.state       :1813][INFO    ][12463] Executing state module.wait for [iptables_extra.flush_all]
2019-06-03 02:51:49,005 [salt.state       :300 ][INFO    ][12463] No changes made for iptables_extra.flush_all
2019-06-03 02:51:49,005 [salt.state       :1951][INFO    ][12463] Completed state [iptables_extra.flush_all] at time 02:51:49.005509 duration_in_ms=0.916
2019-06-03 02:51:49,009 [salt.minion      :1711][INFO    ][12463] Returning information for job: 20190603025136040375
2019-06-03 02:51:49,579 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command state.apply with jid 20190603025149564428
2019-06-03 02:51:49,605 [salt.minion      :1432][INFO    ][12544] Starting a new job with PID 12544
2019-06-03 02:51:50,705 [salt.state       :915 ][INFO    ][12544] Loading fresh modules for state activity
2019-06-03 02:51:51,648 [salt.state       :1780][INFO    ][12544] Running state [maas-rack-controller] at time 02:51:51.648479
2019-06-03 02:51:51,648 [salt.state       :1813][INFO    ][12544] Executing state pkg.installed for [maas-rack-controller]
2019-06-03 02:51:51,649 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12544] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-03 02:51:51,759 [salt.state       :300 ][INFO    ][12544] All specified packages are already installed
2019-06-03 02:51:51,759 [salt.state       :1951][INFO    ][12544] Completed state [maas-rack-controller] at time 02:51:51.759367 duration_in_ms=110.888
2019-06-03 02:51:51,759 [salt.state       :1780][INFO    ][12544] Running state [ipmitool] at time 02:51:51.759691
2019-06-03 02:51:51,760 [salt.state       :1813][INFO    ][12544] Executing state pkg.installed for [ipmitool]
2019-06-03 02:51:51,769 [salt.state       :300 ][INFO    ][12544] All specified packages are already installed
2019-06-03 02:51:51,769 [salt.state       :1951][INFO    ][12544] Completed state [ipmitool] at time 02:51:51.769642 duration_in_ms=9.951
2019-06-03 02:51:51,773 [salt.state       :1780][INFO    ][12544] Running state [/etc/maas/rackd.conf] at time 02:51:51.773396
2019-06-03 02:51:51,773 [salt.state       :1813][INFO    ][12544] Executing state file.line for [/etc/maas/rackd.conf]
2019-06-03 02:51:51,774 [salt.state       :300 ][INFO    ][12544] No changes needed to be made
2019-06-03 02:51:51,774 [salt.state       :1951][INFO    ][12544] Completed state [/etc/maas/rackd.conf] at time 02:51:51.774760 duration_in_ms=1.364
2019-06-03 02:51:51,775 [salt.state       :1780][INFO    ][12544] Running state [/etc/maas/rackd.conf] at time 02:51:51.774996
2019-06-03 02:51:51,775 [salt.state       :1813][INFO    ][12544] Executing state file.managed for [/etc/maas/rackd.conf]
2019-06-03 02:51:51,775 [salt.loaded.int.states.file:2298][WARNING ][12544] 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-06-03 02:51:51,776 [salt.state       :300 ][INFO    ][12544] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-06-03 02:51:51,776 [salt.state       :1951][INFO    ][12544] Completed state [/etc/maas/rackd.conf] at time 02:51:51.776430 duration_in_ms=1.434
2019-06-03 02:51:51,777 [salt.state       :1780][INFO    ][12544] Running state [maas-rackd] at time 02:51:51.777398
2019-06-03 02:51:51,777 [salt.state       :1813][INFO    ][12544] Executing state service.running for [maas-rackd]
2019-06-03 02:51:51,778 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12544] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-06-03 02:51:51,822 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12544] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-06-03 02:51:51,846 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12544] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-06-03 02:51:51,868 [salt.state       :300 ][INFO    ][12544] The service maas-rackd is already running
2019-06-03 02:51:51,869 [salt.state       :1951][INFO    ][12544] Completed state [maas-rackd] at time 02:51:51.869357 duration_in_ms=91.957
2019-06-03 02:51:51,872 [salt.minion      :1711][INFO    ][12544] Returning information for job: 20190603025149564428
2019-06-03 02:51:52,431 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command state.apply with jid 20190603025152419469
2019-06-03 02:51:52,458 [salt.minion      :1432][INFO    ][12567] Starting a new job with PID 12567
2019-06-03 02:51:53,550 [salt.state       :915 ][INFO    ][12567] Loading fresh modules for state activity
2019-06-03 02:51:54,598 [salt.state       :1780][INFO    ][12567] Running state [maas-region-controller] at time 02:51:54.598278
2019-06-03 02:51:54,598 [salt.state       :1813][INFO    ][12567] Executing state pkg.installed for [maas-region-controller]
2019-06-03 02:51:54,599 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12567] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-03 02:51:54,707 [salt.state       :300 ][INFO    ][12567] All specified packages are already installed
2019-06-03 02:51:54,707 [salt.state       :1951][INFO    ][12567] Completed state [maas-region-controller] at time 02:51:54.707765 duration_in_ms=109.487
2019-06-03 02:51:54,708 [salt.state       :1780][INFO    ][12567] Running state [python-oauth] at time 02:51:54.708083
2019-06-03 02:51:54,708 [salt.state       :1813][INFO    ][12567] Executing state pkg.installed for [python-oauth]
2019-06-03 02:51:54,717 [salt.state       :300 ][INFO    ][12567] All specified packages are already installed
2019-06-03 02:51:54,718 [salt.state       :1951][INFO    ][12567] Completed state [python-oauth] at time 02:51:54.718053 duration_in_ms=9.97
2019-06-03 02:51:54,721 [salt.state       :1780][INFO    ][12567] Running state [/etc/maas/regiond.conf] at time 02:51:54.721101
2019-06-03 02:51:54,721 [salt.state       :1813][INFO    ][12567] Executing state file.replace for [/etc/maas/regiond.conf]
2019-06-03 02:51:54,727 [salt.state       :300 ][INFO    ][12567] No changes needed to be made
2019-06-03 02:51:54,727 [salt.state       :1951][INFO    ][12567] Completed state [/etc/maas/regiond.conf] at time 02:51:54.727471 duration_in_ms=6.37
2019-06-03 02:51:54,727 [salt.state       :1780][INFO    ][12567] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:51:54.727943
2019-06-03 02:51:54,728 [salt.state       :1813][INFO    ][12567] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-06-03 02:51:54,798 [salt.state       :300 ][INFO    ][12567] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-06-03 02:51:54,798 [salt.state       :1951][INFO    ][12567] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:51:54.798415 duration_in_ms=70.472
2019-06-03 02:51:54,798 [salt.state       :1780][INFO    ][12567] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:51:54.798891
2019-06-03 02:51:54,799 [salt.state       :1813][INFO    ][12567] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-06-03 02:51:54,802 [salt.state       :300 ][INFO    ][12567] No changes needed to be made
2019-06-03 02:51:54,802 [salt.state       :1951][INFO    ][12567] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:51:54.802801 duration_in_ms=3.91
2019-06-03 02:51:54,803 [salt.state       :1780][INFO    ][12567] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:51:54.803267
2019-06-03 02:51:54,803 [salt.state       :1813][INFO    ][12567] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-06-03 02:51:54,807 [salt.state       :300 ][INFO    ][12567] No changes needed to be made
2019-06-03 02:51:54,808 [salt.state       :1951][INFO    ][12567] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:51:54.807978 duration_in_ms=4.71
2019-06-03 02:51:54,808 [salt.state       :1780][INFO    ][12567] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:51:54.808439
2019-06-03 02:51:54,808 [salt.state       :1813][INFO    ][12567] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-06-03 02:51:54,837 [salt.state       :300 ][INFO    ][12567] No changes needed to be made
2019-06-03 02:51:54,838 [salt.state       :1951][INFO    ][12567] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:51:54.838181 duration_in_ms=29.741
2019-06-03 02:51:54,838 [salt.state       :1780][INFO    ][12567] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:51:54.838826
2019-06-03 02:51:54,839 [salt.state       :1813][INFO    ][12567] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-06-03 02:51:54,856 [salt.state       :300 ][INFO    ][12567] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-06-03 02:51:54,856 [salt.state       :1951][INFO    ][12567] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:51:54.856781 duration_in_ms=17.954
2019-06-03 02:51:54,858 [salt.state       :1780][INFO    ][12567] Running state [a2enmod headers] at time 02:51:54.858307
2019-06-03 02:51:54,858 [salt.state       :1813][INFO    ][12567] Executing state cmd.run for [a2enmod headers]
2019-06-03 02:51:54,859 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12567] Executing command 'a2enmod headers' in directory '/root'
2019-06-03 02:51:54,948 [salt.state       :300 ][INFO    ][12567] {'pid': 12586, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-06-03 02:51:54,948 [salt.state       :1951][INFO    ][12567] Completed state [a2enmod headers] at time 02:51:54.948596 duration_in_ms=90.289
2019-06-03 02:51:54,949 [salt.state       :1780][INFO    ][12567] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:51:54.949173
2019-06-03 02:51:54,955 [salt.state       :1813][INFO    ][12567] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-06-03 02:51:54,992 [salt.state       :300 ][INFO    ][12567] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-06-03 02:51:54,993 [salt.state       :1951][INFO    ][12567] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:51:54.993089 duration_in_ms=43.916
2019-06-03 02:51:54,993 [salt.state       :1780][INFO    ][12567] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:51:54.993698
2019-06-03 02:51:54,994 [salt.state       :1813][INFO    ][12567] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-06-03 02:51:55,062 [salt.state       :300 ][INFO    ][12567] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-06-03 02:51:55,062 [salt.state       :1951][INFO    ][12567] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:51:55.062428 duration_in_ms=68.73
2019-06-03 02:51:55,062 [salt.state       :1780][INFO    ][12567] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:51:55.062941
2019-06-03 02:51:55,063 [salt.state       :1813][INFO    ][12567] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-06-03 02:51:55,124 [salt.state       :300 ][INFO    ][12567] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-06-03 02:51:55,124 [salt.state       :1951][INFO    ][12567] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:51:55.124619 duration_in_ms=61.678
2019-06-03 02:51:55,125 [salt.state       :1780][INFO    ][12567] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:51:55.125154
2019-06-03 02:51:55,125 [salt.state       :1813][INFO    ][12567] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-06-03 02:51:55,207 [salt.state       :300 ][INFO    ][12567] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-06-03 02:51:55,207 [salt.state       :1951][INFO    ][12567] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:51:55.207588 duration_in_ms=82.433
2019-06-03 02:51:55,207 [salt.state       :1780][INFO    ][12567] Running state [/root/.pgpass] at time 02:51:55.207831
2019-06-03 02:51:55,208 [salt.state       :1813][INFO    ][12567] Executing state file.managed for [/root/.pgpass]
2019-06-03 02:51:55,254 [salt.state       :300 ][INFO    ][12567] File /root/.pgpass is in the correct state
2019-06-03 02:51:55,254 [salt.state       :1951][INFO    ][12567] Completed state [/root/.pgpass] at time 02:51:55.254760 duration_in_ms=46.928
2019-06-03 02:51:55,260 [salt.state       :1780][INFO    ][12567] Running state [maas-region syncdb --noinput] at time 02:51:55.260094
2019-06-03 02:51:55,260 [salt.state       :1813][INFO    ][12567] Executing state cmd.run for [maas-region syncdb --noinput]
2019-06-03 02:51:55,260 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12567] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-06-03 02:51:57,716 [salt.state       :300 ][INFO    ][12567] {'pid': 12612, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: metadataserver, contenttypes, sites, maasserver, auth, piston3, sessions\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-06-03 02:51:57,717 [salt.state       :1951][INFO    ][12567] Completed state [maas-region syncdb --noinput] at time 02:51:57.717086 duration_in_ms=2456.991
2019-06-03 02:51:57,717 [salt.state       :2022][WARNING ][12567] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-06-03 02:51:57,721 [salt.state       :1780][INFO    ][12567] Running state [maas-regiond] at time 02:51:57.721115
2019-06-03 02:51:57,722 [salt.state       :1813][INFO    ][12567] Executing state service.running for [maas-regiond]
2019-06-03 02:51:57,723 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12567] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-06-03 02:51:57,768 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12567] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-06-03 02:51:57,791 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12567] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-06-03 02:51:57,815 [salt.state       :300 ][INFO    ][12567] The service maas-regiond is already running
2019-06-03 02:51:57,815 [salt.state       :1951][INFO    ][12567] Completed state [maas-regiond] at time 02:51:57.815546 duration_in_ms=94.432
2019-06-03 02:51:57,821 [salt.state       :1780][INFO    ][12567] Running state [bind9] at time 02:51:57.820932
2019-06-03 02:51:57,821 [salt.state       :1813][INFO    ][12567] Executing state service.running for [bind9]
2019-06-03 02:51:57,822 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12567] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-06-03 02:51:57,846 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12567] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-06-03 02:51:57,870 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12567] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-06-03 02:51:57,892 [salt.state       :300 ][INFO    ][12567] The service bind9 is already running
2019-06-03 02:51:57,893 [salt.state       :1951][INFO    ][12567] Completed state [bind9] at time 02:51:57.893189 duration_in_ms=72.257
2019-06-03 02:51:57,896 [salt.state       :1780][INFO    ][12567] Running state [apache2] at time 02:51:57.896203
2019-06-03 02:51:57,896 [salt.state       :1813][INFO    ][12567] Executing state service.running for [apache2]
2019-06-03 02:51:57,897 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12567] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-06-03 02:51:57,920 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12567] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-06-03 02:51:57,941 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12567] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-06-03 02:51:57,971 [salt.state       :300 ][INFO    ][12567] The service apache2 is already running
2019-06-03 02:51:57,972 [salt.state       :1951][INFO    ][12567] Completed state [apache2] at time 02:51:57.972164 duration_in_ms=75.961
2019-06-03 02:51:57,976 [salt.state       :1780][INFO    ][12567] Running state [maasng.wait_for_http_code] at time 02:51:57.976665
2019-06-03 02:51:57,977 [salt.state       :1813][INFO    ][12567] Executing state module.run for [maasng.wait_for_http_code]
2019-06-03 02:51:57,977 [salt.utils.decorators:613 ][WARNING ][12567] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-03 02:51:57,984 [salt.state       :300 ][INFO    ][12567] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-06-03 02:51:57,984 [salt.state       :1951][INFO    ][12567] Completed state [maasng.wait_for_http_code] at time 02:51:57.984878 duration_in_ms=8.213
2019-06-03 02:51:57,988 [salt.state       :1780][INFO    ][12567] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:51:57.988759
2019-06-03 02:51:57,989 [salt.state       :1813][INFO    ][12567] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-06-03 02:51:57,989 [salt.state       :300 ][INFO    ][12567] /var/lib/maas/.setup_admin exists
2019-06-03 02:51:57,989 [salt.state       :1951][INFO    ][12567] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:51:57.989733 duration_in_ms=0.975
2019-06-03 02:51:57,990 [salt.state       :1780][INFO    ][12567] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:51:57.990472
2019-06-03 02:51:57,990 [salt.state       :1813][INFO    ][12567] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-03 02:51:57,991 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12567] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-03 02:51:59,737 [salt.state       :300 ][INFO    ][12567] {'pid': 12632, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-03 02:51:59,739 [salt.state       :1951][INFO    ][12567] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:51:59.739266 duration_in_ms=1748.793
2019-06-03 02:51:59,751 [salt.state       :1780][INFO    ][12567] Running state [maas_region_boot_source_resources_mirror] at time 02:51:59.750896
2019-06-03 02:51:59,751 [salt.state       :1813][INFO    ][12567] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-06-03 02:51:59,846 [salt.state       :300 ][INFO    ][12567] {'changes': {}}
2019-06-03 02:51:59,846 [salt.state       :1951][INFO    ][12567] Completed state [maas_region_boot_source_resources_mirror] at time 02:51:59.846850 duration_in_ms=95.954
2019-06-03 02:51:59,848 [salt.state       :1780][INFO    ][12567] Running state [maasng.boot_resources_import] at time 02:51:59.848132
2019-06-03 02:51:59,848 [salt.state       :1813][INFO    ][12567] Executing state module.run for [maasng.boot_resources_import]
2019-06-03 02:51:59,849 [salt.utils.decorators:613 ][WARNING ][12567] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-03 02:51:59,949 [salt.loaded.ext.module.maasng:1600][INFO    ][12567] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-06-03 02:52:05,022 [salt.loaded.ext.module.maasng:1600][INFO    ][12567] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-06-03 02:52:07,508 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603025207489415
2019-06-03 02:52:07,531 [salt.minion      :1432][INFO    ][12788] Starting a new job with PID 12788
2019-06-03 02:52:07,555 [salt.minion      :1711][INFO    ][12788] Returning information for job: 20190603025207489415
2019-06-03 02:52:10,103 [salt.state       :300 ][INFO    ][12567] {'ret': True}
2019-06-03 02:52:10,104 [salt.state       :1951][INFO    ][12567] Completed state [maasng.boot_resources_import] at time 02:52:10.104159 duration_in_ms=10256.026
2019-06-03 02:52:10,105 [salt.state       :1780][INFO    ][12567] Running state [maas_region_boot_sources_selection_xenial] at time 02:52:10.105424
2019-06-03 02:52:10,108 [salt.state       :1813][INFO    ][12567] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-06-03 02:52:10,281 [salt.state       :300 ][INFO    ][12567] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-06-03 02:52:10,282 [salt.state       :1951][INFO    ][12567] Completed state [maas_region_boot_sources_selection_xenial] at time 02:52:10.281642 duration_in_ms=176.217
2019-06-03 02:52:10,283 [salt.state       :1780][INFO    ][12567] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 02:52:10.283581
2019-06-03 02:52:10,284 [salt.state       :1813][INFO    ][12567] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-06-03 02:52:10,284 [salt.utils.decorators:613 ][WARNING ][12567] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-03 02:52:10,284 [salt.loaded.ext.module.maasng:1771][INFO    ][12567] boot-sources sync initiated for ALL Rack's
2019-06-03 02:52:11,265 [salt.state       :300 ][INFO    ][12567] {'ret': True}
2019-06-03 02:52:11,266 [salt.state       :1951][INFO    ][12567] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 02:52:11.266639 duration_in_ms=983.058
2019-06-03 02:52:11,268 [salt.state       :1780][INFO    ][12567] Running state [maas.process_maas_config] at time 02:52:11.268588
2019-06-03 02:52:11,269 [salt.state       :1813][INFO    ][12567] Executing state module.run for [maas.process_maas_config]
2019-06-03 02:52:11,269 [salt.utils.decorators:613 ][WARNING ][12567] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-03 02:52:11,272 [salt.loaded.ext.module.maas:92  ][INFO    ][12567] maasconfig name=enable_http_proxy value=True
2019-06-03 02:52:11,325 [salt.loaded.ext.module.maas:92  ][INFO    ][12567] maasconfig name=upstream_dns value=8.8.8.8
2019-06-03 02:52:12,562 [salt.loaded.ext.module.maas:92  ][INFO    ][12567] maasconfig name=commissioning_distro_series value=xenial
2019-06-03 02:52:12,619 [salt.loaded.ext.module.maas:92  ][INFO    ][12567] maasconfig name=default_osystem value=ubuntu
2019-06-03 02:52:12,748 [salt.loaded.ext.module.maas:92  ][INFO    ][12567] maasconfig name=active_discovery_interval value=600
2019-06-03 02:52:12,848 [salt.loaded.ext.module.maas:92  ][INFO    ][12567] maasconfig name=dnssec_validation value=no
2019-06-03 02:52:12,905 [salt.loaded.ext.module.maas:92  ][INFO    ][12567] maasconfig name=maas_name value=mas01
2019-06-03 02:52:12,957 [salt.loaded.ext.module.maas:92  ][INFO    ][12567] maasconfig name=network_discovery value=enabled
2019-06-03 02:52:13,056 [salt.loaded.ext.module.maas:92  ][INFO    ][12567] maasconfig name=enable_third_party_drivers value=True
2019-06-03 02:52:13,116 [salt.loaded.ext.module.maas:92  ][INFO    ][12567] maasconfig name=default_storage_layout value=lvm
2019-06-03 02:52:13,172 [salt.loaded.ext.module.maas:92  ][INFO    ][12567] maasconfig name=ntp_external_only value=True
2019-06-03 02:52:13,234 [salt.loaded.ext.module.maas:92  ][INFO    ][12567] maasconfig name=disk_erase_with_secure_erase value=False
2019-06-03 02:52:13,283 [salt.loaded.ext.module.maas:92  ][INFO    ][12567] maasconfig name=default_distro_series value=xenial
2019-06-03 02:52:13,345 [salt.loaded.ext.module.maas:92  ][INFO    ][12567] maasconfig name=default_min_hwe_kernel value=ga-16.04
2019-06-03 02:52:13,493 [salt.state       :300 ][INFO    ][12567] {'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-06-03 02:52:13,493 [salt.state       :1951][INFO    ][12567] Completed state [maas.process_maas_config] at time 02:52:13.493859 duration_in_ms=2225.27
2019-06-03 02:52:13,494 [salt.state       :1780][INFO    ][12567] Running state [pxe_admin] at time 02:52:13.494660
2019-06-03 02:52:13,495 [salt.state       :1813][INFO    ][12567] Executing state maasng.fabric_present for [pxe_admin]
2019-06-03 02:52:13,564 [salt.loaded.ext.module.maasng:945 ][INFO    ][12567] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'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'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'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'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'rtkfss', 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-06-03 02:52:13,629 [salt.loaded.ext.module.maasng:1008][WARNING ][12567] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-06-03 02:52:13,630 [salt.loaded.ext.module.maasng:1011][WARNING ][12567] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-06-03 02:52:13,691 [salt.state       :300 ][INFO    ][12567] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-06-03 02:52:13,692 [salt.state       :1951][INFO    ][12567] Completed state [pxe_admin] at time 02:52:13.692307 duration_in_ms=197.646
2019-06-03 02:52:13,692 [salt.state       :1780][INFO    ][12567] Running state [vlan 0] at time 02:52:13.692803
2019-06-03 02:52:13,693 [salt.state       :1813][INFO    ][12567] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-06-03 02:52:13,747 [salt.loaded.ext.module.maasng:945 ][INFO    ][12567] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'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'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'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'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'rtkfss', 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-06-03 02:52:13,874 [salt.loaded.ext.module.maasng:945 ][INFO    ][12567] [{u'name': u'fabric-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'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'name': u'fabric-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'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None}, {u'name': u'pxe_admin', 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'rtkfss', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u''}]
2019-06-03 02:52:14,146 [salt.loaded.ext.module.maasng:945 ][INFO    ][12567] [{u'name': u'fabric-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'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'name': u'fabric-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'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None}, {u'name': u'pxe_admin', 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'rtkfss', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u''}]
2019-06-03 02:52:14,308 [salt.state       :300 ][INFO    ][12567] {'new': 'Vlan untagged was updated'}
2019-06-03 02:52:14,308 [salt.state       :1951][INFO    ][12567] Completed state [vlan 0] at time 02:52:14.308886 duration_in_ms=616.083
2019-06-03 02:52:14,310 [salt.state       :1780][INFO    ][12567] Running state [192.168.11.0/24] at time 02:52:14.310676
2019-06-03 02:52:14,311 [salt.state       :1813][INFO    ][12567] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-06-03 02:52:14,524 [salt.loaded.ext.module.maasng:945 ][INFO    ][12567] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, 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'fabric': u'fabric-2', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'class_type': u'', 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'rtkfss', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-06-03 02:52:14,525 [salt.loaded.ext.module.maasng:1235][WARNING ][12567] Ignoring parameter vlan:0
2019-06-03 02:52:14,589 [salt.state       :300 ][INFO    ][12567] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-06-03 02:52:14,590 [salt.state       :1951][INFO    ][12567] Completed state [192.168.11.0/24] at time 02:52:14.590055 duration_in_ms=279.38
2019-06-03 02:52:14,590 [salt.state       :1780][INFO    ][12567] Running state [maas_create_iprange_1] at time 02:52:14.590832
2019-06-03 02:52:14,591 [salt.state       :1813][INFO    ][12567] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-06-03 02:52:14,659 [salt.state       :300 ][INFO    ][12567] Iprange maas_create_iprange_1 already exist.
2019-06-03 02:52:14,659 [salt.state       :1951][INFO    ][12567] Completed state [maas_create_iprange_1] at time 02:52:14.659571 duration_in_ms=68.739
2019-06-03 02:52:14,659 [salt.state       :1780][INFO    ][12567] Running state [vlan 0] at time 02:52:14.659823
2019-06-03 02:52:14,660 [salt.state       :1813][INFO    ][12567] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-06-03 02:52:14,704 [salt.loaded.ext.module.maasng:945 ][INFO    ][12567] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'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'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'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'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'rtkfss', 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-06-03 02:52:14,817 [salt.loaded.ext.module.maasng:945 ][INFO    ][12567] [{u'name': u'fabric-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'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'name': u'fabric-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'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None}, {u'name': u'pxe_admin', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'rtkfss', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u''}]
2019-06-03 02:52:15,083 [salt.loaded.ext.module.maasng:945 ][INFO    ][12567] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'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'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'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'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'rtkfss', 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-06-03 02:52:15,190 [salt.state       :300 ][INFO    ][12567] {'new': 'Vlan untagged was updated'}
2019-06-03 02:52:15,191 [salt.state       :1951][INFO    ][12567] Completed state [vlan 0] at time 02:52:15.190914 duration_in_ms=531.09
2019-06-03 02:52:15,191 [salt.state       :1780][INFO    ][12567] Running state [opnfv] at time 02:52:15.191692
2019-06-03 02:52:15,192 [salt.state       :1813][INFO    ][12567] Executing state maasng.sshkey_present for [opnfv]
2019-06-03 02:52:15,235 [salt.loaded.ext.module.maasng:1903][INFO    ][12567] [{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-06-03 02:52:15,235 [salt.state       :300 ][INFO    ][12567] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-06-03 02:52:15,235 [salt.state       :1951][INFO    ][12567] Completed state [opnfv] at time 02:52:15.235797 duration_in_ms=44.105
2019-06-03 02:52:15,239 [salt.minion      :1711][INFO    ][12567] Returning information for job: 20190603025152419469
2019-06-03 02:52:15,861 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command state.apply with jid 20190603025215840926
2019-06-03 02:52:15,882 [salt.minion      :1432][INFO    ][13045] Starting a new job with PID 13045
2019-06-03 02:52:24,113 [salt.state       :915 ][INFO    ][13045] Loading fresh modules for state activity
2019-06-03 02:52:24,216 [salt.state       :1780][INFO    ][13045] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:52:24.216861
2019-06-03 02:52:24,217 [salt.state       :1813][INFO    ][13045] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-03 02:52:24,220 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13045] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-03 02:52:26,054 [salt.state       :300 ][INFO    ][13045] {'pid': 13071, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-03 02:52:26,055 [salt.state       :1951][INFO    ][13045] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:52:26.055495 duration_in_ms=1838.633
2019-06-03 02:52:26,058 [salt.state       :1780][INFO    ][13045] Running state [maas.process_machines] at time 02:52:26.058630
2019-06-03 02:52:26,058 [salt.state       :1813][INFO    ][13045] Executing state module.run for [maas.process_machines]
2019-06-03 02:52:26,059 [salt.utils.decorators:613 ][WARNING ][13045] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-03 02:52:26,636 [salt.loaded.ext.module.maas:412 ][WARNING ][13045] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-03 02:52:26,637 [salt.loaded.ext.module.maas:92  ][INFO    ][13045] 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=ncmrka architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-03 02:52:27,841 [salt.loaded.ext.module.maas:412 ][WARNING ][13045] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-03 02:52:27,842 [salt.loaded.ext.module.maas:92  ][INFO    ][13045] 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=mgbdqp architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-03 02:52:29,053 [salt.loaded.ext.module.maas:412 ][WARNING ][13045] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-03 02:52:29,055 [salt.loaded.ext.module.maas:92  ][INFO    ][13045] 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=et6k6w architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-03 02:52:30,255 [salt.loaded.ext.module.maas:412 ][WARNING ][13045] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-03 02:52:30,256 [salt.loaded.ext.module.maas:92  ][INFO    ][13045] 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=kc3tnh architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-03 02:52:30,983 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603025230970377
2019-06-03 02:52:31,008 [salt.minion      :1432][INFO    ][13246] Starting a new job with PID 13246
2019-06-03 02:52:31,039 [salt.minion      :1711][INFO    ][13246] Returning information for job: 20190603025230970377
2019-06-03 02:52:31,477 [salt.loaded.ext.module.maas:412 ][WARNING ][13045] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-03 02:52:31,478 [salt.loaded.ext.module.maas:92  ][INFO    ][13045] 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=yhhd6x architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-03 02:52:32,659 [salt.state       :300 ][INFO    ][13045] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-06-03 02:52:32,660 [salt.state       :1951][INFO    ][13045] Completed state [maas.process_machines] at time 02:52:32.660266 duration_in_ms=6601.634
2019-06-03 02:52:32,663 [salt.minion      :1711][INFO    ][13045] Returning information for job: 20190603025215840926
2019-06-03 02:53:05,695 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command state.apply with jid 20190603025305681024
2019-06-03 02:53:05,723 [salt.minion      :1432][INFO    ][13315] Starting a new job with PID 13315
2019-06-03 02:53:13,890 [salt.state       :915 ][INFO    ][13315] Loading fresh modules for state activity
2019-06-03 02:53:13,989 [salt.state       :1780][INFO    ][13315] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:13.989821
2019-06-03 02:53:13,990 [salt.state       :1813][INFO    ][13315] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-03 02:53:13,992 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13315] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-03 02:53:15,760 [salt.state       :300 ][INFO    ][13315] {'pid': 13352, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-03 02:53:15,760 [salt.state       :1951][INFO    ][13315] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:15.760661 duration_in_ms=1770.841
2019-06-03 02:53:15,762 [salt.state       :1780][INFO    ][13315] Running state [maas.wait_for_machine_status] at time 02:53:15.762326
2019-06-03 02:53:15,762 [salt.state       :1813][INFO    ][13315] Executing state module.run for [maas.wait_for_machine_status]
2019-06-03 02:53:15,763 [salt.utils.decorators:613 ][WARNING ][13315] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-03 02:53:18,460 [salt.state       :300 ][INFO    ][13315] {'ret': True}
2019-06-03 02:53:18,460 [salt.state       :1951][INFO    ][13315] Completed state [maas.wait_for_machine_status] at time 02:53:18.460341 duration_in_ms=2698.014
2019-06-03 02:53:18,462 [salt.minion      :1711][INFO    ][13315] Returning information for job: 20190603025305681024
2019-06-03 02:53:19,051 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command state.apply with jid 20190603025318997658
2019-06-03 02:53:19,077 [salt.minion      :1432][INFO    ][13374] Starting a new job with PID 13374
2019-06-03 02:53:20,239 [salt.state       :915 ][INFO    ][13374] Loading fresh modules for state activity
2019-06-03 02:53:20,392 [salt.state       :1780][INFO    ][13374] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:20.392817
2019-06-03 02:53:20,393 [salt.state       :1813][INFO    ][13374] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-03 02:53:20,395 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13374] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-03 02:53:22,084 [salt.state       :300 ][INFO    ][13374] {'pid': 13381, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-03 02:53:22,085 [salt.state       :1951][INFO    ][13374] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:22.085397 duration_in_ms=1692.58
2019-06-03 02:53:22,089 [salt.state       :1780][INFO    ][13374] Running state [maas_machines_storage_cmp002_lvm] at time 02:53:22.089662
2019-06-03 02:53:22,090 [salt.state       :1813][INFO    ][13374] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-06-03 02:53:22,648 [salt.state       :300 ][INFO    ][13374] Machine cmp002 is not in Ready state.
2019-06-03 02:53:22,648 [salt.state       :1951][INFO    ][13374] Completed state [maas_machines_storage_cmp002_lvm] at time 02:53:22.648606 duration_in_ms=558.944
2019-06-03 02:53:22,649 [salt.state       :1780][INFO    ][13374] Running state [maas_machines_storage_cmp001_lvm] at time 02:53:22.649101
2019-06-03 02:53:22,649 [salt.state       :1813][INFO    ][13374] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-06-03 02:53:23,192 [salt.state       :300 ][INFO    ][13374] Machine cmp001 is not in Ready state.
2019-06-03 02:53:23,192 [salt.state       :1951][INFO    ][13374] Completed state [maas_machines_storage_cmp001_lvm] at time 02:53:23.192579 duration_in_ms=543.479
2019-06-03 02:53:23,196 [salt.minion      :1711][INFO    ][13374] Returning information for job: 20190603025318997658
2019-06-03 02:53:23,758 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command state.apply with jid 20190603025323744563
2019-06-03 02:53:23,789 [salt.minion      :1432][INFO    ][13391] Starting a new job with PID 13391
2019-06-03 02:53:24,950 [salt.state       :915 ][INFO    ][13391] Loading fresh modules for state activity
2019-06-03 02:53:25,057 [salt.state       :1780][INFO    ][13391] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:25.057167
2019-06-03 02:53:25,057 [salt.state       :1813][INFO    ][13391] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-03 02:53:25,059 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13391] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-03 02:53:26,787 [salt.state       :300 ][INFO    ][13391] {'pid': 13402, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-03 02:53:26,788 [salt.state       :1951][INFO    ][13391] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:26.788497 duration_in_ms=1731.33
2019-06-03 02:53:26,791 [salt.state       :1780][INFO    ][13391] Running state [maas.deploy_machines] at time 02:53:26.791411
2019-06-03 02:53:26,792 [salt.state       :1813][INFO    ][13391] Executing state module.run for [maas.deploy_machines]
2019-06-03 02:53:26,792 [salt.utils.decorators:613 ][WARNING ][13391] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-03 02:53:27,360 [salt.loaded.ext.module.maas:684 ][INFO    ][13391] deploymachines hwe_kernel=ga-16.04 system_id=yhhd6x distro_series=xenial
2019-06-03 02:53:29,805 [salt.state       :300 ][INFO    ][13391] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-06-03 02:53:29,806 [salt.state       :1951][INFO    ][13391] Completed state [maas.deploy_machines] at time 02:53:29.806667 duration_in_ms=3015.255
2019-06-03 02:53:29,811 [salt.minion      :1711][INFO    ][13391] Returning information for job: 20190603025323744563
2019-06-03 02:53:30,405 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command state.apply with jid 20190603025330390121
2019-06-03 02:53:30,433 [salt.minion      :1432][INFO    ][13464] Starting a new job with PID 13464
2019-06-03 02:53:38,515 [salt.state       :915 ][INFO    ][13464] Loading fresh modules for state activity
2019-06-03 02:53:38,625 [salt.state       :1780][INFO    ][13464] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:38.625373
2019-06-03 02:53:38,626 [salt.state       :1813][INFO    ][13464] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-03 02:53:38,628 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13464] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-03 02:53:40,349 [salt.state       :300 ][INFO    ][13464] {'pid': 13475, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-03 02:53:40,350 [salt.state       :1951][INFO    ][13464] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:40.350174 duration_in_ms=1724.8
2019-06-03 02:53:40,353 [salt.state       :1780][INFO    ][13464] Running state [maas.wait_for_machine_status] at time 02:53:40.353300
2019-06-03 02:53:40,354 [salt.state       :1813][INFO    ][13464] Executing state module.run for [maas.wait_for_machine_status]
2019-06-03 02:53:40,355 [salt.utils.decorators:613 ][WARNING ][13464] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-03 02:53:43,159 [salt.loaded.ext.module.maas:1023][INFO    ][13464] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.2165339s left)
2019-06-03 02:53:45,489 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603025345474436
2019-06-03 02:53:45,519 [salt.minion      :1432][INFO    ][13491] Starting a new job with PID 13491
2019-06-03 02:53:45,545 [salt.minion      :1711][INFO    ][13491] Returning information for job: 20190603025345474436
2019-06-03 02:54:15,584 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603025415574614
2019-06-03 02:54:15,606 [salt.minion      :1432][INFO    ][13545] Starting a new job with PID 13545
2019-06-03 02:54:15,630 [salt.minion      :1711][INFO    ][13545] Returning information for job: 20190603025415574614
2019-06-03 02:54:15,921 [salt.loaded.ext.module.maas:1023][INFO    ][13464] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.45415807s left)
2019-06-03 02:54:45,664 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603025445648459
2019-06-03 02:54:45,693 [salt.minion      :1432][INFO    ][13562] Starting a new job with PID 13562
2019-06-03 02:54:45,715 [salt.minion      :1711][INFO    ][13562] Returning information for job: 20190603025445648459
2019-06-03 02:54:48,880 [salt.loaded.ext.module.maas:1023][INFO    ][13464] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2181.49546885s left)
2019-06-03 02:55:15,747 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603025515734901
2019-06-03 02:55:15,778 [salt.minion      :1432][INFO    ][13635] Starting a new job with PID 13635
2019-06-03 02:55:15,800 [salt.minion      :1711][INFO    ][13635] Returning information for job: 20190603025515734901
2019-06-03 02:55:21,618 [salt.loaded.ext.module.maas:1023][INFO    ][13464] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2148.75727105s left)
2019-06-03 02:55:45,835 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603025545820549
2019-06-03 02:55:45,864 [salt.minion      :1432][INFO    ][13660] Starting a new job with PID 13660
2019-06-03 02:55:45,886 [salt.minion      :1711][INFO    ][13660] Returning information for job: 20190603025545820549
2019-06-03 02:55:54,511 [salt.loaded.ext.module.maas:1023][INFO    ][13464] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2115.86431503s left)
2019-06-03 02:56:15,930 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603025615915477
2019-06-03 02:56:15,957 [salt.minion      :1432][INFO    ][13720] Starting a new job with PID 13720
2019-06-03 02:56:15,980 [salt.minion      :1711][INFO    ][13720] Returning information for job: 20190603025615915477
2019-06-03 02:56:27,348 [salt.loaded.ext.module.maas:1023][INFO    ][13464] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2083.02700496s left)
2019-06-03 02:56:46,013 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603025645990976
2019-06-03 02:56:46,042 [salt.minion      :1432][INFO    ][13741] Starting a new job with PID 13741
2019-06-03 02:56:46,065 [salt.minion      :1711][INFO    ][13741] Returning information for job: 20190603025645990976
2019-06-03 02:57:00,186 [salt.loaded.ext.module.maas:1023][INFO    ][13464] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2050.18943286s left)
2019-06-03 02:57:16,122 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603025716107239
2019-06-03 02:57:16,148 [salt.minion      :1432][INFO    ][13795] Starting a new job with PID 13795
2019-06-03 02:57:16,171 [salt.minion      :1711][INFO    ][13795] Returning information for job: 20190603025716107239
2019-06-03 02:57:32,887 [salt.loaded.ext.module.maas:1023][INFO    ][13464] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2017.48859906s left)
2019-06-03 02:57:46,216 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603025746205285
2019-06-03 02:57:46,241 [salt.minion      :1432][INFO    ][13843] Starting a new job with PID 13843
2019-06-03 02:57:46,261 [salt.minion      :1711][INFO    ][13843] Returning information for job: 20190603025746205285
2019-06-03 02:58:06,109 [salt.loaded.ext.module.maas:1023][INFO    ][13464] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1984.26654196s left)
2019-06-03 02:58:16,317 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603025816304482
2019-06-03 02:58:16,345 [salt.minion      :1432][INFO    ][13966] Starting a new job with PID 13966
2019-06-03 02:58:16,369 [salt.minion      :1711][INFO    ][13966] Returning information for job: 20190603025816304482
2019-06-03 02:58:39,199 [salt.loaded.ext.module.maas:1023][INFO    ][13464] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1951.17588186s left)
2019-06-03 02:58:46,412 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603025846399946
2019-06-03 02:58:46,436 [salt.minion      :1432][INFO    ][13992] Starting a new job with PID 13992
2019-06-03 02:58:46,460 [salt.minion      :1711][INFO    ][13992] Returning information for job: 20190603025846399946
2019-06-03 02:59:12,576 [salt.loaded.ext.module.maas:1023][INFO    ][13464] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1917.79914594s left)
2019-06-03 02:59:16,532 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603025916520320
2019-06-03 02:59:16,564 [salt.minion      :1432][INFO    ][14157] Starting a new job with PID 14157
2019-06-03 02:59:16,590 [salt.minion      :1711][INFO    ][14157] Returning information for job: 20190603025916520320
2019-06-03 02:59:45,550 [salt.loaded.ext.module.maas:1023][INFO    ][13464] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1884.8260169s left)
2019-06-03 02:59:46,660 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603025946645608
2019-06-03 02:59:46,689 [salt.minion      :1432][INFO    ][14180] Starting a new job with PID 14180
2019-06-03 02:59:46,712 [salt.minion      :1711][INFO    ][14180] Returning information for job: 20190603025946645608
2019-06-03 03:00:16,779 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603030016769609
2019-06-03 03:00:16,800 [salt.minion      :1432][INFO    ][14318] Starting a new job with PID 14318
2019-06-03 03:00:16,828 [salt.minion      :1711][INFO    ][14318] Returning information for job: 20190603030016769609
2019-06-03 03:00:18,436 [salt.loaded.ext.module.maas:1023][INFO    ][13464] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1851.93949294s left)
2019-06-03 03:00:46,920 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603030046903616
2019-06-03 03:00:46,951 [salt.minion      :1432][INFO    ][14336] Starting a new job with PID 14336
2019-06-03 03:00:46,977 [salt.minion      :1711][INFO    ][14336] Returning information for job: 20190603030046903616
2019-06-03 03:00:51,425 [salt.loaded.ext.module.maas:1023][INFO    ][13464] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1818.95065498s left)
2019-06-03 03:01:17,083 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603030117066566
2019-06-03 03:01:17,112 [salt.minion      :1432][INFO    ][14469] Starting a new job with PID 14469
2019-06-03 03:01:17,136 [salt.minion      :1711][INFO    ][14469] Returning information for job: 20190603030117066566
2019-06-03 03:01:24,486 [salt.loaded.ext.module.maas:1023][INFO    ][13464] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1785.88958192s left)
2019-06-03 03:01:47,239 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603030147221503
2019-06-03 03:01:47,267 [salt.minion      :1432][INFO    ][14491] Starting a new job with PID 14491
2019-06-03 03:01:47,292 [salt.minion      :1711][INFO    ][14491] Returning information for job: 20190603030147221503
2019-06-03 03:01:57,368 [salt.loaded.ext.module.maas:1023][INFO    ][13464] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1753.00699592s left)
2019-06-03 03:02:17,397 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603030217382696
2019-06-03 03:02:17,428 [salt.minion      :1432][INFO    ][14582] Starting a new job with PID 14582
2019-06-03 03:02:17,449 [salt.minion      :1711][INFO    ][14582] Returning information for job: 20190603030217382696
2019-06-03 03:02:30,227 [salt.loaded.ext.module.maas:1023][INFO    ][13464] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1720.14846492s left)
2019-06-03 03:02:47,565 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603030247554679
2019-06-03 03:02:47,592 [salt.minion      :1432][INFO    ][14604] Starting a new job with PID 14604
2019-06-03 03:02:47,617 [salt.minion      :1711][INFO    ][14604] Returning information for job: 20190603030247554679
2019-06-03 03:03:03,197 [salt.loaded.ext.module.maas:1023][INFO    ][13464] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1687.17853189s left)
2019-06-03 03:03:17,725 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603030317707068
2019-06-03 03:03:17,753 [salt.minion      :1432][INFO    ][14660] Starting a new job with PID 14660
2019-06-03 03:03:17,776 [salt.minion      :1711][INFO    ][14660] Returning information for job: 20190603030317707068
2019-06-03 03:03:36,130 [salt.loaded.ext.module.maas:1023][INFO    ][13464] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1654.24624395s left)
2019-06-03 03:03:47,896 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603030347883778
2019-06-03 03:03:47,923 [salt.minion      :1432][INFO    ][14679] Starting a new job with PID 14679
2019-06-03 03:03:47,947 [salt.minion      :1711][INFO    ][14679] Returning information for job: 20190603030347883778
2019-06-03 03:04:09,177 [salt.loaded.ext.module.maas:1023][INFO    ][13464] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1621.19851303s left)
2019-06-03 03:04:18,056 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603030418042816
2019-06-03 03:04:18,085 [salt.minion      :1432][INFO    ][14737] Starting a new job with PID 14737
2019-06-03 03:04:18,107 [salt.minion      :1711][INFO    ][14737] Returning information for job: 20190603030418042816
2019-06-03 03:04:42,178 [salt.loaded.ext.module.maas:1023][INFO    ][13464] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1588.19702387s left)
2019-06-03 03:04:48,232 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603030448215993
2019-06-03 03:04:48,264 [salt.minion      :1432][INFO    ][14758] Starting a new job with PID 14758
2019-06-03 03:04:48,286 [salt.minion      :1711][INFO    ][14758] Returning information for job: 20190603030448215993
2019-06-03 03:05:02,773 [salt.utils.schedule:1377][INFO    ][2983] Running scheduled job: __mine_interval
2019-06-03 03:05:16,319 [salt.loaded.ext.module.maas:1023][INFO    ][13464] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1554.05633092s left)
2019-06-03 03:05:18,412 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603030518400687
2019-06-03 03:05:18,431 [salt.minion      :1432][INFO    ][14859] Starting a new job with PID 14859
2019-06-03 03:05:18,454 [salt.minion      :1711][INFO    ][14859] Returning information for job: 20190603030518400687
2019-06-03 03:05:48,587 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command saltutil.find_job with jid 20190603030548575134
2019-06-03 03:05:48,609 [salt.minion      :1432][INFO    ][15049] Starting a new job with PID 15049
2019-06-03 03:05:48,632 [salt.minion      :1711][INFO    ][15049] Returning information for job: 20190603030548575134
2019-06-03 03:05:49,228 [salt.state       :300 ][INFO    ][13464] {'ret': True}
2019-06-03 03:05:49,228 [salt.state       :1951][INFO    ][13464] Completed state [maas.wait_for_machine_status] at time 03:05:49.228827 duration_in_ms=728875.525
2019-06-03 03:05:49,233 [salt.minion      :1711][INFO    ][13464] Returning information for job: 20190603025330390121
2019-06-03 04:05:02,774 [salt.utils.schedule:1377][INFO    ][2983] Running scheduled job: __mine_interval
2019-06-03 04:41:42,633 [salt.minion      :1308][INFO    ][2983] User sudo_ubuntu Executing command cp.push_dir with jid 20190603044142617360
2019-06-03 04:41:42,663 [salt.minion      :1432][INFO    ][21740] Starting a new job with PID 21740
