2019-04-22 07:47:45,668 [salt.minion      :870 ][ERROR   ][277] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-22 07:48:35,708 [salt.minion      :870 ][ERROR   ][277] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-22 07:49:25,752 [salt.minion      :870 ][ERROR   ][277] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-22 07:50:15,792 [salt.minion      :870 ][ERROR   ][277] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-22 07:51:05,841 [salt.minion      :870 ][ERROR   ][277] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-22 07:51:55,884 [salt.minion      :870 ][ERROR   ][277] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-22 07:53:25,700 [salt.utils.decorators:613 ][WARNING ][2824] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-22 07:53:26,654 [salt.utils.decorators:613 ][WARNING ][2824] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-22 07:53:37,323 [salt.loaded.int.states.file:2298][WARNING ][2958] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-04-22 07:53:41,517 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3172] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-04-22 07:53:41,540 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3172] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-04-22 07:53:41,586 [salt.utils.parsers:1051][WARNING ][277] Minion received a SIGTERM. Exiting.
2019-04-22 07:53:42,758 [salt.cli.daemons :293 ][INFO    ][3358] Setting up the Salt Minion "mas01.mcp-ovs-ha.local"
2019-04-22 07:53:42,931 [salt.cli.daemons :82  ][INFO    ][3358] Starting up the Salt Minion
2019-04-22 07:53:42,931 [salt.utils.event :1017][INFO    ][3358] Starting pull socket on /var/run/salt/minion/minion_event_501f9ec045_pull.ipc
2019-04-22 07:53:44,095 [salt.minion      :976 ][INFO    ][3358] Creating minion process manager
2019-04-22 07:53:45,284 [salt.state       :2022][WARNING ][3176] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-04-22 07:53:46,590 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][3358] Executing command ['date', '+%z'] in directory '/root'
2019-04-22 07:53:46,616 [salt.utils.schedule:568 ][INFO    ][3358] Updating job settings for scheduled job: __mine_interval
2019-04-22 07:53:46,617 [salt.minion      :1108][INFO    ][3358] Added mine.update to scheduler
2019-04-22 07:53:46,622 [salt.minion      :1975][INFO    ][3358] Minion is starting as user 'root'
2019-04-22 07:53:46,633 [salt.minion      :2336][INFO    ][3358] Minion is ready to receive requests!
2019-04-22 07:53:48,393 [salt.utils.decorators:613 ][WARNING ][3176] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-22 07:53:53,530 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20190422075353509801
2019-04-22 07:53:53,566 [salt.minion      :1432][INFO    ][3810] Starting a new job with PID 3810
2019-04-22 07:53:53,628 [salt.minion      :1711][INFO    ][3810] Returning information for job: 20190422075353509801
2019-04-22 07:53:56,877 [salt.utils.decorators:613 ][WARNING ][3176] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-22 07:54:23,706 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20190422075423696053
2019-04-22 07:54:23,737 [salt.minion      :1432][INFO    ][4481] Starting a new job with PID 4481
2019-04-22 07:54:23,795 [salt.minion      :1711][INFO    ][4481] Returning information for job: 20190422075423696053
2019-04-22 07:54:23,845 [salt.utils.decorators:613 ][WARNING ][3176] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-22 07:54:24,857 [salt.utils.decorators:613 ][WARNING ][3176] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-22 07:54:27,108 [salt.loaded.ext.module.maasng:1008][WARNING ][3176] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-04-22 07:54:27,109 [salt.loaded.ext.module.maasng:1011][WARNING ][3176] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-04-22 07:54:27,795 [salt.loaded.ext.module.maasng:1235][WARNING ][3176] Ignoring parameter vlan:0
2019-04-22 07:54:29,491 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command state.apply with jid 20190422075429479313
2019-04-22 07:54:29,509 [salt.minion      :1432][INFO    ][4707] Starting a new job with PID 4707
2019-04-22 07:54:37,689 [salt.state       :915 ][INFO    ][4707] Loading fresh modules for state activity
2019-04-22 07:54:37,749 [salt.fileclient  :1219][INFO    ][4707] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-04-22 07:54:37,800 [salt.state       :1780][INFO    ][4707] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:54:37.800127
2019-04-22 07:54:37,800 [salt.state       :1813][INFO    ][4707] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-22 07:54:37,804 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4707] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-22 07:54:39,728 [salt.state       :300 ][INFO    ][4707] {'pid': 4733, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-22 07:54:39,728 [salt.state       :1951][INFO    ][4707] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:54:39.728577 duration_in_ms=1928.452
2019-04-22 07:54:39,730 [salt.state       :1780][INFO    ][4707] Running state [maas.process_machines] at time 07:54:39.730489
2019-04-22 07:54:39,730 [salt.state       :1813][INFO    ][4707] Executing state module.run for [maas.process_machines]
2019-04-22 07:54:39,731 [salt.utils.decorators:613 ][WARNING ][4707] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-22 07:54:39,779 [salt.loaded.ext.module.maas:412 ][WARNING ][4707] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-22 07:54:39,780 [salt.loaded.ext.module.maas:92  ][INFO    ][4707] machine hostname=cmp002 power_type=ipmi mac_addresses=9c:b6:54:8a:10:18 power_parameters_power_address=172.16.1.20 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-22 07:54:41,161 [salt.loaded.ext.module.maas:412 ][WARNING ][4707] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-22 07:54:41,162 [salt.loaded.ext.module.maas:92  ][INFO    ][4707] machine hostname=cmp001 power_type=ipmi mac_addresses=9c:b6:54:8a:95:a0 power_parameters_power_address=172.16.1.19 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-22 07:54:42,418 [salt.loaded.ext.module.maas:412 ][WARNING ][4707] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-22 07:54:42,419 [salt.loaded.ext.module.maas:92  ][INFO    ][4707] machine hostname=kvm01 power_type=ipmi mac_addresses=14:58:d0:54:e7:88 power_parameters_power_address=172.16.1.16 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-22 07:54:43,694 [salt.loaded.ext.module.maas:412 ][WARNING ][4707] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-22 07:54:43,694 [salt.loaded.ext.module.maas:92  ][INFO    ][4707] machine hostname=kvm03 power_type=ipmi mac_addresses=14:58:d0:54:7a:28 power_parameters_power_address=172.16.1.18 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-22 07:54:44,573 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20190422075444558413
2019-04-22 07:54:44,592 [salt.minion      :1432][INFO    ][4967] Starting a new job with PID 4967
2019-04-22 07:54:44,629 [salt.minion      :1711][INFO    ][4967] Returning information for job: 20190422075444558413
2019-04-22 07:54:44,945 [salt.loaded.ext.module.maas:412 ][WARNING ][4707] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-22 07:54:44,946 [salt.loaded.ext.module.maas:92  ][INFO    ][4707] machine hostname=kvm02 power_type=ipmi mac_addresses=14:58:d0:54:6a:60 power_parameters_power_address=172.16.1.17 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-22 07:54:46,236 [salt.state       :300 ][INFO    ][4707] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-04-22 07:54:46,237 [salt.state       :1951][INFO    ][4707] Completed state [maas.process_machines] at time 07:54:46.237285 duration_in_ms=6506.795
2019-04-22 07:54:46,241 [salt.minion      :1711][INFO    ][4707] Returning information for job: 20190422075429479313
2019-04-22 07:55:29,473 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command state.apply with jid 20190422075529462567
2019-04-22 07:55:29,493 [salt.minion      :1432][INFO    ][5105] Starting a new job with PID 5105
2019-04-22 07:55:37,473 [salt.state       :915 ][INFO    ][5105] Loading fresh modules for state activity
2019-04-22 07:55:37,525 [salt.fileclient  :1219][INFO    ][5105] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-04-22 07:55:37,577 [salt.state       :1780][INFO    ][5105] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:55:37.576940
2019-04-22 07:55:37,577 [salt.state       :1813][INFO    ][5105] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-22 07:55:37,579 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5105] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-22 07:55:39,485 [salt.state       :300 ][INFO    ][5105] {'pid': 5113, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-22 07:55:39,485 [salt.state       :1951][INFO    ][5105] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:55:39.485786 duration_in_ms=1908.847
2019-04-22 07:55:39,488 [salt.state       :1780][INFO    ][5105] Running state [maas.wait_for_machine_status] at time 07:55:39.488016
2019-04-22 07:55:39,488 [salt.state       :1813][INFO    ][5105] Executing state module.run for [maas.wait_for_machine_status]
2019-04-22 07:55:39,488 [salt.utils.decorators:613 ][WARNING ][5105] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-22 07:55:40,261 [salt.loaded.ext.module.maas:1023][INFO    ][5105] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.23881316s left)
2019-04-22 07:55:44,549 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20190422075544529755
2019-04-22 07:55:44,570 [salt.minion      :1432][INFO    ][5150] Starting a new job with PID 5150
2019-04-22 07:55:44,601 [salt.minion      :1711][INFO    ][5150] Returning information for job: 20190422075544529755
2019-04-22 07:56:11,021 [salt.loaded.ext.module.maas:1023][INFO    ][5105] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.47958612s left)
2019-04-22 07:56:14,723 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20190422075614676887
2019-04-22 07:56:14,742 [salt.minion      :1432][INFO    ][5197] Starting a new job with PID 5197
2019-04-22 07:56:14,769 [salt.minion      :1711][INFO    ][5197] Returning information for job: 20190422075614676887
2019-04-22 07:56:41,822 [salt.loaded.ext.module.maas:1023][INFO    ][5105] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.67834997s left)
2019-04-22 07:56:44,742 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20190422075644729256
2019-04-22 07:56:44,761 [salt.minion      :1432][INFO    ][5250] Starting a new job with PID 5250
2019-04-22 07:56:44,788 [salt.minion      :1711][INFO    ][5250] Returning information for job: 20190422075644729256
2019-04-22 07:57:12,685 [salt.loaded.ext.module.maas:1023][INFO    ][5105] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.81495905s left)
2019-04-22 07:57:14,792 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20190422075714779434
2019-04-22 07:57:14,810 [salt.minion      :1432][INFO    ][5326] Starting a new job with PID 5326
2019-04-22 07:57:14,839 [salt.minion      :1711][INFO    ][5326] Returning information for job: 20190422075714779434
2019-04-22 07:57:43,772 [salt.loaded.ext.module.maas:1023][INFO    ][5105] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.72773695s left)
2019-04-22 07:57:45,007 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20190422075744992772
2019-04-22 07:57:45,026 [salt.minion      :1432][INFO    ][5419] Starting a new job with PID 5419
2019-04-22 07:57:45,056 [salt.minion      :1711][INFO    ][5419] Returning information for job: 20190422075744992772
2019-04-22 07:58:14,717 [salt.loaded.ext.module.maas:1023][INFO    ][5105] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.78348207s left)
2019-04-22 07:58:15,230 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20190422075815221054
2019-04-22 07:58:15,246 [salt.minion      :1432][INFO    ][5560] Starting a new job with PID 5560
2019-04-22 07:58:15,270 [salt.minion      :1711][INFO    ][5560] Returning information for job: 20190422075815221054
2019-04-22 07:58:45,322 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20190422075845311548
2019-04-22 07:58:45,355 [salt.minion      :1432][INFO    ][5686] Starting a new job with PID 5686
2019-04-22 07:58:45,397 [salt.minion      :1711][INFO    ][5686] Returning information for job: 20190422075845311548
2019-04-22 07:58:46,116 [salt.loaded.ext.module.maas:1023][INFO    ][5105] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1313.38392496s left)
2019-04-22 07:59:15,429 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20190422075915419505
2019-04-22 07:59:15,451 [salt.minion      :1432][INFO    ][5966] Starting a new job with PID 5966
2019-04-22 07:59:15,479 [salt.minion      :1711][INFO    ][5966] Returning information for job: 20190422075915419505
2019-04-22 07:59:17,433 [salt.loaded.ext.module.maas:1023][INFO    ][5105] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1282.06749415s left)
2019-04-22 07:59:45,494 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20190422075945474585
2019-04-22 07:59:45,517 [salt.minion      :1432][INFO    ][6125] Starting a new job with PID 6125
2019-04-22 07:59:45,546 [salt.minion      :1711][INFO    ][6125] Returning information for job: 20190422075945474585
2019-04-22 07:59:49,537 [salt.loaded.ext.module.maas:1023][INFO    ][5105] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1249.96307898s left)
2019-04-22 08:00:15,590 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20190422080015579445
2019-04-22 08:00:15,610 [salt.minion      :1432][INFO    ][6595] Starting a new job with PID 6595
2019-04-22 08:00:15,644 [salt.minion      :1711][INFO    ][6595] Returning information for job: 20190422080015579445
2019-04-22 08:00:21,539 [salt.loaded.ext.module.maas:1023][INFO    ][5105] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1217.96089315s left)
2019-04-22 08:00:45,669 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20190422080045661486
2019-04-22 08:00:45,680 [salt.minion      :1432][INFO    ][6681] Starting a new job with PID 6681
2019-04-22 08:00:45,708 [salt.minion      :1711][INFO    ][6681] Returning information for job: 20190422080045661486
2019-04-22 08:00:53,537 [salt.loaded.ext.module.maas:1023][INFO    ][5105] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1185.96269202s left)
2019-04-22 08:01:15,729 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20190422080115719481
2019-04-22 08:01:15,742 [salt.minion      :1432][INFO    ][6899] Starting a new job with PID 6899
2019-04-22 08:01:15,768 [salt.minion      :1711][INFO    ][6899] Returning information for job: 20190422080115719481
2019-04-22 08:01:26,270 [salt.state       :300 ][INFO    ][5105] {'ret': True}
2019-04-22 08:01:26,270 [salt.state       :1951][INFO    ][5105] Completed state [maas.wait_for_machine_status] at time 08:01:26.270495 duration_in_ms=346782.478
2019-04-22 08:01:26,275 [salt.minion      :1711][INFO    ][5105] Returning information for job: 20190422075529462567
2019-04-22 08:01:26,960 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command state.apply with jid 20190422080126949313
2019-04-22 08:01:26,975 [salt.minion      :1432][INFO    ][6964] Starting a new job with PID 6964
2019-04-22 08:01:35,308 [salt.state       :915 ][INFO    ][6964] Loading fresh modules for state activity
2019-04-22 08:01:35,362 [salt.fileclient  :1219][INFO    ][6964] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-04-22 08:01:35,464 [salt.state       :1780][INFO    ][6964] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:01:35.464405
2019-04-22 08:01:35,464 [salt.state       :1813][INFO    ][6964] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-22 08:01:35,466 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6964] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-22 08:01:37,336 [salt.state       :300 ][INFO    ][6964] {'pid': 6981, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-22 08:01:37,337 [salt.state       :1951][INFO    ][6964] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:01:37.337461 duration_in_ms=1873.056
2019-04-22 08:01:37,340 [salt.state       :1780][INFO    ][6964] Running state [maas_machines_storage_cmp002_lvm] at time 08:01:37.340151
2019-04-22 08:01:37,340 [salt.state       :1813][INFO    ][6964] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-04-22 08:01:38,459 [salt.loaded.ext.module.maasng:610 ][INFO    ][6964] edhc8d
2019-04-22 08:01:38,460 [salt.loaded.ext.module.maasng:626 ][INFO    ][6964] sda
2019-04-22 08:01:39,118 [salt.loaded.ext.module.maasng:361 ][INFO    ][6964] edhc8d
2019-04-22 08:01:39,211 [salt.loaded.ext.module.maasng:367 ][INFO    ][6964] [{u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'resource_uri': u'/MAAS/api/2.0/nodes/edhc8d/blockdevices/1/', u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'system_id': u'edhc8d', 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'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'7673900e-a3f6-423f-88cd-c60b21239967', u'resource_uri': u'/MAAS/api/2.0/nodes/edhc8d/blockdevices/1/partition/1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'edhc8d', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'3f7186c6-2570-4b98-9e7c-0466acafd1a6', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 1, u'size': 800101236736}], u'type': u'physical', u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}, {u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'resource_uri': u'/MAAS/api/2.0/nodes/edhc8d/blockdevices/3/', u'uuid': u'6c378e1e-f914-45ed-a9c7-804403ec96e1', u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 800097042432, u'system_id': u'edhc8d', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'9b3545d9-6baf-4e5d-932a-ed8a4d7f3b7c', 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'id': 3, u'serial': None, u'size': 800097042432}]
2019-04-22 08:01:39,211 [salt.loaded.ext.module.maasng:632 ][INFO    ][6964] vgroot
2019-04-22 08:01:39,212 [salt.loaded.ext.module.maasng:635 ][INFO    ][6964] lvroot
2019-04-22 08:01:39,212 [salt.loaded.ext.module.maasng:639 ][INFO    ][6964] 107374182400
2019-04-22 08:01:39,815 [salt.loaded.ext.module.maasng:645 ][INFO    ][6964] {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'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'see7dg', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'id': 20, 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'see7dg', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'see7dg', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'edhc8d', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/edhc8d/interfaces/4/'}, 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'size': 800109715456, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/edhc8d/blockdevices/1/', u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'edhc8d', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'partitions': [{u'uuid': u'6cfaa317-1a7c-47d7-ad72-8478d84851a7', u'resource_uri': u'/MAAS/api/2.0/nodes/edhc8d/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'edhc8d', u'filesystem': {u'mount_options': None, u'uuid': u'0b061f92-d3a8-470a-b299-b28a6e1dd361', u'label': None, u'mount_point': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'size': 800101236736}]}, u'zone': {u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default', u'id': 1}, u'current_commissioning_result_id': 2, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'node_type': 0, u'testing_status': 2, u'system_id': u'edhc8d', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/edhc8d/blockdevices/11/', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'filesystem': {u'mount_options': None, u'uuid': u'bd5f448e-e7d0-49b1-ba0b-9784aec3e14d', u'label': u'root', u'mount_point': u'/', u'fstype': u'ext4'}, u'uuid': u'766f7cdf-aff5-41cc-b8da-8cead47635c6', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'edhc8d', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 11, u'partitions': []}], u'blockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/edhc8d/blockdevices/1/', u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'edhc8d', u'block_size': 4096, u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'size': 800109715456, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'6cfaa317-1a7c-47d7-ad72-8478d84851a7', u'resource_uri': u'/MAAS/api/2.0/nodes/edhc8d/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'edhc8d', u'filesystem': {u'mount_options': None, u'uuid': u'0b061f92-d3a8-470a-b299-b28a6e1dd361', u'label': None, u'mount_point': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'size': 800101236736}]}, {u'resource_uri': u'/MAAS/api/2.0/nodes/edhc8d/blockdevices/11/', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'filesystem': {u'mount_options': None, u'uuid': u'bd5f448e-e7d0-49b1-ba0b-9784aec3e14d', u'label': u'root', u'mount_point': u'/', u'fstype': u'ext4'}, u'uuid': u'766f7cdf-aff5-41cc-b8da-8cead47635c6', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'edhc8d', u'block_size': 4096, u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'size': 107374182400, u'used_size': 107374182400, u'id': 11, u'serial': None, u'partitions': []}], u'status': 4, u'bcaches': [], u'cpu_count': 40, u'raids': [], u'physicalblockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/edhc8d/blockdevices/1/', u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'edhc8d', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'partitions': [{u'uuid': u'6cfaa317-1a7c-47d7-ad72-8478d84851a7', u'resource_uri': u'/MAAS/api/2.0/nodes/edhc8d/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'edhc8d', u'filesystem': {u'mount_options': None, u'uuid': u'0b061f92-d3a8-470a-b299-b28a6e1dd361', u'label': None, u'mount_point': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'size': 800101236736}]}], u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'edhc8d', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'commissioning_status_name': u'Passed', u'current_testing_result_id': 3, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'memory_test_status_name': u'Unknown', u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'see7dg', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'id': 20, 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'see7dg', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'see7dg', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'edhc8d', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/edhc8d/interfaces/4/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'id': 12, u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:50', u'system_id': u'edhc8d', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/edhc8d/interfaces/12/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'id': 14, u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:51', u'system_id': u'edhc8d', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/edhc8d/interfaces/14/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'see7dg', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'see7dg', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'id': 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'see7dg', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.40'}], u'mac_address': u'9c:b6:54:8a:10:1c', u'system_id': u'edhc8d', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/edhc8d/interfaces/15/'}, {u'name': u'ens2f1', u'links': [{u'id': 22, 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'id': 11, u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:49', u'system_id': u'edhc8d', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/edhc8d/interfaces/11/'}, {u'name': u'ens2f0', u'links': [{u'id': 23, 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'id': 13, u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:48', u'system_id': u'edhc8d', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/edhc8d/interfaces/13/'}], u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/edhc8d/'}
2019-04-22 08:01:39,817 [salt.state       :300 ][INFO    ][6964] {'new': {'storage_layout': 'lvm'}}
2019-04-22 08:01:39,817 [salt.state       :1951][INFO    ][6964] Completed state [maas_machines_storage_cmp002_lvm] at time 08:01:39.817583 duration_in_ms=2477.433
2019-04-22 08:01:39,818 [salt.state       :1780][INFO    ][6964] Running state [maas_machines_storage_cmp001_lvm] at time 08:01:39.817971
2019-04-22 08:01:39,818 [salt.state       :1813][INFO    ][6964] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-04-22 08:01:40,922 [salt.loaded.ext.module.maasng:610 ][INFO    ][6964] rfhdwy
2019-04-22 08:01:40,925 [salt.loaded.ext.module.maasng:626 ][INFO    ][6964] sda
2019-04-22 08:01:41,468 [salt.loaded.ext.module.maasng:361 ][INFO    ][6964] rfhdwy
2019-04-22 08:01:41,568 [salt.loaded.ext.module.maasng:367 ][INFO    ][6964] [{u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'resource_uri': u'/MAAS/api/2.0/nodes/rfhdwy/blockdevices/2/', u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'system_id': u'rfhdwy', 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'9841709e-037b-4f43-81c1-86a7e3a264ab', u'resource_uri': u'/MAAS/api/2.0/nodes/rfhdwy/blockdevices/2/partition/2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'rfhdwy', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'd482e33c-21c1-4d52-997f-60d457c265c7', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 2, u'size': 800101236736}], u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, {u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rfhdwy/blockdevices/4/', u'uuid': u'92b3cebf-886d-4514-99fc-b933bb3843a5', u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 800097042432, u'system_id': u'rfhdwy', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'a99d3c72-379b-4dfc-8f4b-4b684fa5dbd7', 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'id': 4, u'serial': None, u'size': 800097042432}]
2019-04-22 08:01:41,568 [salt.loaded.ext.module.maasng:632 ][INFO    ][6964] vgroot
2019-04-22 08:01:41,569 [salt.loaded.ext.module.maasng:635 ][INFO    ][6964] lvroot
2019-04-22 08:01:41,569 [salt.loaded.ext.module.maasng:639 ][INFO    ][6964] 107374182400
2019-04-22 08:01:42,049 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20190422080142041218
2019-04-22 08:01:42,067 [salt.minion      :1432][INFO    ][7039] Starting a new job with PID 7039
2019-04-22 08:01:42,092 [salt.minion      :1711][INFO    ][7039] Returning information for job: 20190422080142041218
2019-04-22 08:01:42,157 [salt.loaded.ext.module.maasng:645 ][INFO    ][6964] {u'hwe_kernel': u'', u'swap_size': None, 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'boot_interface': {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'see7dg', 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'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'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'see7dg', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'id': 26, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'effective_mtu': 1500, 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'see7dg', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'system_id': u'rfhdwy', u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rfhdwy/interfaces/5/'}, u'fqdn': u'cmp001.maas', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/rfhdwy/blockdevices/2/', u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 2, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'rfhdwy', 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'block_size': 4096, u'type': u'physical', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'95936791-f0a9-4647-8b10-4b23a048efe7', u'resource_uri': u'/MAAS/api/2.0/nodes/rfhdwy/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'rfhdwy', u'filesystem': {u'label': None, u'uuid': u'c45a25a8-9402-4bad-8b89-bdc926d370b5', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'size': 800101236736}]}, u'blockdevice_set': [{u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/rfhdwy/blockdevices/2/', u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 2, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'rfhdwy', 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'block_size': 4096, u'type': u'physical', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'95936791-f0a9-4647-8b10-4b23a048efe7', u'resource_uri': u'/MAAS/api/2.0/nodes/rfhdwy/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'rfhdwy', u'filesystem': {u'label': None, u'uuid': u'c45a25a8-9402-4bad-8b89-bdc926d370b5', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'size': 800101236736}]}, {u'size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/rfhdwy/blockdevices/12/', u'available_size': 0, u'uuid': u'7e6d1972-0795-455f-a647-ca080911d5a7', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'id': 12, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'rfhdwy', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'uuid': u'795b70a1-2535-48e5-9348-df5cf1c24671', 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'model': None, u'block_size': 4096, u'type': u'virtual', u'serial': 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/rfhdwy/', u'current_commissioning_result_id': 4, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'rfhdwy', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'ip_addresses': [u'192.168.11.39', u'192.168.11.44'], u'min_hwe_kernel': u'hwe-16.04', u'status': 4, u'storage_test_status': 2, u'cpu_count': 40, u'power_state': u'off', u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'rfhdwy', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'commissioning_status_name': u'Passed', u'current_testing_result_id': 5, u'cpu_test_status': -1, u'bcaches': [], u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/rfhdwy/blockdevices/2/', u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 2, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'rfhdwy', 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'block_size': 4096, u'type': u'physical', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'95936791-f0a9-4647-8b10-4b23a048efe7', u'resource_uri': u'/MAAS/api/2.0/nodes/rfhdwy/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'rfhdwy', u'filesystem': {u'label': None, u'uuid': u'c45a25a8-9402-4bad-8b89-bdc926d370b5', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'size': 800101236736}]}], u'netboot': True, u'osystem': u'', u'node_type': 0, u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/rfhdwy/blockdevices/12/', u'available_size': 0, u'uuid': u'7e6d1972-0795-455f-a647-ca080911d5a7', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'id': 12, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'rfhdwy', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'uuid': u'795b70a1-2535-48e5-9348-df5cf1c24671', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'serial': None, u'partitions': []}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'interface_set': [{u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'see7dg', 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'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'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'see7dg', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'id': 26, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'effective_mtu': 1500, 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'see7dg', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'system_id': u'rfhdwy', u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rfhdwy/interfaces/5/'}, {u'vlan': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 17, u'discovered': None, u'system_id': u'rfhdwy', u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d4', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rfhdwy/interfaces/17/'}, {u'vlan': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 19, u'discovered': None, u'system_id': u'rfhdwy', u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d5', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rfhdwy/interfaces/19/'}, {u'vlan': {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'ens2f1', u'links': [{u'id': 27, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 16, u'discovered': None, u'system_id': u'rfhdwy', u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cd', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rfhdwy/interfaces/16/'}, {u'vlan': {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'ens2f0', u'links': [{u'id': 28, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 18, u'discovered': None, u'system_id': u'rfhdwy', u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cc', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rfhdwy/interfaces/18/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'see7dg', 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'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'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'see7dg', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 29, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 20, 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'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'see7dg', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.44'}], u'system_id': u'rfhdwy', u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a4', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rfhdwy/interfaces/20/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-04-22 08:01:42,161 [salt.state       :300 ][INFO    ][6964] {'new': {'storage_layout': 'lvm'}}
2019-04-22 08:01:42,162 [salt.state       :1951][INFO    ][6964] Completed state [maas_machines_storage_cmp001_lvm] at time 08:01:42.161971 duration_in_ms=2343.999
2019-04-22 08:01:42,165 [salt.minion      :1711][INFO    ][6964] Returning information for job: 20190422080126949313
2019-04-22 08:01:42,865 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command state.apply with jid 20190422080142852099
2019-04-22 08:01:42,884 [salt.minion      :1432][INFO    ][7046] Starting a new job with PID 7046
2019-04-22 08:01:44,097 [salt.state       :915 ][INFO    ][7046] Loading fresh modules for state activity
2019-04-22 08:01:44,159 [salt.fileclient  :1219][INFO    ][7046] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-04-22 08:01:44,205 [salt.state       :1780][INFO    ][7046] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:01:44.205554
2019-04-22 08:01:44,205 [salt.state       :1813][INFO    ][7046] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-22 08:01:44,207 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7046] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-22 08:01:46,054 [salt.state       :300 ][INFO    ][7046] {'pid': 7053, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-22 08:01:46,055 [salt.state       :1951][INFO    ][7046] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:01:46.055419 duration_in_ms=1849.865
2019-04-22 08:01:46,058 [salt.state       :1780][INFO    ][7046] Running state [maas.deploy_machines] at time 08:01:46.058393
2019-04-22 08:01:46,060 [salt.state       :1813][INFO    ][7046] Executing state module.run for [maas.deploy_machines]
2019-04-22 08:01:46,061 [salt.utils.decorators:613 ][WARNING ][7046] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-22 08:01:46,634 [salt.loaded.ext.module.maas:684 ][INFO    ][7046] deploymachines hwe_kernel=hwe-16.04 system_id=edhc8d distro_series=xenial
2019-04-22 08:01:49,248 [salt.loaded.ext.module.maas:684 ][INFO    ][7046] deploymachines hwe_kernel=hwe-16.04 system_id=rfhdwy distro_series=xenial
2019-04-22 08:01:51,630 [salt.loaded.ext.module.maas:684 ][INFO    ][7046] deploymachines hwe_kernel=hwe-16.04 system_id=qcdn7b distro_series=xenial
2019-04-22 08:01:53,972 [salt.loaded.ext.module.maas:684 ][INFO    ][7046] deploymachines hwe_kernel=hwe-16.04 system_id=cm63yh distro_series=xenial
2019-04-22 08:01:56,528 [salt.loaded.ext.module.maas:684 ][INFO    ][7046] deploymachines hwe_kernel=hwe-16.04 system_id=g7s6ky distro_series=xenial
2019-04-22 08:01:57,932 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20190422080157921807
2019-04-22 08:01:57,948 [salt.minion      :1432][INFO    ][7363] Starting a new job with PID 7363
2019-04-22 08:01:57,978 [salt.minion      :1711][INFO    ][7363] Returning information for job: 20190422080157921807
2019-04-22 08:01:58,963 [salt.state       :300 ][INFO    ][7046] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-04-22 08:01:58,964 [salt.state       :1951][INFO    ][7046] Completed state [maas.deploy_machines] at time 08:01:58.964069 duration_in_ms=12905.675
2019-04-22 08:01:58,966 [salt.minion      :1711][INFO    ][7046] Returning information for job: 20190422080142852099
2019-04-22 08:01:59,678 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command state.apply with jid 20190422080159669445
2019-04-22 08:01:59,700 [salt.minion      :1432][INFO    ][7382] Starting a new job with PID 7382
2019-04-22 08:02:07,945 [salt.state       :915 ][INFO    ][7382] Loading fresh modules for state activity
2019-04-22 08:02:07,997 [salt.fileclient  :1219][INFO    ][7382] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-04-22 08:02:08,052 [salt.state       :1780][INFO    ][7382] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:02:08.052553
2019-04-22 08:02:08,053 [salt.state       :1813][INFO    ][7382] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-22 08:02:08,056 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7382] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-22 08:02:09,903 [salt.state       :300 ][INFO    ][7382] {'pid': 7394, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-22 08:02:09,904 [salt.state       :1951][INFO    ][7382] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:02:09.904153 duration_in_ms=1851.6
2019-04-22 08:02:09,908 [salt.state       :1780][INFO    ][7382] Running state [maas.wait_for_machine_status] at time 08:02:09.907962
2019-04-22 08:02:09,908 [salt.state       :1813][INFO    ][7382] Executing state module.run for [maas.wait_for_machine_status]
2019-04-22 08:02:09,909 [salt.utils.decorators:613 ][WARNING ][7382] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-22 08:02:12,680 [salt.loaded.ext.module.maas:1023][INFO    ][7382] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.24363303s left)
2019-04-22 08:02:14,801 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20190422080214786481
2019-04-22 08:02:14,821 [salt.minion      :1432][INFO    ][7412] Starting a new job with PID 7412
2019-04-22 08:02:14,851 [salt.minion      :1711][INFO    ][7412] Returning information for job: 20190422080214786481
2019-04-22 08:02:44,838 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20190422080244829110
2019-04-22 08:02:44,856 [salt.minion      :1432][INFO    ][7471] Starting a new job with PID 7471
2019-04-22 08:02:44,887 [salt.minion      :1711][INFO    ][7471] Returning information for job: 20190422080244829110
2019-04-22 08:02:45,503 [salt.loaded.ext.module.maas:1023][INFO    ][7382] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.42039609s left)
2019-04-22 08:03:14,904 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20190422080314895769
2019-04-22 08:03:14,923 [salt.minion      :1432][INFO    ][7515] Starting a new job with PID 7515
2019-04-22 08:03:14,957 [salt.minion      :1711][INFO    ][7515] Returning information for job: 20190422080314895769
2019-04-22 08:03:18,299 [salt.loaded.ext.module.maas:1023][INFO    ][7382] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.62411809s left)
2019-04-22 08:03:44,948 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20190422080344939791
2019-04-22 08:03:44,961 [salt.minion      :1432][INFO    ][7575] Starting a new job with PID 7575
2019-04-22 08:03:44,987 [salt.minion      :1711][INFO    ][7575] Returning information for job: 20190422080344939791
2019-04-22 08:03:51,223 [salt.loaded.ext.module.maas:1023][INFO    ][7382] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2148.70037293s left)
2019-04-22 08:04:14,996 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20190422080414986274
2019-04-22 08:04:15,019 [salt.minion      :1432][INFO    ][7639] Starting a new job with PID 7639
2019-04-22 08:04:15,047 [salt.minion      :1711][INFO    ][7639] Returning information for job: 20190422080414986274
2019-04-22 08:04:24,413 [salt.loaded.ext.module.maas:1023][INFO    ][7382] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2115.51055908s left)
2019-04-22 08:04:45,062 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20190422080445054189
2019-04-22 08:04:45,079 [salt.minion      :1432][INFO    ][7713] Starting a new job with PID 7713
2019-04-22 08:04:45,105 [salt.minion      :1711][INFO    ][7713] Returning information for job: 20190422080445054189
2019-04-22 08:04:57,262 [salt.loaded.ext.module.maas:1023][INFO    ][7382] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2082.66193414s left)
2019-04-22 08:05:15,130 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20190422080515121034
2019-04-22 08:05:15,144 [salt.minion      :1432][INFO    ][7827] Starting a new job with PID 7827
2019-04-22 08:05:15,174 [salt.minion      :1711][INFO    ][7827] Returning information for job: 20190422080515121034
2019-04-22 08:05:29,966 [salt.loaded.ext.module.maas:1023][INFO    ][7382] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2049.95784712s left)
2019-04-22 08:05:45,180 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20190422080545172916
2019-04-22 08:05:45,193 [salt.minion      :1432][INFO    ][7913] Starting a new job with PID 7913
2019-04-22 08:05:45,218 [salt.minion      :1711][INFO    ][7913] Returning information for job: 20190422080545172916
2019-04-22 08:06:02,645 [salt.loaded.ext.module.maas:1023][INFO    ][7382] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2017.27817392s left)
2019-04-22 08:06:15,284 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20190422080615275317
2019-04-22 08:06:15,300 [salt.minion      :1432][INFO    ][8253] Starting a new job with PID 8253
2019-04-22 08:06:15,332 [salt.minion      :1711][INFO    ][8253] Returning information for job: 20190422080615275317
2019-04-22 08:06:35,371 [salt.loaded.ext.module.maas:1023][INFO    ][7382] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1984.55228209s left)
2019-04-22 08:06:45,403 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20190422080645394109
2019-04-22 08:06:45,420 [salt.minion      :1432][INFO    ][8383] Starting a new job with PID 8383
2019-04-22 08:06:45,452 [salt.minion      :1711][INFO    ][8383] Returning information for job: 20190422080645394109
2019-04-22 08:07:08,641 [salt.loaded.ext.module.maas:1023][INFO    ][7382] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1951.28217602s left)
2019-04-22 08:07:15,511 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20190422080715501066
2019-04-22 08:07:15,527 [salt.minion      :1432][INFO    ][8642] Starting a new job with PID 8642
2019-04-22 08:07:15,560 [salt.minion      :1711][INFO    ][8642] Returning information for job: 20190422080715501066
2019-04-22 08:07:41,850 [salt.loaded.ext.module.maas:1023][INFO    ][7382] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1918.07392192s left)
2019-04-22 08:07:45,681 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20190422080745671798
2019-04-22 08:07:45,696 [salt.minion      :1432][INFO    ][8709] Starting a new job with PID 8709
2019-04-22 08:07:45,726 [salt.minion      :1711][INFO    ][8709] Returning information for job: 20190422080745671798
2019-04-22 08:08:15,061 [salt.loaded.ext.module.maas:1023][INFO    ][7382] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1884.86241698s left)
2019-04-22 08:08:15,761 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20190422080815751581
2019-04-22 08:08:15,777 [salt.minion      :1432][INFO    ][9276] Starting a new job with PID 9276
2019-04-22 08:08:15,809 [salt.minion      :1711][INFO    ][9276] Returning information for job: 20190422080815751581
2019-04-22 08:08:45,872 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20190422080845852098
2019-04-22 08:08:45,889 [salt.minion      :1432][INFO    ][9339] Starting a new job with PID 9339
2019-04-22 08:08:45,923 [salt.minion      :1711][INFO    ][9339] Returning information for job: 20190422080845852098
2019-04-22 08:08:48,638 [salt.loaded.ext.module.maas:1023][INFO    ][7382] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1851.28567505s left)
2019-04-22 08:09:15,907 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20190422080915896552
2019-04-22 08:09:15,921 [salt.minion      :1432][INFO    ][9600] Starting a new job with PID 9600
2019-04-22 08:09:15,949 [salt.minion      :1711][INFO    ][9600] Returning information for job: 20190422080915896552
2019-04-22 08:09:21,748 [salt.loaded.ext.module.maas:1023][INFO    ][7382] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1818.17591s left)
2019-04-22 08:09:46,100 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20190422080946072368
2019-04-22 08:09:46,124 [salt.minion      :1432][INFO    ][9657] Starting a new job with PID 9657
2019-04-22 08:09:46,162 [salt.minion      :1711][INFO    ][9657] Returning information for job: 20190422080946072368
2019-04-22 08:09:54,589 [salt.loaded.ext.module.maas:1023][INFO    ][7382] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1785.33402991s left)
2019-04-22 08:10:16,115 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20190422081016103194
2019-04-22 08:10:16,142 [salt.minion      :1432][INFO    ][10064] Starting a new job with PID 10064
2019-04-22 08:10:16,209 [salt.minion      :1711][INFO    ][10064] Returning information for job: 20190422081016103194
2019-04-22 08:10:30,289 [salt.loaded.ext.module.maas:1023][INFO    ][7382] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1749.63468814s left)
2019-04-22 08:10:46,294 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20190422081046278809
2019-04-22 08:10:46,318 [salt.minion      :1432][INFO    ][10151] Starting a new job with PID 10151
2019-04-22 08:10:46,559 [salt.minion      :1711][INFO    ][10151] Returning information for job: 20190422081046278809
2019-04-22 08:11:03,534 [salt.loaded.ext.module.maas:1023][INFO    ][7382] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1716.38991094s left)
2019-04-22 08:11:16,497 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20190422081116481443
2019-04-22 08:11:16,514 [salt.minion      :1432][INFO    ][10406] Starting a new job with PID 10406
2019-04-22 08:11:16,559 [salt.minion      :1711][INFO    ][10406] Returning information for job: 20190422081116481443
2019-04-22 08:11:36,332 [salt.loaded.ext.module.maas:1023][INFO    ][7382] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1683.59135699s left)
2019-04-22 08:11:46,678 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20190422081146669930
2019-04-22 08:11:46,692 [salt.minion      :1432][INFO    ][10497] Starting a new job with PID 10497
2019-04-22 08:11:46,725 [salt.minion      :1711][INFO    ][10497] Returning information for job: 20190422081146669930
2019-04-22 08:12:10,411 [salt.loaded.ext.module.maas:1023][INFO    ][7382] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1649.51299906s left)
2019-04-22 08:12:16,838 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20190422081216825491
2019-04-22 08:12:16,850 [salt.minion      :1432][INFO    ][10747] Starting a new job with PID 10747
2019-04-22 08:12:16,872 [salt.minion      :1711][INFO    ][10747] Returning information for job: 20190422081216825491
2019-04-22 08:12:42,921 [salt.loaded.ext.module.maas:1023][INFO    ][7382] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1617.00278401s left)
2019-04-22 08:12:46,901 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20190422081246889363
2019-04-22 08:12:46,915 [salt.minion      :1432][INFO    ][10800] Starting a new job with PID 10800
2019-04-22 08:12:46,937 [salt.minion      :1711][INFO    ][10800] Returning information for job: 20190422081246889363
2019-04-22 08:13:17,038 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20190422081317027896
2019-04-22 08:13:17,053 [salt.minion      :1432][INFO    ][10846] Starting a new job with PID 10846
2019-04-22 08:13:17,078 [salt.minion      :1711][INFO    ][10846] Returning information for job: 20190422081317027896
2019-04-22 08:13:17,125 [salt.loaded.ext.module.maas:1023][INFO    ][7382] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1582.79841614s left)
2019-04-22 08:13:47,094 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20190422081347083866
2019-04-22 08:13:47,109 [salt.minion      :1432][INFO    ][11026] Starting a new job with PID 11026
2019-04-22 08:13:47,137 [salt.minion      :1711][INFO    ][11026] Returning information for job: 20190422081347083866
2019-04-22 08:13:49,861 [salt.loaded.ext.module.maas:1023][INFO    ][7382] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1550.06225014s left)
2019-04-22 08:14:17,303 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20190422081417277650
2019-04-22 08:14:17,324 [salt.minion      :1432][INFO    ][11109] Starting a new job with PID 11109
2019-04-22 08:14:17,348 [salt.minion      :1711][INFO    ][11109] Returning information for job: 20190422081417277650
2019-04-22 08:14:22,848 [salt.loaded.ext.module.maas:1023][INFO    ][7382] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1517.07527804s left)
2019-04-22 08:14:47,410 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20190422081447399794
2019-04-22 08:14:47,425 [salt.minion      :1432][INFO    ][11259] Starting a new job with PID 11259
2019-04-22 08:14:47,453 [salt.minion      :1711][INFO    ][11259] Returning information for job: 20190422081447399794
2019-04-22 08:14:55,774 [salt.state       :300 ][INFO    ][7382] {'ret': True}
2019-04-22 08:14:55,775 [salt.state       :1951][INFO    ][7382] Completed state [maas.wait_for_machine_status] at time 08:14:55.775055 duration_in_ms=765866.88
2019-04-22 08:14:55,798 [salt.minion      :1711][INFO    ][7382] Returning information for job: 20190422080159669445
2019-04-22 08:53:47,639 [salt.utils.schedule:1377][INFO    ][3358] Running scheduled job: __mine_interval
2019-04-22 09:52:37,151 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command cp.push_dir with jid 20190422095237139061
2019-04-22 09:52:37,206 [salt.minion      :1432][INFO    ][21361] Starting a new job with PID 21361
