2019-06-25 05:02:43,070 [salt.minion      :870 ][ERROR   ][359] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-06-25 05:03:33,115 [salt.minion      :870 ][ERROR   ][359] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-06-25 05:04:23,162 [salt.minion      :870 ][ERROR   ][359] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-06-25 05:05:13,203 [salt.minion      :870 ][ERROR   ][359] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-06-25 05:06:03,253 [salt.minion      :870 ][ERROR   ][359] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-06-25 05:08:12,157 [salt.utils.decorators:613 ][WARNING ][2733] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-25 05:08:12,777 [salt.utils.decorators:613 ][WARNING ][2733] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-25 05:08:14,858 [salt.loaded.int.states.file:2298][WARNING ][2879] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-06-25 05:08:20,074 [salt.state       :2022][WARNING ][2985] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-06-25 05:08:22,714 [salt.utils.decorators:613 ][WARNING ][2985] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-25 05:23:28,549 [salt.utils.decorators:613 ][WARNING ][2985] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-25 05:51:35,368 [salt.utils.decorators:613 ][WARNING ][2985] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-25 06:06:39,173 [salt.utils.decorators:613 ][WARNING ][2985] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-25 06:06:43,466 [salt.loaded.ext.module.maasng:1008][WARNING ][2985] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-06-25 06:06:43,467 [salt.loaded.ext.module.maasng:1011][WARNING ][2985] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-06-25 06:06:44,202 [salt.loaded.ext.module.maasng:1235][WARNING ][2985] Ignoring parameter vlan:0
2019-06-25 06:06:45,169 [salt.utils.decorators:613 ][WARNING ][2985] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-25 06:06:51,126 [salt.utils.decorators:613 ][WARNING ][12670] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-25 06:06:51,192 [salt.loaded.ext.module.maas:412 ][WARNING ][12670] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-25 06:06:55,374 [salt.loaded.ext.module.maas:412 ][WARNING ][12670] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-25 06:06:59,307 [salt.loaded.ext.module.maas:412 ][WARNING ][12670] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-25 06:07:03,241 [salt.loaded.ext.module.maas:412 ][WARNING ][12670] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-25 06:07:07,313 [salt.loaded.ext.module.maas:412 ][WARNING ][12670] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-25 06:07:14,552 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13623] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-06-25 06:07:14,583 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13623] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-06-25 06:07:14,604 [salt.utils.parsers:1051][WARNING ][359] Minion received a SIGTERM. Exiting.
2019-06-25 06:07:15,682 [salt.cli.daemons :293 ][INFO    ][13668] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-06-25 06:07:15,781 [salt.cli.daemons :82  ][INFO    ][13668] Starting up the Salt Minion
2019-06-25 06:07:15,782 [salt.utils.event :1017][INFO    ][13668] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-06-25 06:07:16,668 [salt.minion      :976 ][INFO    ][13668] Creating minion process manager
2019-06-25 06:07:17,983 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13668] Executing command ['date', '+%z'] in directory '/root'
2019-06-25 06:07:18,001 [salt.utils.schedule:568 ][INFO    ][13668] Updating job settings for scheduled job: __mine_interval
2019-06-25 06:07:18,002 [salt.minion      :1108][INFO    ][13668] Added mine.update to scheduler
2019-06-25 06:07:18,005 [salt.minion      :1975][INFO    ][13668] Minion is starting as user 'root'
2019-06-25 06:07:18,018 [salt.minion      :2336][INFO    ][13668] Minion is ready to receive requests!
2019-06-25 06:07:43,008 [salt.minion      :1308][INFO    ][13668] User sudo_ubuntu Executing command state.apply with jid 20190625060742995564
2019-06-25 06:07:43,031 [salt.minion      :1432][INFO    ][13794] Starting a new job with PID 13794
2019-06-25 06:07:46,697 [salt.state       :915 ][INFO    ][13794] Loading fresh modules for state activity
2019-06-25 06:07:46,750 [salt.fileclient  :1219][INFO    ][13794] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-06-25 06:07:46,791 [salt.state       :1780][INFO    ][13794] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:07:46.791669
2019-06-25 06:07:46,792 [salt.state       :1813][INFO    ][13794] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-25 06:07:46,793 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13794] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-25 06:07:48,344 [salt.state       :300 ][INFO    ][13794] {'pid': 13807, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-25 06:07:48,345 [salt.state       :1951][INFO    ][13794] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:07:48.345730 duration_in_ms=1554.06
2019-06-25 06:07:48,349 [salt.state       :1780][INFO    ][13794] Running state [maas.wait_for_machine_status] at time 06:07:48.348971
2019-06-25 06:07:48,349 [salt.state       :1813][INFO    ][13794] Executing state module.run for [maas.wait_for_machine_status]
2019-06-25 06:07:48,350 [salt.utils.decorators:613 ][WARNING ][13794] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-25 06:07:49,309 [salt.loaded.ext.module.maas:1023][INFO    ][13794] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.05019307s left)
2019-06-25 06:07:58,087 [salt.minion      :1308][INFO    ][13668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625060758074739
2019-06-25 06:07:58,111 [salt.minion      :1432][INFO    ][13820] Starting a new job with PID 13820
2019-06-25 06:07:58,134 [salt.minion      :1711][INFO    ][13820] Returning information for job: 20190625060758074739
2019-06-25 06:08:20,274 [salt.loaded.ext.module.maas:1023][INFO    ][13794] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.08506703s left)
2019-06-25 06:08:28,138 [salt.minion      :1308][INFO    ][13668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625060828125087
2019-06-25 06:08:28,160 [salt.minion      :1432][INFO    ][13907] Starting a new job with PID 13907
2019-06-25 06:08:28,183 [salt.minion      :1711][INFO    ][13907] Returning information for job: 20190625060828125087
2019-06-25 06:08:52,394 [salt.loaded.ext.module.maas:1023][INFO    ][13794] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1435.96481609s left)
2019-06-25 06:08:58,215 [salt.minion      :1308][INFO    ][13668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625060858200521
2019-06-25 06:08:58,234 [salt.minion      :1432][INFO    ][14279] Starting a new job with PID 14279
2019-06-25 06:08:58,252 [salt.minion      :1711][INFO    ][14279] Returning information for job: 20190625060858200521
2019-06-25 06:09:28,287 [salt.minion      :1308][INFO    ][13668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625060928274378
2019-06-25 06:09:28,312 [salt.minion      :1432][INFO    ][14441] Starting a new job with PID 14441
2019-06-25 06:09:28,335 [salt.minion      :1711][INFO    ][14441] Returning information for job: 20190625060928274378
2019-06-25 06:09:58,370 [salt.minion      :1308][INFO    ][13668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625060958356764
2019-06-25 06:09:58,392 [salt.minion      :1432][INFO    ][14494] Starting a new job with PID 14494
2019-06-25 06:09:58,416 [salt.minion      :1711][INFO    ][14494] Returning information for job: 20190625060958356764
2019-06-25 06:10:28,456 [salt.minion      :1308][INFO    ][13668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625061028442452
2019-06-25 06:10:28,478 [salt.minion      :1432][INFO    ][14580] Starting a new job with PID 14580
2019-06-25 06:10:28,502 [salt.minion      :1711][INFO    ][14580] Returning information for job: 20190625061028442452
2019-06-25 06:10:58,536 [salt.minion      :1308][INFO    ][13668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625061058527504
2019-06-25 06:10:58,560 [salt.minion      :1432][INFO    ][14640] Starting a new job with PID 14640
2019-06-25 06:10:58,584 [salt.minion      :1711][INFO    ][14640] Returning information for job: 20190625061058527504
2019-06-25 06:11:28,627 [salt.minion      :1308][INFO    ][13668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625061128613852
2019-06-25 06:11:28,644 [salt.minion      :1432][INFO    ][14706] Starting a new job with PID 14706
2019-06-25 06:11:28,660 [salt.minion      :1711][INFO    ][14706] Returning information for job: 20190625061128613852
2019-06-25 06:11:58,715 [salt.minion      :1308][INFO    ][13668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625061158706325
2019-06-25 06:11:58,735 [salt.minion      :1432][INFO    ][14736] Starting a new job with PID 14736
2019-06-25 06:11:58,759 [salt.minion      :1711][INFO    ][14736] Returning information for job: 20190625061158706325
2019-06-25 06:12:28,805 [salt.minion      :1308][INFO    ][13668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625061228792861
2019-06-25 06:12:28,828 [salt.minion      :1432][INFO    ][14776] Starting a new job with PID 14776
2019-06-25 06:12:28,851 [salt.minion      :1711][INFO    ][14776] Returning information for job: 20190625061228792861
2019-06-25 06:12:58,907 [salt.minion      :1308][INFO    ][13668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625061258895249
2019-06-25 06:12:58,929 [salt.minion      :1432][INFO    ][14804] Starting a new job with PID 14804
2019-06-25 06:12:58,954 [salt.minion      :1711][INFO    ][14804] Returning information for job: 20190625061258895249
2019-06-25 06:13:29,008 [salt.minion      :1308][INFO    ][13668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625061328998723
2019-06-25 06:13:29,027 [salt.minion      :1432][INFO    ][14842] Starting a new job with PID 14842
2019-06-25 06:13:29,046 [salt.minion      :1711][INFO    ][14842] Returning information for job: 20190625061328998723
2019-06-25 06:13:59,112 [salt.minion      :1308][INFO    ][13668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625061359099239
2019-06-25 06:13:59,135 [salt.minion      :1432][INFO    ][14868] Starting a new job with PID 14868
2019-06-25 06:13:59,159 [salt.minion      :1711][INFO    ][14868] Returning information for job: 20190625061359099239
2019-06-25 06:14:16,344 [salt.loaded.ext.module.maas:1023][INFO    ][13794] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1112.01554418s left)
2019-06-25 06:14:29,223 [salt.minion      :1308][INFO    ][13668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625061429210965
2019-06-25 06:14:29,246 [salt.minion      :1432][INFO    ][15376] Starting a new job with PID 15376
2019-06-25 06:14:29,270 [salt.minion      :1711][INFO    ][15376] Returning information for job: 20190625061429210965
2019-06-25 06:14:48,073 [salt.loaded.ext.module.maas:1023][INFO    ][13794] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1080.28641009s left)
2019-06-25 06:14:59,320 [salt.minion      :1308][INFO    ][13668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625061459307622
2019-06-25 06:14:59,343 [salt.minion      :1432][INFO    ][15485] Starting a new job with PID 15485
2019-06-25 06:14:59,367 [salt.minion      :1711][INFO    ][15485] Returning information for job: 20190625061459307622
2019-06-25 06:15:19,715 [salt.loaded.ext.module.maas:1023][INFO    ][13794] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1048.64445615s left)
2019-06-25 06:15:29,414 [salt.minion      :1308][INFO    ][13668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625061529401357
2019-06-25 06:15:29,437 [salt.minion      :1432][INFO    ][15733] Starting a new job with PID 15733
2019-06-25 06:15:29,460 [salt.minion      :1711][INFO    ][15733] Returning information for job: 20190625061529401357
2019-06-25 06:15:52,883 [salt.loaded.ext.module.maas:1023][INFO    ][13794] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1015.47567105s left)
2019-06-25 06:15:59,524 [salt.minion      :1308][INFO    ][13668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625061559510645
2019-06-25 06:15:59,547 [salt.minion      :1432][INFO    ][15856] Starting a new job with PID 15856
2019-06-25 06:15:59,570 [salt.minion      :1711][INFO    ][15856] Returning information for job: 20190625061559510645
2019-06-25 06:16:25,970 [salt.loaded.ext.module.maas:1023][INFO    ][13794] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (982.389361143s left)
2019-06-25 06:16:29,637 [salt.minion      :1308][INFO    ][13668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625061629625109
2019-06-25 06:16:29,657 [salt.minion      :1432][INFO    ][15938] Starting a new job with PID 15938
2019-06-25 06:16:29,679 [salt.minion      :1711][INFO    ][15938] Returning information for job: 20190625061629625109
2019-06-25 06:16:59,079 [salt.loaded.ext.module.maas:1023][INFO    ][13794] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (949.280290127s left)
2019-06-25 06:16:59,751 [salt.minion      :1308][INFO    ][13668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625061659739355
2019-06-25 06:16:59,773 [salt.minion      :1432][INFO    ][15957] Starting a new job with PID 15957
2019-06-25 06:16:59,796 [salt.minion      :1711][INFO    ][15957] Returning information for job: 20190625061659739355
2019-06-25 06:17:29,876 [salt.minion      :1308][INFO    ][13668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625061729863128
2019-06-25 06:17:29,898 [salt.minion      :1432][INFO    ][16014] Starting a new job with PID 16014
2019-06-25 06:17:29,922 [salt.minion      :1711][INFO    ][16014] Returning information for job: 20190625061729863128
2019-06-25 06:17:32,156 [salt.loaded.ext.module.maas:981 ][INFO    ][13794] Machine ky6e4s deleted
2019-06-25 06:17:32,845 [salt.loaded.ext.module.maas:412 ][WARNING ][13794] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-25 06:17:32,846 [salt.loaded.ext.module.maas:92  ][INFO    ][13794] machine hostname=cmp002 power_type=ipmi mac_addresses=['00:25:b5:a0:00:6a'] power_parameters_power_address=172.30.8.72 power_parameters_power_pass=octopus system_id=mh4byw architecture=amd64/generic power_parameters_power_user=admin
2019-06-25 06:17:34,089 [salt.loaded.ext.module.maas:412 ][WARNING ][13794] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-25 06:17:34,090 [salt.loaded.ext.module.maas:92  ][INFO    ][13794] machine hostname=cmp001 power_type=ipmi mac_addresses=['00:25:b5:a0:00:5a'] power_parameters_power_address=172.30.8.73 power_parameters_power_pass=octopus system_id=ac3mb3 architecture=amd64/generic power_parameters_power_user=admin
2019-06-25 06:17:35,323 [salt.loaded.ext.module.maas:412 ][WARNING ][13794] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-25 06:17:35,323 [salt.loaded.ext.module.maas:92  ][INFO    ][13794] machine hostname=kvm01 power_type=ipmi mac_addresses=['00:25:b5:a0:00:2a'] power_parameters_power_address=172.30.8.75 power_parameters_power_pass=octopus system_id=yswerh architecture=amd64/generic power_parameters_power_user=admin
2019-06-25 06:17:36,604 [salt.loaded.ext.module.maas:412 ][WARNING ][13794] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-25 06:17:36,605 [salt.loaded.ext.module.maas:92  ][INFO    ][13794] machine hostname=kvm03 power_type=ipmi mac_addresses=['00:25:b5:a0:00:4a'] power_parameters_power_address=172.30.8.74 power_parameters_power_pass=octopus system_id=qw4pbs architecture=amd64/generic power_parameters_power_user=admin
2019-06-25 06:17:37,694 [salt.loaded.ext.module.maas:412 ][WARNING ][13794] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-25 06:17:37,694 [salt.loaded.ext.module.maas:92  ][INFO    ][13794] machine hostname=kvm02 power_type=ipmi mac_addresses=00:25:b5:a0:00:3a power_parameters_power_address=172.30.8.65 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-06-25 06:17:38,847 [salt.loaded.ext.module.maas:1023][INFO    ][13794] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (909.512447119s left)
2019-06-25 06:18:00,013 [salt.minion      :1308][INFO    ][13668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625061800000399
2019-06-25 06:18:00,036 [salt.minion      :1432][INFO    ][16261] Starting a new job with PID 16261
2019-06-25 06:18:00,059 [salt.minion      :1711][INFO    ][16261] Returning information for job: 20190625061800000399
2019-06-25 06:18:12,018 [salt.loaded.ext.module.maas:1023][INFO    ][13794] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (876.340769053s left)
2019-06-25 06:18:30,156 [salt.minion      :1308][INFO    ][13668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625061830143609
2019-06-25 06:18:30,179 [salt.minion      :1432][INFO    ][16331] Starting a new job with PID 16331
2019-06-25 06:18:30,202 [salt.minion      :1711][INFO    ][16331] Returning information for job: 20190625061830143609
2019-06-25 06:18:45,136 [salt.loaded.ext.module.maas:1023][INFO    ][13794] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (843.223277092s left)
2019-06-25 06:19:00,307 [salt.minion      :1308][INFO    ][13668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625061900294139
2019-06-25 06:19:00,329 [salt.minion      :1432][INFO    ][16363] Starting a new job with PID 16363
2019-06-25 06:19:00,353 [salt.minion      :1711][INFO    ][16363] Returning information for job: 20190625061900294139
2019-06-25 06:19:18,297 [salt.loaded.ext.module.maas:1023][INFO    ][13794] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (810.061950207s left)
2019-06-25 06:19:30,456 [salt.minion      :1308][INFO    ][13668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625061930449579
2019-06-25 06:19:30,476 [salt.minion      :1432][INFO    ][16446] Starting a new job with PID 16446
2019-06-25 06:19:30,500 [salt.minion      :1711][INFO    ][16446] Returning information for job: 20190625061930449579
2019-06-25 06:19:51,362 [salt.loaded.ext.module.maas:1023][INFO    ][13794] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (776.997555017s left)
2019-06-25 06:20:00,614 [salt.minion      :1308][INFO    ][13668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625062000601988
2019-06-25 06:20:00,636 [salt.minion      :1432][INFO    ][16481] Starting a new job with PID 16481
2019-06-25 06:20:00,661 [salt.minion      :1711][INFO    ][16481] Returning information for job: 20190625062000601988
2019-06-25 06:20:23,935 [salt.loaded.ext.module.maas:1023][INFO    ][13794] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (744.423619032s left)
2019-06-25 06:20:30,798 [salt.minion      :1308][INFO    ][13668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625062030785848
2019-06-25 06:20:30,821 [salt.minion      :1432][INFO    ][16680] Starting a new job with PID 16680
2019-06-25 06:20:30,845 [salt.minion      :1711][INFO    ][16680] Returning information for job: 20190625062030785848
2019-06-25 06:20:57,210 [salt.loaded.ext.module.maas:1023][INFO    ][13794] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (711.149136066s left)
2019-06-25 06:21:00,974 [salt.minion      :1308][INFO    ][13668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625062100961234
2019-06-25 06:21:00,997 [salt.minion      :1432][INFO    ][16699] Starting a new job with PID 16699
2019-06-25 06:21:01,021 [salt.minion      :1711][INFO    ][16699] Returning information for job: 20190625062100961234
2019-06-25 06:21:30,838 [salt.state       :300 ][INFO    ][13794] {'ret': True}
2019-06-25 06:21:30,839 [salt.state       :1951][INFO    ][13794] Completed state [maas.wait_for_machine_status] at time 06:21:30.839075 duration_in_ms=822490.102
2019-06-25 06:21:30,843 [salt.minion      :1711][INFO    ][13794] Returning information for job: 20190625060742995564
2019-06-25 06:21:31,500 [salt.minion      :1308][INFO    ][13668] User sudo_ubuntu Executing command state.apply with jid 20190625062131487092
2019-06-25 06:21:31,522 [salt.minion      :1432][INFO    ][16781] Starting a new job with PID 16781
2019-06-25 06:21:35,265 [salt.state       :915 ][INFO    ][16781] Loading fresh modules for state activity
2019-06-25 06:21:35,316 [salt.fileclient  :1219][INFO    ][16781] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-06-25 06:21:35,408 [salt.state       :1780][INFO    ][16781] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:21:35.408203
2019-06-25 06:21:35,408 [salt.state       :1813][INFO    ][16781] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-25 06:21:35,410 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16781] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-25 06:21:36,718 [salt.state       :300 ][INFO    ][16781] {'pid': 16788, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-25 06:21:36,719 [salt.state       :1951][INFO    ][16781] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:21:36.719067 duration_in_ms=1310.864
2019-06-25 06:21:36,720 [salt.state       :1780][INFO    ][16781] Running state [maas_machines_storage_cmp002_lvm] at time 06:21:36.720424
2019-06-25 06:21:36,720 [salt.state       :1813][INFO    ][16781] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-06-25 06:21:38,048 [salt.loaded.ext.module.maasng:610 ][INFO    ][16781] mh4byw
2019-06-25 06:21:38,048 [salt.loaded.ext.module.maasng:626 ][INFO    ][16781] sda
2019-06-25 06:21:38,778 [salt.loaded.ext.module.maasng:361 ][INFO    ][16781] mh4byw
2019-06-25 06:21:38,910 [salt.loaded.ext.module.maasng:367 ][INFO    ][16781] [{u'size': 2397998940160, u'resource_uri': u'/MAAS/api/2.0/nodes/mh4byw/blockdevices/4/', u'uuid': None, u'tags': [u'rotary'], u'system_id': u'mh4byw', u'used_size': 2397998940160, u'partitions': [{u'uuid': u'6fc6f920-e9c7-4200-a9c1-edde7c7739e1', u'resource_uri': u'/MAAS/api/2.0/nodes/mh4byw/blockdevices/4/partition/4', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'mh4byw', u'filesystem': {u'label': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'2018130b-29b2-455f-926d-a74d01d807e6', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 4, u'type': u'partition', u'id': 4, u'size': 2397992648704}], u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'id': 4, u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'type': u'physical', u'serial': u'618e728372755980239b15112698bc66', u'name': u'sda'}, {u'size': 2397988454400, u'resource_uri': u'/MAAS/api/2.0/nodes/mh4byw/blockdevices/8/', u'uuid': u'33187965-4f77-4b4c-8007-7f86544abc64', u'tags': [], u'system_id': u'mh4byw', u'used_size': 2397988454400, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'id': 8, u'partition_table_type': None, u'filesystem': {u'label': u'root', u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'5dab31a0-5c6f-43a4-8b11-6972db21c3d3', u'mount_options': None}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'type': u'virtual', u'serial': None, u'name': u'vgroot-lvroot'}]
2019-06-25 06:21:38,911 [salt.loaded.ext.module.maasng:632 ][INFO    ][16781] vgroot
2019-06-25 06:21:38,911 [salt.loaded.ext.module.maasng:635 ][INFO    ][16781] lvroot
2019-06-25 06:21:38,912 [salt.loaded.ext.module.maasng:639 ][INFO    ][16781] 107374182400
2019-06-25 06:21:39,673 [salt.loaded.ext.module.maasng:645 ][INFO    ][16781] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'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'iscsiblockdevice_set': [], u'boot_disk': {u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/mh4byw/blockdevices/4/', u'uuid': None, u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'used_size': 2397998940160, u'partitions': [{u'uuid': u'70d5a558-f9aa-4875-a138-6519ecdaa68e', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'mh4byw', u'device_id': 4, u'filesystem': {u'uuid': u'853d0a76-954d-4b2c-b3d4-14cea776cbb3', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/mh4byw/blockdevices/4/partition/6', u'type': u'partition', u'id': 6, u'size': 2397992648704}], u'filesystem': None, u'name': u'sda', u'system_id': u'mh4byw', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'type': u'physical', u'id': 4, u'size': 2397998940160}, u'blockdevice_set': [{u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/mh4byw/blockdevices/4/', u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'70d5a558-f9aa-4875-a138-6519ecdaa68e', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'mh4byw', u'device_id': 4, u'filesystem': {u'uuid': u'853d0a76-954d-4b2c-b3d4-14cea776cbb3', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/mh4byw/blockdevices/4/partition/6', u'type': u'partition', u'id': 6, u'size': 2397992648704}], u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'mh4byw', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'type': u'physical', u'id': 4, u'size': 2397998940160}, {u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/mh4byw/blockdevices/11/', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'uuid': u'065c9402-f693-4965-b14d-ea6803035078', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'uuid': u'e96a40d0-982e-4274-affb-ab06fb133b4c', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'mh4byw', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 11, u'size': 107374182400}], u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'commissioning_status': 2, u'testing_status': 2, u'system_id': u'mh4byw', u'raids': [], u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, 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/mh4byw/blockdevices/11/', u'uuid': u'e96a40d0-982e-4274-affb-ab06fb133b4c', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'uuid': u'065c9402-f693-4965-b14d-ea6803035078', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'name': u'vgroot-lvroot', u'system_id': u'mh4byw', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 11, u'size': 107374182400}], u'architecture': u'amd64/generic', u'status': 4, u'storage_test_status': 2, u'cpu_count': 16, u'power_state': u'off', u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'mh4byw', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_interface': {u'name': u'enp6s0', 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': 2, u'name': u'untagged', u'primary_rack': u'7mt8gk', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, 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': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40', u'id': 24, u'mode': u'dhcp'}], u'tags': [], u'enabled': True, u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'name': u'untagged', u'primary_rack': u'7mt8gk', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'effective_mtu': 1500, u'id': 4, 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': 2, u'name': u'untagged', u'primary_rack': u'7mt8gk', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, 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': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40'}], u'parents': [], u'mac_address': u'00:25:b5:a0:00:6a', u'params': u'', u'system_id': u'mh4byw', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/mh4byw/interfaces/4/'}, u'interface_set': [{u'name': u'enp6s0', 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': 2, u'name': u'untagged', u'primary_rack': u'7mt8gk', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, 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': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40', u'id': 24, u'mode': u'dhcp'}], u'tags': [], u'enabled': True, u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'name': u'untagged', u'primary_rack': u'7mt8gk', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'effective_mtu': 1500, u'id': 4, 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': 2, u'name': u'untagged', u'primary_rack': u'7mt8gk', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, 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': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40'}], u'parents': [], u'mac_address': u'00:25:b5:a0:00:6a', u'params': u'', u'system_id': u'mh4byw', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/mh4byw/interfaces/4/'}, {u'name': u'enp7s0', u'links': [{u'id': 27, u'mode': u'link_up'}], u'tags': [], u'enabled': True, u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'effective_mtu': 1500, u'id': 13, u'discovered': None, u'parents': [], u'mac_address': u'00:25:b5:a0:00:6b', u'params': u'', u'system_id': u'mh4byw', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/mh4byw/interfaces/13/'}, {u'name': u'enp8s0', u'links': [{u'id': 30, u'mode': u'link_up'}], u'tags': [], u'enabled': True, u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'effective_mtu': 1500, u'id': 19, u'discovered': None, u'parents': [], u'mac_address': u'00:25:b5:a0:00:6c', u'params': u'', u'system_id': u'mh4byw', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/mh4byw/interfaces/19/'}, {u'name': u'enp9s0', u'links': [{u'id': 32, u'mode': u'link_up'}], u'tags': [], u'enabled': True, u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'effective_mtu': 1500, u'id': 20, u'discovered': None, u'parents': [], u'mac_address': u'00:25:b5:a0:00:6d', u'params': u'', u'system_id': u'mh4byw', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/mh4byw/interfaces/20/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/mh4byw/blockdevices/4/', u'uuid': None, u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'used_size': 2397998940160, u'partitions': [{u'uuid': u'70d5a558-f9aa-4875-a138-6519ecdaa68e', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'mh4byw', u'device_id': 4, u'filesystem': {u'uuid': u'853d0a76-954d-4b2c-b3d4-14cea776cbb3', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/mh4byw/blockdevices/4/partition/6', u'type': u'partition', u'id': 6, u'size': 2397992648704}], u'filesystem': None, u'name': u'sda', u'system_id': u'mh4byw', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'type': u'physical', u'id': 4, u'size': 2397998940160}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'node_type': 0, u'ip_addresses': [u'192.168.11.40'], u'min_hwe_kernel': u'hwe-16.04', u'commissioning_status_name': u'Passed', u'current_commissioning_result_id': 2, u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/mh4byw/'}
2019-06-25 06:21:39,675 [salt.state       :300 ][INFO    ][16781] {'new': {'storage_layout': 'lvm'}}
2019-06-25 06:21:39,676 [salt.state       :1951][INFO    ][16781] Completed state [maas_machines_storage_cmp002_lvm] at time 06:21:39.676211 duration_in_ms=2955.785
2019-06-25 06:21:39,676 [salt.state       :1780][INFO    ][16781] Running state [maas_machines_storage_cmp001_lvm] at time 06:21:39.676812
2019-06-25 06:21:39,677 [salt.state       :1813][INFO    ][16781] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-06-25 06:21:41,126 [salt.loaded.ext.module.maasng:610 ][INFO    ][16781] ac3mb3
2019-06-25 06:21:41,126 [salt.loaded.ext.module.maasng:626 ][INFO    ][16781] sda
2019-06-25 06:21:41,765 [salt.loaded.ext.module.maasng:361 ][INFO    ][16781] ac3mb3
2019-06-25 06:21:41,892 [salt.loaded.ext.module.maasng:367 ][INFO    ][16781] [{u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/ac3mb3/blockdevices/1/', u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'61b0b10a-2428-483b-b75b-e62339b797ad', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ac3mb3', u'device_id': 1, u'filesystem': {u'uuid': u'd5d88b72-eb08-47aa-a2db-3d58882b34ea', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/ac3mb3/blockdevices/1/partition/1', u'type': u'partition', u'id': 1, u'size': 2397992648704}], u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'ac3mb3', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'type': u'physical', u'id': 1, u'size': 2397998940160}, {u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/ac3mb3/blockdevices/5/', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 2397988454400, u'partitions': [], u'filesystem': {u'uuid': u'3f2c9559-b2bd-4ac8-99c0-e25b5f4f2898', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'uuid': u'831d81c6-0984-4742-939a-4661d581678c', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'ac3mb3', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 5, u'size': 2397988454400}]
2019-06-25 06:21:41,893 [salt.loaded.ext.module.maasng:632 ][INFO    ][16781] vgroot
2019-06-25 06:21:41,893 [salt.loaded.ext.module.maasng:635 ][INFO    ][16781] lvroot
2019-06-25 06:21:41,893 [salt.loaded.ext.module.maasng:639 ][INFO    ][16781] 107374182400
2019-06-25 06:21:42,617 [salt.loaded.ext.module.maasng:645 ][INFO    ][16781] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'disable_ipv4': False, u'cpu_count': 16, u'power_type': u'ipmi', 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'boot_interface': {u'name': u'enp6s0', 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': 2, u'mtu': 1500, u'primary_rack': u'7mt8gk', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, 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': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 14, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'7mt8gk', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'7mt8gk', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, 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': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'00:25:b5:a0:00:5a', u'system_id': u'ac3mb3', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ac3mb3/interfaces/5/'}, u'min_hwe_kernel': u'hwe-16.04', u'node_type': 0, 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'status_action': u'', u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/ac3mb3/', u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'ac3mb3', u'power_state': u'off', u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, 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'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'uuid': u'1fb34908-d6b6-45e4-a1d8-4cc871463a89', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'ac3mb3', u'partition_table_type': None, u'filesystem': {u'uuid': u'c8fbacee-67fa-41a7-9c3b-a94d34a6aa62', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'label': u'root'}, u'id_path': None, u'available_size': 0, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/ac3mb3/blockdevices/12/', u'type': u'virtual', u'id': 12, u'serial': None, u'size': 107374182400}], u'blockdevice_set': [{u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/ac3mb3/blockdevices/1/', u'used_size': 2397998940160, u'partitions': [{u'uuid': u'9618e0ed-0d14-42e5-8229-f78b5deb2262', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ac3mb3', u'device_id': 1, u'filesystem': {u'uuid': u'7f8cfc2b-b8a0-47f3-b3e2-45d1477f080a', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/ac3mb3/blockdevices/1/partition/7', u'type': u'partition', u'id': 7, u'size': 2397992648704}], u'tags': [u'rotary'], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'ac3mb3', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'type': u'physical', u'id': 1, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}, {u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/ac3mb3/blockdevices/12/', u'used_size': 107374182400, u'partitions': [], u'tags': [], u'uuid': u'1fb34908-d6b6-45e4-a1d8-4cc871463a89', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'ac3mb3', u'partition_table_type': None, u'filesystem': {u'uuid': u'c8fbacee-67fa-41a7-9c3b-a94d34a6aa62', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'label': u'root'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 12, u'serial': None, u'size': 107374182400}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'physicalblockdevice_set': [{u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'9618e0ed-0d14-42e5-8229-f78b5deb2262', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ac3mb3', u'device_id': 1, u'filesystem': {u'uuid': u'7f8cfc2b-b8a0-47f3-b3e2-45d1477f080a', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/ac3mb3/blockdevices/1/partition/7', u'type': u'partition', u'id': 7, u'size': 2397992648704}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'ac3mb3', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/ac3mb3/blockdevices/1/', u'type': u'physical', u'id': 1, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}], u'ip_addresses': [u'192.168.11.38'], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'ac3mb3', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'boot_disk': {u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'9618e0ed-0d14-42e5-8229-f78b5deb2262', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ac3mb3', u'device_id': 1, u'filesystem': {u'uuid': u'7f8cfc2b-b8a0-47f3-b3e2-45d1477f080a', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/ac3mb3/blockdevices/1/partition/7', u'type': u'partition', u'id': 7, u'size': 2397992648704}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'ac3mb3', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/ac3mb3/blockdevices/1/', u'type': u'physical', u'id': 1, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}, 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'memory_test_status_name': u'Unknown', u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'interface_set': [{u'name': u'enp6s0', 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': 2, u'mtu': 1500, u'primary_rack': u'7mt8gk', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, 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': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 14, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'7mt8gk', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'7mt8gk', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, 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': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'00:25:b5:a0:00:5a', u'system_id': u'ac3mb3', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ac3mb3/interfaces/5/'}, {u'name': u'enp7s0', u'links': [{u'id': 15, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'id': 9, u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5b', u'system_id': u'ac3mb3', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ac3mb3/interfaces/9/'}, {u'name': u'enp9s0', u'links': [{u'id': 16, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'id': 10, u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5d', u'system_id': u'ac3mb3', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ac3mb3/interfaces/10/'}, {u'name': u'enp8s0', u'links': [{u'id': 17, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'id': 11, u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5c', u'system_id': u'ac3mb3', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ac3mb3/interfaces/11/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-06-25 06:21:42,619 [salt.state       :300 ][INFO    ][16781] {'new': {'storage_layout': 'lvm'}}
2019-06-25 06:21:42,620 [salt.state       :1951][INFO    ][16781] Completed state [maas_machines_storage_cmp001_lvm] at time 06:21:42.620122 duration_in_ms=2943.309
2019-06-25 06:21:42,624 [salt.minion      :1711][INFO    ][16781] Returning information for job: 20190625062131487092
2019-06-25 06:21:43,248 [salt.minion      :1308][INFO    ][13668] User sudo_ubuntu Executing command state.apply with jid 20190625062143235601
2019-06-25 06:21:43,269 [salt.minion      :1432][INFO    ][16806] Starting a new job with PID 16806
2019-06-25 06:21:44,007 [salt.state       :915 ][INFO    ][16806] Loading fresh modules for state activity
2019-06-25 06:21:44,057 [salt.fileclient  :1219][INFO    ][16806] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-06-25 06:21:44,096 [salt.state       :1780][INFO    ][16806] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:21:44.096220
2019-06-25 06:21:44,096 [salt.state       :1813][INFO    ][16806] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-25 06:21:44,098 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16806] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-25 06:21:45,460 [salt.state       :300 ][INFO    ][16806] {'pid': 16822, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-25 06:21:45,460 [salt.state       :1951][INFO    ][16806] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:21:45.460587 duration_in_ms=1364.367
2019-06-25 06:21:45,461 [salt.state       :1780][INFO    ][16806] Running state [maas.deploy_machines] at time 06:21:45.461655
2019-06-25 06:21:45,461 [salt.state       :1813][INFO    ][16806] Executing state module.run for [maas.deploy_machines]
2019-06-25 06:21:45,462 [salt.utils.decorators:613 ][WARNING ][16806] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-25 06:21:46,183 [salt.loaded.ext.module.maas:684 ][INFO    ][16806] deploymachines hwe_kernel=hwe-16.04 system_id=mh4byw distro_series=xenial
2019-06-25 06:21:49,101 [salt.loaded.ext.module.maas:684 ][INFO    ][16806] deploymachines hwe_kernel=hwe-16.04 system_id=ac3mb3 distro_series=xenial
2019-06-25 06:21:51,217 [salt.loaded.ext.module.maas:684 ][INFO    ][16806] deploymachines hwe_kernel=hwe-16.04 system_id=yswerh distro_series=xenial
2019-06-25 06:21:53,278 [salt.loaded.ext.module.maas:684 ][INFO    ][16806] deploymachines hwe_kernel=hwe-16.04 system_id=qw4pbs distro_series=xenial
2019-06-25 06:21:55,444 [salt.loaded.ext.module.maas:684 ][INFO    ][16806] deploymachines hwe_kernel=hwe-16.04 system_id=s8cffs distro_series=xenial
2019-06-25 06:21:58,219 [salt.state       :300 ][INFO    ][16806] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-06-25 06:21:58,220 [salt.state       :1951][INFO    ][16806] Completed state [maas.deploy_machines] at time 06:21:58.220127 duration_in_ms=12758.469
2019-06-25 06:21:58,223 [salt.minion      :1711][INFO    ][16806] Returning information for job: 20190625062143235601
2019-06-25 06:21:58,864 [salt.minion      :1308][INFO    ][13668] User sudo_ubuntu Executing command state.apply with jid 20190625062158851032
2019-06-25 06:21:58,887 [salt.minion      :1432][INFO    ][17097] Starting a new job with PID 17097
2019-06-25 06:22:02,507 [salt.state       :915 ][INFO    ][17097] Loading fresh modules for state activity
2019-06-25 06:22:02,561 [salt.fileclient  :1219][INFO    ][17097] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-06-25 06:22:02,613 [salt.state       :1780][INFO    ][17097] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:22:02.613336
2019-06-25 06:22:02,613 [salt.state       :1813][INFO    ][17097] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-25 06:22:02,616 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17097] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-25 06:22:03,874 [salt.state       :300 ][INFO    ][17097] {'pid': 17111, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-25 06:22:03,875 [salt.state       :1951][INFO    ][17097] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:22:03.875561 duration_in_ms=1262.225
2019-06-25 06:22:03,878 [salt.state       :1780][INFO    ][17097] Running state [maas.wait_for_machine_status] at time 06:22:03.878911
2019-06-25 06:22:03,879 [salt.state       :1813][INFO    ][17097] Executing state module.run for [maas.wait_for_machine_status]
2019-06-25 06:22:03,880 [salt.utils.decorators:613 ][WARNING ][17097] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-25 06:22:07,476 [salt.loaded.ext.module.maas:1023][INFO    ][17097] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.41391706s left)
2019-06-25 06:22:13,902 [salt.minion      :1308][INFO    ][13668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625062213890481
2019-06-25 06:22:13,924 [salt.minion      :1432][INFO    ][17124] Starting a new job with PID 17124
2019-06-25 06:22:13,946 [salt.minion      :1711][INFO    ][17124] Returning information for job: 20190625062213890481
2019-06-25 06:22:41,057 [salt.loaded.ext.module.maas:1023][INFO    ][17097] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2212.83299398s left)
2019-06-25 06:22:43,956 [salt.minion      :1308][INFO    ][13668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625062243941560
2019-06-25 06:22:43,979 [salt.minion      :1432][INFO    ][17178] Starting a new job with PID 17178
2019-06-25 06:22:44,000 [salt.minion      :1711][INFO    ][17178] Returning information for job: 20190625062243941560
2019-06-25 06:23:14,048 [salt.minion      :1308][INFO    ][13668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625062314036374
2019-06-25 06:23:14,071 [salt.minion      :1432][INFO    ][17199] Starting a new job with PID 17199
2019-06-25 06:23:14,095 [salt.minion      :1711][INFO    ][17199] Returning information for job: 20190625062314036374
2019-06-25 06:23:14,363 [salt.loaded.ext.module.maas:1023][INFO    ][17097] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2179.52725601s left)
2019-06-25 06:23:44,094 [salt.minion      :1308][INFO    ][13668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625062344082413
2019-06-25 06:23:44,113 [salt.minion      :1432][INFO    ][17341] Starting a new job with PID 17341
2019-06-25 06:23:44,136 [salt.minion      :1711][INFO    ][17341] Returning information for job: 20190625062344082413
2019-06-25 06:23:47,968 [salt.loaded.ext.module.maas:1023][INFO    ][17097] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2145.9225769s left)
2019-06-25 06:24:14,141 [salt.minion      :1308][INFO    ][13668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625062414128544
2019-06-25 06:24:14,164 [salt.minion      :1432][INFO    ][17507] Starting a new job with PID 17507
2019-06-25 06:24:14,189 [salt.minion      :1711][INFO    ][17507] Returning information for job: 20190625062414128544
2019-06-25 06:24:21,207 [salt.loaded.ext.module.maas:1023][INFO    ][17097] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2112.68294096s left)
2019-06-25 06:24:44,201 [salt.minion      :1308][INFO    ][13668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625062444188667
2019-06-25 06:24:44,225 [salt.minion      :1432][INFO    ][18190] Starting a new job with PID 18190
2019-06-25 06:24:44,249 [salt.minion      :1711][INFO    ][18190] Returning information for job: 20190625062444188667
2019-06-25 06:24:54,450 [salt.loaded.ext.module.maas:1023][INFO    ][17097] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2079.4404521s left)
2019-06-25 06:25:14,268 [salt.minion      :1308][INFO    ][13668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625062514255169
2019-06-25 06:25:14,291 [salt.minion      :1432][INFO    ][18285] Starting a new job with PID 18285
2019-06-25 06:25:14,316 [salt.minion      :1711][INFO    ][18285] Returning information for job: 20190625062514255169
2019-06-25 06:25:27,990 [salt.loaded.ext.module.maas:1023][INFO    ][17097] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2045.90001202s left)
2019-06-25 06:25:44,327 [salt.minion      :1308][INFO    ][13668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625062544315408
2019-06-25 06:25:44,350 [salt.minion      :1432][INFO    ][18540] Starting a new job with PID 18540
2019-06-25 06:25:44,373 [salt.minion      :1711][INFO    ][18540] Returning information for job: 20190625062544315408
2019-06-25 06:26:01,566 [salt.loaded.ext.module.maas:1023][INFO    ][17097] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2012.32441807s left)
2019-06-25 06:26:14,391 [salt.minion      :1308][INFO    ][13668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625062614378122
2019-06-25 06:26:14,414 [salt.minion      :1432][INFO    ][18567] Starting a new job with PID 18567
2019-06-25 06:26:14,438 [salt.minion      :1711][INFO    ][18567] Returning information for job: 20190625062614378122
2019-06-25 06:26:34,898 [salt.loaded.ext.module.maas:1023][INFO    ][17097] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1978.99215102s left)
2019-06-25 06:26:44,460 [salt.minion      :1308][INFO    ][13668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625062644447297
2019-06-25 06:26:44,483 [salt.minion      :1432][INFO    ][19079] Starting a new job with PID 19079
2019-06-25 06:26:44,507 [salt.minion      :1711][INFO    ][19079] Returning information for job: 20190625062644447297
2019-06-25 06:27:08,438 [salt.loaded.ext.module.maas:1023][INFO    ][17097] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1945.45265293s left)
2019-06-25 06:27:14,533 [salt.minion      :1308][INFO    ][13668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625062714521079
2019-06-25 06:27:14,557 [salt.minion      :1432][INFO    ][19112] Starting a new job with PID 19112
2019-06-25 06:27:14,580 [salt.minion      :1711][INFO    ][19112] Returning information for job: 20190625062714521079
2019-06-25 06:27:42,074 [salt.loaded.ext.module.maas:1023][INFO    ][17097] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1911.81662393s left)
2019-06-25 06:27:44,619 [salt.minion      :1308][INFO    ][13668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625062744605850
2019-06-25 06:27:44,643 [salt.minion      :1432][INFO    ][19610] Starting a new job with PID 19610
2019-06-25 06:27:44,668 [salt.minion      :1711][INFO    ][19610] Returning information for job: 20190625062744605850
2019-06-25 06:28:14,703 [salt.minion      :1308][INFO    ][13668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625062814690906
2019-06-25 06:28:14,722 [salt.minion      :1432][INFO    ][19630] Starting a new job with PID 19630
2019-06-25 06:28:14,743 [salt.minion      :1711][INFO    ][19630] Returning information for job: 20190625062814690906
2019-06-25 06:28:15,379 [salt.loaded.ext.module.maas:1023][INFO    ][17097] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1878.51126909s left)
2019-06-25 06:28:44,782 [salt.minion      :1308][INFO    ][13668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625062844769353
2019-06-25 06:28:44,805 [salt.minion      :1432][INFO    ][19703] Starting a new job with PID 19703
2019-06-25 06:28:44,830 [salt.minion      :1711][INFO    ][19703] Returning information for job: 20190625062844769353
2019-06-25 06:28:48,589 [salt.loaded.ext.module.maas:1023][INFO    ][17097] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1845.30125499s left)
2019-06-25 06:29:14,883 [salt.minion      :1308][INFO    ][13668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625062914870268
2019-06-25 06:29:14,905 [salt.minion      :1432][INFO    ][19846] Starting a new job with PID 19846
2019-06-25 06:29:14,929 [salt.minion      :1711][INFO    ][19846] Returning information for job: 20190625062914870268
2019-06-25 06:29:21,534 [salt.state       :300 ][INFO    ][17097] {'ret': True}
2019-06-25 06:29:21,534 [salt.state       :1951][INFO    ][17097] Completed state [maas.wait_for_machine_status] at time 06:29:21.534894 duration_in_ms=437655.983
2019-06-25 06:29:21,537 [salt.minion      :1711][INFO    ][17097] Returning information for job: 20190625062158851032
2019-06-25 07:07:19,020 [salt.utils.schedule:1377][INFO    ][13668] Running scheduled job: __mine_interval
2019-06-25 07:58:17,885 [salt.minion      :1308][INFO    ][13668] User sudo_ubuntu Executing command cp.push_dir with jid 20190625075817873110
2019-06-25 07:58:17,910 [salt.minion      :1432][INFO    ][26316] Starting a new job with PID 26316
