2019-07-12 02:04:49,537 [salt.utils.decorators:613 ][WARNING ][1980] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-12 02:04:50,471 [salt.utils.decorators:613 ][WARNING ][1980] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-12 02:04:53,355 [salt.loaded.int.states.file:2298][WARNING ][2437] 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-07-12 02:05:16,146 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2961] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-07-12 02:05:16,168 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2961] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-07-12 02:05:16,391 [salt.utils.parsers:1051][WARNING ][378] Minion received a SIGTERM. Exiting.
2019-07-12 02:05:17,377 [salt.cli.daemons :293 ][INFO    ][3072] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-07-12 02:05:17,491 [salt.cli.daemons :82  ][INFO    ][3072] Starting up the Salt Minion
2019-07-12 02:05:17,491 [salt.utils.event :1017][INFO    ][3072] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-07-12 02:05:18,512 [salt.minion      :976 ][INFO    ][3072] Creating minion process manager
2019-07-12 02:05:20,338 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][3072] Executing command ['date', '+%z'] in directory '/root'
2019-07-12 02:05:20,357 [salt.utils.schedule:568 ][INFO    ][3072] Updating job settings for scheduled job: __mine_interval
2019-07-12 02:05:20,359 [salt.minion      :1108][INFO    ][3072] Added mine.update to scheduler
2019-07-12 02:05:20,363 [salt.minion      :1975][INFO    ][3072] Minion is starting as user 'root'
2019-07-12 02:05:20,375 [salt.minion      :2336][INFO    ][3072] Minion is ready to receive requests!
2019-07-12 02:05:24,713 [salt.state       :2022][WARNING ][2967] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-07-12 02:05:27,800 [salt.utils.decorators:613 ][WARNING ][2967] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-12 02:05:29,288 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712020529273275
2019-07-12 02:05:29,305 [salt.minion      :1432][INFO    ][3474] Starting a new job with PID 3474
2019-07-12 02:05:29,328 [salt.minion      :1711][INFO    ][3474] Returning information for job: 20190712020529273275
2019-07-12 02:05:59,372 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712020559358811
2019-07-12 02:05:59,393 [salt.minion      :1432][INFO    ][3714] Starting a new job with PID 3714
2019-07-12 02:05:59,417 [salt.minion      :1711][INFO    ][3714] Returning information for job: 20190712020559358811
2019-07-12 02:06:29,455 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712020629445619
2019-07-12 02:06:29,470 [salt.minion      :1432][INFO    ][4020] Starting a new job with PID 4020
2019-07-12 02:06:29,491 [salt.minion      :1711][INFO    ][4020] Returning information for job: 20190712020629445619
2019-07-12 02:06:40,289 [salt.utils.decorators:613 ][WARNING ][2967] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-12 02:06:59,501 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712020659489333
2019-07-12 02:06:59,521 [salt.minion      :1432][INFO    ][4117] Starting a new job with PID 4117
2019-07-12 02:06:59,543 [salt.minion      :1711][INFO    ][4117] Returning information for job: 20190712020659489333
2019-07-12 02:07:07,252 [salt.utils.decorators:613 ][WARNING ][2967] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-12 02:07:29,528 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712020729516116
2019-07-12 02:07:29,551 [salt.minion      :1432][INFO    ][4236] Starting a new job with PID 4236
2019-07-12 02:07:29,573 [salt.minion      :1711][INFO    ][4236] Returning information for job: 20190712020729516116
2019-07-12 02:07:34,047 [salt.utils.decorators:613 ][WARNING ][2967] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-12 02:07:36,073 [salt.loaded.ext.module.maasng:1008][WARNING ][2967] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-07-12 02:07:36,073 [salt.loaded.ext.module.maasng:1011][WARNING ][2967] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-07-12 02:07:36,733 [salt.loaded.ext.module.maasng:1235][WARNING ][2967] Ignoring parameter vlan:0
2019-07-12 02:07:38,323 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command state.apply with jid 20190712020738308626
2019-07-12 02:07:38,345 [salt.minion      :1432][INFO    ][4445] Starting a new job with PID 4445
2019-07-12 02:07:44,472 [salt.state       :915 ][INFO    ][4445] Loading fresh modules for state activity
2019-07-12 02:07:44,538 [salt.fileclient  :1219][INFO    ][4445] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-07-12 02:07:44,583 [salt.state       :1780][INFO    ][4445] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:07:44.583579
2019-07-12 02:07:44,583 [salt.state       :1813][INFO    ][4445] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-12 02:07:44,586 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4445] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-12 02:07:46,338 [salt.state       :300 ][INFO    ][4445] {'pid': 4476, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-12 02:07:46,339 [salt.state       :1951][INFO    ][4445] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:07:46.339518 duration_in_ms=1755.938
2019-07-12 02:07:46,342 [salt.state       :1780][INFO    ][4445] Running state [maas.process_machines] at time 02:07:46.342528
2019-07-12 02:07:46,343 [salt.state       :1813][INFO    ][4445] Executing state module.run for [maas.process_machines]
2019-07-12 02:07:46,344 [salt.utils.decorators:613 ][WARNING ][4445] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-12 02:07:46,402 [salt.loaded.ext.module.maas:412 ][WARNING ][4445] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-12 02:07:46,403 [salt.loaded.ext.module.maas:92  ][INFO    ][4445] 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-07-12 02:07:47,797 [salt.loaded.ext.module.maas:412 ][WARNING ][4445] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-12 02:07:47,797 [salt.loaded.ext.module.maas:92  ][INFO    ][4445] 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-07-12 02:07:49,123 [salt.loaded.ext.module.maas:412 ][WARNING ][4445] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-12 02:07:49,123 [salt.loaded.ext.module.maas:92  ][INFO    ][4445] 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-07-12 02:07:50,460 [salt.loaded.ext.module.maas:412 ][WARNING ][4445] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-12 02:07:50,461 [salt.loaded.ext.module.maas:92  ][INFO    ][4445] 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-07-12 02:07:51,800 [salt.loaded.ext.module.maas:412 ][WARNING ][4445] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-12 02:07:51,800 [salt.loaded.ext.module.maas:92  ][INFO    ][4445] 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-07-12 02:07:53,170 [salt.state       :300 ][INFO    ][4445] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-07-12 02:07:53,171 [salt.state       :1951][INFO    ][4445] Completed state [maas.process_machines] at time 02:07:53.171165 duration_in_ms=6828.637
2019-07-12 02:07:53,176 [salt.minion      :1711][INFO    ][4445] Returning information for job: 20190712020738308626
2019-07-12 02:08:24,231 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command state.apply with jid 20190712020824222228
2019-07-12 02:08:24,250 [salt.minion      :1432][INFO    ][4794] Starting a new job with PID 4794
2019-07-12 02:08:30,277 [salt.state       :915 ][INFO    ][4794] Loading fresh modules for state activity
2019-07-12 02:08:30,332 [salt.fileclient  :1219][INFO    ][4794] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-07-12 02:08:30,378 [salt.state       :1780][INFO    ][4794] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:08:30.378458
2019-07-12 02:08:30,378 [salt.state       :1813][INFO    ][4794] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-12 02:08:30,380 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4794] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-12 02:08:32,079 [salt.state       :300 ][INFO    ][4794] {'pid': 4813, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-12 02:08:32,081 [salt.state       :1951][INFO    ][4794] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:08:32.080843 duration_in_ms=1702.384
2019-07-12 02:08:32,084 [salt.state       :1780][INFO    ][4794] Running state [maas.wait_for_machine_status] at time 02:08:32.084046
2019-07-12 02:08:32,084 [salt.state       :1813][INFO    ][4794] Executing state module.run for [maas.wait_for_machine_status]
2019-07-12 02:08:32,085 [salt.utils.decorators:613 ][WARNING ][4794] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-12 02:08:32,978 [salt.loaded.ext.module.maas:1023][INFO    ][4794] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.11958194s left)
2019-07-12 02:08:39,348 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712020839340791
2019-07-12 02:08:39,368 [salt.minion      :1432][INFO    ][4824] Starting a new job with PID 4824
2019-07-12 02:08:39,390 [salt.minion      :1711][INFO    ][4824] Returning information for job: 20190712020839340791
2019-07-12 02:09:03,809 [salt.loaded.ext.module.maas:1023][INFO    ][4794] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.28843093s left)
2019-07-12 02:09:09,406 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712020909397518
2019-07-12 02:09:09,428 [salt.minion      :1432][INFO    ][4843] Starting a new job with PID 4843
2019-07-12 02:09:09,449 [salt.minion      :1711][INFO    ][4843] Returning information for job: 20190712020909397518
2019-07-12 02:09:34,683 [salt.loaded.ext.module.maas:1023][INFO    ][4794] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.41412497s left)
2019-07-12 02:09:39,480 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712020939466464
2019-07-12 02:09:39,512 [salt.minion      :1432][INFO    ][4898] Starting a new job with PID 4898
2019-07-12 02:09:39,535 [salt.minion      :1711][INFO    ][4898] Returning information for job: 20190712020939466464
2019-07-12 02:10:05,532 [salt.loaded.ext.module.maas:1023][INFO    ][4794] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.56574297s left)
2019-07-12 02:10:09,562 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712021009546135
2019-07-12 02:10:09,588 [salt.minion      :1432][INFO    ][4919] Starting a new job with PID 4919
2019-07-12 02:10:09,613 [salt.minion      :1711][INFO    ][4919] Returning information for job: 20190712021009546135
2019-07-12 02:10:36,661 [salt.loaded.ext.module.maas:1023][INFO    ][4794] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.43622899s left)
2019-07-12 02:10:39,660 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712021039646711
2019-07-12 02:10:39,688 [salt.minion      :1432][INFO    ][5022] Starting a new job with PID 5022
2019-07-12 02:10:39,714 [salt.minion      :1711][INFO    ][5022] Returning information for job: 20190712021039646711
2019-07-12 02:11:07,836 [salt.loaded.ext.module.maas:1023][INFO    ][4794] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.26097393s left)
2019-07-12 02:11:09,749 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712021109735348
2019-07-12 02:11:09,775 [salt.minion      :1432][INFO    ][5118] Starting a new job with PID 5118
2019-07-12 02:11:09,797 [salt.minion      :1711][INFO    ][5118] Returning information for job: 20190712021109735348
2019-07-12 02:11:39,211 [salt.loaded.ext.module.maas:1023][INFO    ][4794] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1312.88674188s left)
2019-07-12 02:11:39,874 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712021139858145
2019-07-12 02:11:39,900 [salt.minion      :1432][INFO    ][5388] Starting a new job with PID 5388
2019-07-12 02:11:39,919 [salt.minion      :1711][INFO    ][5388] Returning information for job: 20190712021139858145
2019-07-12 02:12:09,948 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712021209936945
2019-07-12 02:12:09,960 [salt.minion      :1432][INFO    ][5535] Starting a new job with PID 5535
2019-07-12 02:12:09,983 [salt.minion      :1711][INFO    ][5535] Returning information for job: 20190712021209936945
2019-07-12 02:12:10,928 [salt.loaded.ext.module.maas:1023][INFO    ][4794] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1281.16919303s left)
2019-07-12 02:12:40,061 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712021240047348
2019-07-12 02:12:40,090 [salt.minion      :1432][INFO    ][5719] Starting a new job with PID 5719
2019-07-12 02:12:40,113 [salt.minion      :1711][INFO    ][5719] Returning information for job: 20190712021240047348
2019-07-12 02:12:42,771 [salt.loaded.ext.module.maas:1023][INFO    ][4794] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1249.32603002s left)
2019-07-12 02:13:10,187 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712021310173606
2019-07-12 02:13:10,217 [salt.minion      :1432][INFO    ][5888] Starting a new job with PID 5888
2019-07-12 02:13:10,239 [salt.minion      :1711][INFO    ][5888] Returning information for job: 20190712021310173606
2019-07-12 02:13:15,425 [salt.loaded.ext.module.maas:1023][INFO    ][4794] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1216.67232394s left)
2019-07-12 02:13:40,356 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712021340340186
2019-07-12 02:13:40,384 [salt.minion      :1432][INFO    ][6274] Starting a new job with PID 6274
2019-07-12 02:13:40,408 [salt.minion      :1711][INFO    ][6274] Returning information for job: 20190712021340340186
2019-07-12 02:13:47,599 [salt.loaded.ext.module.maas:1023][INFO    ][4794] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1184.49849391s left)
2019-07-12 02:14:10,461 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712021410454310
2019-07-12 02:14:10,484 [salt.minion      :1432][INFO    ][6389] Starting a new job with PID 6389
2019-07-12 02:14:10,505 [salt.minion      :1711][INFO    ][6389] Returning information for job: 20190712021410454310
2019-07-12 02:14:20,695 [salt.state       :300 ][INFO    ][4794] {'ret': True}
2019-07-12 02:14:20,696 [salt.state       :1951][INFO    ][4794] Completed state [maas.wait_for_machine_status] at time 02:14:20.696182 duration_in_ms=348612.135
2019-07-12 02:14:20,700 [salt.minion      :1711][INFO    ][4794] Returning information for job: 20190712020824222228
2019-07-12 02:14:21,303 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command state.apply with jid 20190712021421287373
2019-07-12 02:14:21,328 [salt.minion      :1432][INFO    ][6415] Starting a new job with PID 6415
2019-07-12 02:14:27,508 [salt.state       :915 ][INFO    ][6415] Loading fresh modules for state activity
2019-07-12 02:14:27,572 [salt.fileclient  :1219][INFO    ][6415] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-07-12 02:14:27,684 [salt.state       :1780][INFO    ][6415] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:14:27.684175
2019-07-12 02:14:27,684 [salt.state       :1813][INFO    ][6415] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-12 02:14:27,686 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6415] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-12 02:14:29,473 [salt.state       :300 ][INFO    ][6415] {'pid': 6459, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-12 02:14:29,475 [salt.state       :1951][INFO    ][6415] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:14:29.475764 duration_in_ms=1791.588
2019-07-12 02:14:29,480 [salt.state       :1780][INFO    ][6415] Running state [maas_machines_storage_cmp002_lvm] at time 02:14:29.480478
2019-07-12 02:14:29,481 [salt.state       :1813][INFO    ][6415] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-07-12 02:14:30,611 [salt.loaded.ext.module.maasng:610 ][INFO    ][6415] t4dm36
2019-07-12 02:14:30,612 [salt.loaded.ext.module.maasng:626 ][INFO    ][6415] sda
2019-07-12 02:14:31,215 [salt.loaded.ext.module.maasng:361 ][INFO    ][6415] t4dm36
2019-07-12 02:14:31,313 [salt.loaded.ext.module.maasng:367 ][INFO    ][6415] [{u'model': u'LOGICAL VOLUME', u'available_size': 0, u'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/t4dm36/blockdevices/1/', u'used_size': 800106479616, u'partitions': [{u'uuid': u'03def414-e78e-4462-bf4c-73d101b113b7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u't4dm36', u'device_id': 1, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'232d3471-c5b1-46ba-9f8f-63e3f91e18b9', u'fstype': u'lvm-pv', u'label': 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/t4dm36/blockdevices/1/partition/1'}], u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u't4dm36', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'type': u'physical', u'id': 1, u'size': 800109715456}, {u'model': None, u'available_size': 0, u'uuid': u'7153c931-28e3-48e2-8500-3cbea1ff2780', u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/t4dm36/blockdevices/3/', u'used_size': 800097042432, u'partitions': [], u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u't4dm36', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'c37a812b-3f82-4fe7-841e-72a30032bb75', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 3, u'size': 800097042432}]
2019-07-12 02:14:31,314 [salt.loaded.ext.module.maasng:632 ][INFO    ][6415] vgroot
2019-07-12 02:14:31,315 [salt.loaded.ext.module.maasng:635 ][INFO    ][6415] lvroot
2019-07-12 02:14:31,315 [salt.loaded.ext.module.maasng:639 ][INFO    ][6415] 107374182400
2019-07-12 02:14:31,907 [salt.loaded.ext.module.maasng:645 ][INFO    ][6415] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'testing_status_name': u'Passed', u'boot_interface': {u'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'wxar8s', 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'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'wxar8s', 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'id': 18, u'mode': u'dhcp'}], 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'wxar8s', 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'children': [], u'effective_mtu': 1500, u'parents': [], u'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'system_id': u't4dm36', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/t4dm36/interfaces/4/'}, u'disable_ipv4': False, u'cpu_count': 40, u'owner_data': {}, u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'status_action': u'', u'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default'}, u'resource_uri': u'/MAAS/api/2.0/machines/t4dm36/', u'hostname': u'cmp002', u'storage': 800109.715456, u'commissioning_status': 2, u'testing_status': 2, u'system_id': u't4dm36', 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/t4dm36/blockdevices/11/', u'available_size': 0, u'uuid': u'aadfd2b0-068b-423c-abc8-5df63cce319a', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u't4dm36', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'f807dab5-b109-4141-8525-e6cac78d546f', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 11, u'size': 107374182400}], u'blockdevice_set': [{u'model': u'LOGICAL VOLUME', u'available_size': 0, u'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/t4dm36/blockdevices/1/', u'used_size': 800106479616, u'partitions': [{u'uuid': u'8967142e-7fdc-45ed-9450-d5e5a7f417bb', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u't4dm36', u'device_id': 1, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'758177a5-a3e9-4153-a758-6139e529ec9c', u'fstype': u'lvm-pv', u'label': 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/t4dm36/blockdevices/1/partition/6'}], u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u't4dm36', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'type': u'physical', u'id': 1, u'size': 800109715456}, {u'model': None, u'available_size': 0, u'uuid': u'aadfd2b0-068b-423c-abc8-5df63cce319a', u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/t4dm36/blockdevices/11/', u'used_size': 107374182400, u'partitions': [], u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u't4dm36', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'f807dab5-b109-4141-8525-e6cac78d546f', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 11, u'size': 107374182400}], u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'raids': [], u'power_type': u'ipmi', u'physicalblockdevice_set': [{u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/t4dm36/blockdevices/1/', u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'8967142e-7fdc-45ed-9450-d5e5a7f417bb', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u't4dm36', u'device_id': 1, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'758177a5-a3e9-4153-a758-6139e529ec9c', u'fstype': u'lvm-pv', u'label': 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/t4dm36/blockdevices/1/partition/6'}], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u't4dm36', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'type': u'physical', u'id': 1, u'size': 800109715456}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u't4dm36', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'node_type_name': u'Machine', u'interface_set': [{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'wxar8s', 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'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'wxar8s', 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'id': 18, u'mode': u'dhcp'}], 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'wxar8s', 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'children': [], u'effective_mtu': 1500, u'parents': [], u'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'system_id': u't4dm36', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/t4dm36/interfaces/4/'}, {u'discovered': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'parents': [], u'mac_address': u'38:ea:a7:8f:07:51', u'params': u'', u'system_id': u't4dm36', u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/t4dm36/interfaces/14/'}, {u'discovered': None, u'name': u'ens2f0', u'links': [{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': 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'children': [], u'effective_mtu': 1500, u'parents': [], u'mac_address': u'38:ea:a7:8f:12:48', u'params': u'', u'system_id': u't4dm36', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/t4dm36/interfaces/13/'}, {u'discovered': None, u'name': u'ens2f1', 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'children': [], u'effective_mtu': 1500, u'parents': [], u'mac_address': u'38:ea:a7:8f:12:49', u'params': u'', u'system_id': u't4dm36', u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/t4dm36/interfaces/15/'}, {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'wxar8s', 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'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'wxar8s', 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': 21, 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'wxar8s', 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'children': [], u'effective_mtu': 1500, u'parents': [], u'mac_address': u'9c:b6:54:8a:10:1c', u'params': u'', u'system_id': u't4dm36', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/t4dm36/interfaces/11/'}, {u'discovered': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'parents': [], u'mac_address': u'38:ea:a7:8f:07:50', u'params': u'', u'system_id': u't4dm36', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/t4dm36/interfaces/12/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'bcaches': [], u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'min_hwe_kernel': u'ga-16.04', u'commissioning_status_name': u'Passed', u'boot_disk': {u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/t4dm36/blockdevices/1/', u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'8967142e-7fdc-45ed-9450-d5e5a7f417bb', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u't4dm36', u'device_id': 1, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'758177a5-a3e9-4153-a758-6139e529ec9c', u'fstype': u'lvm-pv', u'label': 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/t4dm36/blockdevices/1/partition/6'}], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u't4dm36', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'type': u'physical', u'id': 1, u'size': 800109715456}, u'current_commissioning_result_id': 2, u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-07-12 02:14:31,908 [salt.state       :300 ][INFO    ][6415] {'new': {'storage_layout': 'lvm'}}
2019-07-12 02:14:31,908 [salt.state       :1951][INFO    ][6415] Completed state [maas_machines_storage_cmp002_lvm] at time 02:14:31.908830 duration_in_ms=2428.352
2019-07-12 02:14:31,909 [salt.state       :1780][INFO    ][6415] Running state [maas_machines_storage_cmp001_lvm] at time 02:14:31.909196
2019-07-12 02:14:31,909 [salt.state       :1813][INFO    ][6415] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-07-12 02:14:33,071 [salt.loaded.ext.module.maasng:610 ][INFO    ][6415] a6bdpg
2019-07-12 02:14:33,072 [salt.loaded.ext.module.maasng:626 ][INFO    ][6415] sda
2019-07-12 02:14:33,663 [salt.loaded.ext.module.maasng:361 ][INFO    ][6415] a6bdpg
2019-07-12 02:14:33,751 [salt.loaded.ext.module.maasng:367 ][INFO    ][6415] [{u'size': 800109715456, u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 2, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'a6bdpg', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'29dcbaef-5b62-4123-ad07-1519b250d3e5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'a6bdpg', u'device_id': 2, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'2877f528-16eb-4e99-adec-3b89bc619677', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/a6bdpg/blockdevices/2/partition/2'}], u'type': u'physical', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/a6bdpg/blockdevices/2/'}, {u'size': 800097042432, u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'id': 4, u'uuid': u'f92255f3-42de-4992-b330-40ebc232074c', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'a6bdpg', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'a6771633-f4a5-46cb-a6c6-aee2459c226e', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'partitions': [], u'type': u'virtual', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/a6bdpg/blockdevices/4/'}]
2019-07-12 02:14:33,752 [salt.loaded.ext.module.maasng:632 ][INFO    ][6415] vgroot
2019-07-12 02:14:33,752 [salt.loaded.ext.module.maasng:635 ][INFO    ][6415] lvroot
2019-07-12 02:14:33,753 [salt.loaded.ext.module.maasng:639 ][INFO    ][6415] 107374182400
2019-07-12 02:14:34,370 [salt.loaded.ext.module.maasng:645 ][INFO    ][6415] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'memory_test_status': -1, 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wxar8s', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wxar8s', u'name': u'untagged', 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.39', u'mode': u'dhcp', u'id': 25}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wxar8s', u'name': u'untagged', 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.39'}], u'system_id': u'a6bdpg', u'mac_address': u'9c:b6:54:8a:95:a0', u'id': 5, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/a6bdpg/interfaces/5/'}, u'ip_addresses': [u'192.168.11.39', u'192.168.11.41'], u'cpu_count': 40, u'owner_data': {}, 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'memory_test_status_name': u'Unknown', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'blockdevice_set': [{u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/a6bdpg/blockdevices/2/', u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'id': 2, u'name': u'sda', u'system_id': u'a6bdpg', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'type': u'physical', u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'd544340d-b7eb-4bfd-8302-f0005e7a4cd2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'a6bdpg', u'device_id': 2, u'filesystem': {u'uuid': u'33abda5b-aea0-46ec-b375-08a74029dbcb', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/a6bdpg/blockdevices/2/partition/7'}]}, {u'size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/a6bdpg/blockdevices/12/', u'available_size': 0, u'uuid': u'dcc5e966-dc38-4cc1-b5af-8aae677a3715', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'id': 12, u'name': u'vgroot-lvroot', u'system_id': u'a6bdpg', u'partition_table_type': None, u'filesystem': {u'uuid': u'b7c3be53-f1a0-46c7-9072-916f4415438d', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'model': None, u'partitions': []}], 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/a6bdpg/', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'a6bdpg', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'disable_ipv4': False, u'architecture': u'amd64/generic', u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'power_type': u'ipmi', u'physicalblockdevice_set': [{u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/a6bdpg/blockdevices/2/', u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'id': 2, u'name': u'sda', u'system_id': u'a6bdpg', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'type': u'physical', u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'd544340d-b7eb-4bfd-8302-f0005e7a4cd2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'a6bdpg', u'device_id': 2, u'filesystem': {u'uuid': u'33abda5b-aea0-46ec-b375-08a74029dbcb', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/a6bdpg/blockdevices/2/partition/7'}]}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'a6bdpg', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'node_type_name': u'Machine', u'current_testing_result_id': 5, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'node_type': 0, u'virtualblockdevice_set': [{u'size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/a6bdpg/blockdevices/12/', u'available_size': 0, u'uuid': u'dcc5e966-dc38-4cc1-b5af-8aae677a3715', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'id': 12, u'name': u'vgroot-lvroot', u'system_id': u'a6bdpg', u'partition_table_type': None, u'filesystem': {u'uuid': u'b7c3be53-f1a0-46c7-9072-916f4415438d', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'model': None, u'partitions': []}], u'commissioning_status': 2, u'min_hwe_kernel': u'ga-16.04', u'commissioning_status_name': u'Passed', 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wxar8s', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wxar8s', u'name': u'untagged', 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.39', u'mode': u'dhcp', u'id': 25}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wxar8s', u'name': u'untagged', 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.39'}], u'system_id': u'a6bdpg', u'mac_address': u'9c:b6:54:8a:95:a0', u'id': 5, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/a6bdpg/interfaces/5/'}, {u'vlan': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'parents': [], u'discovered': None, u'system_id': u'a6bdpg', u'mac_address': u'38:ea:a7:8f:1f:d4', u'id': 16, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/a6bdpg/interfaces/16/'}, {u'vlan': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'parents': [], u'discovered': None, u'system_id': u'a6bdpg', u'mac_address': u'38:ea:a7:8f:1f:d5', u'id': 18, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/a6bdpg/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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 26}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'parents': [], u'discovered': None, u'system_id': u'a6bdpg', u'mac_address': u'38:ea:a7:8f:52:cc', u'id': 17, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/a6bdpg/interfaces/17/'}, {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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wxar8s', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wxar8s', u'name': u'untagged', 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'mode': u'link_up', u'id': 27}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wxar8s', u'name': u'untagged', 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.41'}], u'system_id': u'a6bdpg', u'mac_address': u'9c:b6:54:8a:95:a4', u'id': 19, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/a6bdpg/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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 28}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'parents': [], u'discovered': None, u'system_id': u'a6bdpg', u'mac_address': u'38:ea:a7:8f:52:cd', u'id': 20, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/a6bdpg/interfaces/20/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'boot_disk': {u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/a6bdpg/blockdevices/2/', u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'id': 2, u'name': u'sda', u'system_id': u'a6bdpg', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'type': u'physical', u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'd544340d-b7eb-4bfd-8302-f0005e7a4cd2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'a6bdpg', u'device_id': 2, u'filesystem': {u'uuid': u'33abda5b-aea0-46ec-b375-08a74029dbcb', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/a6bdpg/blockdevices/2/partition/7'}]}}
2019-07-12 02:14:34,373 [salt.state       :300 ][INFO    ][6415] {'new': {'storage_layout': 'lvm'}}
2019-07-12 02:14:34,374 [salt.state       :1951][INFO    ][6415] Completed state [maas_machines_storage_cmp001_lvm] at time 02:14:34.373671 duration_in_ms=2464.473
2019-07-12 02:14:34,378 [salt.minion      :1711][INFO    ][6415] Returning information for job: 20190712021421287373
2019-07-12 02:14:34,941 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command state.apply with jid 20190712021434928389
2019-07-12 02:14:34,967 [salt.minion      :1432][INFO    ][6494] Starting a new job with PID 6494
2019-07-12 02:14:35,992 [salt.state       :915 ][INFO    ][6494] Loading fresh modules for state activity
2019-07-12 02:14:36,056 [salt.fileclient  :1219][INFO    ][6494] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-07-12 02:14:36,107 [salt.state       :1780][INFO    ][6494] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:14:36.107500
2019-07-12 02:14:36,107 [salt.state       :1813][INFO    ][6494] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-12 02:14:36,109 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6494] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-12 02:14:37,866 [salt.state       :300 ][INFO    ][6494] {'pid': 6501, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-12 02:14:37,867 [salt.state       :1951][INFO    ][6494] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:14:37.867077 duration_in_ms=1759.577
2019-07-12 02:14:37,870 [salt.state       :1780][INFO    ][6494] Running state [maas.deploy_machines] at time 02:14:37.870138
2019-07-12 02:14:37,870 [salt.state       :1813][INFO    ][6494] Executing state module.run for [maas.deploy_machines]
2019-07-12 02:14:37,871 [salt.utils.decorators:613 ][WARNING ][6494] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-12 02:14:38,481 [salt.loaded.ext.module.maas:684 ][INFO    ][6494] deploymachines hwe_kernel=ga-16.04 system_id=t4dm36 distro_series=xenial
2019-07-12 02:14:41,047 [salt.loaded.ext.module.maas:684 ][INFO    ][6494] deploymachines hwe_kernel=ga-16.04 system_id=a6bdpg distro_series=xenial
2019-07-12 02:14:43,603 [salt.loaded.ext.module.maas:684 ][INFO    ][6494] deploymachines hwe_kernel=ga-16.04 system_id=6q63dy distro_series=xenial
2019-07-12 02:14:46,175 [salt.loaded.ext.module.maas:684 ][INFO    ][6494] deploymachines hwe_kernel=ga-16.04 system_id=pbfe3a distro_series=xenial
2019-07-12 02:14:48,671 [salt.loaded.ext.module.maas:684 ][INFO    ][6494] deploymachines hwe_kernel=ga-16.04 system_id=kmwat6 distro_series=xenial
2019-07-12 02:14:50,073 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712021450059489
2019-07-12 02:14:50,100 [salt.minion      :1432][INFO    ][6760] Starting a new job with PID 6760
2019-07-12 02:14:50,124 [salt.minion      :1711][INFO    ][6760] Returning information for job: 20190712021450059489
2019-07-12 02:14:51,293 [salt.state       :300 ][INFO    ][6494] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-07-12 02:14:51,294 [salt.state       :1951][INFO    ][6494] Completed state [maas.deploy_machines] at time 02:14:51.293936 duration_in_ms=13423.797
2019-07-12 02:14:51,297 [salt.minion      :1711][INFO    ][6494] Returning information for job: 20190712021434928389
2019-07-12 02:14:51,862 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command state.apply with jid 20190712021451847104
2019-07-12 02:14:51,888 [salt.minion      :1432][INFO    ][6779] Starting a new job with PID 6779
2019-07-12 02:14:57,965 [salt.state       :915 ][INFO    ][6779] Loading fresh modules for state activity
2019-07-12 02:14:58,027 [salt.fileclient  :1219][INFO    ][6779] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-07-12 02:14:58,075 [salt.state       :1780][INFO    ][6779] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:14:58.075813
2019-07-12 02:14:58,076 [salt.state       :1813][INFO    ][6779] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-12 02:14:58,078 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6779] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-12 02:14:59,808 [salt.state       :300 ][INFO    ][6779] {'pid': 6798, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-12 02:14:59,809 [salt.state       :1951][INFO    ][6779] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:14:59.809355 duration_in_ms=1733.541
2019-07-12 02:14:59,814 [salt.state       :1780][INFO    ][6779] Running state [maas.wait_for_machine_status] at time 02:14:59.814434
2019-07-12 02:14:59,815 [salt.state       :1813][INFO    ][6779] Executing state module.run for [maas.wait_for_machine_status]
2019-07-12 02:14:59,815 [salt.utils.decorators:613 ][WARNING ][6779] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-12 02:15:03,035 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.79371405s left)
2019-07-12 02:15:06,931 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712021506915376
2019-07-12 02:15:06,963 [salt.minion      :1432][INFO    ][6819] Starting a new job with PID 6819
2019-07-12 02:15:06,984 [salt.minion      :1711][INFO    ][6819] Returning information for job: 20190712021506915376
2019-07-12 02:15:35,919 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.90909696s left)
2019-07-12 02:15:37,019 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712021536996825
2019-07-12 02:15:37,045 [salt.minion      :1432][INFO    ][6899] Starting a new job with PID 6899
2019-07-12 02:15:37,064 [salt.minion      :1711][INFO    ][6899] Returning information for job: 20190712021536996825
2019-07-12 02:16:07,098 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712021607086294
2019-07-12 02:16:07,124 [salt.minion      :1432][INFO    ][6921] Starting a new job with PID 6921
2019-07-12 02:16:07,149 [salt.minion      :1711][INFO    ][6921] Returning information for job: 20190712021607086294
2019-07-12 02:16:08,836 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2180.99183798s left)
2019-07-12 02:16:37,184 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712021637168933
2019-07-12 02:16:37,213 [salt.minion      :1432][INFO    ][6984] Starting a new job with PID 6984
2019-07-12 02:16:37,241 [salt.minion      :1711][INFO    ][6984] Returning information for job: 20190712021637168933
2019-07-12 02:16:41,869 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2147.95944309s left)
2019-07-12 02:17:07,277 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712021707261023
2019-07-12 02:17:07,306 [salt.minion      :1432][INFO    ][7009] Starting a new job with PID 7009
2019-07-12 02:17:07,326 [salt.minion      :1711][INFO    ][7009] Returning information for job: 20190712021707261023
2019-07-12 02:17:14,817 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2115.01119709s left)
2019-07-12 02:17:37,347 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712021737332762
2019-07-12 02:17:37,374 [salt.minion      :1432][INFO    ][7082] Starting a new job with PID 7082
2019-07-12 02:17:37,393 [salt.minion      :1711][INFO    ][7082] Returning information for job: 20190712021737332762
2019-07-12 02:17:47,850 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2081.97866201s left)
2019-07-12 02:18:07,421 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712021807404402
2019-07-12 02:18:07,445 [salt.minion      :1432][INFO    ][7155] Starting a new job with PID 7155
2019-07-12 02:18:07,465 [salt.minion      :1711][INFO    ][7155] Returning information for job: 20190712021807404402
2019-07-12 02:18:20,991 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2048.83764601s left)
2019-07-12 02:18:37,568 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712021837549097
2019-07-12 02:18:37,595 [salt.minion      :1432][INFO    ][7500] Starting a new job with PID 7500
2019-07-12 02:18:37,617 [salt.minion      :1711][INFO    ][7500] Returning information for job: 20190712021837549097
2019-07-12 02:18:54,184 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2015.64432001s left)
2019-07-12 02:19:07,651 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712021907642241
2019-07-12 02:19:07,674 [salt.minion      :1432][INFO    ][7559] Starting a new job with PID 7559
2019-07-12 02:19:07,693 [salt.minion      :1711][INFO    ][7559] Returning information for job: 20190712021907642241
2019-07-12 02:19:27,125 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1982.70330811s left)
2019-07-12 02:19:37,758 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712021937746278
2019-07-12 02:19:37,780 [salt.minion      :1432][INFO    ][7762] Starting a new job with PID 7762
2019-07-12 02:19:37,800 [salt.minion      :1711][INFO    ][7762] Returning information for job: 20190712021937746278
2019-07-12 02:20:00,169 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1949.65920496s left)
2019-07-12 02:20:07,865 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712022007844837
2019-07-12 02:20:07,890 [salt.minion      :1432][INFO    ][7797] Starting a new job with PID 7797
2019-07-12 02:20:07,912 [salt.minion      :1711][INFO    ][7797] Returning information for job: 20190712022007844837
2019-07-12 02:20:33,696 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1916.13232803s left)
2019-07-12 02:20:38,058 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712022038044795
2019-07-12 02:20:38,077 [salt.minion      :1432][INFO    ][8326] Starting a new job with PID 8326
2019-07-12 02:20:38,101 [salt.minion      :1711][INFO    ][8326] Returning information for job: 20190712022038044795
2019-07-12 02:21:06,872 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1882.95641112s left)
2019-07-12 02:21:08,173 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712022108159573
2019-07-12 02:21:08,200 [salt.minion      :1432][INFO    ][8372] Starting a new job with PID 8372
2019-07-12 02:21:08,223 [salt.minion      :1711][INFO    ][8372] Returning information for job: 20190712022108159573
2019-07-12 02:21:38,360 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712022138347811
2019-07-12 02:21:38,387 [salt.minion      :1432][INFO    ][8838] Starting a new job with PID 8838
2019-07-12 02:21:38,418 [salt.minion      :1711][INFO    ][8838] Returning information for job: 20190712022138347811
2019-07-12 02:21:40,148 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1849.68027091s left)
2019-07-12 02:22:08,501 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712022208483235
2019-07-12 02:22:08,530 [salt.minion      :1432][INFO    ][8863] Starting a new job with PID 8863
2019-07-12 02:22:08,551 [salt.minion      :1711][INFO    ][8863] Returning information for job: 20190712022208483235
2019-07-12 02:22:13,015 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1816.81286311s left)
2019-07-12 02:22:38,665 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712022238655814
2019-07-12 02:22:38,699 [salt.minion      :1432][INFO    ][9032] Starting a new job with PID 9032
2019-07-12 02:22:38,720 [salt.minion      :1711][INFO    ][9032] Returning information for job: 20190712022238655814
2019-07-12 02:22:45,899 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1783.92926908s left)
2019-07-12 02:23:08,802 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712022308790971
2019-07-12 02:23:08,824 [salt.minion      :1432][INFO    ][9072] Starting a new job with PID 9072
2019-07-12 02:23:08,843 [salt.minion      :1711][INFO    ][9072] Returning information for job: 20190712022308790971
2019-07-12 02:23:18,837 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1750.99135613s left)
2019-07-12 02:23:38,993 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712022338975417
2019-07-12 02:23:39,027 [salt.minion      :1432][INFO    ][9312] Starting a new job with PID 9312
2019-07-12 02:23:39,056 [salt.minion      :1711][INFO    ][9312] Returning information for job: 20190712022338975417
2019-07-12 02:23:51,833 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1717.99488997s left)
2019-07-12 02:24:09,200 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712022409186544
2019-07-12 02:24:09,227 [salt.minion      :1432][INFO    ][9362] Starting a new job with PID 9362
2019-07-12 02:24:09,251 [salt.minion      :1711][INFO    ][9362] Returning information for job: 20190712022409186544
2019-07-12 02:24:25,192 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1684.6359601s left)
2019-07-12 02:24:39,388 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712022439375168
2019-07-12 02:24:39,417 [salt.minion      :1432][INFO    ][9609] Starting a new job with PID 9609
2019-07-12 02:24:39,438 [salt.minion      :1711][INFO    ][9609] Returning information for job: 20190712022439375168
2019-07-12 02:24:58,042 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1651.78712702s left)
2019-07-12 02:25:09,545 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712022509531268
2019-07-12 02:25:09,574 [salt.minion      :1432][INFO    ][9638] Starting a new job with PID 9638
2019-07-12 02:25:09,597 [salt.minion      :1711][INFO    ][9638] Returning information for job: 20190712022509531268
2019-07-12 02:25:31,339 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1618.48966312s left)
2019-07-12 02:25:39,751 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712022539738553
2019-07-12 02:25:39,775 [salt.minion      :1432][INFO    ][9911] Starting a new job with PID 9911
2019-07-12 02:25:39,797 [salt.minion      :1711][INFO    ][9911] Returning information for job: 20190712022539738553
2019-07-12 02:26:04,415 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1585.41318393s left)
2019-07-12 02:26:09,924 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712022609911059
2019-07-12 02:26:09,945 [salt.minion      :1432][INFO    ][9934] Starting a new job with PID 9934
2019-07-12 02:26:09,966 [salt.minion      :1711][INFO    ][9934] Returning information for job: 20190712022609911059
2019-07-12 02:26:37,434 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1552.39422107s left)
2019-07-12 02:26:40,097 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712022640083592
2019-07-12 02:26:40,126 [salt.minion      :1432][INFO    ][9995] Starting a new job with PID 9995
2019-07-12 02:26:40,146 [salt.minion      :1711][INFO    ][9995] Returning information for job: 20190712022640083592
2019-07-12 02:27:10,291 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712022710278674
2019-07-12 02:27:10,316 [salt.minion      :1432][INFO    ][10038] Starting a new job with PID 10038
2019-07-12 02:27:10,338 [salt.minion      :1711][INFO    ][10038] Returning information for job: 20190712022710278674
2019-07-12 02:27:10,521 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1519.30733609s left)
2019-07-12 02:27:40,404 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712022740387694
2019-07-12 02:27:40,433 [salt.minion      :1432][INFO    ][10141] Starting a new job with PID 10141
2019-07-12 02:27:40,457 [salt.minion      :1711][INFO    ][10141] Returning information for job: 20190712022740387694
2019-07-12 02:27:43,371 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1486.45750403s left)
2019-07-12 02:28:10,624 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712022810607823
2019-07-12 02:28:10,655 [salt.minion      :1432][INFO    ][10185] Starting a new job with PID 10185
2019-07-12 02:28:10,678 [salt.minion      :1711][INFO    ][10185] Returning information for job: 20190712022810607823
2019-07-12 02:28:16,432 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1453.39636612s left)
2019-07-12 02:28:40,663 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712022840648921
2019-07-12 02:28:40,690 [salt.minion      :1432][INFO    ][10454] Starting a new job with PID 10454
2019-07-12 02:28:40,713 [salt.minion      :1711][INFO    ][10454] Returning information for job: 20190712022840648921
2019-07-12 02:28:49,317 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1420.51126099s left)
2019-07-12 02:29:10,685 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712022910670905
2019-07-12 02:29:10,713 [salt.minion      :1432][INFO    ][10476] Starting a new job with PID 10476
2019-07-12 02:29:10,737 [salt.minion      :1711][INFO    ][10476] Returning information for job: 20190712022910670905
2019-07-12 02:29:22,210 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1387.61838293s left)
2019-07-12 02:29:40,733 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712022940717555
2019-07-12 02:29:40,765 [salt.minion      :1432][INFO    ][10530] Starting a new job with PID 10530
2019-07-12 02:29:40,800 [salt.minion      :1711][INFO    ][10530] Returning information for job: 20190712022940717555
2019-07-12 02:29:55,194 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1354.63443208s left)
2019-07-12 02:30:10,797 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712023010787688
2019-07-12 02:30:10,830 [salt.minion      :1432][INFO    ][10551] Starting a new job with PID 10551
2019-07-12 02:30:10,859 [salt.minion      :1711][INFO    ][10551] Returning information for job: 20190712023010787688
2019-07-12 02:30:28,285 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1321.543782s left)
2019-07-12 02:30:40,866 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712023040851855
2019-07-12 02:30:40,895 [salt.minion      :1432][INFO    ][10606] Starting a new job with PID 10606
2019-07-12 02:30:40,917 [salt.minion      :1711][INFO    ][10606] Returning information for job: 20190712023040851855
2019-07-12 02:31:01,099 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1288.72901392s left)
2019-07-12 02:31:10,923 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712023110914147
2019-07-12 02:31:10,947 [salt.minion      :1432][INFO    ][10636] Starting a new job with PID 10636
2019-07-12 02:31:10,968 [salt.minion      :1711][INFO    ][10636] Returning information for job: 20190712023110914147
2019-07-12 02:31:33,970 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1255.85783696s left)
2019-07-12 02:31:41,003 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712023140990900
2019-07-12 02:31:41,024 [salt.minion      :1432][INFO    ][10694] Starting a new job with PID 10694
2019-07-12 02:31:41,048 [salt.minion      :1711][INFO    ][10694] Returning information for job: 20190712023140990900
2019-07-12 02:32:06,757 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1223.07140398s left)
2019-07-12 02:32:11,087 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712023211073136
2019-07-12 02:32:11,116 [salt.minion      :1432][INFO    ][10714] Starting a new job with PID 10714
2019-07-12 02:32:11,140 [salt.minion      :1711][INFO    ][10714] Returning information for job: 20190712023211073136
2019-07-12 02:32:39,751 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1190.07725096s left)
2019-07-12 02:32:41,195 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712023241179129
2019-07-12 02:32:41,223 [salt.minion      :1432][INFO    ][10779] Starting a new job with PID 10779
2019-07-12 02:32:41,244 [salt.minion      :1711][INFO    ][10779] Returning information for job: 20190712023241179129
2019-07-12 02:33:11,311 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712023311298211
2019-07-12 02:33:11,335 [salt.minion      :1432][INFO    ][10799] Starting a new job with PID 10799
2019-07-12 02:33:11,359 [salt.minion      :1711][INFO    ][10799] Returning information for job: 20190712023311298211
2019-07-12 02:33:12,673 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1157.15567994s left)
2019-07-12 02:33:41,444 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712023341427729
2019-07-12 02:33:41,472 [salt.minion      :1432][INFO    ][10854] Starting a new job with PID 10854
2019-07-12 02:33:41,494 [salt.minion      :1711][INFO    ][10854] Returning information for job: 20190712023341427729
2019-07-12 02:33:45,431 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1124.39761806s left)
2019-07-12 02:34:11,597 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712023411581119
2019-07-12 02:34:11,625 [salt.minion      :1432][INFO    ][10874] Starting a new job with PID 10874
2019-07-12 02:34:11,647 [salt.minion      :1711][INFO    ][10874] Returning information for job: 20190712023411581119
2019-07-12 02:34:18,323 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1091.50514698s left)
2019-07-12 02:34:41,769 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712023441758354
2019-07-12 02:34:41,797 [salt.minion      :1432][INFO    ][10932] Starting a new job with PID 10932
2019-07-12 02:34:41,823 [salt.minion      :1711][INFO    ][10932] Returning information for job: 20190712023441758354
2019-07-12 02:34:51,075 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1058.75297308s left)
2019-07-12 02:35:11,961 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712023511950383
2019-07-12 02:35:11,992 [salt.minion      :1432][INFO    ][10953] Starting a new job with PID 10953
2019-07-12 02:35:12,015 [salt.minion      :1711][INFO    ][10953] Returning information for job: 20190712023511950383
2019-07-12 02:35:23,968 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1025.86035991s left)
2019-07-12 02:35:42,167 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712023542152591
2019-07-12 02:35:42,196 [salt.minion      :1432][INFO    ][11029] Starting a new job with PID 11029
2019-07-12 02:35:42,223 [salt.minion      :1711][INFO    ][11029] Returning information for job: 20190712023542152591
2019-07-12 02:35:56,758 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (993.07062602s left)
2019-07-12 02:36:12,354 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712023612340275
2019-07-12 02:36:12,383 [salt.minion      :1432][INFO    ][11054] Starting a new job with PID 11054
2019-07-12 02:36:12,404 [salt.minion      :1711][INFO    ][11054] Returning information for job: 20190712023612340275
2019-07-12 02:36:29,646 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (960.182152987s left)
2019-07-12 02:36:42,588 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712023642568924
2019-07-12 02:36:42,619 [salt.minion      :1432][INFO    ][11117] Starting a new job with PID 11117
2019-07-12 02:36:42,643 [salt.minion      :1711][INFO    ][11117] Returning information for job: 20190712023642568924
2019-07-12 02:37:02,614 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (927.213992119s left)
2019-07-12 02:37:12,810 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712023712795235
2019-07-12 02:37:12,838 [salt.minion      :1432][INFO    ][11142] Starting a new job with PID 11142
2019-07-12 02:37:12,861 [salt.minion      :1711][INFO    ][11142] Returning information for job: 20190712023712795235
2019-07-12 02:37:35,543 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (894.285928011s left)
2019-07-12 02:37:42,863 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712023742854730
2019-07-12 02:37:42,892 [salt.minion      :1432][INFO    ][11198] Starting a new job with PID 11198
2019-07-12 02:37:42,917 [salt.minion      :1711][INFO    ][11198] Returning information for job: 20190712023742854730
2019-07-12 02:38:08,413 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (861.415117025s left)
2019-07-12 02:38:12,910 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712023812898040
2019-07-12 02:38:12,932 [salt.minion      :1432][INFO    ][11221] Starting a new job with PID 11221
2019-07-12 02:38:12,956 [salt.minion      :1711][INFO    ][11221] Returning information for job: 20190712023812898040
2019-07-12 02:38:41,115 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (828.713237047s left)
2019-07-12 02:38:42,964 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712023842947605
2019-07-12 02:38:42,992 [salt.minion      :1432][INFO    ][11276] Starting a new job with PID 11276
2019-07-12 02:38:43,015 [salt.minion      :1711][INFO    ][11276] Returning information for job: 20190712023842947605
2019-07-12 02:39:13,027 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712023913016588
2019-07-12 02:39:13,050 [salt.minion      :1432][INFO    ][11296] Starting a new job with PID 11296
2019-07-12 02:39:13,074 [salt.minion      :1711][INFO    ][11296] Returning information for job: 20190712023913016588
2019-07-12 02:39:14,196 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (795.632544041s left)
2019-07-12 02:39:43,091 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712023943076810
2019-07-12 02:39:43,116 [salt.minion      :1432][INFO    ][11350] Starting a new job with PID 11350
2019-07-12 02:39:43,140 [salt.minion      :1711][INFO    ][11350] Returning information for job: 20190712023943076810
2019-07-12 02:39:46,945 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (762.8834939s left)
2019-07-12 02:40:13,219 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712024013205589
2019-07-12 02:40:13,244 [salt.minion      :1432][INFO    ][11371] Starting a new job with PID 11371
2019-07-12 02:40:13,268 [salt.minion      :1711][INFO    ][11371] Returning information for job: 20190712024013205589
2019-07-12 02:40:19,818 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (730.009902954s left)
2019-07-12 02:40:43,368 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712024043355351
2019-07-12 02:40:43,396 [salt.minion      :1432][INFO    ][11426] Starting a new job with PID 11426
2019-07-12 02:40:43,416 [salt.minion      :1711][INFO    ][11426] Returning information for job: 20190712024043355351
2019-07-12 02:40:52,678 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (697.150748968s left)
2019-07-12 02:41:13,528 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712024113514883
2019-07-12 02:41:13,547 [salt.minion      :1432][INFO    ][11449] Starting a new job with PID 11449
2019-07-12 02:41:13,569 [salt.minion      :1711][INFO    ][11449] Returning information for job: 20190712024113514883
2019-07-12 02:41:25,535 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (664.293106079s left)
2019-07-12 02:41:43,679 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712024143662305
2019-07-12 02:41:43,710 [salt.minion      :1432][INFO    ][11507] Starting a new job with PID 11507
2019-07-12 02:41:43,734 [salt.minion      :1711][INFO    ][11507] Returning information for job: 20190712024143662305
2019-07-12 02:41:58,333 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (631.495217085s left)
2019-07-12 02:42:13,907 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712024213891387
2019-07-12 02:42:13,937 [salt.minion      :1432][INFO    ][11532] Starting a new job with PID 11532
2019-07-12 02:42:13,957 [salt.minion      :1711][INFO    ][11532] Returning information for job: 20190712024213891387
2019-07-12 02:42:31,140 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (598.687932968s left)
2019-07-12 02:42:44,117 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712024244106232
2019-07-12 02:42:44,147 [salt.minion      :1432][INFO    ][11591] Starting a new job with PID 11591
2019-07-12 02:42:44,177 [salt.minion      :1711][INFO    ][11591] Returning information for job: 20190712024244106232
2019-07-12 02:43:03,993 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (565.835736036s left)
2019-07-12 02:43:14,165 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712024314149610
2019-07-12 02:43:14,195 [salt.minion      :1432][INFO    ][11611] Starting a new job with PID 11611
2019-07-12 02:43:14,220 [salt.minion      :1711][INFO    ][11611] Returning information for job: 20190712024314149610
2019-07-12 02:43:36,901 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (532.927743912s left)
2019-07-12 02:43:44,212 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712024344195288
2019-07-12 02:43:44,239 [salt.minion      :1432][INFO    ][11669] Starting a new job with PID 11669
2019-07-12 02:43:44,263 [salt.minion      :1711][INFO    ][11669] Returning information for job: 20190712024344195288
2019-07-12 02:44:09,660 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (500.168009996s left)
2019-07-12 02:44:14,312 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712024414296524
2019-07-12 02:44:14,340 [salt.minion      :1432][INFO    ][11688] Starting a new job with PID 11688
2019-07-12 02:44:14,379 [salt.minion      :1711][INFO    ][11688] Returning information for job: 20190712024414296524
2019-07-12 02:44:42,552 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (467.276506901s left)
2019-07-12 02:44:44,431 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712024444418566
2019-07-12 02:44:44,453 [salt.minion      :1432][INFO    ][11746] Starting a new job with PID 11746
2019-07-12 02:44:44,475 [salt.minion      :1711][INFO    ][11746] Returning information for job: 20190712024444418566
2019-07-12 02:45:14,520 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712024514511352
2019-07-12 02:45:14,548 [salt.minion      :1432][INFO    ][11766] Starting a new job with PID 11766
2019-07-12 02:45:14,571 [salt.minion      :1711][INFO    ][11766] Returning information for job: 20190712024514511352
2019-07-12 02:45:15,778 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (434.051069021s left)
2019-07-12 02:45:44,697 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712024544680239
2019-07-12 02:45:44,729 [salt.minion      :1432][INFO    ][11965] Starting a new job with PID 11965
2019-07-12 02:45:44,750 [salt.minion      :1711][INFO    ][11965] Returning information for job: 20190712024544680239
2019-07-12 02:45:48,479 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (401.349600077s left)
2019-07-12 02:46:14,843 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712024614827546
2019-07-12 02:46:14,872 [salt.minion      :1432][INFO    ][11994] Starting a new job with PID 11994
2019-07-12 02:46:14,896 [salt.minion      :1711][INFO    ][11994] Returning information for job: 20190712024614827546
2019-07-12 02:46:21,317 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (368.511707067s left)
2019-07-12 02:46:45,035 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712024645022088
2019-07-12 02:46:45,067 [salt.minion      :1432][INFO    ][12055] Starting a new job with PID 12055
2019-07-12 02:46:45,092 [salt.minion      :1711][INFO    ][12055] Returning information for job: 20190712024645022088
2019-07-12 02:46:54,093 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (335.73579812s left)
2019-07-12 02:47:15,260 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712024715246608
2019-07-12 02:47:15,287 [salt.minion      :1432][INFO    ][12087] Starting a new job with PID 12087
2019-07-12 02:47:15,308 [salt.minion      :1711][INFO    ][12087] Returning information for job: 20190712024715246608
2019-07-12 02:47:27,131 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (302.697623014s left)
2019-07-12 02:47:45,452 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712024745442641
2019-07-12 02:47:45,480 [salt.minion      :1432][INFO    ][12136] Starting a new job with PID 12136
2019-07-12 02:47:45,501 [salt.minion      :1711][INFO    ][12136] Returning information for job: 20190712024745442641
2019-07-12 02:48:00,056 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (269.772782087s left)
2019-07-12 02:48:15,635 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712024815621576
2019-07-12 02:48:15,660 [salt.minion      :1432][INFO    ][12163] Starting a new job with PID 12163
2019-07-12 02:48:15,680 [salt.minion      :1711][INFO    ][12163] Returning information for job: 20190712024815621576
2019-07-12 02:48:32,875 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (236.953095913s left)
2019-07-12 02:48:45,687 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712024845672986
2019-07-12 02:48:45,715 [salt.minion      :1432][INFO    ][12212] Starting a new job with PID 12212
2019-07-12 02:48:45,737 [salt.minion      :1711][INFO    ][12212] Returning information for job: 20190712024845672986
2019-07-12 02:49:05,814 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (204.015078068s left)
2019-07-12 02:49:15,726 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712024915711316
2019-07-12 02:49:15,752 [salt.minion      :1432][INFO    ][12241] Starting a new job with PID 12241
2019-07-12 02:49:15,775 [salt.minion      :1711][INFO    ][12241] Returning information for job: 20190712024915711316
2019-07-12 02:49:38,626 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (171.202462912s left)
2019-07-12 02:49:45,797 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712024945784065
2019-07-12 02:49:45,824 [salt.minion      :1432][INFO    ][12287] Starting a new job with PID 12287
2019-07-12 02:49:45,848 [salt.minion      :1711][INFO    ][12287] Returning information for job: 20190712024945784065
2019-07-12 02:50:11,669 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (138.159542084s left)
2019-07-12 02:50:15,904 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712025015889290
2019-07-12 02:50:15,921 [salt.minion      :1432][INFO    ][12319] Starting a new job with PID 12319
2019-07-12 02:50:15,949 [salt.minion      :1711][INFO    ][12319] Returning information for job: 20190712025015889290
2019-07-12 02:50:44,416 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (105.41191411s left)
2019-07-12 02:50:46,020 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712025046010561
2019-07-12 02:50:46,050 [salt.minion      :1432][INFO    ][12367] Starting a new job with PID 12367
2019-07-12 02:50:46,072 [salt.minion      :1711][INFO    ][12367] Returning information for job: 20190712025046010561
2019-07-12 02:51:16,171 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712025116158480
2019-07-12 02:51:16,194 [salt.minion      :1432][INFO    ][12398] Starting a new job with PID 12398
2019-07-12 02:51:16,217 [salt.minion      :1711][INFO    ][12398] Returning information for job: 20190712025116158480
2019-07-12 02:51:17,204 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (72.6245679855s left)
2019-07-12 02:51:46,293 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712025146278336
2019-07-12 02:51:46,324 [salt.minion      :1432][INFO    ][12446] Starting a new job with PID 12446
2019-07-12 02:51:46,347 [salt.minion      :1711][INFO    ][12446] Returning information for job: 20190712025146278336
2019-07-12 02:51:50,088 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (39.7401769161s left)
2019-07-12 02:52:16,468 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712025216452375
2019-07-12 02:52:16,499 [salt.minion      :1432][INFO    ][12474] Starting a new job with PID 12474
2019-07-12 02:52:16,523 [salt.minion      :1711][INFO    ][12474] Returning information for job: 20190712025216452375
2019-07-12 02:52:22,859 [salt.loaded.ext.module.maas:1023][INFO    ][6779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (6.96915006638s left)
2019-07-12 02:52:46,685 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712025246670517
2019-07-12 02:52:46,714 [salt.minion      :1432][INFO    ][12523] Starting a new job with PID 12523
2019-07-12 02:52:46,737 [salt.minion      :1711][INFO    ][12523] Returning information for job: 20190712025246670517
2019-07-12 02:52:55,839 [salt.state       :302 ][ERROR   ][6779] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-07-12 02:52:55,840 [salt.state       :1951][INFO    ][6779] Completed state [maas.wait_for_machine_status] at time 02:52:55.840397 duration_in_ms=2276025.958
2019-07-12 02:52:55,845 [salt.minion      :1711][INFO    ][6779] Returning information for job: 20190712021451847104
2019-07-12 02:53:06,640 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command pillar.get with jid 20190712025306630374
2019-07-12 02:53:06,667 [salt.minion      :1432][INFO    ][12541] Starting a new job with PID 12541
2019-07-12 02:53:06,677 [salt.minion      :1711][INFO    ][12541] Returning information for job: 20190712025306630374
2019-07-12 02:53:07,245 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command service.status with jid 20190712025307232597
2019-07-12 02:53:07,269 [salt.minion      :1432][INFO    ][12546] Starting a new job with PID 12546
2019-07-12 02:53:07,824 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12546] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-07-12 02:53:07,868 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12546] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-07-12 02:53:07,885 [salt.minion      :1711][INFO    ][12546] Returning information for job: 20190712025307232597
2019-07-12 02:53:08,459 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command state.apply with jid 20190712025308446974
2019-07-12 02:53:08,485 [salt.minion      :1432][INFO    ][12557] Starting a new job with PID 12557
2019-07-12 02:53:14,459 [salt.state       :915 ][INFO    ][12557] Loading fresh modules for state activity
2019-07-12 02:53:15,130 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12557] Executing command 'salt-minion --version' in directory '/root'
2019-07-12 02:53:15,538 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12557] Executing command 'salt-minion --version' in directory '/root'
2019-07-12 02:53:16,559 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12557] Executing command 'salt-minion --version' in directory '/root'
2019-07-12 02:53:16,858 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12557] Executing command 'salt-minion --version' in directory '/root'
2019-07-12 02:53:18,640 [salt.state       :1780][INFO    ][12557] Running state [salt-minion] at time 02:53:18.640352
2019-07-12 02:53:18,640 [salt.state       :1813][INFO    ][12557] Executing state pkg.installed for [salt-minion]
2019-07-12 02:53:18,641 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12557] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-07-12 02:53:18,751 [salt.state       :300 ][INFO    ][12557] All specified packages are already installed
2019-07-12 02:53:18,751 [salt.state       :1951][INFO    ][12557] Completed state [salt-minion] at time 02:53:18.751272 duration_in_ms=110.92
2019-07-12 02:53:18,751 [salt.state       :1780][INFO    ][12557] Running state [salt_minion_dependency_packages] at time 02:53:18.751611
2019-07-12 02:53:18,751 [salt.state       :1813][INFO    ][12557] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-07-12 02:53:18,760 [salt.state       :300 ][INFO    ][12557] All specified packages are already installed
2019-07-12 02:53:18,761 [salt.state       :1951][INFO    ][12557] Completed state [salt_minion_dependency_packages] at time 02:53:18.761084 duration_in_ms=9.473
2019-07-12 02:53:18,764 [salt.state       :1780][INFO    ][12557] Running state [/etc/salt/minion.d/minion.conf] at time 02:53:18.764350
2019-07-12 02:53:18,764 [salt.state       :1813][INFO    ][12557] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-07-12 02:53:19,046 [salt.state       :300 ][INFO    ][12557] File /etc/salt/minion.d/minion.conf is in the correct state
2019-07-12 02:53:19,046 [salt.state       :1951][INFO    ][12557] Completed state [/etc/salt/minion.d/minion.conf] at time 02:53:19.046404 duration_in_ms=282.054
2019-07-12 02:53:19,046 [salt.state       :1780][INFO    ][12557] Running state [python-netaddr] at time 02:53:19.046659
2019-07-12 02:53:19,046 [salt.state       :1813][INFO    ][12557] Executing state pkg.installed for [python-netaddr]
2019-07-12 02:53:19,055 [salt.state       :300 ][INFO    ][12557] All specified packages are already installed
2019-07-12 02:53:19,056 [salt.state       :1951][INFO    ][12557] Completed state [python-netaddr] at time 02:53:19.056007 duration_in_ms=9.348
2019-07-12 02:53:19,059 [salt.state       :1780][INFO    ][12557] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:53:19.059632
2019-07-12 02:53:19,059 [salt.state       :1813][INFO    ][12557] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-07-12 02:53:19,075 [salt.state       :300 ][INFO    ][12557] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-07-12 02:53:19,075 [salt.state       :1951][INFO    ][12557] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:53:19.075864 duration_in_ms=16.231
2019-07-12 02:53:19,078 [salt.state       :1780][INFO    ][12557] Running state [salt-minion] at time 02:53:19.078259
2019-07-12 02:53:19,078 [salt.state       :1813][INFO    ][12557] Executing state service.running for [salt-minion]
2019-07-12 02:53:19,080 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12557] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-07-12 02:53:19,124 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12557] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-07-12 02:53:19,146 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12557] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-07-12 02:53:19,162 [salt.state       :300 ][INFO    ][12557] The service salt-minion is already running
2019-07-12 02:53:19,163 [salt.state       :1951][INFO    ][12557] Completed state [salt-minion] at time 02:53:19.163069 duration_in_ms=84.809
2019-07-12 02:53:19,166 [salt.state       :1780][INFO    ][12557] Running state [/etc/salt/grains.d] at time 02:53:19.166773
2019-07-12 02:53:19,167 [salt.state       :1813][INFO    ][12557] Executing state file.directory for [/etc/salt/grains.d]
2019-07-12 02:53:19,169 [salt.state       :300 ][INFO    ][12557] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-07-12 02:53:19,169 [salt.state       :1951][INFO    ][12557] Completed state [/etc/salt/grains.d] at time 02:53:19.169385 duration_in_ms=2.611
2019-07-12 02:53:19,172 [salt.state       :1780][INFO    ][12557] Running state [/etc/salt/grains] at time 02:53:19.172139
2019-07-12 02:53:19,172 [salt.state       :1813][INFO    ][12557] Executing state file.managed for [/etc/salt/grains]
2019-07-12 02:53:19,173 [salt.state       :300 ][INFO    ][12557] File /etc/salt/grains exists with proper permissions. No changes made.
2019-07-12 02:53:19,173 [salt.state       :1951][INFO    ][12557] Completed state [/etc/salt/grains] at time 02:53:19.173356 duration_in_ms=1.217
2019-07-12 02:53:19,174 [salt.state       :1780][INFO    ][12557] Running state [/etc/salt/grains.d/placeholder] at time 02:53:19.173957
2019-07-12 02:53:19,174 [salt.state       :1813][INFO    ][12557] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-07-12 02:53:19,174 [salt.state       :300 ][INFO    ][12557] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-07-12 02:53:19,175 [salt.state       :1951][INFO    ][12557] Completed state [/etc/salt/grains.d/placeholder] at time 02:53:19.175127 duration_in_ms=1.17
2019-07-12 02:53:19,175 [salt.state       :1780][INFO    ][12557] Running state [/etc/salt/grains.d/sphinx] at time 02:53:19.175709
2019-07-12 02:53:19,176 [salt.state       :1813][INFO    ][12557] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-07-12 02:53:19,177 [salt.state       :300 ][INFO    ][12557] File /etc/salt/grains.d/sphinx is in the correct state
2019-07-12 02:53:19,179 [salt.state       :1951][INFO    ][12557] Completed state [/etc/salt/grains.d/sphinx] at time 02:53:19.179525 duration_in_ms=3.816
2019-07-12 02:53:19,181 [salt.state       :1780][INFO    ][12557] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:53:19.181480
2019-07-12 02:53:19,182 [salt.state       :1813][INFO    ][12557] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-07-12 02:53:19,182 [salt.state       :300 ][INFO    ][12557] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-07-12 02:53:19,182 [salt.state       :1951][INFO    ][12557] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:53:19.182579 duration_in_ms=1.099
2019-07-12 02:53:19,183 [salt.state       :1780][INFO    ][12557] Running state [/etc/salt/grains.d/dns_records] at time 02:53:19.183052
2019-07-12 02:53:19,183 [salt.state       :1813][INFO    ][12557] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-07-12 02:53:19,184 [salt.state       :300 ][INFO    ][12557] File /etc/salt/grains.d/dns_records is in the correct state
2019-07-12 02:53:19,184 [salt.state       :1951][INFO    ][12557] Completed state [/etc/salt/grains.d/dns_records] at time 02:53:19.184359 duration_in_ms=1.307
2019-07-12 02:53:19,185 [salt.state       :1780][INFO    ][12557] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:53:19.185266
2019-07-12 02:53:19,185 [salt.state       :1813][INFO    ][12557] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-07-12 02:53:19,186 [salt.state       :300 ][INFO    ][12557] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-07-12 02:53:19,186 [salt.state       :1951][INFO    ][12557] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:53:19.186324 duration_in_ms=1.059
2019-07-12 02:53:19,186 [salt.state       :1780][INFO    ][12557] Running state [/etc/salt/grains.d/salt] at time 02:53:19.186795
2019-07-12 02:53:19,187 [salt.state       :1813][INFO    ][12557] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-07-12 02:53:19,187 [salt.state       :300 ][INFO    ][12557] File /etc/salt/grains.d/salt is in the correct state
2019-07-12 02:53:19,188 [salt.state       :1951][INFO    ][12557] Completed state [/etc/salt/grains.d/salt] at time 02:53:19.188101 duration_in_ms=1.306
2019-07-12 02:53:19,189 [salt.state       :1780][INFO    ][12557] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:53:19.188968
2019-07-12 02:53:19,189 [salt.state       :1813][INFO    ][12557] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-07-12 02:53:19,189 [salt.state       :300 ][INFO    ][12557] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-07-12 02:53:19,191 [salt.state       :1951][INFO    ][12557] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:53:19.189696 duration_in_ms=0.728
2019-07-12 02:53:19,193 [salt.state       :1780][INFO    ][12557] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:53:19.193314
2019-07-12 02:53:19,193 [salt.state       :1813][INFO    ][12557] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-07-12 02:53:19,193 [salt.state       :300 ][INFO    ][12557] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-07-12 02:53:19,194 [salt.state       :1951][INFO    ][12557] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:53:19.194083 duration_in_ms=0.77
2019-07-12 02:53:19,194 [salt.state       :1780][INFO    ][12557] Running state [mine.update] at time 02:53:19.194728
2019-07-12 02:53:19,194 [salt.state       :1813][INFO    ][12557] Executing state module.wait for [mine.update]
2019-07-12 02:53:19,195 [salt.state       :300 ][INFO    ][12557] No changes made for mine.update
2019-07-12 02:53:19,195 [salt.state       :1951][INFO    ][12557] Completed state [mine.update] at time 02:53:19.195399 duration_in_ms=0.672
2019-07-12 02:53:19,195 [salt.state       :1780][INFO    ][12557] Running state [ca-certificates] at time 02:53:19.195629
2019-07-12 02:53:19,195 [salt.state       :1813][INFO    ][12557] Executing state pkg.installed for [ca-certificates]
2019-07-12 02:53:19,206 [salt.state       :300 ][INFO    ][12557] All specified packages are already installed
2019-07-12 02:53:19,206 [salt.state       :1951][INFO    ][12557] Completed state [ca-certificates] at time 02:53:19.206486 duration_in_ms=10.856
2019-07-12 02:53:19,207 [salt.state       :1780][INFO    ][12557] Running state [update-ca-certificates] at time 02:53:19.207167
2019-07-12 02:53:19,207 [salt.state       :1813][INFO    ][12557] Executing state cmd.wait for [update-ca-certificates]
2019-07-12 02:53:19,207 [salt.state       :300 ][INFO    ][12557] No changes made for update-ca-certificates
2019-07-12 02:53:19,207 [salt.state       :1951][INFO    ][12557] Completed state [update-ca-certificates] at time 02:53:19.207881 duration_in_ms=0.714
2019-07-12 02:53:19,208 [salt.state       :1780][INFO    ][12557] Running state [iptables] at time 02:53:19.208112
2019-07-12 02:53:19,208 [salt.state       :1813][INFO    ][12557] Executing state pkg.installed for [iptables]
2019-07-12 02:53:19,218 [salt.state       :300 ][INFO    ][12557] All specified packages are already installed
2019-07-12 02:53:19,218 [salt.state       :1951][INFO    ][12557] Completed state [iptables] at time 02:53:19.218180 duration_in_ms=10.068
2019-07-12 02:53:19,218 [salt.state       :1780][INFO    ][12557] Running state [iptables-persistent] at time 02:53:19.218415
2019-07-12 02:53:19,218 [salt.state       :1813][INFO    ][12557] Executing state pkg.installed for [iptables-persistent]
2019-07-12 02:53:19,228 [salt.state       :300 ][INFO    ][12557] All specified packages are already installed
2019-07-12 02:53:19,228 [salt.state       :1951][INFO    ][12557] Completed state [iptables-persistent] at time 02:53:19.228191 duration_in_ms=9.776
2019-07-12 02:53:19,229 [salt.state       :1780][INFO    ][12557] Running state [iptables_modules_v4_load] at time 02:53:19.229411
2019-07-12 02:53:19,229 [salt.state       :1813][INFO    ][12557] Executing state kmod.present for [iptables_modules_v4_load]
2019-07-12 02:53:19,230 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12557] Executing command 'lsmod' in directory '/root'
2019-07-12 02:53:19,253 [salt.state       :300 ][INFO    ][12557] Kernel modules iptable_filter, ip_tables are already present
2019-07-12 02:53:19,254 [salt.state       :1951][INFO    ][12557] Completed state [iptables_modules_v4_load] at time 02:53:19.254187 duration_in_ms=24.776
2019-07-12 02:53:19,255 [salt.state       :1780][INFO    ][12557] Running state [/etc/iptables/rules.v4] at time 02:53:19.254931
2019-07-12 02:53:19,255 [salt.state       :1813][INFO    ][12557] Executing state file.managed for [/etc/iptables/rules.v4]
2019-07-12 02:53:19,361 [salt.state       :300 ][INFO    ][12557] File /etc/iptables/rules.v4 is in the correct state
2019-07-12 02:53:19,362 [salt.state       :1951][INFO    ][12557] Completed state [/etc/iptables/rules.v4] at time 02:53:19.361927 duration_in_ms=106.997
2019-07-12 02:53:19,364 [salt.state       :1780][INFO    ][12557] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:53:19.364203
2019-07-12 02:53:19,364 [salt.state       :1813][INFO    ][12557] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-07-12 02:53:19,366 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12557] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-07-12 02:53:19,390 [salt.state       :300 ][INFO    ][12557] onlyif execution failed
2019-07-12 02:53:19,390 [salt.state       :1951][INFO    ][12557] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:53:19.390582 duration_in_ms=26.379
2019-07-12 02:53:19,392 [salt.state       :1780][INFO    ][12557] Running state [netfilter-persistent] at time 02:53:19.392426
2019-07-12 02:53:19,392 [salt.state       :1813][INFO    ][12557] Executing state service.running for [netfilter-persistent]
2019-07-12 02:53:19,396 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12557] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-07-12 02:53:19,419 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12557] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-07-12 02:53:19,442 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12557] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-07-12 02:53:19,463 [salt.state       :300 ][INFO    ][12557] The service netfilter-persistent is already running
2019-07-12 02:53:19,464 [salt.state       :1951][INFO    ][12557] Completed state [netfilter-persistent] at time 02:53:19.464021 duration_in_ms=71.595
2019-07-12 02:53:19,465 [salt.state       :1780][INFO    ][12557] Running state [iptables_extra.remove_stale_tables] at time 02:53:19.465471
2019-07-12 02:53:19,467 [salt.state       :1813][INFO    ][12557] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-07-12 02:53:19,468 [salt.state       :300 ][INFO    ][12557] No changes made for iptables_extra.remove_stale_tables
2019-07-12 02:53:19,468 [salt.state       :1951][INFO    ][12557] Completed state [iptables_extra.remove_stale_tables] at time 02:53:19.468646 duration_in_ms=3.174
2019-07-12 02:53:19,469 [salt.state       :1780][INFO    ][12557] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:53:19.469007
2019-07-12 02:53:19,469 [salt.state       :1813][INFO    ][12557] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-07-12 02:53:19,470 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12557] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-07-12 02:53:19,486 [salt.state       :300 ][INFO    ][12557] onlyif execution failed
2019-07-12 02:53:19,487 [salt.state       :1951][INFO    ][12557] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:53:19.487120 duration_in_ms=18.113
2019-07-12 02:53:19,488 [salt.state       :1780][INFO    ][12557] Running state [/etc/iptables/rules.v6] at time 02:53:19.488309
2019-07-12 02:53:19,488 [salt.state       :1813][INFO    ][12557] Executing state file.absent for [/etc/iptables/rules.v6]
2019-07-12 02:53:19,489 [salt.state       :300 ][INFO    ][12557] File /etc/iptables/rules.v6 is not present
2019-07-12 02:53:19,489 [salt.state       :1951][INFO    ][12557] Completed state [/etc/iptables/rules.v6] at time 02:53:19.489459 duration_in_ms=1.15
2019-07-12 02:53:19,491 [salt.state       :1780][INFO    ][12557] Running state [iptables_extra.flush_all] at time 02:53:19.491151
2019-07-12 02:53:19,491 [salt.state       :1813][INFO    ][12557] Executing state module.wait for [iptables_extra.flush_all]
2019-07-12 02:53:19,491 [salt.state       :300 ][INFO    ][12557] No changes made for iptables_extra.flush_all
2019-07-12 02:53:19,492 [salt.state       :1951][INFO    ][12557] Completed state [iptables_extra.flush_all] at time 02:53:19.492099 duration_in_ms=0.948
2019-07-12 02:53:19,497 [salt.minion      :1711][INFO    ][12557] Returning information for job: 20190712025308446974
2019-07-12 02:53:20,065 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command state.apply with jid 20190712025320051881
2019-07-12 02:53:20,092 [salt.minion      :1432][INFO    ][12645] Starting a new job with PID 12645
2019-07-12 02:53:21,041 [salt.state       :915 ][INFO    ][12645] Loading fresh modules for state activity
2019-07-12 02:53:21,996 [salt.state       :1780][INFO    ][12645] Running state [maas-rack-controller] at time 02:53:21.996821
2019-07-12 02:53:21,997 [salt.state       :1813][INFO    ][12645] Executing state pkg.installed for [maas-rack-controller]
2019-07-12 02:53:21,997 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12645] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-07-12 02:53:22,107 [salt.state       :300 ][INFO    ][12645] All specified packages are already installed
2019-07-12 02:53:22,107 [salt.state       :1951][INFO    ][12645] Completed state [maas-rack-controller] at time 02:53:22.107560 duration_in_ms=110.74
2019-07-12 02:53:22,107 [salt.state       :1780][INFO    ][12645] Running state [ipmitool] at time 02:53:22.107879
2019-07-12 02:53:22,108 [salt.state       :1813][INFO    ][12645] Executing state pkg.installed for [ipmitool]
2019-07-12 02:53:22,116 [salt.state       :300 ][INFO    ][12645] All specified packages are already installed
2019-07-12 02:53:22,117 [salt.state       :1951][INFO    ][12645] Completed state [ipmitool] at time 02:53:22.117147 duration_in_ms=9.268
2019-07-12 02:53:22,120 [salt.state       :1780][INFO    ][12645] Running state [/etc/maas/rackd.conf] at time 02:53:22.120718
2019-07-12 02:53:22,121 [salt.state       :1813][INFO    ][12645] Executing state file.line for [/etc/maas/rackd.conf]
2019-07-12 02:53:22,121 [salt.state       :300 ][INFO    ][12645] No changes needed to be made
2019-07-12 02:53:22,122 [salt.state       :1951][INFO    ][12645] Completed state [/etc/maas/rackd.conf] at time 02:53:22.122072 duration_in_ms=1.354
2019-07-12 02:53:22,122 [salt.state       :1780][INFO    ][12645] Running state [/etc/maas/rackd.conf] at time 02:53:22.122315
2019-07-12 02:53:22,122 [salt.state       :1813][INFO    ][12645] Executing state file.managed for [/etc/maas/rackd.conf]
2019-07-12 02:53:22,122 [salt.loaded.int.states.file:2298][WARNING ][12645] 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-07-12 02:53:22,123 [salt.state       :300 ][INFO    ][12645] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-07-12 02:53:22,123 [salt.state       :1951][INFO    ][12645] Completed state [/etc/maas/rackd.conf] at time 02:53:22.123824 duration_in_ms=1.509
2019-07-12 02:53:22,124 [salt.state       :1780][INFO    ][12645] Running state [maas-rackd] at time 02:53:22.124785
2019-07-12 02:53:22,125 [salt.state       :1813][INFO    ][12645] Executing state service.running for [maas-rackd]
2019-07-12 02:53:22,125 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12645] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-07-12 02:53:22,162 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12645] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-07-12 02:53:22,182 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12645] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-07-12 02:53:22,205 [salt.state       :300 ][INFO    ][12645] The service maas-rackd is already running
2019-07-12 02:53:22,206 [salt.state       :1951][INFO    ][12645] Completed state [maas-rackd] at time 02:53:22.206180 duration_in_ms=81.393
2019-07-12 02:53:22,208 [salt.minion      :1711][INFO    ][12645] Returning information for job: 20190712025320051881
2019-07-12 02:53:22,800 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command state.apply with jid 20190712025322790739
2019-07-12 02:53:22,827 [salt.minion      :1432][INFO    ][12678] Starting a new job with PID 12678
2019-07-12 02:53:23,773 [salt.state       :915 ][INFO    ][12678] Loading fresh modules for state activity
2019-07-12 02:53:24,856 [salt.state       :1780][INFO    ][12678] Running state [maas-region-controller] at time 02:53:24.856341
2019-07-12 02:53:24,856 [salt.state       :1813][INFO    ][12678] Executing state pkg.installed for [maas-region-controller]
2019-07-12 02:53:24,857 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12678] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-07-12 02:53:24,968 [salt.state       :300 ][INFO    ][12678] All specified packages are already installed
2019-07-12 02:53:24,968 [salt.state       :1951][INFO    ][12678] Completed state [maas-region-controller] at time 02:53:24.968693 duration_in_ms=112.353
2019-07-12 02:53:24,969 [salt.state       :1780][INFO    ][12678] Running state [python-oauth] at time 02:53:24.969007
2019-07-12 02:53:24,969 [salt.state       :1813][INFO    ][12678] Executing state pkg.installed for [python-oauth]
2019-07-12 02:53:24,978 [salt.state       :300 ][INFO    ][12678] All specified packages are already installed
2019-07-12 02:53:24,979 [salt.state       :1951][INFO    ][12678] Completed state [python-oauth] at time 02:53:24.979068 duration_in_ms=10.061
2019-07-12 02:53:24,981 [salt.state       :1780][INFO    ][12678] Running state [/etc/maas/regiond.conf] at time 02:53:24.981936
2019-07-12 02:53:24,982 [salt.state       :1813][INFO    ][12678] Executing state file.replace for [/etc/maas/regiond.conf]
2019-07-12 02:53:24,987 [salt.state       :300 ][INFO    ][12678] No changes needed to be made
2019-07-12 02:53:24,987 [salt.state       :1951][INFO    ][12678] Completed state [/etc/maas/regiond.conf] at time 02:53:24.987217 duration_in_ms=5.281
2019-07-12 02:53:24,987 [salt.state       :1780][INFO    ][12678] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:53:24.987693
2019-07-12 02:53:24,987 [salt.state       :1813][INFO    ][12678] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-07-12 02:53:25,052 [salt.state       :300 ][INFO    ][12678] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-07-12 02:53:25,053 [salt.state       :1951][INFO    ][12678] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:53:25.053092 duration_in_ms=65.399
2019-07-12 02:53:25,053 [salt.state       :1780][INFO    ][12678] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:53:25.053624
2019-07-12 02:53:25,053 [salt.state       :1813][INFO    ][12678] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-07-12 02:53:25,057 [salt.state       :300 ][INFO    ][12678] No changes needed to be made
2019-07-12 02:53:25,058 [salt.state       :1951][INFO    ][12678] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:53:25.057694 duration_in_ms=4.07
2019-07-12 02:53:25,059 [salt.state       :1780][INFO    ][12678] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:53:25.059069
2019-07-12 02:53:25,059 [salt.state       :1813][INFO    ][12678] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-07-12 02:53:25,062 [salt.state       :300 ][INFO    ][12678] No changes needed to be made
2019-07-12 02:53:25,062 [salt.state       :1951][INFO    ][12678] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:53:25.062899 duration_in_ms=3.83
2019-07-12 02:53:25,063 [salt.state       :1780][INFO    ][12678] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:53:25.063371
2019-07-12 02:53:25,063 [salt.state       :1813][INFO    ][12678] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-07-12 02:53:25,091 [salt.state       :300 ][INFO    ][12678] No changes needed to be made
2019-07-12 02:53:25,091 [salt.state       :1951][INFO    ][12678] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:53:25.091472 duration_in_ms=28.101
2019-07-12 02:53:25,092 [salt.state       :1780][INFO    ][12678] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:53:25.091987
2019-07-12 02:53:25,092 [salt.state       :1813][INFO    ][12678] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-07-12 02:53:25,105 [salt.state       :300 ][INFO    ][12678] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-07-12 02:53:25,107 [salt.state       :1951][INFO    ][12678] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:53:25.107363 duration_in_ms=15.376
2019-07-12 02:53:25,108 [salt.state       :1780][INFO    ][12678] Running state [a2enmod headers] at time 02:53:25.108512
2019-07-12 02:53:25,108 [salt.state       :1813][INFO    ][12678] Executing state cmd.run for [a2enmod headers]
2019-07-12 02:53:25,109 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12678] Executing command 'a2enmod headers' in directory '/root'
2019-07-12 02:53:25,180 [salt.state       :300 ][INFO    ][12678] {'pid': 12702, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-07-12 02:53:25,180 [salt.state       :1951][INFO    ][12678] Completed state [a2enmod headers] at time 02:53:25.180725 duration_in_ms=72.212
2019-07-12 02:53:25,181 [salt.state       :1780][INFO    ][12678] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:53:25.181397
2019-07-12 02:53:25,181 [salt.state       :1813][INFO    ][12678] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-07-12 02:53:25,210 [salt.state       :300 ][INFO    ][12678] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-07-12 02:53:25,210 [salt.state       :1951][INFO    ][12678] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:53:25.210803 duration_in_ms=29.406
2019-07-12 02:53:25,211 [salt.state       :1780][INFO    ][12678] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:53:25.211870
2019-07-12 02:53:25,212 [salt.state       :1813][INFO    ][12678] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-07-12 02:53:25,282 [salt.state       :300 ][INFO    ][12678] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-07-12 02:53:25,282 [salt.state       :1951][INFO    ][12678] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:53:25.282888 duration_in_ms=71.019
2019-07-12 02:53:25,283 [salt.state       :1780][INFO    ][12678] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:53:25.283388
2019-07-12 02:53:25,283 [salt.state       :1813][INFO    ][12678] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-07-12 02:53:25,347 [salt.state       :300 ][INFO    ][12678] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-07-12 02:53:25,347 [salt.state       :1951][INFO    ][12678] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:53:25.347453 duration_in_ms=64.065
2019-07-12 02:53:25,348 [salt.state       :1780][INFO    ][12678] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:53:25.348023
2019-07-12 02:53:25,348 [salt.state       :1813][INFO    ][12678] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-07-12 02:53:25,424 [salt.state       :300 ][INFO    ][12678] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-07-12 02:53:25,424 [salt.state       :1951][INFO    ][12678] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:53:25.424476 duration_in_ms=76.453
2019-07-12 02:53:25,424 [salt.state       :1780][INFO    ][12678] Running state [/root/.pgpass] at time 02:53:25.424740
2019-07-12 02:53:25,425 [salt.state       :1813][INFO    ][12678] Executing state file.managed for [/root/.pgpass]
2019-07-12 02:53:25,467 [salt.state       :300 ][INFO    ][12678] File /root/.pgpass is in the correct state
2019-07-12 02:53:25,468 [salt.state       :1951][INFO    ][12678] Completed state [/root/.pgpass] at time 02:53:25.468115 duration_in_ms=43.375
2019-07-12 02:53:25,473 [salt.state       :1780][INFO    ][12678] Running state [maas-region syncdb --noinput] at time 02:53:25.473170
2019-07-12 02:53:25,473 [salt.state       :1813][INFO    ][12678] Executing state cmd.run for [maas-region syncdb --noinput]
2019-07-12 02:53:25,474 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12678] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-07-12 02:53:28,249 [salt.state       :300 ][INFO    ][12678] {'pid': 12715, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: maasserver, sites, contenttypes, auth, metadataserver, 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-07-12 02:53:28,250 [salt.state       :1951][INFO    ][12678] Completed state [maas-region syncdb --noinput] at time 02:53:28.250749 duration_in_ms=2777.579
2019-07-12 02:53:28,251 [salt.state       :2022][WARNING ][12678] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-07-12 02:53:28,252 [salt.state       :1780][INFO    ][12678] Running state [maas-regiond] at time 02:53:28.252762
2019-07-12 02:53:28,253 [salt.state       :1813][INFO    ][12678] Executing state service.running for [maas-regiond]
2019-07-12 02:53:28,253 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12678] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-07-12 02:53:28,294 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12678] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-07-12 02:53:28,314 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12678] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-07-12 02:53:28,331 [salt.state       :300 ][INFO    ][12678] The service maas-regiond is already running
2019-07-12 02:53:28,331 [salt.state       :1951][INFO    ][12678] Completed state [maas-regiond] at time 02:53:28.331740 duration_in_ms=78.977
2019-07-12 02:53:28,336 [salt.state       :1780][INFO    ][12678] Running state [bind9] at time 02:53:28.336158
2019-07-12 02:53:28,336 [salt.state       :1813][INFO    ][12678] Executing state service.running for [bind9]
2019-07-12 02:53:28,337 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12678] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-07-12 02:53:28,356 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12678] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-07-12 02:53:28,375 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12678] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-07-12 02:53:28,397 [salt.state       :300 ][INFO    ][12678] The service bind9 is already running
2019-07-12 02:53:28,398 [salt.state       :1951][INFO    ][12678] Completed state [bind9] at time 02:53:28.397988 duration_in_ms=61.831
2019-07-12 02:53:28,400 [salt.state       :1780][INFO    ][12678] Running state [apache2] at time 02:53:28.400697
2019-07-12 02:53:28,401 [salt.state       :1813][INFO    ][12678] Executing state service.running for [apache2]
2019-07-12 02:53:28,402 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12678] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-07-12 02:53:28,421 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12678] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-07-12 02:53:28,444 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12678] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-07-12 02:53:28,471 [salt.state       :300 ][INFO    ][12678] The service apache2 is already running
2019-07-12 02:53:28,472 [salt.state       :1951][INFO    ][12678] Completed state [apache2] at time 02:53:28.472333 duration_in_ms=71.636
2019-07-12 02:53:28,474 [salt.state       :1780][INFO    ][12678] Running state [maasng.wait_for_http_code] at time 02:53:28.474604
2019-07-12 02:53:28,475 [salt.state       :1813][INFO    ][12678] Executing state module.run for [maasng.wait_for_http_code]
2019-07-12 02:53:28,475 [salt.utils.decorators:613 ][WARNING ][12678] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-12 02:53:28,484 [salt.state       :300 ][INFO    ][12678] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-07-12 02:53:28,485 [salt.state       :1951][INFO    ][12678] Completed state [maasng.wait_for_http_code] at time 02:53:28.485079 duration_in_ms=10.476
2019-07-12 02:53:28,485 [salt.state       :1780][INFO    ][12678] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:53:28.485867
2019-07-12 02:53:28,486 [salt.state       :1813][INFO    ][12678] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-07-12 02:53:28,486 [salt.state       :300 ][INFO    ][12678] /var/lib/maas/.setup_admin exists
2019-07-12 02:53:28,486 [salt.state       :1951][INFO    ][12678] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:53:28.486774 duration_in_ms=0.906
2019-07-12 02:53:28,487 [salt.state       :1780][INFO    ][12678] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:28.487435
2019-07-12 02:53:28,487 [salt.state       :1813][INFO    ][12678] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-12 02:53:28,488 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12678] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-12 02:53:30,192 [salt.state       :300 ][INFO    ][12678] {'pid': 12749, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-12 02:53:30,193 [salt.state       :1951][INFO    ][12678] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:30.192998 duration_in_ms=1705.561
2019-07-12 02:53:30,201 [salt.state       :1780][INFO    ][12678] Running state [maas_region_boot_source_resources_mirror] at time 02:53:30.200977
2019-07-12 02:53:30,201 [salt.state       :1813][INFO    ][12678] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-07-12 02:53:30,282 [salt.state       :300 ][INFO    ][12678] {'changes': {}}
2019-07-12 02:53:30,283 [salt.state       :1951][INFO    ][12678] Completed state [maas_region_boot_source_resources_mirror] at time 02:53:30.283062 duration_in_ms=82.086
2019-07-12 02:53:30,283 [salt.state       :1780][INFO    ][12678] Running state [maasng.boot_resources_import] at time 02:53:30.283754
2019-07-12 02:53:30,284 [salt.state       :1813][INFO    ][12678] Executing state module.run for [maasng.boot_resources_import]
2019-07-12 02:53:30,284 [salt.utils.decorators:613 ][WARNING ][12678] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-12 02:53:30,356 [salt.loaded.ext.module.maasng:1600][INFO    ][12678] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-07-12 02:53:35,420 [salt.loaded.ext.module.maasng:1600][INFO    ][12678] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-07-12 02:53:37,858 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712025337825533
2019-07-12 02:53:37,907 [salt.minion      :1432][INFO    ][12904] Starting a new job with PID 12904
2019-07-12 02:53:37,944 [salt.minion      :1711][INFO    ][12904] Returning information for job: 20190712025337825533
2019-07-12 02:53:40,529 [salt.state       :300 ][INFO    ][12678] {'ret': True}
2019-07-12 02:53:40,530 [salt.state       :1951][INFO    ][12678] Completed state [maasng.boot_resources_import] at time 02:53:40.530743 duration_in_ms=10246.988
2019-07-12 02:53:40,532 [salt.state       :1780][INFO    ][12678] Running state [maas_region_boot_sources_selection_xenial] at time 02:53:40.532048
2019-07-12 02:53:40,532 [salt.state       :1813][INFO    ][12678] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-07-12 02:53:40,738 [salt.state       :300 ][INFO    ][12678] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-07-12 02:53:40,738 [salt.state       :1951][INFO    ][12678] Completed state [maas_region_boot_sources_selection_xenial] at time 02:53:40.738791 duration_in_ms=206.742
2019-07-12 02:53:40,740 [salt.state       :1780][INFO    ][12678] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 02:53:40.740029
2019-07-12 02:53:40,740 [salt.state       :1813][INFO    ][12678] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-07-12 02:53:40,740 [salt.utils.decorators:613 ][WARNING ][12678] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-12 02:53:40,741 [salt.loaded.ext.module.maasng:1771][INFO    ][12678] boot-sources sync initiated for ALL Rack's
2019-07-12 02:53:41,789 [salt.state       :300 ][INFO    ][12678] {'ret': True}
2019-07-12 02:53:41,790 [salt.state       :1951][INFO    ][12678] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 02:53:41.790375 duration_in_ms=1050.344
2019-07-12 02:53:41,793 [salt.state       :1780][INFO    ][12678] Running state [maas.process_maas_config] at time 02:53:41.793084
2019-07-12 02:53:41,793 [salt.state       :1813][INFO    ][12678] Executing state module.run for [maas.process_maas_config]
2019-07-12 02:53:41,794 [salt.utils.decorators:613 ][WARNING ][12678] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-12 02:53:41,795 [salt.loaded.ext.module.maas:92  ][INFO    ][12678] maasconfig name=enable_http_proxy value=True
2019-07-12 02:53:41,853 [salt.loaded.ext.module.maas:92  ][INFO    ][12678] maasconfig name=upstream_dns value=8.8.8.8
2019-07-12 02:53:41,913 [salt.loaded.ext.module.maas:92  ][INFO    ][12678] maasconfig name=commissioning_distro_series value=xenial
2019-07-12 02:53:41,968 [salt.loaded.ext.module.maas:92  ][INFO    ][12678] maasconfig name=default_osystem value=ubuntu
2019-07-12 02:53:43,395 [salt.loaded.ext.module.maas:92  ][INFO    ][12678] maasconfig name=active_discovery_interval value=600
2019-07-12 02:53:43,443 [salt.loaded.ext.module.maas:92  ][INFO    ][12678] maasconfig name=dnssec_validation value=no
2019-07-12 02:53:43,493 [salt.loaded.ext.module.maas:92  ][INFO    ][12678] maasconfig name=maas_name value=mas01
2019-07-12 02:53:43,545 [salt.loaded.ext.module.maas:92  ][INFO    ][12678] maasconfig name=network_discovery value=enabled
2019-07-12 02:53:43,642 [salt.loaded.ext.module.maas:92  ][INFO    ][12678] maasconfig name=enable_third_party_drivers value=True
2019-07-12 02:53:43,690 [salt.loaded.ext.module.maas:92  ][INFO    ][12678] maasconfig name=default_storage_layout value=lvm
2019-07-12 02:53:43,749 [salt.loaded.ext.module.maas:92  ][INFO    ][12678] maasconfig name=ntp_external_only value=True
2019-07-12 02:53:43,798 [salt.loaded.ext.module.maas:92  ][INFO    ][12678] maasconfig name=disk_erase_with_secure_erase value=False
2019-07-12 02:53:43,844 [salt.loaded.ext.module.maas:92  ][INFO    ][12678] maasconfig name=default_distro_series value=xenial
2019-07-12 02:53:43,901 [salt.loaded.ext.module.maas:92  ][INFO    ][12678] maasconfig name=default_min_hwe_kernel value=ga-16.04
2019-07-12 02:53:44,031 [salt.state       :300 ][INFO    ][12678] {'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-07-12 02:53:44,031 [salt.state       :1951][INFO    ][12678] Completed state [maas.process_maas_config] at time 02:53:44.031389 duration_in_ms=2238.305
2019-07-12 02:53:44,032 [salt.state       :1780][INFO    ][12678] Running state [pxe_admin] at time 02:53:44.032071
2019-07-12 02:53:44,032 [salt.state       :1813][INFO    ][12678] Executing state maasng.fabric_present for [pxe_admin]
2019-07-12 02:53:44,090 [salt.loaded.ext.module.maasng:945 ][INFO    ][12678] [{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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0, u'name': u'fabric-0', u'class_type': None}, {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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2, u'name': u'fabric-2', u'class_type': None}, {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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wxar8s', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1, u'name': u'pxe_admin', u'class_type': u''}]
2019-07-12 02:53:44,151 [salt.loaded.ext.module.maasng:1008][WARNING ][12678] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-07-12 02:53:44,151 [salt.loaded.ext.module.maasng:1011][WARNING ][12678] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-07-12 02:53:44,213 [salt.state       :300 ][INFO    ][12678] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-07-12 02:53:44,215 [salt.state       :1951][INFO    ][12678] Completed state [pxe_admin] at time 02:53:44.215806 duration_in_ms=183.734
2019-07-12 02:53:44,216 [salt.state       :1780][INFO    ][12678] Running state [vlan 0] at time 02:53:44.216267
2019-07-12 02:53:44,216 [salt.state       :1813][INFO    ][12678] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-07-12 02:53:44,297 [salt.loaded.ext.module.maasng:945 ][INFO    ][12678] [{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'wxar8s', 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-07-12 02:53:44,431 [salt.loaded.ext.module.maasng:945 ][INFO    ][12678] [{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'wxar8s', 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-07-12 02:53:44,679 [salt.loaded.ext.module.maasng:945 ][INFO    ][12678] [{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'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0, u'class_type': None, u'name': u'fabric-0'}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'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'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2, u'class_type': None, u'name': u'fabric-2'}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'wxar8s', 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'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1, u'class_type': u'', u'name': u'pxe_admin'}]
2019-07-12 02:53:44,757 [salt.state       :300 ][INFO    ][12678] {'new': 'Vlan untagged was updated'}
2019-07-12 02:53:44,758 [salt.state       :1951][INFO    ][12678] Completed state [vlan 0] at time 02:53:44.758258 duration_in_ms=541.991
2019-07-12 02:53:44,759 [salt.state       :1780][INFO    ][12678] Running state [192.168.11.0/24] at time 02:53:44.759514
2019-07-12 02:53:44,760 [salt.state       :1813][INFO    ][12678] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-07-12 02:53:44,941 [salt.loaded.ext.module.maasng:945 ][INFO    ][12678] [{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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0, u'name': u'fabric-0', u'class_type': None}, {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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2, u'name': u'fabric-2', u'class_type': None}, {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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wxar8s', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1, u'name': u'pxe_admin', u'class_type': u''}]
2019-07-12 02:53:44,941 [salt.loaded.ext.module.maasng:1235][WARNING ][12678] Ignoring parameter vlan:0
2019-07-12 02:53:45,031 [salt.state       :300 ][INFO    ][12678] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-07-12 02:53:45,031 [salt.state       :1951][INFO    ][12678] Completed state [192.168.11.0/24] at time 02:53:45.031825 duration_in_ms=272.312
2019-07-12 02:53:45,032 [salt.state       :1780][INFO    ][12678] Running state [maas_create_iprange_1] at time 02:53:45.032712
2019-07-12 02:53:45,033 [salt.state       :1813][INFO    ][12678] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-07-12 02:53:45,100 [salt.state       :300 ][INFO    ][12678] Iprange maas_create_iprange_1 already exist.
2019-07-12 02:53:45,101 [salt.state       :1951][INFO    ][12678] Completed state [maas_create_iprange_1] at time 02:53:45.101037 duration_in_ms=68.324
2019-07-12 02:53:45,101 [salt.state       :1780][INFO    ][12678] Running state [vlan 0] at time 02:53:45.101338
2019-07-12 02:53:45,101 [salt.state       :1813][INFO    ][12678] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-07-12 02:53:45,161 [salt.loaded.ext.module.maasng:945 ][INFO    ][12678] [{u'id': 0, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'class_type': None, u'vlans': [{u'name': u'untagged', 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'fabric': u'fabric-2'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'wxar8s', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-07-12 02:53:45,259 [salt.loaded.ext.module.maasng:945 ][INFO    ][12678] [{u'id': 0, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'class_type': None, u'vlans': [{u'name': u'untagged', 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'fabric': u'fabric-2'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'wxar8s', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-07-12 02:53:45,517 [salt.loaded.ext.module.maasng:945 ][INFO    ][12678] [{u'id': 0, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'class_type': None, u'vlans': [{u'name': u'untagged', 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'fabric': u'fabric-2'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'wxar8s', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-07-12 02:53:45,610 [salt.state       :300 ][INFO    ][12678] {'new': 'Vlan untagged was updated'}
2019-07-12 02:53:45,611 [salt.state       :1951][INFO    ][12678] Completed state [vlan 0] at time 02:53:45.610950 duration_in_ms=509.611
2019-07-12 02:53:45,611 [salt.state       :1780][INFO    ][12678] Running state [opnfv] at time 02:53:45.611745
2019-07-12 02:53:45,612 [salt.state       :1813][INFO    ][12678] Executing state maasng.sshkey_present for [opnfv]
2019-07-12 02:53:45,665 [salt.loaded.ext.module.maasng:1903][INFO    ][12678] [{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-07-12 02:53:45,666 [salt.state       :300 ][INFO    ][12678] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-07-12 02:53:45,666 [salt.state       :1951][INFO    ][12678] Completed state [opnfv] at time 02:53:45.666475 duration_in_ms=54.73
2019-07-12 02:53:45,669 [salt.minion      :1711][INFO    ][12678] Returning information for job: 20190712025322790739
2019-07-12 02:53:46,288 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command state.apply with jid 20190712025346279641
2019-07-12 02:53:46,306 [salt.minion      :1432][INFO    ][13131] Starting a new job with PID 13131
2019-07-12 02:53:52,384 [salt.state       :915 ][INFO    ][13131] Loading fresh modules for state activity
2019-07-12 02:53:52,494 [salt.state       :1780][INFO    ][13131] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:52.494909
2019-07-12 02:53:52,495 [salt.state       :1813][INFO    ][13131] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-12 02:53:52,497 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13131] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-12 02:53:54,204 [salt.state       :300 ][INFO    ][13131] {'pid': 13156, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-12 02:53:54,205 [salt.state       :1951][INFO    ][13131] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:54.205287 duration_in_ms=1710.377
2019-07-12 02:53:54,208 [salt.state       :1780][INFO    ][13131] Running state [maas.process_machines] at time 02:53:54.208261
2019-07-12 02:53:54,208 [salt.state       :1813][INFO    ][13131] Executing state module.run for [maas.process_machines]
2019-07-12 02:53:54,209 [salt.utils.decorators:613 ][WARNING ][13131] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-12 02:53:54,940 [salt.loaded.ext.module.maas:412 ][WARNING ][13131] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-12 02:53:54,941 [salt.loaded.ext.module.maas:92  ][INFO    ][13131] 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=t4dm36 architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-12 02:53:56,194 [salt.loaded.ext.module.maas:412 ][WARNING ][13131] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-12 02:53:56,195 [salt.loaded.ext.module.maas:92  ][INFO    ][13131] 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=a6bdpg architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-12 02:53:57,441 [salt.loaded.ext.module.maas:412 ][WARNING ][13131] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-12 02:53:57,442 [salt.loaded.ext.module.maas:92  ][INFO    ][13131] 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=6q63dy architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-12 02:53:58,667 [salt.loaded.ext.module.maas:412 ][WARNING ][13131] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-12 02:53:58,668 [salt.loaded.ext.module.maas:92  ][INFO    ][13131] 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=pbfe3a architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-12 02:53:59,878 [salt.loaded.ext.module.maas:412 ][WARNING ][13131] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-12 02:53:59,879 [salt.loaded.ext.module.maas:92  ][INFO    ][13131] 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=kmwat6 architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-12 02:54:01,050 [salt.state       :300 ][INFO    ][13131] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-07-12 02:54:01,051 [salt.state       :1951][INFO    ][13131] Completed state [maas.process_machines] at time 02:54:01.051418 duration_in_ms=6843.155
2019-07-12 02:54:01,055 [salt.minion      :1711][INFO    ][13131] Returning information for job: 20190712025346279641
2019-07-12 02:54:34,061 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command state.apply with jid 20190712025434049649
2019-07-12 02:54:34,086 [salt.minion      :1432][INFO    ][13424] Starting a new job with PID 13424
2019-07-12 02:54:40,065 [salt.state       :915 ][INFO    ][13424] Loading fresh modules for state activity
2019-07-12 02:54:40,162 [salt.state       :1780][INFO    ][13424] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:40.162016
2019-07-12 02:54:40,162 [salt.state       :1813][INFO    ][13424] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-12 02:54:40,167 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13424] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-12 02:54:41,914 [salt.state       :300 ][INFO    ][13424] {'pid': 13431, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-12 02:54:41,915 [salt.state       :1951][INFO    ][13424] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:41.915436 duration_in_ms=1753.42
2019-07-12 02:54:41,917 [salt.state       :1780][INFO    ][13424] Running state [maas.wait_for_machine_status] at time 02:54:41.916976
2019-07-12 02:54:41,917 [salt.state       :1813][INFO    ][13424] Executing state module.run for [maas.wait_for_machine_status]
2019-07-12 02:54:41,917 [salt.utils.decorators:613 ][WARNING ][13424] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-12 02:54:44,695 [salt.state       :300 ][INFO    ][13424] {'ret': True}
2019-07-12 02:54:44,695 [salt.state       :1951][INFO    ][13424] Completed state [maas.wait_for_machine_status] at time 02:54:44.695765 duration_in_ms=2778.786
2019-07-12 02:54:44,700 [salt.minion      :1711][INFO    ][13424] Returning information for job: 20190712025434049649
2019-07-12 02:54:45,334 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command state.apply with jid 20190712025445275104
2019-07-12 02:54:45,364 [salt.minion      :1432][INFO    ][13451] Starting a new job with PID 13451
2019-07-12 02:54:46,347 [salt.state       :915 ][INFO    ][13451] Loading fresh modules for state activity
2019-07-12 02:54:46,495 [salt.state       :1780][INFO    ][13451] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:46.495605
2019-07-12 02:54:46,495 [salt.state       :1813][INFO    ][13451] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-12 02:54:46,498 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13451] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-12 02:54:48,238 [salt.state       :300 ][INFO    ][13451] {'pid': 13458, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-12 02:54:48,239 [salt.state       :1951][INFO    ][13451] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:48.239806 duration_in_ms=1744.199
2019-07-12 02:54:48,243 [salt.state       :1780][INFO    ][13451] Running state [maas_machines_storage_cmp002_lvm] at time 02:54:48.243931
2019-07-12 02:54:48,244 [salt.state       :1813][INFO    ][13451] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-07-12 02:54:48,826 [salt.state       :300 ][INFO    ][13451] Machine cmp002 is not in Ready state.
2019-07-12 02:54:48,826 [salt.state       :1951][INFO    ][13451] Completed state [maas_machines_storage_cmp002_lvm] at time 02:54:48.826614 duration_in_ms=582.681
2019-07-12 02:54:48,827 [salt.state       :1780][INFO    ][13451] Running state [maas_machines_storage_cmp001_lvm] at time 02:54:48.827202
2019-07-12 02:54:48,827 [salt.state       :1813][INFO    ][13451] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-07-12 02:54:49,365 [salt.state       :300 ][INFO    ][13451] Machine cmp001 is not in Ready state.
2019-07-12 02:54:49,366 [salt.state       :1951][INFO    ][13451] Completed state [maas_machines_storage_cmp001_lvm] at time 02:54:49.365634 duration_in_ms=538.43
2019-07-12 02:54:49,372 [salt.minion      :1711][INFO    ][13451] Returning information for job: 20190712025445275104
2019-07-12 02:54:49,931 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command state.apply with jid 20190712025449920815
2019-07-12 02:54:49,956 [salt.minion      :1432][INFO    ][13468] Starting a new job with PID 13468
2019-07-12 02:54:50,928 [salt.state       :915 ][INFO    ][13468] Loading fresh modules for state activity
2019-07-12 02:54:51,030 [salt.state       :1780][INFO    ][13468] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:51.030325
2019-07-12 02:54:51,030 [salt.state       :1813][INFO    ][13468] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-12 02:54:51,032 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13468] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-12 02:54:52,752 [salt.state       :300 ][INFO    ][13468] {'pid': 13475, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-12 02:54:52,753 [salt.state       :1951][INFO    ][13468] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:52.753113 duration_in_ms=1722.787
2019-07-12 02:54:52,757 [salt.state       :1780][INFO    ][13468] Running state [maas.deploy_machines] at time 02:54:52.757053
2019-07-12 02:54:52,757 [salt.state       :1813][INFO    ][13468] Executing state module.run for [maas.deploy_machines]
2019-07-12 02:54:52,758 [salt.utils.decorators:613 ][WARNING ][13468] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-12 02:54:53,326 [salt.loaded.ext.module.maas:684 ][INFO    ][13468] deploymachines hwe_kernel=ga-16.04 system_id=kmwat6 distro_series=xenial
2019-07-12 02:54:55,771 [salt.state       :300 ][INFO    ][13468] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-07-12 02:54:55,772 [salt.state       :1951][INFO    ][13468] Completed state [maas.deploy_machines] at time 02:54:55.772507 duration_in_ms=3015.452
2019-07-12 02:54:55,776 [salt.minion      :1711][INFO    ][13468] Returning information for job: 20190712025449920815
2019-07-12 02:54:56,339 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command state.apply with jid 20190712025456325592
2019-07-12 02:54:56,363 [salt.minion      :1432][INFO    ][13534] Starting a new job with PID 13534
2019-07-12 02:55:02,311 [salt.state       :915 ][INFO    ][13534] Loading fresh modules for state activity
2019-07-12 02:55:02,408 [salt.state       :1780][INFO    ][13534] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:55:02.408091
2019-07-12 02:55:02,408 [salt.state       :1813][INFO    ][13534] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-12 02:55:02,410 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13534] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-12 02:55:04,199 [salt.state       :300 ][INFO    ][13534] {'pid': 13549, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-12 02:55:04,200 [salt.state       :1951][INFO    ][13534] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:55:04.200560 duration_in_ms=1792.468
2019-07-12 02:55:04,204 [salt.state       :1780][INFO    ][13534] Running state [maas.wait_for_machine_status] at time 02:55:04.204387
2019-07-12 02:55:04,205 [salt.state       :1813][INFO    ][13534] Executing state module.run for [maas.wait_for_machine_status]
2019-07-12 02:55:04,205 [salt.utils.decorators:613 ][WARNING ][13534] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-12 02:55:07,247 [salt.loaded.ext.module.maas:1023][INFO    ][13534] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2246.98161697s left)
2019-07-12 02:55:11,388 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712025511372377
2019-07-12 02:55:11,411 [salt.minion      :1432][INFO    ][13563] Starting a new job with PID 13563
2019-07-12 02:55:11,434 [salt.minion      :1711][INFO    ][13563] Returning information for job: 20190712025511372377
2019-07-12 02:55:39,994 [salt.loaded.ext.module.maas:1023][INFO    ][13534] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.23420882s left)
2019-07-12 02:55:41,456 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712025541448221
2019-07-12 02:55:41,480 [salt.minion      :1432][INFO    ][13636] Starting a new job with PID 13636
2019-07-12 02:55:41,501 [salt.minion      :1711][INFO    ][13636] Returning information for job: 20190712025541448221
2019-07-12 02:56:11,527 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712025611517305
2019-07-12 02:56:11,548 [salt.minion      :1432][INFO    ][13660] Starting a new job with PID 13660
2019-07-12 02:56:11,571 [salt.minion      :1711][INFO    ][13660] Returning information for job: 20190712025611517305
2019-07-12 02:56:12,727 [salt.loaded.ext.module.maas:1023][INFO    ][13534] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2181.50155687s left)
2019-07-12 02:56:41,603 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712025641588817
2019-07-12 02:56:41,632 [salt.minion      :1432][INFO    ][13711] Starting a new job with PID 13711
2019-07-12 02:56:41,656 [salt.minion      :1711][INFO    ][13711] Returning information for job: 20190712025641588817
2019-07-12 02:56:45,607 [salt.loaded.ext.module.maas:1023][INFO    ][13534] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2148.62117982s left)
2019-07-12 02:57:11,686 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712025711674520
2019-07-12 02:57:11,716 [salt.minion      :1432][INFO    ][13735] Starting a new job with PID 13735
2019-07-12 02:57:11,741 [salt.minion      :1711][INFO    ][13735] Returning information for job: 20190712025711674520
2019-07-12 02:57:18,608 [salt.loaded.ext.module.maas:1023][INFO    ][13534] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2115.61994886s left)
2019-07-12 02:57:41,784 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712025741771027
2019-07-12 02:57:41,811 [salt.minion      :1432][INFO    ][13790] Starting a new job with PID 13790
2019-07-12 02:57:41,836 [salt.minion      :1711][INFO    ][13790] Returning information for job: 20190712025741771027
2019-07-12 02:57:51,433 [salt.loaded.ext.module.maas:1023][INFO    ][13534] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2082.79552484s left)
2019-07-12 02:58:11,863 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712025811847560
2019-07-12 02:58:11,886 [salt.minion      :1432][INFO    ][13810] Starting a new job with PID 13810
2019-07-12 02:58:11,911 [salt.minion      :1711][INFO    ][13810] Returning information for job: 20190712025811847560
2019-07-12 02:58:24,221 [salt.loaded.ext.module.maas:1023][INFO    ][13534] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2050.00717592s left)
2019-07-12 02:58:41,974 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712025841955890
2019-07-12 02:58:41,997 [salt.minion      :1432][INFO    ][13865] Starting a new job with PID 13865
2019-07-12 02:58:42,020 [salt.minion      :1711][INFO    ][13865] Returning information for job: 20190712025841955890
2019-07-12 02:58:57,083 [salt.loaded.ext.module.maas:1023][INFO    ][13534] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2017.14549088s left)
2019-07-12 02:59:12,044 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712025912031811
2019-07-12 02:59:12,066 [salt.minion      :1432][INFO    ][13911] Starting a new job with PID 13911
2019-07-12 02:59:12,091 [salt.minion      :1711][INFO    ][13911] Returning information for job: 20190712025912031811
2019-07-12 02:59:30,153 [salt.loaded.ext.module.maas:1023][INFO    ][13534] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1984.07542396s left)
2019-07-12 02:59:42,143 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712025942129412
2019-07-12 02:59:42,174 [salt.minion      :1432][INFO    ][13996] Starting a new job with PID 13996
2019-07-12 02:59:42,212 [salt.minion      :1711][INFO    ][13996] Returning information for job: 20190712025942129412
2019-07-12 03:00:03,030 [salt.loaded.ext.module.maas:1023][INFO    ][13534] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1951.19785786s left)
2019-07-12 03:00:12,264 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712030012248238
2019-07-12 03:00:12,288 [salt.minion      :1432][INFO    ][14022] Starting a new job with PID 14022
2019-07-12 03:00:12,315 [salt.minion      :1711][INFO    ][14022] Returning information for job: 20190712030012248238
2019-07-12 03:00:35,875 [salt.loaded.ext.module.maas:1023][INFO    ][13534] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1918.35330796s left)
2019-07-12 03:00:42,396 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712030042379720
2019-07-12 03:00:42,428 [salt.minion      :1432][INFO    ][14240] Starting a new job with PID 14240
2019-07-12 03:00:42,454 [salt.minion      :1711][INFO    ][14240] Returning information for job: 20190712030042379720
2019-07-12 03:01:08,855 [salt.loaded.ext.module.maas:1023][INFO    ][13534] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1885.37325501s left)
2019-07-12 03:01:12,512 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712030112503501
2019-07-12 03:01:12,534 [salt.minion      :1432][INFO    ][14265] Starting a new job with PID 14265
2019-07-12 03:01:12,556 [salt.minion      :1711][INFO    ][14265] Returning information for job: 20190712030112503501
2019-07-12 03:01:42,124 [salt.loaded.ext.module.maas:1023][INFO    ][13534] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1852.10441279s left)
2019-07-12 03:01:42,653 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712030142639430
2019-07-12 03:01:42,681 [salt.minion      :1432][INFO    ][14370] Starting a new job with PID 14370
2019-07-12 03:01:42,705 [salt.minion      :1711][INFO    ][14370] Returning information for job: 20190712030142639430
2019-07-12 03:02:12,800 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712030212790748
2019-07-12 03:02:12,820 [salt.minion      :1432][INFO    ][14388] Starting a new job with PID 14388
2019-07-12 03:02:12,840 [salt.minion      :1711][INFO    ][14388] Returning information for job: 20190712030212790748
2019-07-12 03:02:14,971 [salt.loaded.ext.module.maas:1023][INFO    ][13534] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1819.2574749s left)
2019-07-12 03:02:42,937 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712030242923272
2019-07-12 03:02:42,963 [salt.minion      :1432][INFO    ][14512] Starting a new job with PID 14512
2019-07-12 03:02:42,987 [salt.minion      :1711][INFO    ][14512] Returning information for job: 20190712030242923272
2019-07-12 03:02:47,993 [salt.loaded.ext.module.maas:1023][INFO    ][13534] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1786.23484087s left)
2019-07-12 03:03:13,089 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712030313074637
2019-07-12 03:03:13,116 [salt.minion      :1432][INFO    ][14537] Starting a new job with PID 14537
2019-07-12 03:03:13,137 [salt.minion      :1711][INFO    ][14537] Returning information for job: 20190712030313074637
2019-07-12 03:03:20,721 [salt.loaded.ext.module.maas:1023][INFO    ][13534] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1753.50764084s left)
2019-07-12 03:03:43,249 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712030343234129
2019-07-12 03:03:43,270 [salt.minion      :1432][INFO    ][14661] Starting a new job with PID 14661
2019-07-12 03:03:43,294 [salt.minion      :1711][INFO    ][14661] Returning information for job: 20190712030343234129
2019-07-12 03:03:53,517 [salt.loaded.ext.module.maas:1023][INFO    ][13534] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1720.71140695s left)
2019-07-12 03:04:13,412 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712030413398064
2019-07-12 03:04:13,440 [salt.minion      :1432][INFO    ][14682] Starting a new job with PID 14682
2019-07-12 03:04:13,462 [salt.minion      :1711][INFO    ][14682] Returning information for job: 20190712030413398064
2019-07-12 03:04:26,235 [salt.loaded.ext.module.maas:1023][INFO    ][13534] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1687.99267197s left)
2019-07-12 03:04:43,584 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712030443569350
2019-07-12 03:04:43,615 [salt.minion      :1432][INFO    ][14739] Starting a new job with PID 14739
2019-07-12 03:04:43,637 [salt.minion      :1711][INFO    ][14739] Returning information for job: 20190712030443569350
2019-07-12 03:04:59,206 [salt.loaded.ext.module.maas:1023][INFO    ][13534] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1655.02266788s left)
2019-07-12 03:05:13,771 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712030513754952
2019-07-12 03:05:13,798 [salt.minion      :1432][INFO    ][14761] Starting a new job with PID 14761
2019-07-12 03:05:13,822 [salt.minion      :1711][INFO    ][14761] Returning information for job: 20190712030513754952
2019-07-12 03:05:21,378 [salt.utils.schedule:1377][INFO    ][3072] Running scheduled job: __mine_interval
2019-07-12 03:05:32,112 [salt.loaded.ext.module.maas:1023][INFO    ][13534] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1622.11594701s left)
2019-07-12 03:05:43,960 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712030543945200
2019-07-12 03:05:43,987 [salt.minion      :1432][INFO    ][14846] Starting a new job with PID 14846
2019-07-12 03:05:44,010 [salt.minion      :1711][INFO    ][14846] Returning information for job: 20190712030543945200
2019-07-12 03:06:05,248 [salt.loaded.ext.module.maas:1023][INFO    ][13534] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1588.980304s left)
2019-07-12 03:06:14,153 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712030614135199
2019-07-12 03:06:14,182 [salt.minion      :1432][INFO    ][14867] Starting a new job with PID 14867
2019-07-12 03:06:14,205 [salt.minion      :1711][INFO    ][14867] Returning information for job: 20190712030614135199
2019-07-12 03:06:38,031 [salt.loaded.ext.module.maas:1023][INFO    ][13534] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1556.19699383s left)
2019-07-12 03:06:44,352 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712030644336268
2019-07-12 03:06:44,382 [salt.minion      :1432][INFO    ][15066] Starting a new job with PID 15066
2019-07-12 03:06:44,404 [salt.minion      :1711][INFO    ][15066] Returning information for job: 20190712030644336268
2019-07-12 03:07:10,962 [salt.state       :300 ][INFO    ][13534] {'ret': True}
2019-07-12 03:07:10,963 [salt.state       :1951][INFO    ][13534] Completed state [maas.wait_for_machine_status] at time 03:07:10.963281 duration_in_ms=726758.889
2019-07-12 03:07:10,968 [salt.minion      :1711][INFO    ][13534] Returning information for job: 20190712025456325592
2019-07-12 04:05:21,378 [salt.utils.schedule:1377][INFO    ][3072] Running scheduled job: __mine_interval
2019-07-12 04:35:23,886 [salt.minion      :1308][INFO    ][3072] User sudo_ubuntu Executing command cp.push_dir with jid 20190712043523874230
2019-07-12 04:35:23,910 [salt.minion      :1432][INFO    ][21325] Starting a new job with PID 21325
