2019-02-24 02:08:34,386 [salt.utils.decorators:613 ][WARNING ][2270] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-24 02:08:34,915 [salt.utils.decorators:613 ][WARNING ][2270] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-24 02:08:36,936 [salt.loaded.int.states.file:2298][WARNING ][2522] 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-02-24 02:08:57,457 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3173] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-02-24 02:08:57,477 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3173] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-02-24 02:08:57,489 [salt.utils.parsers:1051][WARNING ][362] Minion received a SIGTERM. Exiting.
2019-02-24 02:08:58,381 [salt.cli.daemons :293 ][INFO    ][3287] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-02-24 02:08:58,452 [salt.cli.daemons :82  ][INFO    ][3287] Starting up the Salt Minion
2019-02-24 02:08:58,452 [salt.utils.event :1017][INFO    ][3287] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-02-24 02:08:59,160 [salt.minion      :976 ][INFO    ][3287] Creating minion process manager
2019-02-24 02:09:00,295 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][3287] Executing command ['date', '+%z'] in directory '/root'
2019-02-24 02:09:00,313 [salt.utils.schedule:568 ][INFO    ][3287] Updating job settings for scheduled job: __mine_interval
2019-02-24 02:09:00,353 [salt.minion      :1108][INFO    ][3287] Added mine.update to scheduler
2019-02-24 02:09:00,357 [salt.minion      :1975][INFO    ][3287] Minion is starting as user 'root'
2019-02-24 02:09:00,370 [salt.minion      :2336][INFO    ][3287] Minion is ready to receive requests!
2019-02-24 02:09:03,210 [salt.state       :2022][WARNING ][3186] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-02-24 02:09:05,706 [salt.utils.decorators:613 ][WARNING ][3186] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-24 02:09:11,458 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224020911446780
2019-02-24 02:09:11,476 [salt.minion      :1432][INFO    ][3786] Starting a new job with PID 3786
2019-02-24 02:09:11,489 [salt.minion      :1711][INFO    ][3786] Returning information for job: 20190224020911446780
2019-02-24 02:09:41,504 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224020941487223
2019-02-24 02:09:41,529 [salt.minion      :1432][INFO    ][4108] Starting a new job with PID 4108
2019-02-24 02:09:41,552 [salt.minion      :1711][INFO    ][4108] Returning information for job: 20190224020941487223
2019-02-24 02:09:50,868 [salt.utils.decorators:613 ][WARNING ][3186] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-24 02:10:11,612 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224021011595710
2019-02-24 02:10:11,637 [salt.minion      :1432][INFO    ][4523] Starting a new job with PID 4523
2019-02-24 02:10:11,659 [salt.minion      :1711][INFO    ][4523] Returning information for job: 20190224021011595710
2019-02-24 02:10:27,800 [salt.utils.decorators:613 ][WARNING ][3186] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-24 02:10:28,695 [salt.utils.decorators:613 ][WARNING ][3186] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-24 02:10:33,680 [salt.loaded.ext.module.maasng:1008][WARNING ][3186] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-02-24 02:10:33,681 [salt.loaded.ext.module.maasng:1011][WARNING ][3186] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-02-24 02:10:34,484 [salt.loaded.ext.module.maasng:1235][WARNING ][3186] Ignoring parameter vlan:0
2019-02-24 02:10:36,517 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command state.apply with jid 20190224021036504722
2019-02-24 02:10:36,542 [salt.minion      :1432][INFO    ][4946] Starting a new job with PID 4946
2019-02-24 02:10:40,129 [salt.state       :915 ][INFO    ][4946] Loading fresh modules for state activity
2019-02-24 02:10:40,191 [salt.fileclient  :1219][INFO    ][4946] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-02-24 02:10:40,232 [salt.state       :1780][INFO    ][4946] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:10:40.232658
2019-02-24 02:10:40,233 [salt.state       :1813][INFO    ][4946] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-24 02:10:40,235 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4946] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-24 02:10:41,559 [salt.state       :300 ][INFO    ][4946] {'pid': 4957, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-24 02:10:41,560 [salt.state       :1951][INFO    ][4946] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:10:41.560283 duration_in_ms=1327.626
2019-02-24 02:10:41,561 [salt.state       :1780][INFO    ][4946] Running state [maas.process_machines] at time 02:10:41.561385
2019-02-24 02:10:41,561 [salt.state       :1813][INFO    ][4946] Executing state module.run for [maas.process_machines]
2019-02-24 02:10:41,561 [salt.utils.decorators:613 ][WARNING ][4946] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-24 02:10:41,634 [salt.loaded.ext.module.maas:412 ][WARNING ][4946] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-24 02:10:41,634 [salt.loaded.ext.module.maas:92  ][INFO    ][4946] 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 architecture=amd64/generic power_parameters_power_user=admin
2019-02-24 02:10:43,102 [salt.loaded.ext.module.maas:412 ][WARNING ][4946] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-24 02:10:43,102 [salt.loaded.ext.module.maas:92  ][INFO    ][4946] 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 architecture=amd64/generic power_parameters_power_user=admin
2019-02-24 02:10:44,566 [salt.loaded.ext.module.maas:412 ][WARNING ][4946] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-24 02:10:44,566 [salt.loaded.ext.module.maas:92  ][INFO    ][4946] 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 architecture=amd64/generic power_parameters_power_user=admin
2019-02-24 02:10:46,003 [salt.loaded.ext.module.maas:412 ][WARNING ][4946] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-24 02:10:46,004 [salt.loaded.ext.module.maas:92  ][INFO    ][4946] 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 architecture=amd64/generic power_parameters_power_user=admin
2019-02-24 02:10:47,696 [salt.loaded.ext.module.maas:412 ][WARNING ][4946] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-24 02:10:47,696 [salt.loaded.ext.module.maas:92  ][INFO    ][4946] 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-02-24 02:10:49,067 [salt.state       :300 ][INFO    ][4946] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-02-24 02:10:49,067 [salt.state       :1951][INFO    ][4946] Completed state [maas.process_machines] at time 02:10:49.067745 duration_in_ms=7506.357
2019-02-24 02:10:49,071 [salt.minion      :1711][INFO    ][4946] Returning information for job: 20190224021036504722
2019-02-24 02:11:20,278 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command state.apply with jid 20190224021120265346
2019-02-24 02:11:20,301 [salt.minion      :1432][INFO    ][5306] Starting a new job with PID 5306
2019-02-24 02:11:23,895 [salt.state       :915 ][INFO    ][5306] Loading fresh modules for state activity
2019-02-24 02:11:23,951 [salt.fileclient  :1219][INFO    ][5306] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-02-24 02:11:23,994 [salt.state       :1780][INFO    ][5306] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:11:23.994371
2019-02-24 02:11:23,994 [salt.state       :1813][INFO    ][5306] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-24 02:11:23,997 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5306] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-24 02:11:25,423 [salt.state       :300 ][INFO    ][5306] {'pid': 5328, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-24 02:11:25,424 [salt.state       :1951][INFO    ][5306] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:11:25.424092 duration_in_ms=1429.721
2019-02-24 02:11:25,426 [salt.state       :1780][INFO    ][5306] Running state [maas.wait_for_machine_status] at time 02:11:25.426504
2019-02-24 02:11:25,427 [salt.state       :1813][INFO    ][5306] Executing state module.run for [maas.wait_for_machine_status]
2019-02-24 02:11:25,427 [salt.utils.decorators:613 ][WARNING ][5306] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-24 02:11:26,345 [salt.loaded.ext.module.maas:1023][INFO    ][5306] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.09122491s left)
2019-02-24 02:11:35,402 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224021135389230
2019-02-24 02:11:35,427 [salt.minion      :1432][INFO    ][5348] Starting a new job with PID 5348
2019-02-24 02:11:35,451 [salt.minion      :1711][INFO    ][5348] Returning information for job: 20190224021135389230
2019-02-24 02:11:57,256 [salt.loaded.ext.module.maas:1023][INFO    ][5306] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.18056488s left)
2019-02-24 02:12:05,454 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224021205441816
2019-02-24 02:12:05,479 [salt.minion      :1432][INFO    ][5377] Starting a new job with PID 5377
2019-02-24 02:12:05,500 [salt.minion      :1711][INFO    ][5377] Returning information for job: 20190224021205441816
2019-02-24 02:12:28,333 [salt.loaded.ext.module.maas:1023][INFO    ][5306] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.1035428s left)
2019-02-24 02:12:35,536 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224021235523064
2019-02-24 02:12:35,561 [salt.minion      :1432][INFO    ][5549] Starting a new job with PID 5549
2019-02-24 02:12:35,583 [salt.minion      :1711][INFO    ][5549] Returning information for job: 20190224021235523064
2019-02-24 02:12:59,738 [salt.loaded.ext.module.maas:1023][INFO    ][5306] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1405.69866681s left)
2019-02-24 02:13:05,590 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224021305577237
2019-02-24 02:13:05,614 [salt.minion      :1432][INFO    ][5676] Starting a new job with PID 5676
2019-02-24 02:13:05,636 [salt.minion      :1711][INFO    ][5676] Returning information for job: 20190224021305577237
2019-02-24 02:13:31,249 [salt.loaded.ext.module.maas:1023][INFO    ][5306] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1374.18744993s left)
2019-02-24 02:13:35,649 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224021335637881
2019-02-24 02:13:35,672 [salt.minion      :1432][INFO    ][6228] Starting a new job with PID 6228
2019-02-24 02:13:35,692 [salt.minion      :1711][INFO    ][6228] Returning information for job: 20190224021335637881
2019-02-24 02:14:03,057 [salt.loaded.ext.module.maas:1023][INFO    ][5306] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1342.37953091s left)
2019-02-24 02:14:05,699 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224021405685908
2019-02-24 02:14:05,724 [salt.minion      :1432][INFO    ][6356] Starting a new job with PID 6356
2019-02-24 02:14:05,747 [salt.minion      :1711][INFO    ][6356] Returning information for job: 20190224021405685908
2019-02-24 02:14:35,768 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224021435750461
2019-02-24 02:14:35,793 [salt.minion      :1432][INFO    ][6661] Starting a new job with PID 6661
2019-02-24 02:14:35,815 [salt.minion      :1711][INFO    ][6661] Returning information for job: 20190224021435750461
2019-02-24 02:14:36,411 [salt.state       :300 ][INFO    ][5306] {'ret': True}
2019-02-24 02:14:36,411 [salt.state       :1951][INFO    ][5306] Completed state [maas.wait_for_machine_status] at time 02:14:36.411762 duration_in_ms=190985.257
2019-02-24 02:14:36,415 [salt.minion      :1711][INFO    ][5306] Returning information for job: 20190224021120265346
2019-02-24 02:14:37,053 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command state.apply with jid 20190224021437036997
2019-02-24 02:14:37,076 [salt.minion      :1432][INFO    ][6668] Starting a new job with PID 6668
2019-02-24 02:14:40,711 [salt.state       :915 ][INFO    ][6668] Loading fresh modules for state activity
2019-02-24 02:14:40,740 [salt.fileclient  :1219][INFO    ][6668] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-02-24 02:14:40,794 [salt.state       :1780][INFO    ][6668] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:14:40.794484
2019-02-24 02:14:40,794 [salt.state       :1813][INFO    ][6668] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-24 02:14:40,795 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6668] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-24 02:14:42,335 [salt.state       :300 ][INFO    ][6668] {'pid': 6678, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-24 02:14:42,336 [salt.state       :1951][INFO    ][6668] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:14:42.336324 duration_in_ms=1541.844
2019-02-24 02:14:42,339 [salt.state       :1780][INFO    ][6668] Running state [maas_machines_storage_cmp002_lvm] at time 02:14:42.339353
2019-02-24 02:14:42,339 [salt.state       :1813][INFO    ][6668] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-02-24 02:14:43,759 [salt.loaded.ext.module.maasng:610 ][INFO    ][6668] mq8wg3
2019-02-24 02:14:43,759 [salt.loaded.ext.module.maasng:626 ][INFO    ][6668] sda
2019-02-24 02:14:44,395 [salt.loaded.ext.module.maasng:361 ][INFO    ][6668] mq8wg3
2019-02-24 02:14:44,513 [salt.loaded.ext.module.maasng:367 ][INFO    ][6668] [{u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'1c70360d-6429-4dd1-8848-138f71a7a6a6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'mq8wg3', u'filesystem': {u'uuid': u'ca2ec4ea-f478-4ccc-a575-db0f9dc0ccbd', 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'device_id': 5, u'type': u'partition', u'id': 8, u'resource_uri': u'/MAAS/api/2.0/nodes/mq8wg3/blockdevices/5/partition/8'}], u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'mq8wg3', 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'size': 2397998940160, u'used_size': 2397998940160, u'id': 5, u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/mq8wg3/blockdevices/5/'}, {u'block_size': 4096, u'uuid': u'689a6719-e191-458f-862f-374650e619a5', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'mq8wg3', u'partition_table_type': None, u'filesystem': {u'uuid': u'cf34be4a-649d-4002-ae65-c2672d5c4950', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'model': None, u'size': 2397988454400, u'used_size': 2397988454400, u'id': 13, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/mq8wg3/blockdevices/13/'}]
2019-02-24 02:14:44,514 [salt.loaded.ext.module.maasng:632 ][INFO    ][6668] vgroot
2019-02-24 02:14:44,514 [salt.loaded.ext.module.maasng:635 ][INFO    ][6668] lvroot
2019-02-24 02:14:44,515 [salt.loaded.ext.module.maasng:639 ][INFO    ][6668] 107374182400
2019-02-24 02:14:45,263 [salt.loaded.ext.module.maasng:645 ][INFO    ][6668] {u'hwe_kernel': u'', u'status_action': u'modules-final', u'testing_status_name': u'Passed', u'ip_addresses': [u'192.168.11.42'], 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'memory_test_status_name': u'Unknown', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/mq8wg3/blockdevices/5/', u'system_id': u'mq8wg3', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'partitions': [{u'uuid': u'aee3c77f-4ff4-4f23-9f7b-6257a26e41c0', u'resource_uri': u'/MAAS/api/2.0/nodes/mq8wg3/blockdevices/5/partition/9', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'mq8wg3', u'filesystem': {u'uuid': u'556b58dd-a990-461b-8493-a35fce3bf445', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 5, u'type': u'partition', u'id': 9, u'size': 2397992648704}], u'used_size': 2397998940160, u'id': 5, u'serial': u'618e728372755980239b15112698bc66', u'size': 2397998940160}, u'blockdevice_set': [{u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/mq8wg3/blockdevices/5/', u'system_id': u'mq8wg3', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'size': 2397998940160, u'used_size': 2397998940160, u'id': 5, u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'uuid': u'aee3c77f-4ff4-4f23-9f7b-6257a26e41c0', u'resource_uri': u'/MAAS/api/2.0/nodes/mq8wg3/blockdevices/5/partition/9', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'mq8wg3', u'filesystem': {u'uuid': u'556b58dd-a990-461b-8493-a35fce3bf445', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 5, u'type': u'partition', u'id': 9, u'size': 2397992648704}]}, {u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'uuid': u'1db17911-c9d7-4810-955c-89d363b95a2d', u'used_for': u'ext4 formatted filesystem mounted at /', u'resource_uri': u'/MAAS/api/2.0/nodes/mq8wg3/blockdevices/14/', u'system_id': u'mq8wg3', u'partition_table_type': None, u'filesystem': {u'uuid': u'c2283a59-ef64-4104-8d7f-abb25205b669', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'size': 107374182400, u'used_size': 107374182400, u'id': 14, u'serial': None, u'partitions': []}], 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'testing_status': 2, u'system_id': u'mq8wg3', u'power_state': u'on', 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"'cloudinit' running modules for final", u'disable_ipv4': False, u'min_hwe_kernel': u'ga-16.04', u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'mq8wg3', u'id': 9}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'boot_interface': {u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'snf7xq', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'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'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'snf7xq', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'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.42', u'mode': u'dhcp', u'id': 47}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6a', u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'snf7xq', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'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.42'}], u'parents': [], u'system_id': u'mq8wg3', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/mq8wg3/interfaces/4/'}, u'interface_set': [{u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'snf7xq', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'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'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'snf7xq', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'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.42', u'mode': u'dhcp', u'id': 47}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6a', u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'snf7xq', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'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.42'}], u'parents': [], u'system_id': u'mq8wg3', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/mq8wg3/interfaces/4/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 48}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6c', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'system_id': u'mq8wg3', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 21, u'resource_uri': u'/MAAS/api/2.0/nodes/mq8wg3/interfaces/21/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 49}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6b', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'system_id': u'mq8wg3', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 22, u'resource_uri': u'/MAAS/api/2.0/nodes/mq8wg3/interfaces/22/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 50}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6d', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'system_id': u'mq8wg3', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 23, u'resource_uri': u'/MAAS/api/2.0/nodes/mq8wg3/interfaces/23/'}], 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'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/mq8wg3/blockdevices/5/', u'system_id': u'mq8wg3', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'partitions': [{u'uuid': u'aee3c77f-4ff4-4f23-9f7b-6257a26e41c0', u'resource_uri': u'/MAAS/api/2.0/nodes/mq8wg3/blockdevices/5/partition/9', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'mq8wg3', u'filesystem': {u'uuid': u'556b58dd-a990-461b-8493-a35fce3bf445', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 5, u'type': u'partition', u'id': 9, u'size': 2397992648704}], u'used_size': 2397998940160, u'id': 5, u'serial': u'618e728372755980239b15112698bc66', u'size': 2397998940160}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'virtualblockdevice_set': [{u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'uuid': u'1db17911-c9d7-4810-955c-89d363b95a2d', u'used_for': u'ext4 formatted filesystem mounted at /', u'resource_uri': u'/MAAS/api/2.0/nodes/mq8wg3/blockdevices/14/', u'system_id': u'mq8wg3', u'partition_table_type': None, u'filesystem': {u'uuid': u'c2283a59-ef64-4104-8d7f-abb25205b669', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'partitions': [], u'used_size': 107374182400, u'id': 14, u'serial': None, u'size': 107374182400}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/mq8wg3/', u'distro_series': u'', u'memory_test_status': -1}
2019-02-24 02:14:45,266 [salt.state       :300 ][INFO    ][6668] {'new': {'storage_layout': 'lvm'}}
2019-02-24 02:14:45,266 [salt.state       :1951][INFO    ][6668] Completed state [maas_machines_storage_cmp002_lvm] at time 02:14:45.266420 duration_in_ms=2927.065
2019-02-24 02:14:45,267 [salt.state       :1780][INFO    ][6668] Running state [maas_machines_storage_cmp001_lvm] at time 02:14:45.266952
2019-02-24 02:14:45,267 [salt.state       :1813][INFO    ][6668] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-02-24 02:14:46,734 [salt.loaded.ext.module.maasng:610 ][INFO    ][6668] sng3fe
2019-02-24 02:14:46,735 [salt.loaded.ext.module.maasng:626 ][INFO    ][6668] sda
2019-02-24 02:14:47,477 [salt.loaded.ext.module.maasng:361 ][INFO    ][6668] sng3fe
2019-02-24 02:14:47,597 [salt.loaded.ext.module.maasng:367 ][INFO    ][6668] [{u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'7f864be5-fc1f-49e2-9a7a-b865769094d3', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'sng3fe', u'device_id': 3, u'filesystem': {u'mount_options': None, u'uuid': u'b7686bbe-d3dc-43e9-8769-2d5dff96500c', u'label': None, u'mount_point': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/sng3fe/blockdevices/3/partition/7', u'type': u'partition', u'id': 7, u'size': 2397992648704}], u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'sng3fe', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/sng3fe/blockdevices/3/', u'used_size': 2397998940160, u'id': 3, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}, {u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'mount_options': None, u'uuid': u'0567e84c-d92e-4523-911f-cc3d31a5e23b', u'label': u'root', u'mount_point': u'/', u'fstype': u'ext4'}, u'uuid': u'b469bdaa-c675-42c1-b233-6a7a9baa6e0c', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'sng3fe', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/sng3fe/blockdevices/12/', u'used_size': 2397988454400, u'id': 12, u'serial': None, u'size': 2397988454400}]
2019-02-24 02:14:47,598 [salt.loaded.ext.module.maasng:632 ][INFO    ][6668] vgroot
2019-02-24 02:14:47,598 [salt.loaded.ext.module.maasng:635 ][INFO    ][6668] lvroot
2019-02-24 02:14:47,599 [salt.loaded.ext.module.maasng:639 ][INFO    ][6668] 107374182400
2019-02-24 02:14:48,281 [salt.loaded.ext.module.maasng:645 ][INFO    ][6668] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'testing_status_name': u'Passed', u'boot_interface': {u'children': [], 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'snf7xq', 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': 42, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'snf7xq', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'snf7xq', 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'params': u'', u'effective_mtu': 1500, u'system_id': u'sng3fe', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/sng3fe/interfaces/5/'}, u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'status_action': u'modules-final', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'4d44842a-b0af-4207-a6b5-b9d5f8188177', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'sng3fe', u'device_id': 3, u'filesystem': {u'mount_options': None, u'uuid': u'095afcea-73b1-4e1f-92f6-e243780cc773', u'label': None, u'mount_point': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/sng3fe/blockdevices/3/partition/10', u'type': u'partition', u'id': 10, u'size': 2397992648704}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'sng3fe', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/sng3fe/blockdevices/3/', u'used_size': 2397998940160, u'id': 3, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}, u'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default'}, u'resource_uri': u'/MAAS/api/2.0/machines/sng3fe/', u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'sng3fe', u'power_state': u'on', 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"'cloudinit' running modules for final", u'virtualblockdevice_set': [{u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'16b6f8ee-cf5a-4a6d-b701-0d999ce01a56', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'sng3fe', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'c18b003a-fa98-42b9-9473-efb48ddf175c', u'label': u'root', u'mount_point': u'/', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/sng3fe/blockdevices/15/', u'used_size': 107374182400, u'id': 15, u'serial': None, u'size': 107374182400}], u'blockdevice_set': [{u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'4d44842a-b0af-4207-a6b5-b9d5f8188177', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'sng3fe', u'device_id': 3, u'filesystem': {u'mount_options': None, u'uuid': u'095afcea-73b1-4e1f-92f6-e243780cc773', u'label': None, u'mount_point': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/sng3fe/blockdevices/3/partition/10', u'type': u'partition', u'id': 10, u'size': 2397992648704}], u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'sng3fe', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/sng3fe/blockdevices/3/', u'used_size': 2397998940160, u'id': 3, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}, {u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'mount_options': None, u'uuid': u'c18b003a-fa98-42b9-9473-efb48ddf175c', u'label': u'root', u'mount_point': u'/', u'fstype': u'ext4'}, u'uuid': u'16b6f8ee-cf5a-4a6d-b701-0d999ce01a56', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'sng3fe', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/sng3fe/blockdevices/15/', u'used_size': 107374182400, u'id': 15, u'serial': None, u'size': 107374182400}], u'status': 4, u'storage_test_status': 2, u'cpu_count': 16, u'raids': [], u'owner_data': {}, u'ip_addresses': [u'192.168.11.38'], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'sng3fe', u'id': 10}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'node_type_name': u'Machine', u'interface_set': [{u'children': [], 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'snf7xq', 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': 42, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'snf7xq', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'snf7xq', 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'params': u'', u'effective_mtu': 1500, u'system_id': u'sng3fe', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/sng3fe/interfaces/5/'}, {u'children': [], u'name': u'enp8s0', u'links': [{u'id': 43, u'mode': u'link_up'}], u'tags': [], 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'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5c', u'params': u'', u'effective_mtu': 1500, u'system_id': u'sng3fe', u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/sng3fe/interfaces/19/'}, {u'children': [], u'name': u'enp9s0', u'links': [{u'id': 44, u'mode': u'link_up'}], u'tags': [], 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'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5d', u'params': u'', u'effective_mtu': 1500, u'system_id': u'sng3fe', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/sng3fe/interfaces/12/'}, {u'children': [], u'name': u'enp7s0', u'links': [{u'id': 45, u'mode': u'link_up'}], u'tags': [], 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'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5b', u'params': u'', u'effective_mtu': 1500, u'system_id': u'sng3fe', u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/sng3fe/interfaces/16/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'bcaches': [], u'status_name': u'Ready', u'physicalblockdevice_set': [{u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'4d44842a-b0af-4207-a6b5-b9d5f8188177', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'sng3fe', u'device_id': 3, u'filesystem': {u'mount_options': None, u'uuid': u'095afcea-73b1-4e1f-92f6-e243780cc773', u'label': None, u'mount_point': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/sng3fe/blockdevices/3/partition/10', u'type': u'partition', u'id': 10, u'size': 2397992648704}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'sng3fe', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/sng3fe/blockdevices/3/', u'used_size': 2397998940160, u'id': 3, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'node_type': 0, u'commissioning_status': 2, u'min_hwe_kernel': u'ga-16.04', u'commissioning_status_name': u'Passed', u'current_commissioning_result_id': 4, u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-02-24 02:14:48,283 [salt.state       :300 ][INFO    ][6668] {'new': {'storage_layout': 'lvm'}}
2019-02-24 02:14:48,284 [salt.state       :1951][INFO    ][6668] Completed state [maas_machines_storage_cmp001_lvm] at time 02:14:48.284058 duration_in_ms=3017.106
2019-02-24 02:14:48,287 [salt.minion      :1711][INFO    ][6668] Returning information for job: 20190224021437036997
2019-02-24 02:14:48,899 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command state.apply with jid 20190224021448886370
2019-02-24 02:14:48,922 [salt.minion      :1432][INFO    ][6704] Starting a new job with PID 6704
2019-02-24 02:14:49,473 [salt.state       :915 ][INFO    ][6704] Loading fresh modules for state activity
2019-02-24 02:14:49,525 [salt.fileclient  :1219][INFO    ][6704] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-02-24 02:14:49,565 [salt.state       :1780][INFO    ][6704] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:14:49.565697
2019-02-24 02:14:49,566 [salt.state       :1813][INFO    ][6704] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-24 02:14:49,568 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6704] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-24 02:14:51,005 [salt.state       :300 ][INFO    ][6704] {'pid': 6736, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-24 02:14:51,006 [salt.state       :1951][INFO    ][6704] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:14:51.006030 duration_in_ms=1440.334
2019-02-24 02:14:51,007 [salt.state       :1780][INFO    ][6704] Running state [maas.deploy_machines] at time 02:14:51.007507
2019-02-24 02:14:51,007 [salt.state       :1813][INFO    ][6704] Executing state module.run for [maas.deploy_machines]
2019-02-24 02:14:51,008 [salt.utils.decorators:613 ][WARNING ][6704] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-24 02:14:51,605 [salt.loaded.ext.module.maas:684 ][INFO    ][6704] deploymachines hwe_kernel=ga-16.04 system_id=mq8wg3 distro_series=xenial
2019-02-24 02:14:54,677 [salt.loaded.ext.module.maas:684 ][INFO    ][6704] deploymachines hwe_kernel=ga-16.04 system_id=sng3fe distro_series=xenial
2019-02-24 02:14:57,348 [salt.loaded.ext.module.maas:684 ][INFO    ][6704] deploymachines hwe_kernel=ga-16.04 system_id=kfh76e distro_series=xenial
2019-02-24 02:14:59,978 [salt.loaded.ext.module.maas:684 ][INFO    ][6704] deploymachines hwe_kernel=ga-16.04 system_id=sfd8kg distro_series=xenial
2019-02-24 02:15:02,436 [salt.loaded.ext.module.maas:684 ][INFO    ][6704] deploymachines hwe_kernel=ga-16.04 system_id=qe3txr distro_series=xenial
2019-02-24 02:15:03,975 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224021503963763
2019-02-24 02:15:03,998 [salt.minion      :1432][INFO    ][7050] Starting a new job with PID 7050
2019-02-24 02:15:04,020 [salt.minion      :1711][INFO    ][7050] Returning information for job: 20190224021503963763
2019-02-24 02:15:04,833 [salt.state       :300 ][INFO    ][6704] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-02-24 02:15:04,834 [salt.state       :1951][INFO    ][6704] Completed state [maas.deploy_machines] at time 02:15:04.834472 duration_in_ms=13826.963
2019-02-24 02:15:04,856 [salt.minion      :1711][INFO    ][6704] Returning information for job: 20190224021448886370
2019-02-24 02:15:05,469 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command state.apply with jid 20190224021505455092
2019-02-24 02:15:05,493 [salt.minion      :1432][INFO    ][7068] Starting a new job with PID 7068
2019-02-24 02:15:09,146 [salt.state       :915 ][INFO    ][7068] Loading fresh modules for state activity
2019-02-24 02:15:09,207 [salt.fileclient  :1219][INFO    ][7068] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-02-24 02:15:09,251 [salt.state       :1780][INFO    ][7068] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:15:09.251181
2019-02-24 02:15:09,251 [salt.state       :1813][INFO    ][7068] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-24 02:15:09,253 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7068] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-24 02:15:10,681 [salt.state       :300 ][INFO    ][7068] {'pid': 7088, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-24 02:15:10,682 [salt.state       :1951][INFO    ][7068] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:15:10.682389 duration_in_ms=1431.208
2019-02-24 02:15:10,684 [salt.state       :1780][INFO    ][7068] Running state [maas.wait_for_machine_status] at time 02:15:10.684765
2019-02-24 02:15:10,685 [salt.state       :1813][INFO    ][7068] Executing state module.run for [maas.wait_for_machine_status]
2019-02-24 02:15:10,685 [salt.utils.decorators:613 ][WARNING ][7068] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-24 02:15:13,845 [salt.loaded.ext.module.maas:1023][INFO    ][7068] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.84939098s left)
2019-02-24 02:15:20,550 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224021520533306
2019-02-24 02:15:20,575 [salt.minion      :1432][INFO    ][7106] Starting a new job with PID 7106
2019-02-24 02:15:20,596 [salt.minion      :1711][INFO    ][7106] Returning information for job: 20190224021520533306
2019-02-24 02:15:47,263 [salt.loaded.ext.module.maas:1023][INFO    ][7068] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.432019s left)
2019-02-24 02:15:50,609 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224021550592834
2019-02-24 02:15:50,634 [salt.minion      :1432][INFO    ][7144] Starting a new job with PID 7144
2019-02-24 02:15:50,655 [salt.minion      :1711][INFO    ][7144] Returning information for job: 20190224021550592834
2019-02-24 02:16:20,697 [salt.loaded.ext.module.maas:1023][INFO    ][7068] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2179.99798894s left)
2019-02-24 02:16:20,706 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224021620693750
2019-02-24 02:16:20,727 [salt.minion      :1432][INFO    ][7179] Starting a new job with PID 7179
2019-02-24 02:16:20,742 [salt.minion      :1711][INFO    ][7179] Returning information for job: 20190224021620693750
2019-02-24 02:16:50,740 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224021650727628
2019-02-24 02:16:50,765 [salt.minion      :1432][INFO    ][7300] Starting a new job with PID 7300
2019-02-24 02:16:50,787 [salt.minion      :1711][INFO    ][7300] Returning information for job: 20190224021650727628
2019-02-24 02:16:53,979 [salt.loaded.ext.module.maas:1023][INFO    ][7068] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2146.71577191s left)
2019-02-24 02:17:20,795 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224021720783009
2019-02-24 02:17:20,820 [salt.minion      :1432][INFO    ][7632] Starting a new job with PID 7632
2019-02-24 02:17:20,841 [salt.minion      :1711][INFO    ][7632] Returning information for job: 20190224021720783009
2019-02-24 02:17:27,219 [salt.loaded.ext.module.maas:1023][INFO    ][7068] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2113.4754889s left)
2019-02-24 02:17:50,857 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224021750844849
2019-02-24 02:17:50,883 [salt.minion      :1432][INFO    ][7894] Starting a new job with PID 7894
2019-02-24 02:17:50,904 [salt.minion      :1711][INFO    ][7894] Returning information for job: 20190224021750844849
2019-02-24 02:18:00,678 [salt.loaded.ext.module.maas:1023][INFO    ][7068] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2080.01641488s left)
2019-02-24 02:18:20,922 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224021820908382
2019-02-24 02:18:20,946 [salt.minion      :1432][INFO    ][8154] Starting a new job with PID 8154
2019-02-24 02:18:20,966 [salt.minion      :1711][INFO    ][8154] Returning information for job: 20190224021820908382
2019-02-24 02:18:33,872 [salt.loaded.ext.module.maas:1023][INFO    ][7068] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2046.82265306s left)
2019-02-24 02:18:50,991 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224021850978646
2019-02-24 02:18:51,017 [salt.minion      :1432][INFO    ][8401] Starting a new job with PID 8401
2019-02-24 02:18:51,039 [salt.minion      :1711][INFO    ][8401] Returning information for job: 20190224021850978646
2019-02-24 02:19:07,302 [salt.loaded.ext.module.maas:1023][INFO    ][7068] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2013.39291096s left)
2019-02-24 02:19:21,063 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224021921045888
2019-02-24 02:19:21,088 [salt.minion      :1432][INFO    ][8483] Starting a new job with PID 8483
2019-02-24 02:19:21,110 [salt.minion      :1711][INFO    ][8483] Returning information for job: 20190224021921045888
2019-02-24 02:19:40,910 [salt.loaded.ext.module.maas:1023][INFO    ][7068] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1979.78496003s left)
2019-02-24 02:19:51,132 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224021951118906
2019-02-24 02:19:51,157 [salt.minion      :1432][INFO    ][8527] Starting a new job with PID 8527
2019-02-24 02:19:51,178 [salt.minion      :1711][INFO    ][8527] Returning information for job: 20190224021951118906
2019-02-24 02:20:14,261 [salt.loaded.ext.module.maas:1023][INFO    ][7068] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1946.43410301s left)
2019-02-24 02:20:21,207 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224022021195924
2019-02-24 02:20:21,232 [salt.minion      :1432][INFO    ][8658] Starting a new job with PID 8658
2019-02-24 02:20:21,252 [salt.minion      :1711][INFO    ][8658] Returning information for job: 20190224022021195924
2019-02-24 02:20:48,012 [salt.loaded.ext.module.maas:1023][INFO    ][7068] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1912.68251896s left)
2019-02-24 02:20:51,284 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224022051271625
2019-02-24 02:20:51,307 [salt.minion      :1432][INFO    ][8800] Starting a new job with PID 8800
2019-02-24 02:20:51,327 [salt.minion      :1711][INFO    ][8800] Returning information for job: 20190224022051271625
2019-02-24 02:21:21,364 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224022121355449
2019-02-24 02:21:21,384 [salt.minion      :1432][INFO    ][9061] Starting a new job with PID 9061
2019-02-24 02:21:21,399 [salt.minion      :1711][INFO    ][9061] Returning information for job: 20190224022121355449
2019-02-24 02:21:21,549 [salt.loaded.ext.module.maas:1023][INFO    ][7068] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1879.14593506s left)
2019-02-24 02:21:51,439 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224022151426061
2019-02-24 02:21:51,463 [salt.minion      :1432][INFO    ][9232] Starting a new job with PID 9232
2019-02-24 02:21:51,486 [salt.minion      :1711][INFO    ][9232] Returning information for job: 20190224022151426061
2019-02-24 02:21:55,079 [salt.loaded.ext.module.maas:1023][INFO    ][7068] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1845.616225s left)
2019-02-24 02:22:21,533 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224022221521121
2019-02-24 02:22:21,559 [salt.minion      :1432][INFO    ][9321] Starting a new job with PID 9321
2019-02-24 02:22:21,581 [salt.minion      :1711][INFO    ][9321] Returning information for job: 20190224022221521121
2019-02-24 02:22:28,644 [salt.loaded.ext.module.maas:1023][INFO    ][7068] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1812.05099392s left)
2019-02-24 02:22:51,633 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224022251620287
2019-02-24 02:22:51,659 [salt.minion      :1432][INFO    ][9374] Starting a new job with PID 9374
2019-02-24 02:22:51,682 [salt.minion      :1711][INFO    ][9374] Returning information for job: 20190224022251620287
2019-02-24 02:23:01,494 [salt.loaded.ext.module.maas:1023][INFO    ][7068] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1779.20106196s left)
2019-02-24 02:23:21,825 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224022321813544
2019-02-24 02:23:21,851 [salt.minion      :1432][INFO    ][9629] Starting a new job with PID 9629
2019-02-24 02:23:21,873 [salt.minion      :1711][INFO    ][9629] Returning information for job: 20190224022321813544
2019-02-24 02:23:34,979 [salt.loaded.ext.module.maas:1023][INFO    ][7068] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1745.71619105s left)
2019-02-24 02:23:51,939 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224022351926479
2019-02-24 02:23:51,965 [salt.minion      :1432][INFO    ][9788] Starting a new job with PID 9788
2019-02-24 02:23:51,988 [salt.minion      :1711][INFO    ][9788] Returning information for job: 20190224022351926479
2019-02-24 02:24:08,374 [salt.loaded.ext.module.maas:1023][INFO    ][7068] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1712.32121205s left)
2019-02-24 02:24:22,062 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224022422049252
2019-02-24 02:24:22,088 [salt.minion      :1432][INFO    ][10025] Starting a new job with PID 10025
2019-02-24 02:24:22,109 [salt.minion      :1711][INFO    ][10025] Returning information for job: 20190224022422049252
2019-02-24 02:24:41,952 [salt.loaded.ext.module.maas:1023][INFO    ][7068] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1678.74279809s left)
2019-02-24 02:24:52,189 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224022452176086
2019-02-24 02:24:52,214 [salt.minion      :1432][INFO    ][10051] Starting a new job with PID 10051
2019-02-24 02:24:52,236 [salt.minion      :1711][INFO    ][10051] Returning information for job: 20190224022452176086
2019-02-24 02:25:15,528 [salt.loaded.ext.module.maas:1023][INFO    ][7068] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1645.16638207s left)
2019-02-24 02:25:22,329 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224022522316950
2019-02-24 02:25:22,354 [salt.minion      :1432][INFO    ][10095] Starting a new job with PID 10095
2019-02-24 02:25:22,376 [salt.minion      :1711][INFO    ][10095] Returning information for job: 20190224022522316950
2019-02-24 02:25:48,980 [salt.loaded.ext.module.maas:1023][INFO    ][7068] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1611.71530795s left)
2019-02-24 02:25:52,477 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224022552465276
2019-02-24 02:25:52,502 [salt.minion      :1432][INFO    ][10121] Starting a new job with PID 10121
2019-02-24 02:25:52,522 [salt.minion      :1711][INFO    ][10121] Returning information for job: 20190224022552465276
2019-02-24 02:26:22,181 [salt.loaded.ext.module.maas:1023][INFO    ][7068] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1578.51350808s left)
2019-02-24 02:26:22,628 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224022622615681
2019-02-24 02:26:22,652 [salt.minion      :1432][INFO    ][10167] Starting a new job with PID 10167
2019-02-24 02:26:22,675 [salt.minion      :1711][INFO    ][10167] Returning information for job: 20190224022622615681
2019-02-24 02:26:52,786 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224022652772830
2019-02-24 02:26:52,811 [salt.minion      :1432][INFO    ][10192] Starting a new job with PID 10192
2019-02-24 02:26:52,833 [salt.minion      :1711][INFO    ][10192] Returning information for job: 20190224022652772830
2019-02-24 02:26:55,486 [salt.loaded.ext.module.maas:1023][INFO    ][7068] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1545.20841408s left)
2019-02-24 02:27:22,952 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224022722939309
2019-02-24 02:27:22,977 [salt.minion      :1432][INFO    ][10245] Starting a new job with PID 10245
2019-02-24 02:27:23,000 [salt.minion      :1711][INFO    ][10245] Returning information for job: 20190224022722939309
2019-02-24 02:27:29,228 [salt.loaded.ext.module.maas:1023][INFO    ][7068] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1511.46649098s left)
2019-02-24 02:27:53,145 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224022753132253
2019-02-24 02:27:53,170 [salt.minion      :1432][INFO    ][10279] Starting a new job with PID 10279
2019-02-24 02:27:53,190 [salt.minion      :1711][INFO    ][10279] Returning information for job: 20190224022753132253
2019-02-24 02:28:02,647 [salt.loaded.ext.module.maas:1023][INFO    ][7068] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1478.04783988s left)
2019-02-24 02:28:23,332 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224022823319500
2019-02-24 02:28:23,357 [salt.minion      :1432][INFO    ][10327] Starting a new job with PID 10327
2019-02-24 02:28:23,379 [salt.minion      :1711][INFO    ][10327] Returning information for job: 20190224022823319500
2019-02-24 02:28:36,223 [salt.loaded.ext.module.maas:1023][INFO    ][7068] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1444.47153902s left)
2019-02-24 02:28:53,530 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224022853518063
2019-02-24 02:28:53,555 [salt.minion      :1432][INFO    ][10356] Starting a new job with PID 10356
2019-02-24 02:28:53,576 [salt.minion      :1711][INFO    ][10356] Returning information for job: 20190224022853518063
2019-02-24 02:29:09,450 [salt.loaded.ext.module.maas:1023][INFO    ][7068] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1411.24499702s left)
2019-02-24 02:29:23,729 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224022923717358
2019-02-24 02:29:23,750 [salt.minion      :1432][INFO    ][10550] Starting a new job with PID 10550
2019-02-24 02:29:23,769 [salt.minion      :1711][INFO    ][10550] Returning information for job: 20190224022923717358
2019-02-24 02:29:42,953 [salt.loaded.ext.module.maas:1023][INFO    ][7068] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1377.74138093s left)
2019-02-24 02:29:53,943 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224022953930104
2019-02-24 02:29:53,969 [salt.minion      :1432][INFO    ][10581] Starting a new job with PID 10581
2019-02-24 02:29:53,991 [salt.minion      :1711][INFO    ][10581] Returning information for job: 20190224022953930104
2019-02-24 02:30:16,290 [salt.loaded.ext.module.maas:1023][INFO    ][7068] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1344.40473294s left)
2019-02-24 02:30:23,961 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224023023948175
2019-02-24 02:30:23,986 [salt.minion      :1432][INFO    ][10635] Starting a new job with PID 10635
2019-02-24 02:30:24,008 [salt.minion      :1711][INFO    ][10635] Returning information for job: 20190224023023948175
2019-02-24 02:30:49,794 [salt.loaded.ext.module.maas:993 ][INFO    ][7068] Machine qe3txr mark broken
2019-02-24 02:30:50,369 [salt.loaded.ext.module.maas:996 ][INFO    ][7068] Machine qe3txr mark fixed
2019-02-24 02:30:51,722 [salt.loaded.ext.module.maas:684 ][INFO    ][7068] deploymachines hwe_kernel=ga-16.04 system_id=qe3txr distro_series=xenial
2019-02-24 02:30:53,877 [salt.loaded.ext.module.maas:160 ][ERROR   ][7068] Failed for object kvm02 reason Unable to change power state to 'cycle' for node kvm02: another action is already in progress for that node.
2019-02-24 02:30:53,878 [salt.state       :302 ][ERROR   ][7068] Module function maas.wait_for_machine_status threw an exception. Exception: {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {'kvm02': "Unable to change power state to 'cycle' for node kvm02: another action is already in progress for that node."}, 'success': []}
2019-02-24 02:30:53,879 [salt.state       :1951][INFO    ][7068] Completed state [maas.wait_for_machine_status] at time 02:30:53.878956 duration_in_ms=943194.186
2019-02-24 02:30:53,883 [salt.minion      :1711][INFO    ][7068] Returning information for job: 20190224021505455092
2019-02-24 02:31:04,577 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command pillar.get with jid 20190224023104562440
2019-02-24 02:31:04,600 [salt.minion      :1432][INFO    ][10745] Starting a new job with PID 10745
2019-02-24 02:31:04,609 [salt.minion      :1711][INFO    ][10745] Returning information for job: 20190224023104562440
2019-02-24 02:31:05,116 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command service.status with jid 20190224023105100951
2019-02-24 02:31:05,139 [salt.minion      :1432][INFO    ][10750] Starting a new job with PID 10750
2019-02-24 02:31:05,539 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][10750] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-02-24 02:31:05,573 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][10750] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-02-24 02:31:05,590 [salt.minion      :1711][INFO    ][10750] Returning information for job: 20190224023105100951
2019-02-24 02:31:06,138 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command state.apply with jid 20190224023106123325
2019-02-24 02:31:06,161 [salt.minion      :1432][INFO    ][10761] Starting a new job with PID 10761
2019-02-24 02:31:09,737 [salt.state       :915 ][INFO    ][10761] Loading fresh modules for state activity
2019-02-24 02:31:10,182 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10761] Executing command 'salt-minion --version' in directory '/root'
2019-02-24 02:31:10,544 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10761] Executing command 'salt-minion --version' in directory '/root'
2019-02-24 02:31:11,462 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10761] Executing command 'salt-minion --version' in directory '/root'
2019-02-24 02:31:11,828 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10761] Executing command 'salt-minion --version' in directory '/root'
2019-02-24 02:31:13,202 [salt.state       :1780][INFO    ][10761] Running state [salt-minion] at time 02:31:13.202916
2019-02-24 02:31:13,203 [salt.state       :1813][INFO    ][10761] Executing state pkg.installed for [salt-minion]
2019-02-24 02:31:13,203 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10761] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-24 02:31:13,299 [salt.state       :300 ][INFO    ][10761] All specified packages are already installed
2019-02-24 02:31:13,300 [salt.state       :1951][INFO    ][10761] Completed state [salt-minion] at time 02:31:13.300019 duration_in_ms=97.103
2019-02-24 02:31:13,300 [salt.state       :1780][INFO    ][10761] Running state [salt_minion_dependency_packages] at time 02:31:13.300372
2019-02-24 02:31:13,300 [salt.state       :1813][INFO    ][10761] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-02-24 02:31:13,307 [salt.state       :300 ][INFO    ][10761] All specified packages are already installed
2019-02-24 02:31:13,308 [salt.state       :1951][INFO    ][10761] Completed state [salt_minion_dependency_packages] at time 02:31:13.307993 duration_in_ms=7.621
2019-02-24 02:31:13,311 [salt.state       :1780][INFO    ][10761] Running state [/etc/salt/minion.d/minion.conf] at time 02:31:13.311330
2019-02-24 02:31:13,311 [salt.state       :1813][INFO    ][10761] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-02-24 02:31:13,507 [salt.state       :300 ][INFO    ][10761] File /etc/salt/minion.d/minion.conf is in the correct state
2019-02-24 02:31:13,507 [salt.state       :1951][INFO    ][10761] Completed state [/etc/salt/minion.d/minion.conf] at time 02:31:13.507612 duration_in_ms=196.282
2019-02-24 02:31:13,507 [salt.state       :1780][INFO    ][10761] Running state [python-netaddr] at time 02:31:13.507796
2019-02-24 02:31:13,507 [salt.state       :1813][INFO    ][10761] Executing state pkg.installed for [python-netaddr]
2019-02-24 02:31:13,512 [salt.state       :300 ][INFO    ][10761] All specified packages are already installed
2019-02-24 02:31:13,512 [salt.state       :1951][INFO    ][10761] Completed state [python-netaddr] at time 02:31:13.512810 duration_in_ms=5.014
2019-02-24 02:31:13,515 [salt.state       :1780][INFO    ][10761] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:31:13.514972
2019-02-24 02:31:13,515 [salt.state       :1813][INFO    ][10761] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-02-24 02:31:13,525 [salt.state       :300 ][INFO    ][10761] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-02-24 02:31:13,525 [salt.state       :1951][INFO    ][10761] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:31:13.525175 duration_in_ms=10.203
2019-02-24 02:31:13,525 [salt.state       :1780][INFO    ][10761] Running state [salt-minion] at time 02:31:13.525841
2019-02-24 02:31:13,526 [salt.state       :1813][INFO    ][10761] Executing state service.running for [salt-minion]
2019-02-24 02:31:13,526 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10761] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-02-24 02:31:13,561 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10761] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-02-24 02:31:13,577 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10761] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-02-24 02:31:13,593 [salt.state       :300 ][INFO    ][10761] The service salt-minion is already running
2019-02-24 02:31:13,593 [salt.state       :1951][INFO    ][10761] Completed state [salt-minion] at time 02:31:13.593723 duration_in_ms=67.882
2019-02-24 02:31:13,595 [salt.state       :1780][INFO    ][10761] Running state [/etc/salt/grains.d] at time 02:31:13.595046
2019-02-24 02:31:13,595 [salt.state       :1813][INFO    ][10761] Executing state file.directory for [/etc/salt/grains.d]
2019-02-24 02:31:13,596 [salt.state       :300 ][INFO    ][10761] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-02-24 02:31:13,596 [salt.state       :1951][INFO    ][10761] Completed state [/etc/salt/grains.d] at time 02:31:13.596367 duration_in_ms=1.321
2019-02-24 02:31:13,597 [salt.state       :1780][INFO    ][10761] Running state [/etc/salt/grains] at time 02:31:13.596969
2019-02-24 02:31:13,597 [salt.state       :1813][INFO    ][10761] Executing state file.managed for [/etc/salt/grains]
2019-02-24 02:31:13,597 [salt.state       :300 ][INFO    ][10761] File /etc/salt/grains exists with proper permissions. No changes made.
2019-02-24 02:31:13,597 [salt.state       :1951][INFO    ][10761] Completed state [/etc/salt/grains] at time 02:31:13.597913 duration_in_ms=0.944
2019-02-24 02:31:13,598 [salt.state       :1780][INFO    ][10761] Running state [/etc/salt/grains.d/placeholder] at time 02:31:13.598318
2019-02-24 02:31:13,598 [salt.state       :1813][INFO    ][10761] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-02-24 02:31:13,599 [salt.state       :300 ][INFO    ][10761] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-02-24 02:31:13,599 [salt.state       :1951][INFO    ][10761] Completed state [/etc/salt/grains.d/placeholder] at time 02:31:13.599219 duration_in_ms=0.901
2019-02-24 02:31:13,599 [salt.state       :1780][INFO    ][10761] Running state [/etc/salt/grains.d/sphinx] at time 02:31:13.599623
2019-02-24 02:31:13,599 [salt.state       :1813][INFO    ][10761] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-02-24 02:31:13,615 [salt.state       :300 ][INFO    ][10761] File /etc/salt/grains.d/sphinx is in the correct state
2019-02-24 02:31:13,615 [salt.state       :1951][INFO    ][10761] Completed state [/etc/salt/grains.d/sphinx] at time 02:31:13.615346 duration_in_ms=15.723
2019-02-24 02:31:13,617 [salt.state       :1780][INFO    ][10761] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:31:13.617316
2019-02-24 02:31:13,617 [salt.state       :1813][INFO    ][10761] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-02-24 02:31:13,617 [salt.state       :300 ][INFO    ][10761] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-02-24 02:31:13,618 [salt.state       :1951][INFO    ][10761] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:31:13.618102 duration_in_ms=0.786
2019-02-24 02:31:13,618 [salt.state       :1780][INFO    ][10761] Running state [/etc/salt/grains.d/dns_records] at time 02:31:13.618528
2019-02-24 02:31:13,618 [salt.state       :1813][INFO    ][10761] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-02-24 02:31:13,627 [salt.state       :300 ][INFO    ][10761] File /etc/salt/grains.d/dns_records is in the correct state
2019-02-24 02:31:13,627 [salt.state       :1951][INFO    ][10761] Completed state [/etc/salt/grains.d/dns_records] at time 02:31:13.627331 duration_in_ms=8.803
2019-02-24 02:31:13,628 [salt.state       :1780][INFO    ][10761] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:31:13.628179
2019-02-24 02:31:13,628 [salt.state       :1813][INFO    ][10761] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-02-24 02:31:13,628 [salt.state       :300 ][INFO    ][10761] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-02-24 02:31:13,629 [salt.state       :1951][INFO    ][10761] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:31:13.628973 duration_in_ms=0.794
2019-02-24 02:31:13,629 [salt.state       :1780][INFO    ][10761] Running state [/etc/salt/grains.d/salt] at time 02:31:13.629398
2019-02-24 02:31:13,629 [salt.state       :1813][INFO    ][10761] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-02-24 02:31:13,645 [salt.state       :300 ][INFO    ][10761] File /etc/salt/grains.d/salt is in the correct state
2019-02-24 02:31:13,645 [salt.state       :1951][INFO    ][10761] Completed state [/etc/salt/grains.d/salt] at time 02:31:13.645220 duration_in_ms=15.822
2019-02-24 02:31:13,646 [salt.state       :1780][INFO    ][10761] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:31:13.646043
2019-02-24 02:31:13,646 [salt.state       :1813][INFO    ][10761] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-02-24 02:31:13,646 [salt.state       :300 ][INFO    ][10761] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-02-24 02:31:13,646 [salt.state       :1951][INFO    ][10761] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:31:13.646885 duration_in_ms=0.843
2019-02-24 02:31:13,648 [salt.state       :1780][INFO    ][10761] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:31:13.648611
2019-02-24 02:31:13,648 [salt.state       :1813][INFO    ][10761] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-02-24 02:31:13,649 [salt.state       :300 ][INFO    ][10761] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-02-24 02:31:13,649 [salt.state       :1951][INFO    ][10761] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:31:13.649436 duration_in_ms=0.825
2019-02-24 02:31:13,650 [salt.state       :1780][INFO    ][10761] Running state [mine.update] at time 02:31:13.650077
2019-02-24 02:31:13,650 [salt.state       :1813][INFO    ][10761] Executing state module.wait for [mine.update]
2019-02-24 02:31:13,650 [salt.state       :300 ][INFO    ][10761] No changes made for mine.update
2019-02-24 02:31:13,650 [salt.state       :1951][INFO    ][10761] Completed state [mine.update] at time 02:31:13.650869 duration_in_ms=0.792
2019-02-24 02:31:13,651 [salt.state       :1780][INFO    ][10761] Running state [ca-certificates] at time 02:31:13.651110
2019-02-24 02:31:13,651 [salt.state       :1813][INFO    ][10761] Executing state pkg.installed for [ca-certificates]
2019-02-24 02:31:13,658 [salt.state       :300 ][INFO    ][10761] All specified packages are already installed
2019-02-24 02:31:13,658 [salt.state       :1951][INFO    ][10761] Completed state [ca-certificates] at time 02:31:13.658270 duration_in_ms=7.16
2019-02-24 02:31:13,658 [salt.state       :1780][INFO    ][10761] Running state [update-ca-certificates] at time 02:31:13.658902
2019-02-24 02:31:13,659 [salt.state       :1813][INFO    ][10761] Executing state cmd.wait for [update-ca-certificates]
2019-02-24 02:31:13,659 [salt.state       :300 ][INFO    ][10761] No changes made for update-ca-certificates
2019-02-24 02:31:13,659 [salt.state       :1951][INFO    ][10761] Completed state [update-ca-certificates] at time 02:31:13.659666 duration_in_ms=0.764
2019-02-24 02:31:13,659 [salt.state       :1780][INFO    ][10761] Running state [iptables] at time 02:31:13.659893
2019-02-24 02:31:13,660 [salt.state       :1813][INFO    ][10761] Executing state pkg.installed for [iptables]
2019-02-24 02:31:13,668 [salt.state       :300 ][INFO    ][10761] All specified packages are already installed
2019-02-24 02:31:13,668 [salt.state       :1951][INFO    ][10761] Completed state [iptables] at time 02:31:13.668593 duration_in_ms=8.7
2019-02-24 02:31:13,668 [salt.state       :1780][INFO    ][10761] Running state [iptables-persistent] at time 02:31:13.668818
2019-02-24 02:31:13,669 [salt.state       :1813][INFO    ][10761] Executing state pkg.installed for [iptables-persistent]
2019-02-24 02:31:13,674 [salt.state       :300 ][INFO    ][10761] All specified packages are already installed
2019-02-24 02:31:13,675 [salt.state       :1951][INFO    ][10761] Completed state [iptables-persistent] at time 02:31:13.675089 duration_in_ms=6.271
2019-02-24 02:31:13,676 [salt.state       :1780][INFO    ][10761] Running state [iptables_modules_v4_load] at time 02:31:13.675983
2019-02-24 02:31:13,676 [salt.state       :1813][INFO    ][10761] Executing state kmod.present for [iptables_modules_v4_load]
2019-02-24 02:31:13,676 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10761] Executing command 'lsmod' in directory '/root'
2019-02-24 02:31:13,699 [salt.state       :300 ][INFO    ][10761] Kernel modules iptable_filter, ip_tables are already present
2019-02-24 02:31:13,699 [salt.state       :1951][INFO    ][10761] Completed state [iptables_modules_v4_load] at time 02:31:13.699731 duration_in_ms=23.748
2019-02-24 02:31:13,700 [salt.state       :1780][INFO    ][10761] Running state [/etc/iptables/rules.v4] at time 02:31:13.700302
2019-02-24 02:31:13,700 [salt.state       :1813][INFO    ][10761] Executing state file.managed for [/etc/iptables/rules.v4]
2019-02-24 02:31:13,782 [salt.state       :300 ][INFO    ][10761] File /etc/iptables/rules.v4 is in the correct state
2019-02-24 02:31:13,783 [salt.state       :1951][INFO    ][10761] Completed state [/etc/iptables/rules.v4] at time 02:31:13.783079 duration_in_ms=82.778
2019-02-24 02:31:13,783 [salt.state       :1780][INFO    ][10761] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:31:13.783894
2019-02-24 02:31:13,784 [salt.state       :1813][INFO    ][10761] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-02-24 02:31:13,784 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10761] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-02-24 02:31:13,801 [salt.state       :300 ][INFO    ][10761] onlyif execution failed
2019-02-24 02:31:13,802 [salt.state       :1951][INFO    ][10761] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:31:13.802025 duration_in_ms=18.129
2019-02-24 02:31:13,803 [salt.state       :1780][INFO    ][10761] Running state [netfilter-persistent] at time 02:31:13.803776
2019-02-24 02:31:13,804 [salt.state       :1813][INFO    ][10761] Executing state service.running for [netfilter-persistent]
2019-02-24 02:31:13,805 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10761] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-02-24 02:31:13,825 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10761] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-02-24 02:31:13,841 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10761] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-02-24 02:31:13,857 [salt.state       :300 ][INFO    ][10761] The service netfilter-persistent is already running
2019-02-24 02:31:13,858 [salt.state       :1951][INFO    ][10761] Completed state [netfilter-persistent] at time 02:31:13.858112 duration_in_ms=54.337
2019-02-24 02:31:13,858 [salt.state       :1780][INFO    ][10761] Running state [iptables_extra.remove_stale_tables] at time 02:31:13.858867
2019-02-24 02:31:13,859 [salt.state       :1813][INFO    ][10761] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-02-24 02:31:13,859 [salt.state       :300 ][INFO    ][10761] No changes made for iptables_extra.remove_stale_tables
2019-02-24 02:31:13,859 [salt.state       :1951][INFO    ][10761] Completed state [iptables_extra.remove_stale_tables] at time 02:31:13.859718 duration_in_ms=0.851
2019-02-24 02:31:13,859 [salt.state       :1780][INFO    ][10761] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:31:13.859951
2019-02-24 02:31:13,860 [salt.state       :1813][INFO    ][10761] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-02-24 02:31:13,860 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10761] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-02-24 02:31:13,875 [salt.state       :300 ][INFO    ][10761] onlyif execution failed
2019-02-24 02:31:13,875 [salt.state       :1951][INFO    ][10761] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:31:13.875834 duration_in_ms=15.882
2019-02-24 02:31:13,877 [salt.state       :1780][INFO    ][10761] Running state [/etc/iptables/rules.v6] at time 02:31:13.877312
2019-02-24 02:31:13,877 [salt.state       :1813][INFO    ][10761] Executing state file.absent for [/etc/iptables/rules.v6]
2019-02-24 02:31:13,878 [salt.state       :300 ][INFO    ][10761] File /etc/iptables/rules.v6 is not present
2019-02-24 02:31:13,878 [salt.state       :1951][INFO    ][10761] Completed state [/etc/iptables/rules.v6] at time 02:31:13.878831 duration_in_ms=1.519
2019-02-24 02:31:13,879 [salt.state       :1780][INFO    ][10761] Running state [iptables_extra.flush_all] at time 02:31:13.879860
2019-02-24 02:31:13,880 [salt.state       :1813][INFO    ][10761] Executing state module.wait for [iptables_extra.flush_all]
2019-02-24 02:31:13,880 [salt.state       :300 ][INFO    ][10761] No changes made for iptables_extra.flush_all
2019-02-24 02:31:13,881 [salt.state       :1951][INFO    ][10761] Completed state [iptables_extra.flush_all] at time 02:31:13.881021 duration_in_ms=1.161
2019-02-24 02:31:13,885 [salt.minion      :1711][INFO    ][10761] Returning information for job: 20190224023106123325
2019-02-24 02:31:14,467 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command state.apply with jid 20190224023114455273
2019-02-24 02:31:14,491 [salt.minion      :1432][INFO    ][10848] Starting a new job with PID 10848
2019-02-24 02:31:15,145 [salt.state       :915 ][INFO    ][10848] Loading fresh modules for state activity
2019-02-24 02:31:15,739 [salt.state       :1780][INFO    ][10848] Running state [maas-rack-controller] at time 02:31:15.738941
2019-02-24 02:31:15,739 [salt.state       :1813][INFO    ][10848] Executing state pkg.installed for [maas-rack-controller]
2019-02-24 02:31:15,739 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10848] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-24 02:31:15,832 [salt.state       :300 ][INFO    ][10848] All specified packages are already installed
2019-02-24 02:31:15,832 [salt.state       :1951][INFO    ][10848] Completed state [maas-rack-controller] at time 02:31:15.832411 duration_in_ms=93.471
2019-02-24 02:31:15,832 [salt.state       :1780][INFO    ][10848] Running state [ipmitool] at time 02:31:15.832806
2019-02-24 02:31:15,833 [salt.state       :1813][INFO    ][10848] Executing state pkg.installed for [ipmitool]
2019-02-24 02:31:15,842 [salt.state       :300 ][INFO    ][10848] All specified packages are already installed
2019-02-24 02:31:15,842 [salt.state       :1951][INFO    ][10848] Completed state [ipmitool] at time 02:31:15.842492 duration_in_ms=9.672
2019-02-24 02:31:15,845 [salt.state       :1780][INFO    ][10848] Running state [/etc/maas/rackd.conf] at time 02:31:15.845545
2019-02-24 02:31:15,845 [salt.state       :1813][INFO    ][10848] Executing state file.line for [/etc/maas/rackd.conf]
2019-02-24 02:31:15,846 [salt.state       :300 ][INFO    ][10848] No changes needed to be made
2019-02-24 02:31:15,847 [salt.state       :1951][INFO    ][10848] Completed state [/etc/maas/rackd.conf] at time 02:31:15.846981 duration_in_ms=1.436
2019-02-24 02:31:15,847 [salt.state       :1780][INFO    ][10848] Running state [/etc/maas/rackd.conf] at time 02:31:15.847219
2019-02-24 02:31:15,847 [salt.state       :1813][INFO    ][10848] Executing state file.managed for [/etc/maas/rackd.conf]
2019-02-24 02:31:15,847 [salt.loaded.int.states.file:2298][WARNING ][10848] 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-02-24 02:31:15,848 [salt.state       :300 ][INFO    ][10848] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-02-24 02:31:15,848 [salt.state       :1951][INFO    ][10848] Completed state [/etc/maas/rackd.conf] at time 02:31:15.848537 duration_in_ms=1.317
2019-02-24 02:31:15,901 [salt.state       :1780][INFO    ][10848] Running state [maas-rackd] at time 02:31:15.901471
2019-02-24 02:31:15,901 [salt.state       :1813][INFO    ][10848] Executing state service.running for [maas-rackd]
2019-02-24 02:31:15,902 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10848] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-02-24 02:31:15,939 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10848] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-02-24 02:31:15,956 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10848] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-02-24 02:31:15,973 [salt.state       :300 ][INFO    ][10848] The service maas-rackd is already running
2019-02-24 02:31:15,973 [salt.state       :1951][INFO    ][10848] Completed state [maas-rackd] at time 02:31:15.973440 duration_in_ms=71.968
2019-02-24 02:31:15,975 [salt.minion      :1711][INFO    ][10848] Returning information for job: 20190224023114455273
2019-02-24 02:31:16,511 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command state.apply with jid 20190224023116498707
2019-02-24 02:31:16,532 [salt.minion      :1432][INFO    ][10871] Starting a new job with PID 10871
2019-02-24 02:31:17,121 [salt.state       :915 ][INFO    ][10871] Loading fresh modules for state activity
2019-02-24 02:31:17,829 [salt.state       :1780][INFO    ][10871] Running state [maas-region-controller] at time 02:31:17.829887
2019-02-24 02:31:17,830 [salt.state       :1813][INFO    ][10871] Executing state pkg.installed for [maas-region-controller]
2019-02-24 02:31:17,830 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10871] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-24 02:31:17,909 [salt.state       :300 ][INFO    ][10871] All specified packages are already installed
2019-02-24 02:31:17,909 [salt.state       :1951][INFO    ][10871] Completed state [maas-region-controller] at time 02:31:17.909349 duration_in_ms=79.463
2019-02-24 02:31:17,909 [salt.state       :1780][INFO    ][10871] Running state [python-oauth] at time 02:31:17.909643
2019-02-24 02:31:17,909 [salt.state       :1813][INFO    ][10871] Executing state pkg.installed for [python-oauth]
2019-02-24 02:31:17,915 [salt.state       :300 ][INFO    ][10871] All specified packages are already installed
2019-02-24 02:31:17,915 [salt.state       :1951][INFO    ][10871] Completed state [python-oauth] at time 02:31:17.915232 duration_in_ms=5.589
2019-02-24 02:31:17,918 [salt.state       :1780][INFO    ][10871] Running state [/etc/maas/regiond.conf] at time 02:31:17.918036
2019-02-24 02:31:17,918 [salt.state       :1813][INFO    ][10871] Executing state file.replace for [/etc/maas/regiond.conf]
2019-02-24 02:31:17,939 [salt.state       :300 ][INFO    ][10871] No changes needed to be made
2019-02-24 02:31:17,939 [salt.state       :1951][INFO    ][10871] Completed state [/etc/maas/regiond.conf] at time 02:31:17.939608 duration_in_ms=21.572
2019-02-24 02:31:17,940 [salt.state       :1780][INFO    ][10871] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:31:17.939982
2019-02-24 02:31:17,940 [salt.state       :1813][INFO    ][10871] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-02-24 02:31:17,999 [salt.state       :300 ][INFO    ][10871] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-02-24 02:31:17,999 [salt.state       :1951][INFO    ][10871] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:31:17.999386 duration_in_ms=59.402
2019-02-24 02:31:18,000 [salt.state       :1780][INFO    ][10871] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:31:18.000214
2019-02-24 02:31:18,000 [salt.state       :1813][INFO    ][10871] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-02-24 02:31:18,013 [salt.state       :300 ][INFO    ][10871] No changes needed to be made
2019-02-24 02:31:18,013 [salt.state       :1951][INFO    ][10871] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:31:18.013290 duration_in_ms=13.075
2019-02-24 02:31:18,014 [salt.state       :1780][INFO    ][10871] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:31:18.014047
2019-02-24 02:31:18,014 [salt.state       :1813][INFO    ][10871] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-02-24 02:31:18,043 [salt.state       :300 ][INFO    ][10871] No changes needed to be made
2019-02-24 02:31:18,043 [salt.state       :1951][INFO    ][10871] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:31:18.043363 duration_in_ms=29.316
2019-02-24 02:31:18,044 [salt.state       :1780][INFO    ][10871] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:31:18.044149
2019-02-24 02:31:18,044 [salt.state       :1813][INFO    ][10871] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-02-24 02:31:18,076 [salt.state       :300 ][INFO    ][10871] No changes needed to be made
2019-02-24 02:31:18,076 [salt.state       :1951][INFO    ][10871] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:31:18.076269 duration_in_ms=32.12
2019-02-24 02:31:18,076 [salt.state       :1780][INFO    ][10871] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:31:18.076936
2019-02-24 02:31:18,077 [salt.state       :1813][INFO    ][10871] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-02-24 02:31:18,090 [salt.state       :300 ][INFO    ][10871] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-02-24 02:31:18,090 [salt.state       :1951][INFO    ][10871] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:31:18.090660 duration_in_ms=13.723
2019-02-24 02:31:18,092 [salt.state       :1780][INFO    ][10871] Running state [a2enmod headers] at time 02:31:18.092040
2019-02-24 02:31:18,092 [salt.state       :1813][INFO    ][10871] Executing state cmd.run for [a2enmod headers]
2019-02-24 02:31:18,093 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10871] Executing command 'a2enmod headers' in directory '/root'
2019-02-24 02:31:18,163 [salt.state       :300 ][INFO    ][10871] {'pid': 10890, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-02-24 02:31:18,163 [salt.state       :1951][INFO    ][10871] Completed state [a2enmod headers] at time 02:31:18.163848 duration_in_ms=71.808
2019-02-24 02:31:18,164 [salt.state       :1780][INFO    ][10871] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:31:18.164489
2019-02-24 02:31:18,165 [salt.state       :1813][INFO    ][10871] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-02-24 02:31:18,185 [salt.state       :300 ][INFO    ][10871] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-02-24 02:31:18,185 [salt.state       :1951][INFO    ][10871] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:31:18.185718 duration_in_ms=21.241
2019-02-24 02:31:18,186 [salt.state       :1780][INFO    ][10871] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:31:18.186660
2019-02-24 02:31:18,187 [salt.state       :1813][INFO    ][10871] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-02-24 02:31:18,276 [salt.state       :300 ][INFO    ][10871] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-02-24 02:31:18,277 [salt.state       :1951][INFO    ][10871] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:31:18.277093 duration_in_ms=90.432
2019-02-24 02:31:18,278 [salt.state       :1780][INFO    ][10871] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:31:18.278155
2019-02-24 02:31:18,278 [salt.state       :1813][INFO    ][10871] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-02-24 02:31:18,364 [salt.state       :300 ][INFO    ][10871] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-02-24 02:31:18,364 [salt.state       :1951][INFO    ][10871] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:31:18.364748 duration_in_ms=86.593
2019-02-24 02:31:18,365 [salt.state       :1780][INFO    ][10871] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:31:18.365324
2019-02-24 02:31:18,365 [salt.state       :1813][INFO    ][10871] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-02-24 02:31:18,424 [salt.state       :300 ][INFO    ][10871] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-02-24 02:31:18,424 [salt.state       :1951][INFO    ][10871] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:31:18.424621 duration_in_ms=59.296
2019-02-24 02:31:18,424 [salt.state       :1780][INFO    ][10871] Running state [/root/.pgpass] at time 02:31:18.424922
2019-02-24 02:31:18,425 [salt.state       :1813][INFO    ][10871] Executing state file.managed for [/root/.pgpass]
2019-02-24 02:31:18,478 [salt.state       :300 ][INFO    ][10871] File /root/.pgpass is in the correct state
2019-02-24 02:31:18,478 [salt.state       :1951][INFO    ][10871] Completed state [/root/.pgpass] at time 02:31:18.478355 duration_in_ms=53.433
2019-02-24 02:31:18,484 [salt.state       :1780][INFO    ][10871] Running state [maas-region syncdb --noinput] at time 02:31:18.483984
2019-02-24 02:31:18,484 [salt.state       :1813][INFO    ][10871] Executing state cmd.run for [maas-region syncdb --noinput]
2019-02-24 02:31:18,485 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10871] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-02-24 02:31:20,449 [salt.state       :300 ][INFO    ][10871] {'pid': 10903, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: metadataserver, sites, contenttypes, auth, sessions, piston3, maasserver\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-02-24 02:31:20,450 [salt.state       :1951][INFO    ][10871] Completed state [maas-region syncdb --noinput] at time 02:31:20.450179 duration_in_ms=1966.194
2019-02-24 02:31:20,450 [salt.state       :2022][WARNING ][10871] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-02-24 02:31:20,453 [salt.state       :1780][INFO    ][10871] Running state [maas-regiond] at time 02:31:20.453086
2019-02-24 02:31:20,453 [salt.state       :1813][INFO    ][10871] Executing state service.running for [maas-regiond]
2019-02-24 02:31:20,455 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10871] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-02-24 02:31:20,492 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10871] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-02-24 02:31:20,509 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10871] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-02-24 02:31:20,526 [salt.state       :300 ][INFO    ][10871] The service maas-regiond is already running
2019-02-24 02:31:20,526 [salt.state       :1951][INFO    ][10871] Completed state [maas-regiond] at time 02:31:20.526810 duration_in_ms=73.724
2019-02-24 02:31:20,529 [salt.state       :1780][INFO    ][10871] Running state [bind9] at time 02:31:20.529155
2019-02-24 02:31:20,529 [salt.state       :1813][INFO    ][10871] Executing state service.running for [bind9]
2019-02-24 02:31:20,530 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10871] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-02-24 02:31:20,547 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10871] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-02-24 02:31:20,563 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10871] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-02-24 02:31:20,578 [salt.state       :300 ][INFO    ][10871] The service bind9 is already running
2019-02-24 02:31:20,579 [salt.state       :1951][INFO    ][10871] Completed state [bind9] at time 02:31:20.579308 duration_in_ms=50.151
2019-02-24 02:31:20,581 [salt.state       :1780][INFO    ][10871] Running state [apache2] at time 02:31:20.581660
2019-02-24 02:31:20,582 [salt.state       :1813][INFO    ][10871] Executing state service.running for [apache2]
2019-02-24 02:31:20,583 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10871] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-02-24 02:31:20,599 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10871] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-02-24 02:31:20,615 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10871] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-02-24 02:31:20,635 [salt.state       :300 ][INFO    ][10871] The service apache2 is already running
2019-02-24 02:31:20,635 [salt.state       :1951][INFO    ][10871] Completed state [apache2] at time 02:31:20.635702 duration_in_ms=54.041
2019-02-24 02:31:20,637 [salt.state       :1780][INFO    ][10871] Running state [maasng.wait_for_http_code] at time 02:31:20.637863
2019-02-24 02:31:20,638 [salt.state       :1813][INFO    ][10871] Executing state module.run for [maasng.wait_for_http_code]
2019-02-24 02:31:20,639 [salt.utils.decorators:613 ][WARNING ][10871] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-24 02:31:20,646 [salt.state       :300 ][INFO    ][10871] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-02-24 02:31:20,647 [salt.state       :1951][INFO    ][10871] Completed state [maasng.wait_for_http_code] at time 02:31:20.646993 duration_in_ms=9.13
2019-02-24 02:31:20,648 [salt.state       :1780][INFO    ][10871] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:31:20.648103
2019-02-24 02:31:20,648 [salt.state       :1813][INFO    ][10871] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-02-24 02:31:20,649 [salt.state       :300 ][INFO    ][10871] /var/lib/maas/.setup_admin exists
2019-02-24 02:31:20,649 [salt.state       :1951][INFO    ][10871] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:31:20.649471 duration_in_ms=1.369
2019-02-24 02:31:20,650 [salt.state       :1780][INFO    ][10871] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:31:20.650428
2019-02-24 02:31:20,650 [salt.state       :1813][INFO    ][10871] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-24 02:31:20,651 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10871] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-24 02:31:22,213 [salt.state       :300 ][INFO    ][10871] {'pid': 10922, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-24 02:31:22,214 [salt.state       :1951][INFO    ][10871] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:31:22.214657 duration_in_ms=1564.228
2019-02-24 02:31:22,224 [salt.state       :1780][INFO    ][10871] Running state [maas_region_boot_source_resources_mirror] at time 02:31:22.224829
2019-02-24 02:31:22,225 [salt.state       :1813][INFO    ][10871] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-02-24 02:31:22,322 [salt.state       :300 ][INFO    ][10871] {'changes': {}}
2019-02-24 02:31:22,322 [salt.state       :1951][INFO    ][10871] Completed state [maas_region_boot_source_resources_mirror] at time 02:31:22.322428 duration_in_ms=97.599
2019-02-24 02:31:22,323 [salt.state       :1780][INFO    ][10871] Running state [maasng.boot_resources_import] at time 02:31:22.323430
2019-02-24 02:31:22,323 [salt.state       :1813][INFO    ][10871] Executing state module.run for [maasng.boot_resources_import]
2019-02-24 02:31:22,324 [salt.utils.decorators:613 ][WARNING ][10871] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-24 02:31:22,466 [salt.loaded.ext.module.maasng:1600][INFO    ][10871] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-02-24 02:31:27,527 [salt.loaded.ext.module.maasng:1600][INFO    ][10871] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-02-24 02:31:31,613 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224023131600644
2019-02-24 02:31:31,636 [salt.minion      :1432][INFO    ][10965] Starting a new job with PID 10965
2019-02-24 02:31:31,657 [salt.minion      :1711][INFO    ][10965] Returning information for job: 20190224023131600644
2019-02-24 02:31:32,581 [salt.loaded.ext.module.maasng:1600][INFO    ][10871] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-02-24 02:31:37,695 [salt.state       :300 ][INFO    ][10871] {'ret': True}
2019-02-24 02:31:37,695 [salt.state       :1951][INFO    ][10871] Completed state [maasng.boot_resources_import] at time 02:31:37.695748 duration_in_ms=15372.317
2019-02-24 02:31:37,696 [salt.state       :1780][INFO    ][10871] Running state [maas_region_boot_sources_selection_xenial] at time 02:31:37.696917
2019-02-24 02:31:37,697 [salt.state       :1813][INFO    ][10871] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-02-24 02:31:37,898 [salt.state       :300 ][INFO    ][10871] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-02-24 02:31:37,899 [salt.state       :1951][INFO    ][10871] Completed state [maas_region_boot_sources_selection_xenial] at time 02:31:37.899140 duration_in_ms=202.223
2019-02-24 02:31:37,900 [salt.state       :1780][INFO    ][10871] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 02:31:37.900514
2019-02-24 02:31:37,901 [salt.state       :1813][INFO    ][10871] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-02-24 02:31:37,901 [salt.utils.decorators:613 ][WARNING ][10871] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-24 02:31:37,902 [salt.loaded.ext.module.maasng:1771][INFO    ][10871] boot-sources sync initiated for ALL Rack's
2019-02-24 02:31:38,880 [salt.state       :300 ][INFO    ][10871] {'ret': True}
2019-02-24 02:31:38,881 [salt.state       :1951][INFO    ][10871] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 02:31:38.881002 duration_in_ms=980.488
2019-02-24 02:31:38,882 [salt.state       :1780][INFO    ][10871] Running state [maas.process_maas_config] at time 02:31:38.882907
2019-02-24 02:31:38,883 [salt.state       :1813][INFO    ][10871] Executing state module.run for [maas.process_maas_config]
2019-02-24 02:31:38,883 [salt.utils.decorators:613 ][WARNING ][10871] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-24 02:31:38,884 [salt.loaded.ext.module.maas:92  ][INFO    ][10871] maasconfig name=enable_http_proxy value=True
2019-02-24 02:31:38,948 [salt.loaded.ext.module.maas:92  ][INFO    ][10871] maasconfig name=upstream_dns value=8.8.8.8
2019-02-24 02:31:39,028 [salt.loaded.ext.module.maas:92  ][INFO    ][10871] maasconfig name=commissioning_distro_series value=xenial
2019-02-24 02:31:39,090 [salt.loaded.ext.module.maas:92  ][INFO    ][10871] maasconfig name=default_osystem value=ubuntu
2019-02-24 02:31:42,428 [salt.loaded.ext.module.maas:92  ][INFO    ][10871] maasconfig name=active_discovery_interval value=600
2019-02-24 02:31:42,499 [salt.loaded.ext.module.maas:92  ][INFO    ][10871] maasconfig name=dnssec_validation value=no
2019-02-24 02:31:42,565 [salt.loaded.ext.module.maas:92  ][INFO    ][10871] maasconfig name=maas_name value=mas01
2019-02-24 02:31:42,625 [salt.loaded.ext.module.maas:92  ][INFO    ][10871] maasconfig name=network_discovery value=enabled
2019-02-24 02:31:42,751 [salt.loaded.ext.module.maas:92  ][INFO    ][10871] maasconfig name=enable_third_party_drivers value=True
2019-02-24 02:31:42,803 [salt.loaded.ext.module.maas:92  ][INFO    ][10871] maasconfig name=default_storage_layout value=lvm
2019-02-24 02:31:42,863 [salt.loaded.ext.module.maas:92  ][INFO    ][10871] maasconfig name=ntp_external_only value=True
2019-02-24 02:31:42,911 [salt.loaded.ext.module.maas:92  ][INFO    ][10871] maasconfig name=disk_erase_with_secure_erase value=False
2019-02-24 02:31:42,964 [salt.loaded.ext.module.maas:92  ][INFO    ][10871] maasconfig name=default_distro_series value=xenial
2019-02-24 02:31:43,049 [salt.loaded.ext.module.maas:92  ][INFO    ][10871] maasconfig name=default_min_hwe_kernel value=ga-16.04
2019-02-24 02:31:43,169 [salt.state       :300 ][INFO    ][10871] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-02-24 02:31:43,170 [salt.state       :1951][INFO    ][10871] Completed state [maas.process_maas_config] at time 02:31:43.169997 duration_in_ms=4287.09
2019-02-24 02:31:43,170 [salt.state       :1780][INFO    ][10871] Running state [pxe_admin] at time 02:31:43.170667
2019-02-24 02:31:43,171 [salt.state       :1813][INFO    ][10871] Executing state maasng.fabric_present for [pxe_admin]
2019-02-24 02:31:43,241 [salt.loaded.ext.module.maasng:945 ][INFO    ][10871] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'snf7xq', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'class_type': u''}]
2019-02-24 02:31:43,306 [salt.loaded.ext.module.maasng:1008][WARNING ][10871] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-02-24 02:31:43,306 [salt.loaded.ext.module.maasng:1011][WARNING ][10871] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-02-24 02:31:43,391 [salt.state       :300 ][INFO    ][10871] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-02-24 02:31:43,392 [salt.state       :1951][INFO    ][10871] Completed state [pxe_admin] at time 02:31:43.392368 duration_in_ms=221.699
2019-02-24 02:31:43,393 [salt.state       :1780][INFO    ][10871] Running state [vlan 0] at time 02:31:43.392993
2019-02-24 02:31:43,393 [salt.state       :1813][INFO    ][10871] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-02-24 02:31:43,462 [salt.loaded.ext.module.maasng:945 ][INFO    ][10871] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'snf7xq', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'class_type': u''}]
2019-02-24 02:31:43,582 [salt.loaded.ext.module.maasng:945 ][INFO    ][10871] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'snf7xq', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u''}]
2019-02-24 02:31:43,838 [salt.loaded.ext.module.maasng:945 ][INFO    ][10871] [{u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0'}, {u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1'}, {u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'snf7xq', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin'}]
2019-02-24 02:31:43,931 [salt.state       :300 ][INFO    ][10871] {'new': 'Vlan untagged was updated'}
2019-02-24 02:31:43,932 [salt.state       :1951][INFO    ][10871] Completed state [vlan 0] at time 02:31:43.932157 duration_in_ms=539.163
2019-02-24 02:31:43,934 [salt.state       :1780][INFO    ][10871] Running state [192.168.11.0/24] at time 02:31:43.934007
2019-02-24 02:31:43,934 [salt.state       :1813][INFO    ][10871] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-02-24 02:31:44,174 [salt.loaded.ext.module.maasng:945 ][INFO    ][10871] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'snf7xq', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-02-24 02:31:44,175 [salt.loaded.ext.module.maasng:1235][WARNING ][10871] Ignoring parameter vlan:0
2019-02-24 02:31:44,253 [salt.state       :300 ][INFO    ][10871] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-02-24 02:31:44,254 [salt.state       :1951][INFO    ][10871] Completed state [192.168.11.0/24] at time 02:31:44.254021 duration_in_ms=320.013
2019-02-24 02:31:44,255 [salt.state       :1780][INFO    ][10871] Running state [maas_create_iprange_1] at time 02:31:44.255288
2019-02-24 02:31:44,255 [salt.state       :1813][INFO    ][10871] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-02-24 02:31:44,324 [salt.state       :300 ][INFO    ][10871] Iprange maas_create_iprange_1 already exist.
2019-02-24 02:31:44,325 [salt.state       :1951][INFO    ][10871] Completed state [maas_create_iprange_1] at time 02:31:44.325207 duration_in_ms=69.919
2019-02-24 02:31:44,325 [salt.state       :1780][INFO    ][10871] Running state [vlan 0] at time 02:31:44.325601
2019-02-24 02:31:44,326 [salt.state       :1813][INFO    ][10871] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-02-24 02:31:44,384 [salt.loaded.ext.module.maasng:945 ][INFO    ][10871] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'snf7xq', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-02-24 02:31:44,492 [salt.loaded.ext.module.maasng:945 ][INFO    ][10871] [{u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0'}, {u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1'}, {u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'snf7xq', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin'}]
2019-02-24 02:31:44,767 [salt.loaded.ext.module.maasng:945 ][INFO    ][10871] [{u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0'}, {u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1'}, {u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'snf7xq', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin'}]
2019-02-24 02:31:44,870 [salt.state       :300 ][INFO    ][10871] {'new': 'Vlan untagged was updated'}
2019-02-24 02:31:44,870 [salt.state       :1951][INFO    ][10871] Completed state [vlan 0] at time 02:31:44.870605 duration_in_ms=545.003
2019-02-24 02:31:44,871 [salt.state       :1780][INFO    ][10871] Running state [opnfv] at time 02:31:44.871436
2019-02-24 02:31:44,871 [salt.state       :1813][INFO    ][10871] Executing state maasng.sshkey_present for [opnfv]
2019-02-24 02:31:44,923 [salt.loaded.ext.module.maasng:1903][INFO    ][10871] [{u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP', u'keysource': u''}]
2019-02-24 02:31:44,923 [salt.state       :300 ][INFO    ][10871] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-02-24 02:31:44,924 [salt.state       :1951][INFO    ][10871] Completed state [opnfv] at time 02:31:44.924035 duration_in_ms=52.598
2019-02-24 02:31:44,927 [salt.minion      :1711][INFO    ][10871] Returning information for job: 20190224023116498707
2019-02-24 02:31:45,497 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command state.apply with jid 20190224023145486014
2019-02-24 02:31:45,521 [salt.minion      :1432][INFO    ][11333] Starting a new job with PID 11333
2019-02-24 02:31:49,030 [salt.state       :915 ][INFO    ][11333] Loading fresh modules for state activity
2019-02-24 02:31:49,123 [salt.state       :1780][INFO    ][11333] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:31:49.123879
2019-02-24 02:31:49,124 [salt.state       :1813][INFO    ][11333] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-24 02:31:49,126 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11333] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-24 02:31:50,585 [salt.state       :300 ][INFO    ][11333] {'pid': 11356, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-24 02:31:50,586 [salt.state       :1951][INFO    ][11333] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:31:50.586160 duration_in_ms=1462.282
2019-02-24 02:31:50,587 [salt.state       :1780][INFO    ][11333] Running state [maas.process_machines] at time 02:31:50.587322
2019-02-24 02:31:50,587 [salt.state       :1813][INFO    ][11333] Executing state module.run for [maas.process_machines]
2019-02-24 02:31:50,588 [salt.utils.decorators:613 ][WARNING ][11333] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-24 02:31:51,313 [salt.loaded.ext.module.maas:412 ][WARNING ][11333] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-24 02:31:51,314 [salt.loaded.ext.module.maas:92  ][INFO    ][11333] 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=mq8wg3 architecture=amd64/generic power_parameters_power_user=admin
2019-02-24 02:31:52,607 [salt.loaded.ext.module.maas:412 ][WARNING ][11333] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-24 02:31:52,607 [salt.loaded.ext.module.maas:92  ][INFO    ][11333] 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=sng3fe architecture=amd64/generic power_parameters_power_user=admin
2019-02-24 02:31:53,840 [salt.loaded.ext.module.maas:412 ][WARNING ][11333] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-24 02:31:53,842 [salt.loaded.ext.module.maas:92  ][INFO    ][11333] 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=kfh76e architecture=amd64/generic power_parameters_power_user=admin
2019-02-24 02:31:54,870 [salt.loaded.ext.module.maas:412 ][WARNING ][11333] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-24 02:31:54,871 [salt.loaded.ext.module.maas:92  ][INFO    ][11333] 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=sfd8kg architecture=amd64/generic power_parameters_power_user=admin
2019-02-24 02:31:55,858 [salt.loaded.ext.module.maas:412 ][WARNING ][11333] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-24 02:31:55,859 [salt.loaded.ext.module.maas:92  ][INFO    ][11333] 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 system_id=qe3txr architecture=amd64/generic power_parameters_power_user=admin
2019-02-24 02:31:56,901 [salt.state       :300 ][INFO    ][11333] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-02-24 02:31:56,902 [salt.state       :1951][INFO    ][11333] Completed state [maas.process_machines] at time 02:31:56.902243 duration_in_ms=6314.92
2019-02-24 02:31:56,907 [salt.minion      :1711][INFO    ][11333] Returning information for job: 20190224023145486014
2019-02-24 02:32:30,294 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command state.apply with jid 20190224023230280721
2019-02-24 02:32:30,320 [salt.minion      :1432][INFO    ][11620] Starting a new job with PID 11620
2019-02-24 02:32:33,854 [salt.state       :915 ][INFO    ][11620] Loading fresh modules for state activity
2019-02-24 02:32:33,939 [salt.state       :1780][INFO    ][11620] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:32:33.939602
2019-02-24 02:32:33,940 [salt.state       :1813][INFO    ][11620] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-24 02:32:33,942 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11620] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-24 02:32:35,436 [salt.state       :300 ][INFO    ][11620] {'pid': 11628, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-24 02:32:35,437 [salt.state       :1951][INFO    ][11620] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:32:35.437733 duration_in_ms=1498.132
2019-02-24 02:32:35,440 [salt.state       :1780][INFO    ][11620] Running state [maas.wait_for_machine_status] at time 02:32:35.440843
2019-02-24 02:32:35,441 [salt.state       :1813][INFO    ][11620] Executing state module.run for [maas.wait_for_machine_status]
2019-02-24 02:32:35,442 [salt.utils.decorators:613 ][WARNING ][11620] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-24 02:32:38,743 [salt.loaded.ext.module.maas:993 ][INFO    ][11620] Machine qe3txr mark broken
2019-02-24 02:32:39,385 [salt.loaded.ext.module.maas:996 ][INFO    ][11620] Machine qe3txr mark fixed
2019-02-24 02:32:40,635 [salt.loaded.ext.module.maas:684 ][INFO    ][11620] deploymachines hwe_kernel=ga-16.04 system_id=qe3txr distro_series=xenial
2019-02-24 02:32:43,374 [salt.loaded.ext.module.maas:1023][INFO    ][11620] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1492.0762229s left)
2019-02-24 02:32:45,366 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224023245307376
2019-02-24 02:32:45,389 [salt.minion      :1432][INFO    ][11704] Starting a new job with PID 11704
2019-02-24 02:32:45,407 [salt.minion      :1711][INFO    ][11704] Returning information for job: 20190224023245307376
2019-02-24 02:33:15,409 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224023315397219
2019-02-24 02:33:15,433 [salt.minion      :1432][INFO    ][11750] Starting a new job with PID 11750
2019-02-24 02:33:15,457 [salt.minion      :1711][INFO    ][11750] Returning information for job: 20190224023315397219
2019-02-24 02:33:16,785 [salt.loaded.ext.module.maas:1023][INFO    ][11620] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1458.66558003s left)
2019-02-24 02:33:45,457 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224023345444471
2019-02-24 02:33:45,482 [salt.minion      :1432][INFO    ][11787] Starting a new job with PID 11787
2019-02-24 02:33:45,506 [salt.minion      :1711][INFO    ][11787] Returning information for job: 20190224023345444471
2019-02-24 02:33:50,311 [salt.loaded.ext.module.maas:1023][INFO    ][11620] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1425.13913703s left)
2019-02-24 02:34:15,508 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224023415495786
2019-02-24 02:34:15,532 [salt.minion      :1432][INFO    ][11827] Starting a new job with PID 11827
2019-02-24 02:34:15,555 [salt.minion      :1711][INFO    ][11827] Returning information for job: 20190224023415495786
2019-02-24 02:34:23,024 [salt.loaded.ext.module.maas:1023][INFO    ][11620] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1392.42687201s left)
2019-02-24 02:34:45,566 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224023445554195
2019-02-24 02:34:45,588 [salt.minion      :1432][INFO    ][11918] Starting a new job with PID 11918
2019-02-24 02:34:45,611 [salt.minion      :1711][INFO    ][11918] Returning information for job: 20190224023445554195
2019-02-24 02:34:56,714 [salt.loaded.ext.module.maas:1023][INFO    ][11620] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1358.73695397s left)
2019-02-24 02:35:15,625 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224023515611798
2019-02-24 02:35:15,650 [salt.minion      :1432][INFO    ][11991] Starting a new job with PID 11991
2019-02-24 02:35:15,674 [salt.minion      :1711][INFO    ][11991] Returning information for job: 20190224023515611798
2019-02-24 02:35:30,344 [salt.loaded.ext.module.maas:1023][INFO    ][11620] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1325.10638285s left)
2019-02-24 02:35:45,691 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224023545677943
2019-02-24 02:35:45,718 [salt.minion      :1432][INFO    ][12088] Starting a new job with PID 12088
2019-02-24 02:35:45,742 [salt.minion      :1711][INFO    ][12088] Returning information for job: 20190224023545677943
2019-02-24 02:36:04,218 [salt.loaded.ext.module.maas:1023][INFO    ][11620] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1291.23259902s left)
2019-02-24 02:36:15,759 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224023615746566
2019-02-24 02:36:15,785 [salt.minion      :1432][INFO    ][12169] Starting a new job with PID 12169
2019-02-24 02:36:15,809 [salt.minion      :1711][INFO    ][12169] Returning information for job: 20190224023615746566
2019-02-24 02:36:37,606 [salt.loaded.ext.module.maas:1023][INFO    ][11620] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1257.84481192s left)
2019-02-24 02:36:45,833 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224023645820952
2019-02-24 02:36:45,859 [salt.minion      :1432][INFO    ][12251] Starting a new job with PID 12251
2019-02-24 02:36:45,881 [salt.minion      :1711][INFO    ][12251] Returning information for job: 20190224023645820952
2019-02-24 02:37:11,361 [salt.loaded.ext.module.maas:1023][INFO    ][11620] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1224.08969498s left)
2019-02-24 02:37:15,900 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224023715886947
2019-02-24 02:37:15,925 [salt.minion      :1432][INFO    ][12291] Starting a new job with PID 12291
2019-02-24 02:37:15,947 [salt.minion      :1711][INFO    ][12291] Returning information for job: 20190224023715886947
2019-02-24 02:37:45,075 [salt.loaded.ext.module.maas:1023][INFO    ][11620] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1190.37609291s left)
2019-02-24 02:37:45,975 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224023745962877
2019-02-24 02:37:46,000 [salt.minion      :1432][INFO    ][12331] Starting a new job with PID 12331
2019-02-24 02:37:46,024 [salt.minion      :1711][INFO    ][12331] Returning information for job: 20190224023745962877
2019-02-24 02:38:16,047 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224023816034876
2019-02-24 02:38:16,073 [salt.minion      :1432][INFO    ][12385] Starting a new job with PID 12385
2019-02-24 02:38:16,095 [salt.minion      :1711][INFO    ][12385] Returning information for job: 20190224023816034876
2019-02-24 02:38:18,692 [salt.loaded.ext.module.maas:1023][INFO    ][11620] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1156.75904083s left)
2019-02-24 02:38:46,133 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224023846120912
2019-02-24 02:38:46,157 [salt.minion      :1432][INFO    ][12450] Starting a new job with PID 12450
2019-02-24 02:38:46,180 [salt.minion      :1711][INFO    ][12450] Returning information for job: 20190224023846120912
2019-02-24 02:38:52,309 [salt.loaded.ext.module.maas:1023][INFO    ][11620] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1123.14179397s left)
2019-02-24 02:39:16,220 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224023916207582
2019-02-24 02:39:16,245 [salt.minion      :1432][INFO    ][12524] Starting a new job with PID 12524
2019-02-24 02:39:16,268 [salt.minion      :1711][INFO    ][12524] Returning information for job: 20190224023916207582
2019-02-24 02:39:25,846 [salt.loaded.ext.module.maas:1023][INFO    ][11620] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1089.60436201s left)
2019-02-24 02:39:46,319 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224023946306457
2019-02-24 02:39:46,345 [salt.minion      :1432][INFO    ][12605] Starting a new job with PID 12605
2019-02-24 02:39:46,372 [salt.minion      :1711][INFO    ][12605] Returning information for job: 20190224023946306457
2019-02-24 02:39:59,068 [salt.loaded.ext.module.maas:1023][INFO    ][11620] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1056.38230705s left)
2019-02-24 02:40:16,423 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224024016410710
2019-02-24 02:40:16,450 [salt.minion      :1432][INFO    ][12673] Starting a new job with PID 12673
2019-02-24 02:40:16,475 [salt.minion      :1711][INFO    ][12673] Returning information for job: 20190224024016410710
2019-02-24 02:40:32,550 [salt.loaded.ext.module.maas:1023][INFO    ][11620] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1022.90056705s left)
2019-02-24 02:40:46,534 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224024046522123
2019-02-24 02:40:46,561 [salt.minion      :1432][INFO    ][12715] Starting a new job with PID 12715
2019-02-24 02:40:46,587 [salt.minion      :1711][INFO    ][12715] Returning information for job: 20190224024046522123
2019-02-24 02:41:06,215 [salt.loaded.ext.module.maas:1023][INFO    ][11620] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (989.235971928s left)
2019-02-24 02:41:16,643 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190224024116630874
2019-02-24 02:41:16,670 [salt.minion      :1432][INFO    ][12789] Starting a new job with PID 12789
2019-02-24 02:41:16,695 [salt.minion      :1711][INFO    ][12789] Returning information for job: 20190224024116630874
2019-02-24 02:41:39,764 [salt.state       :300 ][INFO    ][11620] {'ret': True}
2019-02-24 02:41:39,764 [salt.state       :1951][INFO    ][11620] Completed state [maas.wait_for_machine_status] at time 02:41:39.764903 duration_in_ms=544324.058
2019-02-24 02:41:39,768 [salt.minion      :1711][INFO    ][11620] Returning information for job: 20190224023230280721
2019-02-24 02:41:40,424 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command state.apply with jid 20190224024140410939
2019-02-24 02:41:40,450 [salt.minion      :1432][INFO    ][12876] Starting a new job with PID 12876
2019-02-24 02:41:43,933 [salt.state       :915 ][INFO    ][12876] Loading fresh modules for state activity
2019-02-24 02:41:44,060 [salt.state       :1780][INFO    ][12876] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:41:44.060553
2019-02-24 02:41:44,060 [salt.state       :1813][INFO    ][12876] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-24 02:41:44,062 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12876] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-24 02:41:45,670 [salt.state       :300 ][INFO    ][12876] {'pid': 12884, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-24 02:41:45,671 [salt.state       :1951][INFO    ][12876] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:41:45.670958 duration_in_ms=1610.404
2019-02-24 02:41:45,674 [salt.state       :1780][INFO    ][12876] Running state [maas_machines_storage_cmp002_lvm] at time 02:41:45.674048
2019-02-24 02:41:45,674 [salt.state       :1813][INFO    ][12876] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-02-24 02:41:46,316 [salt.state       :300 ][INFO    ][12876] Machine cmp002 is not in Ready state.
2019-02-24 02:41:46,316 [salt.state       :1951][INFO    ][12876] Completed state [maas_machines_storage_cmp002_lvm] at time 02:41:46.316827 duration_in_ms=642.778
2019-02-24 02:41:46,317 [salt.state       :1780][INFO    ][12876] Running state [maas_machines_storage_cmp001_lvm] at time 02:41:46.317420
2019-02-24 02:41:46,317 [salt.state       :1813][INFO    ][12876] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-02-24 02:41:47,051 [salt.state       :300 ][INFO    ][12876] Machine cmp001 is not in Ready state.
2019-02-24 02:41:47,051 [salt.state       :1951][INFO    ][12876] Completed state [maas_machines_storage_cmp001_lvm] at time 02:41:47.051565 duration_in_ms=734.144
2019-02-24 02:41:47,055 [salt.minion      :1711][INFO    ][12876] Returning information for job: 20190224024140410939
2019-02-24 02:41:47,674 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command state.apply with jid 20190224024147661231
2019-02-24 02:41:47,697 [salt.minion      :1432][INFO    ][12894] Starting a new job with PID 12894
2019-02-24 02:41:48,362 [salt.state       :915 ][INFO    ][12894] Loading fresh modules for state activity
2019-02-24 02:41:48,452 [salt.state       :1780][INFO    ][12894] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:41:48.452825
2019-02-24 02:41:48,453 [salt.state       :1813][INFO    ][12894] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-24 02:41:48,455 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12894] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-24 02:41:49,867 [salt.state       :300 ][INFO    ][12894] {'pid': 12901, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-24 02:41:49,867 [salt.state       :1951][INFO    ][12894] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:41:49.867442 duration_in_ms=1414.618
2019-02-24 02:41:49,868 [salt.state       :1780][INFO    ][12894] Running state [maas.deploy_machines] at time 02:41:49.868616
2019-02-24 02:41:49,868 [salt.state       :1813][INFO    ][12894] Executing state module.run for [maas.deploy_machines]
2019-02-24 02:41:49,869 [salt.utils.decorators:613 ][WARNING ][12894] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-24 02:41:50,430 [salt.state       :300 ][INFO    ][12894] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-02-24 02:41:50,430 [salt.state       :1951][INFO    ][12894] Completed state [maas.deploy_machines] at time 02:41:50.430598 duration_in_ms=561.979
2019-02-24 02:41:50,434 [salt.minion      :1711][INFO    ][12894] Returning information for job: 20190224024147661231
2019-02-24 02:41:51,078 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command state.apply with jid 20190224024151064312
2019-02-24 02:41:51,101 [salt.minion      :1432][INFO    ][12910] Starting a new job with PID 12910
2019-02-24 02:41:51,724 [salt.state       :915 ][INFO    ][12910] Loading fresh modules for state activity
2019-02-24 02:41:51,811 [salt.state       :1780][INFO    ][12910] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:41:51.811876
2019-02-24 02:41:51,812 [salt.state       :1813][INFO    ][12910] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-24 02:41:51,814 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12910] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-24 02:41:53,356 [salt.state       :300 ][INFO    ][12910] {'pid': 12917, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-24 02:41:53,357 [salt.state       :1951][INFO    ][12910] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:41:53.357141 duration_in_ms=1545.266
2019-02-24 02:41:53,359 [salt.state       :1780][INFO    ][12910] Running state [maas.wait_for_machine_status] at time 02:41:53.359732
2019-02-24 02:41:53,360 [salt.state       :1813][INFO    ][12910] Executing state module.run for [maas.wait_for_machine_status]
2019-02-24 02:41:53,361 [salt.utils.decorators:613 ][WARNING ][12910] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-24 02:41:56,902 [salt.state       :300 ][INFO    ][12910] {'ret': True}
2019-02-24 02:41:56,902 [salt.state       :1951][INFO    ][12910] Completed state [maas.wait_for_machine_status] at time 02:41:56.902714 duration_in_ms=3542.98
2019-02-24 02:41:56,906 [salt.minion      :1711][INFO    ][12910] Returning information for job: 20190224024151064312
2019-02-24 03:09:01,372 [salt.utils.schedule:1377][INFO    ][3287] Running scheduled job: __mine_interval
2019-02-24 04:09:01,372 [salt.utils.schedule:1377][INFO    ][3287] Running scheduled job: __mine_interval
2019-02-24 04:25:57,031 [salt.minion      :1308][INFO    ][3287] User sudo_ubuntu Executing command cp.push_dir with jid 20190224042557020015
2019-02-24 04:25:57,054 [salt.minion      :1432][INFO    ][20273] Starting a new job with PID 20273
