2019-11-13 05:08:36,040 [salt.utils.decorators:613 ][WARNING ][2047] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-13 05:08:36,525 [salt.utils.decorators:613 ][WARNING ][2047] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-13 05:08:38,587 [salt.loaded.int.states.file:2298][WARNING ][2439] 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-11-13 05:09:00,781 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3090] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-11-13 05:09:00,808 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3090] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-11-13 05:09:00,825 [salt.utils.parsers:1051][WARNING ][319] Minion received a SIGTERM. Exiting.
2019-11-13 05:09:01,805 [salt.cli.daemons :293 ][INFO    ][3200] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-11-13 05:09:01,892 [salt.cli.daemons :82  ][INFO    ][3200] Starting up the Salt Minion
2019-11-13 05:09:01,892 [salt.utils.event :1017][INFO    ][3200] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-11-13 05:09:02,738 [salt.minion      :976 ][INFO    ][3200] Creating minion process manager
2019-11-13 05:09:04,010 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][3200] Executing command ['date', '+%z'] in directory '/root'
2019-11-13 05:09:04,361 [salt.utils.schedule:568 ][INFO    ][3200] Updating job settings for scheduled job: __mine_interval
2019-11-13 05:09:04,364 [salt.minion      :1108][INFO    ][3200] Added mine.update to scheduler
2019-11-13 05:09:04,373 [salt.minion      :1975][INFO    ][3200] Minion is starting as user 'root'
2019-11-13 05:09:04,390 [salt.minion      :2336][INFO    ][3200] Minion is ready to receive requests!
2019-11-13 05:09:06,828 [salt.state       :2022][WARNING ][3089] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-11-13 05:09:09,417 [salt.utils.decorators:613 ][WARNING ][3089] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-13 05:09:14,492 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113050914477141
2019-11-13 05:09:14,514 [salt.minion      :1432][INFO    ][3645] Starting a new job with PID 3645
2019-11-13 05:09:14,539 [salt.minion      :1711][INFO    ][3645] Returning information for job: 20191113050914477141
2019-11-13 05:09:44,549 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113050944536462
2019-11-13 05:09:44,570 [salt.minion      :1432][INFO    ][3944] Starting a new job with PID 3944
2019-11-13 05:09:44,592 [salt.minion      :1711][INFO    ][3944] Returning information for job: 20191113050944536462
2019-11-13 05:09:59,789 [salt.utils.decorators:613 ][WARNING ][3089] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-13 05:10:14,601 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113051014589499
2019-11-13 05:10:14,621 [salt.minion      :1432][INFO    ][4052] Starting a new job with PID 4052
2019-11-13 05:10:14,646 [salt.minion      :1711][INFO    ][4052] Returning information for job: 20191113051014589499
2019-11-13 05:10:44,663 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113051044647977
2019-11-13 05:10:44,685 [salt.minion      :1432][INFO    ][4149] Starting a new job with PID 4149
2019-11-13 05:10:44,707 [salt.minion      :1711][INFO    ][4149] Returning information for job: 20191113051044647977
2019-11-13 05:11:12,449 [salt.utils.decorators:613 ][WARNING ][3089] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-13 05:11:14,740 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113051114720581
2019-11-13 05:11:14,761 [salt.minion      :1432][INFO    ][4238] Starting a new job with PID 4238
2019-11-13 05:11:14,785 [salt.minion      :1711][INFO    ][4238] Returning information for job: 20191113051114720581
2019-11-13 05:11:29,237 [salt.utils.decorators:613 ][WARNING ][3089] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-13 05:11:32,523 [salt.loaded.ext.module.maasng:1008][WARNING ][3089] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-11-13 05:11:32,524 [salt.loaded.ext.module.maasng:1011][WARNING ][3089] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-11-13 05:11:33,372 [salt.loaded.ext.module.maasng:1235][WARNING ][3089] Ignoring parameter vlan:0
2019-11-13 05:11:34,454 [salt.utils.decorators:613 ][WARNING ][3089] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-13 05:11:35,242 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command state.apply with jid 20191113051135233629
2019-11-13 05:11:35,263 [salt.minion      :1432][INFO    ][4453] Starting a new job with PID 4453
2019-11-13 05:11:39,189 [salt.state       :915 ][INFO    ][4453] Loading fresh modules for state activity
2019-11-13 05:11:39,245 [salt.fileclient  :1219][INFO    ][4453] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-11-13 05:11:39,287 [salt.state       :1780][INFO    ][4453] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:11:39.287869
2019-11-13 05:11:39,288 [salt.state       :1813][INFO    ][4453] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-13 05:11:39,290 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4453] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-13 05:11:40,853 [salt.state       :300 ][INFO    ][4453] {'pid': 4473, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-13 05:11:40,854 [salt.state       :1951][INFO    ][4453] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:11:40.854107 duration_in_ms=1566.238
2019-11-13 05:11:40,856 [salt.state       :1780][INFO    ][4453] Running state [maas.process_machines] at time 05:11:40.856807
2019-11-13 05:11:40,857 [salt.state       :1813][INFO    ][4453] Executing state module.run for [maas.process_machines]
2019-11-13 05:11:40,858 [salt.utils.decorators:613 ][WARNING ][4453] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-13 05:11:40,927 [salt.loaded.ext.module.maas:412 ][WARNING ][4453] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-13 05:11:40,927 [salt.loaded.ext.module.maas:92  ][INFO    ][4453] 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-11-13 05:11:43,729 [salt.loaded.ext.module.maas:412 ][WARNING ][4453] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-13 05:11:43,729 [salt.loaded.ext.module.maas:92  ][INFO    ][4453] 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-11-13 05:11:45,171 [salt.loaded.ext.module.maas:412 ][WARNING ][4453] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-13 05:11:45,172 [salt.loaded.ext.module.maas:92  ][INFO    ][4453] 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-11-13 05:11:46,593 [salt.loaded.ext.module.maas:412 ][WARNING ][4453] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-13 05:11:46,594 [salt.loaded.ext.module.maas:92  ][INFO    ][4453] 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-11-13 05:11:48,007 [salt.loaded.ext.module.maas:412 ][WARNING ][4453] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-13 05:11:48,008 [salt.loaded.ext.module.maas:92  ][INFO    ][4453] 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-11-13 05:11:49,403 [salt.state       :300 ][INFO    ][4453] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-11-13 05:11:49,403 [salt.state       :1951][INFO    ][4453] Completed state [maas.process_machines] at time 05:11:49.403847 duration_in_ms=8547.038
2019-11-13 05:11:49,424 [salt.minion      :1711][INFO    ][4453] Returning information for job: 20191113051135233629
2019-11-13 05:12:20,492 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command state.apply with jid 20191113051220478777
2019-11-13 05:12:20,514 [salt.minion      :1432][INFO    ][4791] Starting a new job with PID 4791
2019-11-13 05:12:24,252 [salt.state       :915 ][INFO    ][4791] Loading fresh modules for state activity
2019-11-13 05:12:24,309 [salt.fileclient  :1219][INFO    ][4791] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-11-13 05:12:24,354 [salt.state       :1780][INFO    ][4791] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:12:24.354396
2019-11-13 05:12:24,354 [salt.state       :1813][INFO    ][4791] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-13 05:12:24,356 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4791] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-13 05:12:25,764 [salt.state       :300 ][INFO    ][4791] {'pid': 4798, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-13 05:12:25,765 [salt.state       :1951][INFO    ][4791] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:12:25.765157 duration_in_ms=1410.76
2019-11-13 05:12:25,766 [salt.state       :1780][INFO    ][4791] Running state [maas.wait_for_machine_status] at time 05:12:25.766282
2019-11-13 05:12:25,766 [salt.state       :1813][INFO    ][4791] Executing state module.run for [maas.wait_for_machine_status]
2019-11-13 05:12:25,766 [salt.utils.decorators:613 ][WARNING ][4791] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-13 05:12:27,021 [salt.loaded.ext.module.maas:1023][INFO    ][4791] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1498.74984622s left)
2019-11-13 05:12:35,536 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113051235524021
2019-11-13 05:12:35,557 [salt.minion      :1432][INFO    ][4827] Starting a new job with PID 4827
2019-11-13 05:12:35,580 [salt.minion      :1711][INFO    ][4827] Returning information for job: 20191113051235524021
2019-11-13 05:12:57,979 [salt.loaded.ext.module.maas:1023][INFO    ][4791] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1467.79229403s left)
2019-11-13 05:13:05,576 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113051305563665
2019-11-13 05:13:05,598 [salt.minion      :1432][INFO    ][4857] Starting a new job with PID 4857
2019-11-13 05:13:05,622 [salt.minion      :1711][INFO    ][4857] Returning information for job: 20191113051305563665
2019-11-13 05:13:29,229 [salt.loaded.ext.module.maas:1023][INFO    ][4791] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1436.54134607s left)
2019-11-13 05:13:35,676 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113051335665431
2019-11-13 05:13:35,694 [salt.minion      :1432][INFO    ][5012] Starting a new job with PID 5012
2019-11-13 05:13:35,716 [salt.minion      :1711][INFO    ][5012] Returning information for job: 20191113051335665431
2019-11-13 05:14:00,684 [salt.loaded.ext.module.maas:1023][INFO    ][4791] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1405.08642507s left)
2019-11-13 05:14:05,721 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113051405709192
2019-11-13 05:14:05,742 [salt.minion      :1432][INFO    ][5181] Starting a new job with PID 5181
2019-11-13 05:14:05,765 [salt.minion      :1711][INFO    ][5181] Returning information for job: 20191113051405709192
2019-11-13 05:14:32,488 [salt.loaded.ext.module.maas:1023][INFO    ][4791] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1373.28247619s left)
2019-11-13 05:14:35,786 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113051435774381
2019-11-13 05:14:35,807 [salt.minion      :1432][INFO    ][5797] Starting a new job with PID 5797
2019-11-13 05:14:35,835 [salt.minion      :1711][INFO    ][5797] Returning information for job: 20191113051435774381
2019-11-13 05:15:04,336 [salt.loaded.ext.module.maas:1023][INFO    ][4791] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1341.4350121s left)
2019-11-13 05:15:05,847 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113051505833667
2019-11-13 05:15:05,869 [salt.minion      :1432][INFO    ][5937] Starting a new job with PID 5937
2019-11-13 05:15:05,892 [salt.minion      :1711][INFO    ][5937] Returning information for job: 20191113051505833667
2019-11-13 05:15:35,918 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113051535901215
2019-11-13 05:15:35,940 [salt.minion      :1432][INFO    ][6122] Starting a new job with PID 6122
2019-11-13 05:15:35,964 [salt.minion      :1711][INFO    ][6122] Returning information for job: 20191113051535901215
2019-11-13 05:15:37,616 [salt.loaded.ext.module.maas:1023][INFO    ][4791] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1308.15483618s left)
2019-11-13 05:16:05,991 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113051605975530
2019-11-13 05:16:06,014 [salt.minion      :1432][INFO    ][6178] Starting a new job with PID 6178
2019-11-13 05:16:06,039 [salt.minion      :1711][INFO    ][6178] Returning information for job: 20191113051605975530
2019-11-13 05:16:11,048 [salt.state       :300 ][INFO    ][4791] {'ret': True}
2019-11-13 05:16:11,049 [salt.state       :1951][INFO    ][4791] Completed state [maas.wait_for_machine_status] at time 05:16:11.048951 duration_in_ms=225282.667
2019-11-13 05:16:11,053 [salt.minion      :1711][INFO    ][4791] Returning information for job: 20191113051220478777
2019-11-13 05:16:11,726 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command state.apply with jid 20191113051611710450
2019-11-13 05:16:11,748 [salt.minion      :1432][INFO    ][6263] Starting a new job with PID 6263
2019-11-13 05:16:15,429 [salt.state       :915 ][INFO    ][6263] Loading fresh modules for state activity
2019-11-13 05:16:15,480 [salt.fileclient  :1219][INFO    ][6263] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-11-13 05:16:15,565 [salt.state       :1780][INFO    ][6263] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:16:15.565511
2019-11-13 05:16:15,565 [salt.state       :1813][INFO    ][6263] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-13 05:16:15,567 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6263] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-13 05:16:17,033 [salt.state       :300 ][INFO    ][6263] {'pid': 6314, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-13 05:16:17,033 [salt.state       :1951][INFO    ][6263] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:16:17.033549 duration_in_ms=1468.039
2019-11-13 05:16:17,035 [salt.state       :1780][INFO    ][6263] Running state [maas_machines_storage_cmp002_lvm] at time 05:16:17.035051
2019-11-13 05:16:17,035 [salt.state       :1813][INFO    ][6263] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-11-13 05:16:17,937 [salt.loaded.ext.module.maasng:610 ][INFO    ][6263] 7nqcyc
2019-11-13 05:16:17,938 [salt.loaded.ext.module.maasng:626 ][INFO    ][6263] sda
2019-11-13 05:16:18,632 [salt.loaded.ext.module.maasng:361 ][INFO    ][6263] 7nqcyc
2019-11-13 05:16:18,764 [salt.loaded.ext.module.maasng:367 ][INFO    ][6263] [{u'size': 2397998940160, u'resource_uri': u'/MAAS/api/2.0/nodes/7nqcyc/blockdevices/4/', u'available_size': 0, u'uuid': None, u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'1ab17a7c-0a89-441c-8f5b-19734d611fec', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'7nqcyc', u'device_id': 4, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'492823fc-04ee-4b5d-9412-bc958284b2ff', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/7nqcyc/blockdevices/4/partition/6'}], u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'7nqcyc', 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'block_size': 4096, u'used_size': 2397998940160, u'id': 4, u'serial': u'618e728372755980239b15112698bc66', u'name': u'sda'}, {u'size': 2397988454400, u'resource_uri': u'/MAAS/api/2.0/nodes/7nqcyc/blockdevices/11/', u'available_size': 0, u'uuid': u'50aab442-c20f-471c-ab5f-4a0ecd1b49b5', u'tags': [], u'type': u'virtual', u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'7nqcyc', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'63c480f7-a6fd-4e02-8c7d-7519d776a5ad', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'used_size': 2397988454400, u'id': 11, u'serial': None, u'name': u'vgroot-lvroot'}]
2019-11-13 05:16:18,765 [salt.loaded.ext.module.maasng:632 ][INFO    ][6263] vgroot
2019-11-13 05:16:18,765 [salt.loaded.ext.module.maasng:635 ][INFO    ][6263] lvroot
2019-11-13 05:16:18,766 [salt.loaded.ext.module.maasng:639 ][INFO    ][6263] 107374182400
2019-11-13 05:16:19,519 [salt.loaded.ext.module.maasng:645 ][INFO    ][6263] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'swap_size': None, u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.42'], u'storage_test_status_name': u'Passed', u'owner_data': {}, u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'boot_disk': {u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'99ccea38-3af3-4fa6-bc1d-676d71d5df4e', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'7nqcyc', u'device_id': 4, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'38d584d6-971d-4eaa-b3fa-2d69c194983b', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/7nqcyc/blockdevices/4/partition/8', u'type': u'partition', u'id': 8, u'size': 2397992648704}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'7nqcyc', 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'resource_uri': u'/MAAS/api/2.0/nodes/7nqcyc/blockdevices/4/', u'type': u'physical', u'id': 4, 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'used_size': 2397998940160, u'partitions': [{u'uuid': u'99ccea38-3af3-4fa6-bc1d-676d71d5df4e', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'7nqcyc', u'device_id': 4, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'38d584d6-971d-4eaa-b3fa-2d69c194983b', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/7nqcyc/blockdevices/4/partition/8', u'type': u'partition', u'id': 8, u'size': 2397992648704}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'7nqcyc', 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'resource_uri': u'/MAAS/api/2.0/nodes/7nqcyc/blockdevices/4/', u'type': u'physical', u'id': 4, u'serial': u'618e728372755980239b15112698bc66', u'size': 2397998940160}, {u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'uuid': u'94d9a67e-4411-47ac-9130-8ed3dab0f9f1', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'7nqcyc', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'14c303d5-8c1f-4d05-8901-d79bb172c0da', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/7nqcyc/blockdevices/13/', u'type': u'virtual', u'id': 13, u'serial': None, u'size': 107374182400}], u'zone': {u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default', u'id': 1}, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'node_type': 0, u'testing_status': 2, u'system_id': u'7nqcyc', u'power_state': u'off', u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'uuid': u'94d9a67e-4411-47ac-9130-8ed3dab0f9f1', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'7nqcyc', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'14c303d5-8c1f-4d05-8901-d79bb172c0da', 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/7nqcyc/blockdevices/13/', u'type': u'virtual', u'id': 13, u'serial': None, u'size': 107374182400}], u'min_hwe_kernel': u'ga-18.04', u'status': 4, u'storage_test_status': 2, u'cpu_count': 16, u'raids': [], u'power_type': u'ipmi', u'physicalblockdevice_set': [{u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'99ccea38-3af3-4fa6-bc1d-676d71d5df4e', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'7nqcyc', u'device_id': 4, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'38d584d6-971d-4eaa-b3fa-2d69c194983b', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/7nqcyc/blockdevices/4/partition/8', u'type': u'partition', u'id': 8, u'size': 2397992648704}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'7nqcyc', 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'resource_uri': u'/MAAS/api/2.0/nodes/7nqcyc/blockdevices/4/', u'type': u'physical', u'id': 4, u'serial': u'618e728372755980239b15112698bc66', u'size': 2397998940160}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'7nqcyc', u'id': 8}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ebne7x', 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'id': 38, u'mode': u'dhcp'}], u'tags': [], u'enabled': True, 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'ebne7x', 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'effective_mtu': 1500, 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'ebne7x', 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'params': u'', u'mac_address': u'00:25:b5:a0:00:6a', u'system_id': u'7nqcyc', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/7nqcyc/interfaces/4/'}, u'current_testing_result_id': 3, u'cpu_test_status': -1, u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'disable_ipv4': False, u'commissioning_status': 2, u'architecture': u'amd64/generic', u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ebne7x', 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'id': 38, u'mode': u'dhcp'}], u'tags': [], u'enabled': True, 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'ebne7x', 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'effective_mtu': 1500, 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'ebne7x', 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'params': u'', u'mac_address': u'00:25:b5:a0:00:6a', u'system_id': u'7nqcyc', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/7nqcyc/interfaces/4/'}, {u'name': u'enp7s0', u'links': [{u'id': 40, u'mode': u'link_up'}], u'tags': [], u'enabled': True, u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'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'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:6b', u'system_id': u'7nqcyc', u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/7nqcyc/interfaces/19/'}, {u'name': u'enp9s0', u'links': [{u'id': 41, u'mode': u'link_up'}], u'tags': [], u'enabled': True, u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'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'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:6d', u'system_id': u'7nqcyc', u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/7nqcyc/interfaces/20/'}, {u'name': u'enp8s0', u'links': [{u'id': 39, u'mode': u'link_up'}], u'tags': [], u'enabled': True, u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'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'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:6c', u'system_id': u'7nqcyc', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/7nqcyc/interfaces/18/'}], u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/7nqcyc/', u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2019-11-13 05:16:19,521 [salt.state       :300 ][INFO    ][6263] {'new': {'storage_layout': 'lvm'}}
2019-11-13 05:16:19,522 [salt.state       :1951][INFO    ][6263] Completed state [maas_machines_storage_cmp002_lvm] at time 05:16:19.521998 duration_in_ms=2486.945
2019-11-13 05:16:19,522 [salt.state       :1780][INFO    ][6263] Running state [maas_machines_storage_cmp001_lvm] at time 05:16:19.522573
2019-11-13 05:16:19,523 [salt.state       :1813][INFO    ][6263] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-11-13 05:16:20,960 [salt.loaded.ext.module.maasng:610 ][INFO    ][6263] k48fda
2019-11-13 05:16:20,961 [salt.loaded.ext.module.maasng:626 ][INFO    ][6263] sda
2019-11-13 05:16:21,632 [salt.loaded.ext.module.maasng:361 ][INFO    ][6263] k48fda
2019-11-13 05:16:21,764 [salt.loaded.ext.module.maasng:367 ][INFO    ][6263] [{u'partition_table_type': u'GPT', u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'id': 3, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'k48fda', u'resource_uri': u'/MAAS/api/2.0/nodes/k48fda/blockdevices/3/', 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'size': 2397998940160, u'type': u'physical', u'serial': u'618e72837274f1901cc7889705aa1b02', u'partitions': [{u'uuid': u'6316356b-b6ca-4f0d-908e-fd1f80559372', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'k48fda', u'device_id': 3, u'filesystem': {u'mount_options': None, u'uuid': u'89f09858-5b46-41ee-a9f5-34bbe2015363', u'fstype': u'lvm-pv', u'mount_point': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/k48fda/blockdevices/3/partition/5', u'type': u'partition', u'id': 5, u'size': 2397992648704}]}, {u'partition_table_type': None, u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 2397988454400, u'id': 10, u'uuid': u'fba088b5-56cd-4847-906f-0ffb0ccbec9f', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'k48fda', u'resource_uri': u'/MAAS/api/2.0/nodes/k48fda/blockdevices/10/', u'filesystem': {u'mount_options': None, u'uuid': u'ea8281e7-f663-472e-8869-01bf1b86ec0e', u'fstype': u'ext4', u'mount_point': u'/', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'size': 2397988454400, u'type': u'virtual', u'serial': None, u'partitions': []}]
2019-11-13 05:16:21,765 [salt.loaded.ext.module.maasng:632 ][INFO    ][6263] vgroot
2019-11-13 05:16:21,765 [salt.loaded.ext.module.maasng:635 ][INFO    ][6263] lvroot
2019-11-13 05:16:21,766 [salt.loaded.ext.module.maasng:639 ][INFO    ][6263] 107374182400
2019-11-13 05:16:22,515 [salt.loaded.ext.module.maasng:645 ][INFO    ][6263] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'swap_size': None, u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.38'], u'storage_test_status_name': u'Passed', u'owner_data': {}, u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'boot_disk': {u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'e0d86bd4-c9ad-49c3-9745-9e5488c739bf', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'k48fda', u'device_id': 3, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'd1679f1e-e2ad-4fb0-afbd-dcd62e3dfad5', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/k48fda/blockdevices/3/partition/9', u'type': u'partition', u'id': 9, u'size': 2397992648704}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'k48fda', 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/k48fda/blockdevices/3/', u'type': u'physical', u'id': 3, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}, u'blockdevice_set': [{u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'e0d86bd4-c9ad-49c3-9745-9e5488c739bf', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'k48fda', u'device_id': 3, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'd1679f1e-e2ad-4fb0-afbd-dcd62e3dfad5', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/k48fda/blockdevices/3/partition/9', u'type': u'partition', u'id': 9, u'size': 2397992648704}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'k48fda', 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/k48fda/blockdevices/3/', u'type': u'physical', u'id': 3, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}, {u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'uuid': u'224b8bd9-6846-4442-862c-5ac7d63dfd19', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'k48fda', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'e68441e4-d68c-4976-9752-44bf06c4d971', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/k48fda/blockdevices/14/', u'type': u'virtual', u'id': 14, u'serial': None, u'size': 107374182400}], u'zone': {u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default', u'id': 1}, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'node_type': 0, u'testing_status': 2, u'system_id': u'k48fda', u'power_state': u'off', u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'uuid': u'224b8bd9-6846-4442-862c-5ac7d63dfd19', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'k48fda', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'e68441e4-d68c-4976-9752-44bf06c4d971', 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/k48fda/blockdevices/14/', u'type': u'virtual', u'id': 14, u'serial': None, u'size': 107374182400}], u'min_hwe_kernel': u'ga-18.04', u'status': 4, u'storage_test_status': 2, u'cpu_count': 16, u'raids': [], u'power_type': u'ipmi', u'physicalblockdevice_set': [{u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'e0d86bd4-c9ad-49c3-9745-9e5488c739bf', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'k48fda', u'device_id': 3, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'd1679f1e-e2ad-4fb0-afbd-dcd62e3dfad5', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/k48fda/blockdevices/3/partition/9', u'type': u'partition', u'id': 9, u'size': 2397992648704}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'k48fda', 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/k48fda/blockdevices/3/', u'type': u'physical', u'id': 3, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'k48fda', u'id': 9}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ebne7x', 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.38', u'id': 33, u'mode': u'dhcp'}], u'tags': [], u'enabled': True, 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'ebne7x', 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'effective_mtu': 1500, 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'ebne7x', 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.38'}], u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:5a', u'system_id': u'k48fda', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/k48fda/interfaces/5/'}, u'current_testing_result_id': 5, u'cpu_test_status': -1, u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'disable_ipv4': False, u'commissioning_status': 2, u'architecture': u'amd64/generic', u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ebne7x', 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.38', u'id': 33, u'mode': u'dhcp'}], u'tags': [], u'enabled': True, 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'ebne7x', 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'effective_mtu': 1500, 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'ebne7x', 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.38'}], u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:5a', u'system_id': u'k48fda', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/k48fda/interfaces/5/'}, {u'name': u'enp9s0', u'links': [{u'id': 34, u'mode': u'link_up'}], u'tags': [], u'enabled': True, u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'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'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:5d', u'system_id': u'k48fda', u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/k48fda/interfaces/15/'}, {u'name': u'enp7s0', u'links': [{u'id': 35, u'mode': u'link_up'}], u'tags': [], u'enabled': True, u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'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'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:5b', u'system_id': u'k48fda', u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/k48fda/interfaces/16/'}, {u'name': u'enp8s0', u'links': [{u'id': 36, u'mode': u'link_up'}], u'tags': [], u'enabled': True, u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'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'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:5c', u'system_id': u'k48fda', u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/k48fda/interfaces/17/'}], u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/k48fda/', u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2019-11-13 05:16:22,517 [salt.state       :300 ][INFO    ][6263] {'new': {'storage_layout': 'lvm'}}
2019-11-13 05:16:22,517 [salt.state       :1951][INFO    ][6263] Completed state [maas_machines_storage_cmp001_lvm] at time 05:16:22.517464 duration_in_ms=2994.891
2019-11-13 05:16:22,521 [salt.minion      :1711][INFO    ][6263] Returning information for job: 20191113051611710450
2019-11-13 05:16:23,136 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command state.apply with jid 20191113051623125862
2019-11-13 05:16:23,158 [salt.minion      :1432][INFO    ][6332] Starting a new job with PID 6332
2019-11-13 05:16:23,923 [salt.state       :915 ][INFO    ][6332] Loading fresh modules for state activity
2019-11-13 05:16:23,977 [salt.fileclient  :1219][INFO    ][6332] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-11-13 05:16:24,021 [salt.state       :1780][INFO    ][6332] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:16:24.021842
2019-11-13 05:16:24,022 [salt.state       :1813][INFO    ][6332] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-13 05:16:24,024 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6332] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-13 05:16:25,462 [salt.state       :300 ][INFO    ][6332] {'pid': 6339, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-13 05:16:25,462 [salt.state       :1951][INFO    ][6332] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:16:25.462425 duration_in_ms=1440.585
2019-11-13 05:16:25,463 [salt.state       :1780][INFO    ][6332] Running state [maas.deploy_machines] at time 05:16:25.463640
2019-11-13 05:16:25,463 [salt.state       :1813][INFO    ][6332] Executing state module.run for [maas.deploy_machines]
2019-11-13 05:16:25,464 [salt.utils.decorators:613 ][WARNING ][6332] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-13 05:16:26,168 [salt.loaded.ext.module.maas:684 ][INFO    ][6332] deploymachines hwe_kernel=ga-18.04 system_id=7nqcyc distro_series=bionic
2019-11-13 05:16:28,851 [salt.loaded.ext.module.maas:684 ][INFO    ][6332] deploymachines hwe_kernel=ga-18.04 system_id=k48fda distro_series=bionic
2019-11-13 05:16:31,186 [salt.loaded.ext.module.maas:684 ][INFO    ][6332] deploymachines hwe_kernel=ga-18.04 system_id=nda63r distro_series=bionic
2019-11-13 05:16:33,559 [salt.loaded.ext.module.maas:684 ][INFO    ][6332] deploymachines hwe_kernel=ga-18.04 system_id=47dh6q distro_series=bionic
2019-11-13 05:16:35,937 [salt.loaded.ext.module.maas:684 ][INFO    ][6332] deploymachines hwe_kernel=ga-18.04 system_id=agdtq7 distro_series=bionic
2019-11-13 05:16:38,195 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113051638182610
2019-11-13 05:16:38,218 [salt.minion      :1432][INFO    ][6623] Starting a new job with PID 6623
2019-11-13 05:16:38,242 [salt.minion      :1711][INFO    ][6623] Returning information for job: 20191113051638182610
2019-11-13 05:16:38,494 [salt.state       :300 ][INFO    ][6332] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-11-13 05:16:38,495 [salt.state       :1951][INFO    ][6332] Completed state [maas.deploy_machines] at time 05:16:38.494948 duration_in_ms=13031.306
2019-11-13 05:16:38,498 [salt.minion      :1711][INFO    ][6332] Returning information for job: 20191113051623125862
2019-11-13 05:16:39,137 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command state.apply with jid 20191113051639125204
2019-11-13 05:16:39,157 [salt.minion      :1432][INFO    ][6637] Starting a new job with PID 6637
2019-11-13 05:16:42,848 [salt.state       :915 ][INFO    ][6637] Loading fresh modules for state activity
2019-11-13 05:16:42,889 [salt.fileclient  :1219][INFO    ][6637] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-11-13 05:16:42,925 [salt.state       :1780][INFO    ][6637] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:16:42.925180
2019-11-13 05:16:42,925 [salt.state       :1813][INFO    ][6637] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-13 05:16:42,927 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6637] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-13 05:16:44,273 [salt.state       :300 ][INFO    ][6637] {'pid': 6654, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-13 05:16:44,274 [salt.state       :1951][INFO    ][6637] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:16:44.274031 duration_in_ms=1348.85
2019-11-13 05:16:44,277 [salt.state       :1780][INFO    ][6637] Running state [maas.wait_for_machine_status] at time 05:16:44.277257
2019-11-13 05:16:44,277 [salt.state       :1813][INFO    ][6637] Executing state module.run for [maas.wait_for_machine_status]
2019-11-13 05:16:44,278 [salt.utils.decorators:613 ][WARNING ][6637] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-13 05:16:47,650 [salt.loaded.ext.module.maas:1023][INFO    ][6637] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.63808703s left)
2019-11-13 05:16:54,254 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113051654239320
2019-11-13 05:16:54,276 [salt.minion      :1432][INFO    ][6667] Starting a new job with PID 6667
2019-11-13 05:16:54,300 [salt.minion      :1711][INFO    ][6667] Returning information for job: 20191113051654239320
2019-11-13 05:17:21,298 [salt.loaded.ext.module.maas:1023][INFO    ][6637] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2212.98988605s left)
2019-11-13 05:17:24,307 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113051724294418
2019-11-13 05:17:24,330 [salt.minion      :1432][INFO    ][6707] Starting a new job with PID 6707
2019-11-13 05:17:24,353 [salt.minion      :1711][INFO    ][6707] Returning information for job: 20191113051724294418
2019-11-13 05:17:54,391 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113051754378508
2019-11-13 05:17:54,414 [salt.minion      :1432][INFO    ][6743] Starting a new job with PID 6743
2019-11-13 05:17:54,437 [salt.minion      :1711][INFO    ][6743] Returning information for job: 20191113051754378508
2019-11-13 05:17:54,717 [salt.loaded.ext.module.maas:1023][INFO    ][6637] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2179.57091403s left)
2019-11-13 05:18:24,424 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113051824413414
2019-11-13 05:18:24,437 [salt.minion      :1432][INFO    ][6871] Starting a new job with PID 6871
2019-11-13 05:18:24,457 [salt.minion      :1711][INFO    ][6871] Returning information for job: 20191113051824413414
2019-11-13 05:18:27,974 [salt.loaded.ext.module.maas:1023][INFO    ][6637] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2146.31385803s left)
2019-11-13 05:18:54,466 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113051854453433
2019-11-13 05:18:54,488 [salt.minion      :1432][INFO    ][7109] Starting a new job with PID 7109
2019-11-13 05:18:54,513 [salt.minion      :1711][INFO    ][7109] Returning information for job: 20191113051854453433
2019-11-13 05:19:01,878 [salt.loaded.ext.module.maas:1023][INFO    ][6637] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2112.41068101s left)
2019-11-13 05:19:24,528 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113051924513240
2019-11-13 05:19:24,550 [salt.minion      :1432][INFO    ][7673] Starting a new job with PID 7673
2019-11-13 05:19:24,575 [salt.minion      :1711][INFO    ][7673] Returning information for job: 20191113051924513240
2019-11-13 05:19:35,149 [salt.loaded.ext.module.maas:1023][INFO    ][6637] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2079.139153s left)
2019-11-13 05:19:54,582 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113051954569891
2019-11-13 05:19:54,605 [salt.minion      :1432][INFO    ][7904] Starting a new job with PID 7904
2019-11-13 05:19:54,629 [salt.minion      :1711][INFO    ][7904] Returning information for job: 20191113051954569891
2019-11-13 05:20:08,763 [salt.loaded.ext.module.maas:1023][INFO    ][6637] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2045.52523208s left)
2019-11-13 05:20:24,644 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113052024631964
2019-11-13 05:20:24,667 [salt.minion      :1432][INFO    ][8055] Starting a new job with PID 8055
2019-11-13 05:20:24,691 [salt.minion      :1711][INFO    ][8055] Returning information for job: 20191113052024631964
2019-11-13 05:20:42,410 [salt.loaded.ext.module.maas:1023][INFO    ][6637] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2011.87825513s left)
2019-11-13 05:20:54,717 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113052054700436
2019-11-13 05:20:54,739 [salt.minion      :1432][INFO    ][8127] Starting a new job with PID 8127
2019-11-13 05:20:54,763 [salt.minion      :1711][INFO    ][8127] Returning information for job: 20191113052054700436
2019-11-13 05:21:16,141 [salt.loaded.ext.module.maas:1023][INFO    ][6637] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1978.14711499s left)
2019-11-13 05:21:24,794 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113052124779371
2019-11-13 05:21:24,817 [salt.minion      :1432][INFO    ][8332] Starting a new job with PID 8332
2019-11-13 05:21:24,842 [salt.minion      :1711][INFO    ][8332] Returning information for job: 20191113052124779371
2019-11-13 05:21:49,593 [salt.loaded.ext.module.maas:1023][INFO    ][6637] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1944.69542813s left)
2019-11-13 05:21:54,869 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113052154856252
2019-11-13 05:21:54,888 [salt.minion      :1432][INFO    ][8486] Starting a new job with PID 8486
2019-11-13 05:21:54,910 [salt.minion      :1711][INFO    ][8486] Returning information for job: 20191113052154856252
2019-11-13 05:22:23,082 [salt.loaded.ext.module.maas:1023][INFO    ][6637] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1911.20638704s left)
2019-11-13 05:22:24,942 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113052224928835
2019-11-13 05:22:24,965 [salt.minion      :1432][INFO    ][8881] Starting a new job with PID 8881
2019-11-13 05:22:24,990 [salt.minion      :1711][INFO    ][8881] Returning information for job: 20191113052224928835
2019-11-13 05:22:55,025 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113052255013160
2019-11-13 05:22:55,047 [salt.minion      :1432][INFO    ][8985] Starting a new job with PID 8985
2019-11-13 05:22:55,069 [salt.minion      :1711][INFO    ][8985] Returning information for job: 20191113052255013160
2019-11-13 05:22:56,726 [salt.loaded.ext.module.maas:1023][INFO    ][6637] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1877.56206107s left)
2019-11-13 05:23:25,107 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113052325094437
2019-11-13 05:23:25,130 [salt.minion      :1432][INFO    ][9023] Starting a new job with PID 9023
2019-11-13 05:23:25,154 [salt.minion      :1711][INFO    ][9023] Returning information for job: 20191113052325094437
2019-11-13 05:23:30,025 [salt.loaded.ext.module.maas:1023][INFO    ][6637] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1844.26307201s left)
2019-11-13 05:23:55,242 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113052355228356
2019-11-13 05:23:55,264 [salt.minion      :1432][INFO    ][9221] Starting a new job with PID 9221
2019-11-13 05:23:55,287 [salt.minion      :1711][INFO    ][9221] Returning information for job: 20191113052355228356
2019-11-13 05:24:03,167 [salt.loaded.ext.module.maas:1023][INFO    ][6637] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1811.12122393s left)
2019-11-13 05:24:25,397 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113052425384790
2019-11-13 05:24:25,420 [salt.minion      :1432][INFO    ][9450] Starting a new job with PID 9450
2019-11-13 05:24:25,443 [salt.minion      :1711][INFO    ][9450] Returning information for job: 20191113052425384790
2019-11-13 05:24:36,678 [salt.loaded.ext.module.maas:1023][INFO    ][6637] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1777.61060095s left)
2019-11-13 05:24:55,500 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113052455488000
2019-11-13 05:24:55,522 [salt.minion      :1432][INFO    ][9566] Starting a new job with PID 9566
2019-11-13 05:24:55,547 [salt.minion      :1711][INFO    ][9566] Returning information for job: 20191113052455488000
2019-11-13 05:25:09,880 [salt.loaded.ext.module.maas:1023][INFO    ][6637] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1744.40791011s left)
2019-11-13 05:25:25,611 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113052525598214
2019-11-13 05:25:25,634 [salt.minion      :1432][INFO    ][9805] Starting a new job with PID 9805
2019-11-13 05:25:25,657 [salt.minion      :1711][INFO    ][9805] Returning information for job: 20191113052525598214
2019-11-13 05:25:43,505 [salt.loaded.ext.module.maas:1023][INFO    ][6637] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1710.78296995s left)
2019-11-13 05:25:55,728 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113052555715808
2019-11-13 05:25:55,750 [salt.minion      :1432][INFO    ][9837] Starting a new job with PID 9837
2019-11-13 05:25:55,773 [salt.minion      :1711][INFO    ][9837] Returning information for job: 20191113052555715808
2019-11-13 05:26:17,098 [salt.loaded.ext.module.maas:1023][INFO    ][6637] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1677.19050813s left)
2019-11-13 05:26:25,847 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113052625834774
2019-11-13 05:26:25,870 [salt.minion      :1432][INFO    ][9878] Starting a new job with PID 9878
2019-11-13 05:26:25,894 [salt.minion      :1711][INFO    ][9878] Returning information for job: 20191113052625834774
2019-11-13 05:26:50,643 [salt.loaded.ext.module.maas:1023][INFO    ][6637] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1643.64535308s left)
2019-11-13 05:26:55,981 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113052655968532
2019-11-13 05:26:56,005 [salt.minion      :1432][INFO    ][9910] Starting a new job with PID 9910
2019-11-13 05:26:56,030 [salt.minion      :1711][INFO    ][9910] Returning information for job: 20191113052655968532
2019-11-13 05:27:24,120 [salt.loaded.ext.module.maas:1023][INFO    ][6637] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1610.16807604s left)
2019-11-13 05:27:26,127 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113052726112912
2019-11-13 05:27:26,150 [salt.minion      :1432][INFO    ][9949] Starting a new job with PID 9949
2019-11-13 05:27:26,173 [salt.minion      :1711][INFO    ][9949] Returning information for job: 20191113052726112912
2019-11-13 05:27:56,284 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113052756269233
2019-11-13 05:27:56,303 [salt.minion      :1432][INFO    ][9983] Starting a new job with PID 9983
2019-11-13 05:27:56,332 [salt.minion      :1711][INFO    ][9983] Returning information for job: 20191113052756269233
2019-11-13 05:27:57,696 [salt.loaded.ext.module.maas:1023][INFO    ][6637] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1576.59182501s left)
2019-11-13 05:28:26,446 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113052826430640
2019-11-13 05:28:26,469 [salt.minion      :1432][INFO    ][10028] Starting a new job with PID 10028
2019-11-13 05:28:26,493 [salt.minion      :1711][INFO    ][10028] Returning information for job: 20191113052826430640
2019-11-13 05:28:30,997 [salt.loaded.ext.module.maas:1023][INFO    ][6637] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1543.29193401s left)
2019-11-13 05:28:56,619 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113052856606548
2019-11-13 05:28:56,642 [salt.minion      :1432][INFO    ][10065] Starting a new job with PID 10065
2019-11-13 05:28:56,664 [salt.minion      :1711][INFO    ][10065] Returning information for job: 20191113052856606548
2019-11-13 05:29:04,039 [salt.loaded.ext.module.maas:1023][INFO    ][6637] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1510.24935412s left)
2019-11-13 05:29:26,793 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113052926781328
2019-11-13 05:29:26,817 [salt.minion      :1432][INFO    ][10205] Starting a new job with PID 10205
2019-11-13 05:29:26,840 [salt.minion      :1711][INFO    ][10205] Returning information for job: 20191113052926781328
2019-11-13 05:29:37,542 [salt.loaded.ext.module.maas:1023][INFO    ][6637] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1476.74589205s left)
2019-11-13 05:29:56,985 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113052956972695
2019-11-13 05:29:57,008 [salt.minion      :1432][INFO    ][10245] Starting a new job with PID 10245
2019-11-13 05:29:57,032 [salt.minion      :1711][INFO    ][10245] Returning information for job: 20191113052956972695
2019-11-13 05:30:11,182 [salt.loaded.ext.module.maas:1023][INFO    ][6637] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1443.10592008s left)
2019-11-13 05:30:27,183 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113053027171068
2019-11-13 05:30:27,204 [salt.minion      :1432][INFO    ][10286] Starting a new job with PID 10286
2019-11-13 05:30:27,226 [salt.minion      :1711][INFO    ][10286] Returning information for job: 20191113053027171068
2019-11-13 05:30:44,105 [salt.loaded.ext.module.maas:1023][INFO    ][6637] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1410.18292713s left)
2019-11-13 05:30:57,391 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113053057377841
2019-11-13 05:30:57,414 [salt.minion      :1432][INFO    ][10323] Starting a new job with PID 10323
2019-11-13 05:30:57,437 [salt.minion      :1711][INFO    ][10323] Returning information for job: 20191113053057377841
2019-11-13 05:31:17,692 [salt.loaded.ext.module.maas:1023][INFO    ][6637] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1376.59580803s left)
2019-11-13 05:31:27,614 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113053127601857
2019-11-13 05:31:27,638 [salt.minion      :1432][INFO    ][10363] Starting a new job with PID 10363
2019-11-13 05:31:27,663 [salt.minion      :1711][INFO    ][10363] Returning information for job: 20191113053127601857
2019-11-13 05:31:51,250 [salt.loaded.ext.module.maas:1023][INFO    ][6637] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1343.03819108s left)
2019-11-13 05:31:57,641 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113053157627830
2019-11-13 05:31:57,665 [salt.minion      :1432][INFO    ][10399] Starting a new job with PID 10399
2019-11-13 05:31:57,688 [salt.minion      :1711][INFO    ][10399] Returning information for job: 20191113053157627830
2019-11-13 05:32:24,915 [salt.loaded.ext.module.maas:1023][INFO    ][6637] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1309.37322998s left)
2019-11-13 05:32:27,673 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113053227660991
2019-11-13 05:32:27,698 [salt.minion      :1432][INFO    ][10442] Starting a new job with PID 10442
2019-11-13 05:32:27,722 [salt.minion      :1711][INFO    ][10442] Returning information for job: 20191113053227660991
2019-11-13 05:32:57,723 [salt.loaded.ext.module.maas:993 ][INFO    ][6637] Machine 47dh6q mark broken
2019-11-13 05:32:57,739 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113053257726150
2019-11-13 05:32:57,762 [salt.minion      :1432][INFO    ][10486] Starting a new job with PID 10486
2019-11-13 05:32:57,784 [salt.minion      :1711][INFO    ][10486] Returning information for job: 20191113053257726150
2019-11-13 05:32:58,390 [salt.loaded.ext.module.maas:996 ][INFO    ][6637] Machine 47dh6q mark fixed
2019-11-13 05:32:59,444 [salt.loaded.ext.module.maas:684 ][INFO    ][6637] deploymachines hwe_kernel=ga-18.04 system_id=47dh6q distro_series=bionic
2019-11-13 05:33:02,014 [salt.loaded.ext.module.maas:160 ][ERROR   ][6637] Failed for object kvm03 reason Unable to change power state to 'cycle' for node kvm03: another action is already in progress for that node.
2019-11-13 05:33:02,016 [salt.state       :302 ][ERROR   ][6637] Module function maas.wait_for_machine_status threw an exception. Exception: {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm02'], 'errors': {'kvm03': "Unable to change power state to 'cycle' for node kvm03: another action is already in progress for that node."}, 'success': []}
2019-11-13 05:33:02,016 [salt.state       :1951][INFO    ][6637] Completed state [maas.wait_for_machine_status] at time 05:33:02.016582 duration_in_ms=977739.319
2019-11-13 05:33:02,025 [salt.minion      :1711][INFO    ][6637] Returning information for job: 20191113051639125204
2019-11-13 05:33:12,822 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command pillar.get with jid 20191113053312810331
2019-11-13 05:33:12,844 [salt.minion      :1432][INFO    ][10582] Starting a new job with PID 10582
2019-11-13 05:33:12,850 [salt.minion      :1711][INFO    ][10582] Returning information for job: 20191113053312810331
2019-11-13 05:33:13,352 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command service.status with jid 20191113053313340300
2019-11-13 05:33:13,372 [salt.minion      :1432][INFO    ][10587] Starting a new job with PID 10587
2019-11-13 05:33:13,694 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][10587] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-11-13 05:33:13,727 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][10587] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-11-13 05:33:13,742 [salt.minion      :1711][INFO    ][10587] Returning information for job: 20191113053313340300
2019-11-13 05:33:14,286 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command state.apply with jid 20191113053314274377
2019-11-13 05:33:14,304 [salt.minion      :1432][INFO    ][10598] Starting a new job with PID 10598
2019-11-13 05:33:17,984 [salt.state       :915 ][INFO    ][10598] Loading fresh modules for state activity
2019-11-13 05:33:18,403 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10598] Executing command 'salt-minion --version' in directory '/root'
2019-11-13 05:33:18,774 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10598] Executing command 'salt-minion --version' in directory '/root'
2019-11-13 05:33:19,677 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10598] Executing command 'salt-minion --version' in directory '/root'
2019-11-13 05:33:20,056 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10598] Executing command 'salt-minion --version' in directory '/root'
2019-11-13 05:33:21,347 [salt.state       :1780][INFO    ][10598] Running state [salt-minion] at time 05:33:21.347073
2019-11-13 05:33:21,347 [salt.state       :1813][INFO    ][10598] Executing state pkg.installed for [salt-minion]
2019-11-13 05:33:21,347 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10598] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-11-13 05:33:21,444 [salt.state       :300 ][INFO    ][10598] All specified packages are already installed
2019-11-13 05:33:21,445 [salt.state       :1951][INFO    ][10598] Completed state [salt-minion] at time 05:33:21.445238 duration_in_ms=98.164
2019-11-13 05:33:21,445 [salt.state       :1780][INFO    ][10598] Running state [salt_minion_dependency_packages] at time 05:33:21.445671
2019-11-13 05:33:21,446 [salt.state       :1813][INFO    ][10598] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-11-13 05:33:21,453 [salt.state       :300 ][INFO    ][10598] All specified packages are already installed
2019-11-13 05:33:21,454 [salt.state       :1951][INFO    ][10598] Completed state [salt_minion_dependency_packages] at time 05:33:21.454179 duration_in_ms=8.508
2019-11-13 05:33:21,458 [salt.state       :1780][INFO    ][10598] Running state [/etc/salt/minion.d/minion.conf] at time 05:33:21.458000
2019-11-13 05:33:21,458 [salt.state       :1813][INFO    ][10598] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-11-13 05:33:21,673 [salt.state       :300 ][INFO    ][10598] File /etc/salt/minion.d/minion.conf is in the correct state
2019-11-13 05:33:21,673 [salt.state       :1951][INFO    ][10598] Completed state [/etc/salt/minion.d/minion.conf] at time 05:33:21.673446 duration_in_ms=215.446
2019-11-13 05:33:21,673 [salt.state       :1780][INFO    ][10598] Running state [python-netaddr] at time 05:33:21.673653
2019-11-13 05:33:21,673 [salt.state       :1813][INFO    ][10598] Executing state pkg.installed for [python-netaddr]
2019-11-13 05:33:21,680 [salt.state       :300 ][INFO    ][10598] All specified packages are already installed
2019-11-13 05:33:21,680 [salt.state       :1951][INFO    ][10598] Completed state [python-netaddr] at time 05:33:21.680370 duration_in_ms=6.716
2019-11-13 05:33:21,683 [salt.state       :1780][INFO    ][10598] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 05:33:21.683276
2019-11-13 05:33:21,683 [salt.state       :1813][INFO    ][10598] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-11-13 05:33:21,694 [salt.state       :300 ][INFO    ][10598] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-11-13 05:33:21,694 [salt.state       :1951][INFO    ][10598] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 05:33:21.694542 duration_in_ms=11.266
2019-11-13 05:33:21,695 [salt.state       :1780][INFO    ][10598] Running state [salt-minion] at time 05:33:21.695429
2019-11-13 05:33:21,695 [salt.state       :1813][INFO    ][10598] Executing state service.running for [salt-minion]
2019-11-13 05:33:21,696 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10598] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-11-13 05:33:21,734 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10598] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-11-13 05:33:21,751 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10598] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-11-13 05:33:21,768 [salt.state       :300 ][INFO    ][10598] The service salt-minion is already running
2019-11-13 05:33:21,769 [salt.state       :1951][INFO    ][10598] Completed state [salt-minion] at time 05:33:21.769031 duration_in_ms=73.601
2019-11-13 05:33:21,770 [salt.state       :1780][INFO    ][10598] Running state [/etc/salt/grains.d] at time 05:33:21.770906
2019-11-13 05:33:21,771 [salt.state       :1813][INFO    ][10598] Executing state file.directory for [/etc/salt/grains.d]
2019-11-13 05:33:21,772 [salt.state       :300 ][INFO    ][10598] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-11-13 05:33:21,772 [salt.state       :1951][INFO    ][10598] Completed state [/etc/salt/grains.d] at time 05:33:21.772764 duration_in_ms=1.858
2019-11-13 05:33:21,773 [salt.state       :1780][INFO    ][10598] Running state [/etc/salt/grains] at time 05:33:21.773582
2019-11-13 05:33:21,774 [salt.state       :1813][INFO    ][10598] Executing state file.managed for [/etc/salt/grains]
2019-11-13 05:33:21,774 [salt.state       :300 ][INFO    ][10598] File /etc/salt/grains exists with proper permissions. No changes made.
2019-11-13 05:33:21,774 [salt.state       :1951][INFO    ][10598] Completed state [/etc/salt/grains] at time 05:33:21.774890 duration_in_ms=1.309
2019-11-13 05:33:21,775 [salt.state       :1780][INFO    ][10598] Running state [/etc/salt/grains.d/placeholder] at time 05:33:21.775444
2019-11-13 05:33:21,775 [salt.state       :1813][INFO    ][10598] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-11-13 05:33:21,776 [salt.state       :300 ][INFO    ][10598] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-11-13 05:33:21,776 [salt.state       :1951][INFO    ][10598] Completed state [/etc/salt/grains.d/placeholder] at time 05:33:21.776720 duration_in_ms=1.276
2019-11-13 05:33:21,777 [salt.state       :1780][INFO    ][10598] Running state [/etc/salt/grains.d/sphinx] at time 05:33:21.777271
2019-11-13 05:33:21,777 [salt.state       :1813][INFO    ][10598] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-11-13 05:33:21,787 [salt.state       :300 ][INFO    ][10598] File /etc/salt/grains.d/sphinx is in the correct state
2019-11-13 05:33:21,787 [salt.state       :1951][INFO    ][10598] Completed state [/etc/salt/grains.d/sphinx] at time 05:33:21.787327 duration_in_ms=10.057
2019-11-13 05:33:21,789 [salt.state       :1780][INFO    ][10598] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 05:33:21.789930
2019-11-13 05:33:21,790 [salt.state       :1813][INFO    ][10598] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-11-13 05:33:21,790 [salt.state       :300 ][INFO    ][10598] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-11-13 05:33:21,791 [salt.state       :1951][INFO    ][10598] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 05:33:21.790944 duration_in_ms=1.014
2019-11-13 05:33:21,791 [salt.state       :1780][INFO    ][10598] Running state [/etc/salt/grains.d/dns_records] at time 05:33:21.791480
2019-11-13 05:33:21,791 [salt.state       :1813][INFO    ][10598] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-11-13 05:33:21,804 [salt.state       :300 ][INFO    ][10598] File /etc/salt/grains.d/dns_records is in the correct state
2019-11-13 05:33:21,805 [salt.state       :1951][INFO    ][10598] Completed state [/etc/salt/grains.d/dns_records] at time 05:33:21.805181 duration_in_ms=13.7
2019-11-13 05:33:21,806 [salt.state       :1780][INFO    ][10598] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 05:33:21.806230
2019-11-13 05:33:21,806 [salt.state       :1813][INFO    ][10598] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-11-13 05:33:21,807 [salt.state       :300 ][INFO    ][10598] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-11-13 05:33:21,807 [salt.state       :1951][INFO    ][10598] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 05:33:21.807222 duration_in_ms=0.993
2019-11-13 05:33:21,807 [salt.state       :1780][INFO    ][10598] Running state [/etc/salt/grains.d/salt] at time 05:33:21.807788
2019-11-13 05:33:21,808 [salt.state       :1813][INFO    ][10598] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-11-13 05:33:21,816 [salt.state       :300 ][INFO    ][10598] File /etc/salt/grains.d/salt is in the correct state
2019-11-13 05:33:21,817 [salt.state       :1951][INFO    ][10598] Completed state [/etc/salt/grains.d/salt] at time 05:33:21.817193 duration_in_ms=9.405
2019-11-13 05:33:21,818 [salt.state       :1780][INFO    ][10598] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 05:33:21.818202
2019-11-13 05:33:21,818 [salt.state       :1813][INFO    ][10598] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-11-13 05:33:21,818 [salt.state       :300 ][INFO    ][10598] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-11-13 05:33:21,819 [salt.state       :1951][INFO    ][10598] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 05:33:21.819194 duration_in_ms=0.992
2019-11-13 05:33:21,821 [salt.state       :1780][INFO    ][10598] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 05:33:21.821339
2019-11-13 05:33:21,821 [salt.state       :1813][INFO    ][10598] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-11-13 05:33:21,822 [salt.state       :300 ][INFO    ][10598] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-11-13 05:33:21,822 [salt.state       :1951][INFO    ][10598] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 05:33:21.822329 duration_in_ms=0.99
2019-11-13 05:33:21,823 [salt.state       :1780][INFO    ][10598] Running state [mine.update] at time 05:33:21.823111
2019-11-13 05:33:21,823 [salt.state       :1813][INFO    ][10598] Executing state module.wait for [mine.update]
2019-11-13 05:33:21,823 [salt.state       :300 ][INFO    ][10598] No changes made for mine.update
2019-11-13 05:33:21,824 [salt.state       :1951][INFO    ][10598] Completed state [mine.update] at time 05:33:21.824047 duration_in_ms=0.936
2019-11-13 05:33:21,824 [salt.state       :1780][INFO    ][10598] Running state [ca-certificates] at time 05:33:21.824330
2019-11-13 05:33:21,824 [salt.state       :1813][INFO    ][10598] Executing state pkg.installed for [ca-certificates]
2019-11-13 05:33:21,833 [salt.state       :300 ][INFO    ][10598] All specified packages are already installed
2019-11-13 05:33:21,833 [salt.state       :1951][INFO    ][10598] Completed state [ca-certificates] at time 05:33:21.833238 duration_in_ms=8.908
2019-11-13 05:33:21,834 [salt.state       :1780][INFO    ][10598] Running state [update-ca-certificates] at time 05:33:21.834015
2019-11-13 05:33:21,834 [salt.state       :1813][INFO    ][10598] Executing state cmd.wait for [update-ca-certificates]
2019-11-13 05:33:21,834 [salt.state       :300 ][INFO    ][10598] No changes made for update-ca-certificates
2019-11-13 05:33:21,834 [salt.state       :1951][INFO    ][10598] Completed state [update-ca-certificates] at time 05:33:21.834932 duration_in_ms=0.917
2019-11-13 05:33:21,835 [salt.state       :1780][INFO    ][10598] Running state [iptables] at time 05:33:21.835201
2019-11-13 05:33:21,835 [salt.state       :1813][INFO    ][10598] Executing state pkg.installed for [iptables]
2019-11-13 05:33:21,843 [salt.state       :300 ][INFO    ][10598] All specified packages are already installed
2019-11-13 05:33:21,843 [salt.state       :1951][INFO    ][10598] Completed state [iptables] at time 05:33:21.843225 duration_in_ms=8.024
2019-11-13 05:33:21,843 [salt.state       :1780][INFO    ][10598] Running state [iptables-persistent] at time 05:33:21.843507
2019-11-13 05:33:21,843 [salt.state       :1813][INFO    ][10598] Executing state pkg.installed for [iptables-persistent]
2019-11-13 05:33:21,851 [salt.state       :300 ][INFO    ][10598] All specified packages are already installed
2019-11-13 05:33:21,851 [salt.state       :1951][INFO    ][10598] Completed state [iptables-persistent] at time 05:33:21.851311 duration_in_ms=7.804
2019-11-13 05:33:21,852 [salt.state       :1780][INFO    ][10598] Running state [iptables_modules_v4_load] at time 05:33:21.852348
2019-11-13 05:33:21,852 [salt.state       :1813][INFO    ][10598] Executing state kmod.present for [iptables_modules_v4_load]
2019-11-13 05:33:21,853 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10598] Executing command 'lsmod' in directory '/root'
2019-11-13 05:33:21,877 [salt.state       :300 ][INFO    ][10598] Kernel modules iptable_filter, ip_tables are already present
2019-11-13 05:33:21,877 [salt.state       :1951][INFO    ][10598] Completed state [iptables_modules_v4_load] at time 05:33:21.877864 duration_in_ms=25.516
2019-11-13 05:33:21,878 [salt.state       :1780][INFO    ][10598] Running state [/etc/iptables/rules.v4] at time 05:33:21.878606
2019-11-13 05:33:21,879 [salt.state       :1813][INFO    ][10598] Executing state file.managed for [/etc/iptables/rules.v4]
2019-11-13 05:33:21,978 [salt.state       :300 ][INFO    ][10598] File /etc/iptables/rules.v4 is in the correct state
2019-11-13 05:33:21,979 [salt.state       :1951][INFO    ][10598] Completed state [/etc/iptables/rules.v4] at time 05:33:21.979057 duration_in_ms=100.451
2019-11-13 05:33:21,980 [salt.state       :1780][INFO    ][10598] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 05:33:21.980036
2019-11-13 05:33:21,980 [salt.state       :1813][INFO    ][10598] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-11-13 05:33:21,980 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10598] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-11-13 05:33:22,001 [salt.state       :300 ][INFO    ][10598] onlyif execution failed
2019-11-13 05:33:22,001 [salt.state       :1951][INFO    ][10598] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 05:33:22.001427 duration_in_ms=21.39
2019-11-13 05:33:22,002 [salt.state       :1780][INFO    ][10598] Running state [netfilter-persistent] at time 05:33:22.002370
2019-11-13 05:33:22,002 [salt.state       :1813][INFO    ][10598] Executing state service.running for [netfilter-persistent]
2019-11-13 05:33:22,003 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10598] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-11-13 05:33:22,022 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10598] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-11-13 05:33:22,040 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10598] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-11-13 05:33:22,058 [salt.state       :300 ][INFO    ][10598] The service netfilter-persistent is already running
2019-11-13 05:33:22,058 [salt.state       :1951][INFO    ][10598] Completed state [netfilter-persistent] at time 05:33:22.058379 duration_in_ms=56.008
2019-11-13 05:33:22,059 [salt.state       :1780][INFO    ][10598] Running state [iptables_extra.remove_stale_tables] at time 05:33:22.059307
2019-11-13 05:33:22,059 [salt.state       :1813][INFO    ][10598] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-11-13 05:33:22,060 [salt.state       :300 ][INFO    ][10598] No changes made for iptables_extra.remove_stale_tables
2019-11-13 05:33:22,060 [salt.state       :1951][INFO    ][10598] Completed state [iptables_extra.remove_stale_tables] at time 05:33:22.060293 duration_in_ms=0.986
2019-11-13 05:33:22,060 [salt.state       :1780][INFO    ][10598] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 05:33:22.060598
2019-11-13 05:33:22,060 [salt.state       :1813][INFO    ][10598] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-11-13 05:33:22,061 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10598] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-11-13 05:33:22,075 [salt.state       :300 ][INFO    ][10598] onlyif execution failed
2019-11-13 05:33:22,076 [salt.state       :1951][INFO    ][10598] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 05:33:22.076280 duration_in_ms=15.682
2019-11-13 05:33:22,077 [salt.state       :1780][INFO    ][10598] Running state [/etc/iptables/rules.v6] at time 05:33:22.077393
2019-11-13 05:33:22,077 [salt.state       :1813][INFO    ][10598] Executing state file.absent for [/etc/iptables/rules.v6]
2019-11-13 05:33:22,078 [salt.state       :300 ][INFO    ][10598] File /etc/iptables/rules.v6 is not present
2019-11-13 05:33:22,078 [salt.state       :1951][INFO    ][10598] Completed state [/etc/iptables/rules.v6] at time 05:33:22.078576 duration_in_ms=1.183
2019-11-13 05:33:22,079 [salt.state       :1780][INFO    ][10598] Running state [iptables_extra.flush_all] at time 05:33:22.079337
2019-11-13 05:33:22,079 [salt.state       :1813][INFO    ][10598] Executing state module.wait for [iptables_extra.flush_all]
2019-11-13 05:33:22,080 [salt.state       :300 ][INFO    ][10598] No changes made for iptables_extra.flush_all
2019-11-13 05:33:22,080 [salt.state       :1951][INFO    ][10598] Completed state [iptables_extra.flush_all] at time 05:33:22.080254 duration_in_ms=0.917
2019-11-13 05:33:22,083 [salt.minion      :1711][INFO    ][10598] Returning information for job: 20191113053314274377
2019-11-13 05:33:22,718 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command state.apply with jid 20191113053322705100
2019-11-13 05:33:22,740 [salt.minion      :1432][INFO    ][10674] Starting a new job with PID 10674
2019-11-13 05:33:23,488 [salt.state       :915 ][INFO    ][10674] Loading fresh modules for state activity
2019-11-13 05:33:24,162 [salt.state       :1780][INFO    ][10674] Running state [maas-rack-controller] at time 05:33:24.162878
2019-11-13 05:33:24,163 [salt.state       :1813][INFO    ][10674] Executing state pkg.installed for [maas-rack-controller]
2019-11-13 05:33:24,163 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10674] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-11-13 05:33:24,269 [salt.state       :300 ][INFO    ][10674] All specified packages are already installed
2019-11-13 05:33:24,269 [salt.state       :1951][INFO    ][10674] Completed state [maas-rack-controller] at time 05:33:24.269829 duration_in_ms=106.95
2019-11-13 05:33:24,270 [salt.state       :1780][INFO    ][10674] Running state [ipmitool] at time 05:33:24.270235
2019-11-13 05:33:24,270 [salt.state       :1813][INFO    ][10674] Executing state pkg.installed for [ipmitool]
2019-11-13 05:33:24,278 [salt.state       :300 ][INFO    ][10674] All specified packages are already installed
2019-11-13 05:33:24,279 [salt.state       :1951][INFO    ][10674] Completed state [ipmitool] at time 05:33:24.279214 duration_in_ms=8.979
2019-11-13 05:33:24,282 [salt.state       :1780][INFO    ][10674] Running state [/etc/maas/rackd.conf] at time 05:33:24.282870
2019-11-13 05:33:24,283 [salt.state       :1813][INFO    ][10674] Executing state file.line for [/etc/maas/rackd.conf]
2019-11-13 05:33:24,284 [salt.state       :300 ][INFO    ][10674] No changes needed to be made
2019-11-13 05:33:24,284 [salt.state       :1951][INFO    ][10674] Completed state [/etc/maas/rackd.conf] at time 05:33:24.284541 duration_in_ms=1.67
2019-11-13 05:33:24,284 [salt.state       :1780][INFO    ][10674] Running state [/etc/maas/rackd.conf] at time 05:33:24.284832
2019-11-13 05:33:24,285 [salt.state       :1813][INFO    ][10674] Executing state file.managed for [/etc/maas/rackd.conf]
2019-11-13 05:33:24,285 [salt.loaded.int.states.file:2298][WARNING ][10674] 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-11-13 05:33:24,286 [salt.state       :300 ][INFO    ][10674] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-11-13 05:33:24,286 [salt.state       :1951][INFO    ][10674] Completed state [/etc/maas/rackd.conf] at time 05:33:24.286642 duration_in_ms=1.81
2019-11-13 05:33:24,287 [salt.state       :1780][INFO    ][10674] Running state [maas-rackd] at time 05:33:24.287817
2019-11-13 05:33:24,288 [salt.state       :1813][INFO    ][10674] Executing state service.running for [maas-rackd]
2019-11-13 05:33:24,288 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10674] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-11-13 05:33:24,323 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10674] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-11-13 05:33:24,340 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10674] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-11-13 05:33:24,357 [salt.state       :300 ][INFO    ][10674] The service maas-rackd is already running
2019-11-13 05:33:24,357 [salt.state       :1951][INFO    ][10674] Completed state [maas-rackd] at time 05:33:24.357585 duration_in_ms=69.767
2019-11-13 05:33:24,359 [salt.minion      :1711][INFO    ][10674] Returning information for job: 20191113053322705100
2019-11-13 05:33:24,984 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command state.apply with jid 20191113053324971120
2019-11-13 05:33:25,004 [salt.minion      :1432][INFO    ][10697] Starting a new job with PID 10697
2019-11-13 05:33:25,781 [salt.state       :915 ][INFO    ][10697] Loading fresh modules for state activity
2019-11-13 05:33:26,488 [salt.state       :1780][INFO    ][10697] Running state [maas-region-controller] at time 05:33:26.488291
2019-11-13 05:33:26,488 [salt.state       :1813][INFO    ][10697] Executing state pkg.installed for [maas-region-controller]
2019-11-13 05:33:26,489 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10697] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-11-13 05:33:26,563 [salt.state       :300 ][INFO    ][10697] All specified packages are already installed
2019-11-13 05:33:26,564 [salt.state       :1951][INFO    ][10697] Completed state [maas-region-controller] at time 05:33:26.563979 duration_in_ms=75.687
2019-11-13 05:33:26,564 [salt.state       :1780][INFO    ][10697] Running state [python-oauth] at time 05:33:26.564247
2019-11-13 05:33:26,564 [salt.state       :1813][INFO    ][10697] Executing state pkg.installed for [python-oauth]
2019-11-13 05:33:26,569 [salt.state       :300 ][INFO    ][10697] All specified packages are already installed
2019-11-13 05:33:26,569 [salt.state       :1951][INFO    ][10697] Completed state [python-oauth] at time 05:33:26.569371 duration_in_ms=5.124
2019-11-13 05:33:26,571 [salt.state       :1780][INFO    ][10697] Running state [/etc/maas/regiond.conf] at time 05:33:26.571844
2019-11-13 05:33:26,572 [salt.state       :1813][INFO    ][10697] Executing state file.replace for [/etc/maas/regiond.conf]
2019-11-13 05:33:26,620 [salt.state       :300 ][INFO    ][10697] No changes needed to be made
2019-11-13 05:33:26,620 [salt.state       :1951][INFO    ][10697] Completed state [/etc/maas/regiond.conf] at time 05:33:26.620424 duration_in_ms=48.579
2019-11-13 05:33:26,620 [salt.state       :1780][INFO    ][10697] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:33:26.620882
2019-11-13 05:33:26,621 [salt.state       :1813][INFO    ][10697] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-11-13 05:33:26,679 [salt.state       :300 ][INFO    ][10697] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-11-13 05:33:26,679 [salt.state       :1951][INFO    ][10697] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:33:26.679850 duration_in_ms=58.968
2019-11-13 05:33:26,680 [salt.state       :1780][INFO    ][10697] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:33:26.680297
2019-11-13 05:33:26,680 [salt.state       :1813][INFO    ][10697] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-11-13 05:33:26,692 [salt.state       :300 ][INFO    ][10697] No changes needed to be made
2019-11-13 05:33:26,692 [salt.state       :1951][INFO    ][10697] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:33:26.692887 duration_in_ms=12.59
2019-11-13 05:33:26,693 [salt.state       :1780][INFO    ][10697] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:33:26.693338
2019-11-13 05:33:26,693 [salt.state       :1813][INFO    ][10697] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-11-13 05:33:26,722 [salt.state       :300 ][INFO    ][10697] No changes needed to be made
2019-11-13 05:33:26,723 [salt.state       :1951][INFO    ][10697] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:33:26.723134 duration_in_ms=29.796
2019-11-13 05:33:26,723 [salt.state       :1780][INFO    ][10697] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:33:26.723606
2019-11-13 05:33:26,723 [salt.state       :1813][INFO    ][10697] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-11-13 05:33:26,745 [salt.state       :300 ][INFO    ][10697] No changes needed to be made
2019-11-13 05:33:26,746 [salt.state       :1951][INFO    ][10697] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:33:26.746134 duration_in_ms=22.528
2019-11-13 05:33:26,746 [salt.state       :1780][INFO    ][10697] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:33:26.746589
2019-11-13 05:33:26,746 [salt.state       :1813][INFO    ][10697] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-11-13 05:33:26,758 [salt.state       :300 ][INFO    ][10697] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-11-13 05:33:26,758 [salt.state       :1951][INFO    ][10697] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:33:26.758304 duration_in_ms=11.715
2019-11-13 05:33:26,759 [salt.state       :1780][INFO    ][10697] Running state [a2enmod headers] at time 05:33:26.759372
2019-11-13 05:33:26,759 [salt.state       :1813][INFO    ][10697] Executing state cmd.run for [a2enmod headers]
2019-11-13 05:33:26,760 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10697] Executing command 'a2enmod headers' in directory '/root'
2019-11-13 05:33:26,831 [salt.state       :300 ][INFO    ][10697] {'pid': 10716, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-11-13 05:33:26,832 [salt.state       :1951][INFO    ][10697] Completed state [a2enmod headers] at time 05:33:26.831976 duration_in_ms=72.603
2019-11-13 05:33:26,832 [salt.state       :1780][INFO    ][10697] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:33:26.832569
2019-11-13 05:33:26,833 [salt.state       :1813][INFO    ][10697] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-11-13 05:33:26,852 [salt.state       :300 ][INFO    ][10697] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-11-13 05:33:26,852 [salt.state       :1951][INFO    ][10697] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:33:26.852379 duration_in_ms=19.81
2019-11-13 05:33:26,853 [salt.state       :1780][INFO    ][10697] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:33:26.853263
2019-11-13 05:33:26,853 [salt.state       :1813][INFO    ][10697] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-11-13 05:33:26,943 [salt.state       :300 ][INFO    ][10697] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-11-13 05:33:26,944 [salt.state       :1951][INFO    ][10697] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:33:26.944414 duration_in_ms=91.142
2019-11-13 05:33:26,945 [salt.state       :1780][INFO    ][10697] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:33:26.945321
2019-11-13 05:33:26,945 [salt.state       :1813][INFO    ][10697] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-11-13 05:33:27,009 [salt.state       :300 ][INFO    ][10697] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-11-13 05:33:27,009 [salt.state       :1951][INFO    ][10697] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:33:27.009581 duration_in_ms=64.26
2019-11-13 05:33:27,010 [salt.state       :1780][INFO    ][10697] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:33:27.010145
2019-11-13 05:33:27,010 [salt.state       :1813][INFO    ][10697] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-11-13 05:33:27,069 [salt.state       :300 ][INFO    ][10697] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-11-13 05:33:27,069 [salt.state       :1951][INFO    ][10697] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:33:27.069283 duration_in_ms=59.138
2019-11-13 05:33:27,069 [salt.state       :1780][INFO    ][10697] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_bionic] at time 05:33:27.069816
2019-11-13 05:33:27,070 [salt.state       :1813][INFO    ][10697] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_bionic]
2019-11-13 05:33:27,123 [salt.state       :300 ][INFO    ][10697] File /etc/maas/preseeds/curtin_userdata_amd64_generic_bionic is in the correct state
2019-11-13 05:33:27,123 [salt.state       :1951][INFO    ][10697] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_bionic] at time 05:33:27.123244 duration_in_ms=53.428
2019-11-13 05:33:27,123 [salt.state       :1780][INFO    ][10697] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_bionic] at time 05:33:27.123676
2019-11-13 05:33:27,123 [salt.state       :1813][INFO    ][10697] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_bionic]
2019-11-13 05:33:27,170 [salt.state       :300 ][INFO    ][10697] File /etc/maas/preseeds/curtin_userdata_arm64_generic_bionic is in the correct state
2019-11-13 05:33:27,170 [salt.state       :1951][INFO    ][10697] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_bionic] at time 05:33:27.170951 duration_in_ms=47.275
2019-11-13 05:33:27,171 [salt.state       :1780][INFO    ][10697] Running state [/root/.pgpass] at time 05:33:27.171173
2019-11-13 05:33:27,171 [salt.state       :1813][INFO    ][10697] Executing state file.managed for [/root/.pgpass]
2019-11-13 05:33:27,218 [salt.state       :300 ][INFO    ][10697] File /root/.pgpass is in the correct state
2019-11-13 05:33:27,218 [salt.state       :1951][INFO    ][10697] Completed state [/root/.pgpass] at time 05:33:27.218796 duration_in_ms=47.623
2019-11-13 05:33:27,222 [salt.state       :1780][INFO    ][10697] Running state [maas-region syncdb --noinput] at time 05:33:27.222949
2019-11-13 05:33:27,223 [salt.state       :1813][INFO    ][10697] Executing state cmd.run for [maas-region syncdb --noinput]
2019-11-13 05:33:27,223 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10697] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-11-13 05:33:29,299 [salt.state       :300 ][INFO    ][10697] {'pid': 10733, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: auth, sites, contenttypes, maasserver, sessions, metadataserver, piston3\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-11-13 05:33:29,299 [salt.state       :1951][INFO    ][10697] Completed state [maas-region syncdb --noinput] at time 05:33:29.299394 duration_in_ms=2076.445
2019-11-13 05:33:29,299 [salt.state       :2022][WARNING ][10697] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-11-13 05:33:29,300 [salt.state       :1780][INFO    ][10697] Running state [maas-regiond] at time 05:33:29.300709
2019-11-13 05:33:29,300 [salt.state       :1813][INFO    ][10697] Executing state service.running for [maas-regiond]
2019-11-13 05:33:29,301 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10697] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-11-13 05:33:29,337 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10697] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-11-13 05:33:29,353 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10697] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-11-13 05:33:29,372 [salt.state       :300 ][INFO    ][10697] The service maas-regiond is already running
2019-11-13 05:33:29,372 [salt.state       :1951][INFO    ][10697] Completed state [maas-regiond] at time 05:33:29.372417 duration_in_ms=71.708
2019-11-13 05:33:29,373 [salt.state       :1780][INFO    ][10697] Running state [bind9] at time 05:33:29.373901
2019-11-13 05:33:29,374 [salt.state       :1813][INFO    ][10697] Executing state service.running for [bind9]
2019-11-13 05:33:29,374 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10697] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-11-13 05:33:29,394 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10697] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-11-13 05:33:29,410 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10697] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-11-13 05:33:29,426 [salt.state       :300 ][INFO    ][10697] The service bind9 is already running
2019-11-13 05:33:29,427 [salt.state       :1951][INFO    ][10697] Completed state [bind9] at time 05:33:29.427017 duration_in_ms=53.116
2019-11-13 05:33:29,428 [salt.state       :1780][INFO    ][10697] Running state [apache2] at time 05:33:29.428605
2019-11-13 05:33:29,428 [salt.state       :1813][INFO    ][10697] Executing state service.running for [apache2]
2019-11-13 05:33:29,429 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10697] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-11-13 05:33:29,447 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10697] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-11-13 05:33:29,463 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10697] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-11-13 05:33:29,484 [salt.state       :300 ][INFO    ][10697] The service apache2 is already running
2019-11-13 05:33:29,485 [salt.state       :1951][INFO    ][10697] Completed state [apache2] at time 05:33:29.485146 duration_in_ms=56.541
2019-11-13 05:33:29,486 [salt.state       :1780][INFO    ][10697] Running state [maasng.wait_for_http_code] at time 05:33:29.486625
2019-11-13 05:33:29,486 [salt.state       :1813][INFO    ][10697] Executing state module.run for [maasng.wait_for_http_code]
2019-11-13 05:33:29,487 [salt.utils.decorators:613 ][WARNING ][10697] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-13 05:33:29,622 [salt.state       :300 ][INFO    ][10697] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-11-13 05:33:29,623 [salt.state       :1951][INFO    ][10697] Completed state [maasng.wait_for_http_code] at time 05:33:29.622936 duration_in_ms=136.309
2019-11-13 05:33:29,624 [salt.state       :1780][INFO    ][10697] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:33:29.624272
2019-11-13 05:33:29,624 [salt.state       :1813][INFO    ][10697] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-11-13 05:33:29,625 [salt.state       :300 ][INFO    ][10697] /var/lib/maas/.setup_admin exists
2019-11-13 05:33:29,625 [salt.state       :1951][INFO    ][10697] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:33:29.625822 duration_in_ms=1.551
2019-11-13 05:33:29,627 [salt.state       :1780][INFO    ][10697] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:33:29.626939
2019-11-13 05:33:29,627 [salt.state       :1813][INFO    ][10697] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-13 05:33:29,628 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10697] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-13 05:33:31,168 [salt.state       :300 ][INFO    ][10697] {'pid': 10756, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-13 05:33:31,169 [salt.state       :1951][INFO    ][10697] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:33:31.168973 duration_in_ms=1542.033
2019-11-13 05:33:31,178 [salt.state       :1780][INFO    ][10697] Running state [maas_region_boot_source_resources_mirror] at time 05:33:31.178221
2019-11-13 05:33:31,178 [salt.state       :1813][INFO    ][10697] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-11-13 05:33:31,300 [salt.state       :300 ][INFO    ][10697] {'changes': {}}
2019-11-13 05:33:31,301 [salt.state       :1951][INFO    ][10697] Completed state [maas_region_boot_source_resources_mirror] at time 05:33:31.301296 duration_in_ms=123.073
2019-11-13 05:33:31,303 [salt.state       :1780][INFO    ][10697] Running state [maasng.boot_resources_import] at time 05:33:31.303113
2019-11-13 05:33:31,303 [salt.state       :1813][INFO    ][10697] Executing state module.run for [maasng.boot_resources_import]
2019-11-13 05:33:31,304 [salt.utils.decorators:613 ][WARNING ][10697] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-13 05:33:31,419 [salt.loaded.ext.module.maasng:1600][INFO    ][10697] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-11-13 05:33:36,482 [salt.loaded.ext.module.maasng:1600][INFO    ][10697] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-11-13 05:33:40,057 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113053340043904
2019-11-13 05:33:40,077 [salt.minion      :1432][INFO    ][10805] Starting a new job with PID 10805
2019-11-13 05:33:40,102 [salt.minion      :1711][INFO    ][10805] Returning information for job: 20191113053340043904
2019-11-13 05:33:41,548 [salt.loaded.ext.module.maasng:1600][INFO    ][10697] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-11-13 05:33:46,668 [salt.state       :300 ][INFO    ][10697] {'ret': True}
2019-11-13 05:33:46,668 [salt.state       :1951][INFO    ][10697] Completed state [maasng.boot_resources_import] at time 05:33:46.668483 duration_in_ms=15365.37
2019-11-13 05:33:46,669 [salt.state       :1780][INFO    ][10697] Running state [maas_region_boot_sources_selection_bionic] at time 05:33:46.669704
2019-11-13 05:33:46,670 [salt.state       :1813][INFO    ][10697] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_bionic]
2019-11-13 05:33:46,877 [salt.state       :300 ][INFO    ][10697] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-11-13 05:33:46,877 [salt.state       :1951][INFO    ][10697] Completed state [maas_region_boot_sources_selection_bionic] at time 05:33:46.877610 duration_in_ms=207.906
2019-11-13 05:33:46,879 [salt.state       :1780][INFO    ][10697] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 05:33:46.879045
2019-11-13 05:33:46,879 [salt.state       :1813][INFO    ][10697] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-11-13 05:33:46,880 [salt.utils.decorators:613 ][WARNING ][10697] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-13 05:33:46,880 [salt.loaded.ext.module.maasng:1771][INFO    ][10697] boot-sources sync initiated for ALL Rack's
2019-11-13 05:33:47,763 [salt.state       :300 ][INFO    ][10697] {'ret': True}
2019-11-13 05:33:47,763 [salt.state       :1951][INFO    ][10697] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 05:33:47.763549 duration_in_ms=884.504
2019-11-13 05:33:47,765 [salt.state       :1780][INFO    ][10697] Running state [maas.process_maas_config] at time 05:33:47.765359
2019-11-13 05:33:47,765 [salt.state       :1813][INFO    ][10697] Executing state module.run for [maas.process_maas_config]
2019-11-13 05:33:47,766 [salt.utils.decorators:613 ][WARNING ][10697] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-13 05:33:47,767 [salt.loaded.ext.module.maas:92  ][INFO    ][10697] maasconfig name=enable_http_proxy value=True
2019-11-13 05:33:47,831 [salt.loaded.ext.module.maas:92  ][INFO    ][10697] maasconfig name=upstream_dns value=8.8.8.8
2019-11-13 05:33:47,895 [salt.loaded.ext.module.maas:92  ][INFO    ][10697] maasconfig name=commissioning_distro_series value=bionic
2019-11-13 05:33:47,975 [salt.loaded.ext.module.maas:92  ][INFO    ][10697] maasconfig name=default_osystem value=ubuntu
2019-11-13 05:33:48,038 [salt.loaded.ext.module.maas:92  ][INFO    ][10697] maasconfig name=active_discovery_interval value=600
2019-11-13 05:33:48,099 [salt.loaded.ext.module.maas:92  ][INFO    ][10697] maasconfig name=dnssec_validation value=no
2019-11-13 05:33:48,152 [salt.loaded.ext.module.maas:92  ][INFO    ][10697] maasconfig name=maas_name value=mas01
2019-11-13 05:33:48,206 [salt.loaded.ext.module.maas:92  ][INFO    ][10697] maasconfig name=network_discovery value=enabled
2019-11-13 05:33:48,320 [salt.loaded.ext.module.maas:92  ][INFO    ][10697] maasconfig name=enable_third_party_drivers value=True
2019-11-13 05:33:48,388 [salt.loaded.ext.module.maas:92  ][INFO    ][10697] maasconfig name=default_storage_layout value=lvm
2019-11-13 05:33:50,856 [salt.loaded.ext.module.maas:92  ][INFO    ][10697] maasconfig name=ntp_external_only value=True
2019-11-13 05:33:50,933 [salt.loaded.ext.module.maas:92  ][INFO    ][10697] maasconfig name=disk_erase_with_secure_erase value=False
2019-11-13 05:33:50,992 [salt.loaded.ext.module.maas:92  ][INFO    ][10697] maasconfig name=default_distro_series value=bionic
2019-11-13 05:33:51,057 [salt.loaded.ext.module.maas:92  ][INFO    ][10697] maasconfig name=default_min_hwe_kernel value=ga-18.04
2019-11-13 05:33:51,180 [salt.state       :300 ][INFO    ][10697] {'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-11-13 05:33:51,180 [salt.state       :1951][INFO    ][10697] Completed state [maas.process_maas_config] at time 05:33:51.180763 duration_in_ms=3415.403
2019-11-13 05:33:51,181 [salt.state       :1780][INFO    ][10697] Running state [pxe_admin] at time 05:33:51.181741
2019-11-13 05:33:51,182 [salt.state       :1813][INFO    ][10697] Executing state maasng.fabric_present for [pxe_admin]
2019-11-13 05:33:51,248 [salt.loaded.ext.module.maasng:945 ][INFO    ][10697] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ebne7x', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-11-13 05:33:51,338 [salt.loaded.ext.module.maasng:1008][WARNING ][10697] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-11-13 05:33:51,338 [salt.loaded.ext.module.maasng:1011][WARNING ][10697] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-11-13 05:33:51,410 [salt.state       :300 ][INFO    ][10697] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-11-13 05:33:51,410 [salt.state       :1951][INFO    ][10697] Completed state [pxe_admin] at time 05:33:51.410847 duration_in_ms=229.106
2019-11-13 05:33:51,411 [salt.state       :1780][INFO    ][10697] Running state [vlan 0] at time 05:33:51.411263
2019-11-13 05:33:51,411 [salt.state       :1813][INFO    ][10697] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-11-13 05:33:51,475 [salt.loaded.ext.module.maasng:945 ][INFO    ][10697] [{u'id': 0, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'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'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'ebne7x', 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-11-13 05:33:51,595 [salt.loaded.ext.module.maasng:945 ][INFO    ][10697] [{u'id': 0, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'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'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'ebne7x', 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-11-13 05:33:51,896 [salt.loaded.ext.module.maasng:945 ][INFO    ][10697] [{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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {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'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'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {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': True, u'mtu': 1500, u'primary_rack': u'ebne7x', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-11-13 05:33:51,998 [salt.state       :300 ][INFO    ][10697] {'new': 'Vlan untagged was updated'}
2019-11-13 05:33:51,998 [salt.state       :1951][INFO    ][10697] Completed state [vlan 0] at time 05:33:51.998549 duration_in_ms=587.284
2019-11-13 05:33:52,000 [salt.state       :1780][INFO    ][10697] Running state [192.168.11.0/24] at time 05:33:52.000155
2019-11-13 05:33:52,000 [salt.state       :1813][INFO    ][10697] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-11-13 05:33:52,237 [salt.loaded.ext.module.maasng:945 ][INFO    ][10697] [{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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {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'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'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {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'primary_rack': u'ebne7x', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-11-13 05:33:52,238 [salt.loaded.ext.module.maasng:1235][WARNING ][10697] Ignoring parameter vlan:0
2019-11-13 05:33:52,315 [salt.state       :300 ][INFO    ][10697] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-11-13 05:33:52,316 [salt.state       :1951][INFO    ][10697] Completed state [192.168.11.0/24] at time 05:33:52.316167 duration_in_ms=316.012
2019-11-13 05:33:52,317 [salt.state       :1780][INFO    ][10697] Running state [maas_create_iprange_1] at time 05:33:52.317554
2019-11-13 05:33:52,318 [salt.state       :1813][INFO    ][10697] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-11-13 05:33:52,374 [salt.state       :300 ][INFO    ][10697] Iprange maas_create_iprange_1 already exist.
2019-11-13 05:33:52,374 [salt.state       :1951][INFO    ][10697] Completed state [maas_create_iprange_1] at time 05:33:52.374688 duration_in_ms=57.135
2019-11-13 05:33:52,375 [salt.state       :1780][INFO    ][10697] Running state [vlan 0] at time 05:33:52.375098
2019-11-13 05:33:52,375 [salt.state       :1813][INFO    ][10697] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-11-13 05:33:52,428 [salt.loaded.ext.module.maasng:945 ][INFO    ][10697] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ebne7x', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-11-13 05:33:52,568 [salt.loaded.ext.module.maasng:945 ][INFO    ][10697] [{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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {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'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'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {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'primary_rack': u'ebne7x', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-11-13 05:33:52,817 [salt.loaded.ext.module.maasng:945 ][INFO    ][10697] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ebne7x', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-11-13 05:33:52,908 [salt.state       :300 ][INFO    ][10697] {'new': 'Vlan untagged was updated'}
2019-11-13 05:33:52,909 [salt.state       :1951][INFO    ][10697] Completed state [vlan 0] at time 05:33:52.908958 duration_in_ms=533.86
2019-11-13 05:33:52,909 [salt.state       :1780][INFO    ][10697] Running state [opnfv] at time 05:33:52.909805
2019-11-13 05:33:52,910 [salt.state       :1813][INFO    ][10697] Executing state maasng.sshkey_present for [opnfv]
2019-11-13 05:33:52,967 [salt.loaded.ext.module.maasng:1903][INFO    ][10697] [{u'keysource': u'', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP', u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/'}]
2019-11-13 05:33:52,968 [salt.state       :300 ][INFO    ][10697] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-11-13 05:33:52,968 [salt.state       :1951][INFO    ][10697] Completed state [opnfv] at time 05:33:52.968892 duration_in_ms=59.085
2019-11-13 05:33:52,969 [salt.state       :1780][INFO    ][10697] Running state [maas.process_tags] at time 05:33:52.969855
2019-11-13 05:33:52,970 [salt.state       :1813][INFO    ][10697] Executing state module.run for [maas.process_tags]
2019-11-13 05:33:52,970 [salt.utils.decorators:613 ][WARNING ][10697] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-13 05:33:53,023 [salt.loaded.ext.module.maas:92  ][INFO    ][10697] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-11-13 05:33:53,103 [salt.state       :300 ][INFO    ][10697] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-11-13 05:33:53,104 [salt.state       :1951][INFO    ][10697] Completed state [maas.process_tags] at time 05:33:53.104281 duration_in_ms=134.426
2019-11-13 05:33:53,107 [salt.minion      :1711][INFO    ][10697] Returning information for job: 20191113053324971120
2019-11-13 05:33:53,571 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command state.apply with jid 20191113053353564248
2019-11-13 05:33:53,591 [salt.minion      :1432][INFO    ][11065] Starting a new job with PID 11065
2019-11-13 05:33:57,336 [salt.state       :915 ][INFO    ][11065] Loading fresh modules for state activity
2019-11-13 05:33:57,429 [salt.state       :1780][INFO    ][11065] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:33:57.429859
2019-11-13 05:33:57,430 [salt.state       :1813][INFO    ][11065] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-13 05:33:57,433 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11065] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-13 05:33:58,934 [salt.state       :300 ][INFO    ][11065] {'pid': 11090, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-13 05:33:58,935 [salt.state       :1951][INFO    ][11065] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:33:58.934861 duration_in_ms=1505.0
2019-11-13 05:33:58,937 [salt.state       :1780][INFO    ][11065] Running state [maas.process_machines] at time 05:33:58.937784
2019-11-13 05:33:58,938 [salt.state       :1813][INFO    ][11065] Executing state module.run for [maas.process_machines]
2019-11-13 05:33:58,939 [salt.utils.decorators:613 ][WARNING ][11065] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-13 05:33:59,630 [salt.loaded.ext.module.maas:412 ][WARNING ][11065] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-13 05:33:59,631 [salt.loaded.ext.module.maas:92  ][INFO    ][11065] 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=7nqcyc architecture=amd64/generic power_parameters_power_user=admin
2019-11-13 05:34:00,807 [salt.loaded.ext.module.maas:412 ][WARNING ][11065] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-13 05:34:00,808 [salt.loaded.ext.module.maas:92  ][INFO    ][11065] 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=k48fda architecture=amd64/generic power_parameters_power_user=admin
2019-11-13 05:34:02,089 [salt.loaded.ext.module.maas:412 ][WARNING ][11065] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-13 05:34:02,090 [salt.loaded.ext.module.maas:92  ][INFO    ][11065] 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=nda63r architecture=amd64/generic power_parameters_power_user=admin
2019-11-13 05:34:03,352 [salt.loaded.ext.module.maas:412 ][WARNING ][11065] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-13 05:34:03,353 [salt.loaded.ext.module.maas:92  ][INFO    ][11065] 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=47dh6q architecture=amd64/generic power_parameters_power_user=admin
2019-11-13 05:34:04,556 [salt.loaded.ext.module.maas:412 ][WARNING ][11065] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-13 05:34:04,557 [salt.loaded.ext.module.maas:92  ][INFO    ][11065] 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=agdtq7 architecture=amd64/generic power_parameters_power_user=admin
2019-11-13 05:34:05,751 [salt.state       :300 ][INFO    ][11065] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-11-13 05:34:05,752 [salt.state       :1951][INFO    ][11065] Completed state [maas.process_machines] at time 05:34:05.752025 duration_in_ms=6814.24
2019-11-13 05:34:05,756 [salt.minion      :1711][INFO    ][11065] Returning information for job: 20191113053353564248
2019-11-13 05:34:38,978 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command state.apply with jid 20191113053438965721
2019-11-13 05:34:39,000 [salt.minion      :1432][INFO    ][11353] Starting a new job with PID 11353
2019-11-13 05:34:42,787 [salt.state       :915 ][INFO    ][11353] Loading fresh modules for state activity
2019-11-13 05:34:42,872 [salt.state       :1780][INFO    ][11353] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:34:42.872904
2019-11-13 05:34:42,873 [salt.state       :1813][INFO    ][11353] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-13 05:34:42,875 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11353] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-13 05:34:44,109 [salt.state       :300 ][INFO    ][11353] {'pid': 11363, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-13 05:34:44,110 [salt.state       :1951][INFO    ][11353] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:34:44.109890 duration_in_ms=1236.986
2019-11-13 05:34:44,112 [salt.state       :1780][INFO    ][11353] Running state [maas.wait_for_machine_status] at time 05:34:44.112366
2019-11-13 05:34:44,112 [salt.state       :1813][INFO    ][11353] Executing state module.run for [maas.wait_for_machine_status]
2019-11-13 05:34:44,113 [salt.utils.decorators:613 ][WARNING ][11353] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-13 05:34:46,362 [salt.loaded.ext.module.maas:993 ][INFO    ][11353] Machine 47dh6q mark broken
2019-11-13 05:34:46,993 [salt.loaded.ext.module.maas:996 ][INFO    ][11353] Machine 47dh6q mark fixed
2019-11-13 05:34:48,256 [salt.loaded.ext.module.maas:684 ][INFO    ][11353] deploymachines hwe_kernel=ga-18.04 system_id=47dh6q distro_series=bionic
2019-11-13 05:34:51,609 [salt.loaded.ext.module.maas:1023][INFO    ][11353] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1492.51803207s left)
2019-11-13 05:34:54,041 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113053453987825
2019-11-13 05:34:54,063 [salt.minion      :1432][INFO    ][11431] Starting a new job with PID 11431
2019-11-13 05:34:54,086 [salt.minion      :1711][INFO    ][11431] Returning information for job: 20191113053453987825
2019-11-13 05:35:24,086 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113053524074078
2019-11-13 05:35:24,107 [salt.minion      :1432][INFO    ][11475] Starting a new job with PID 11475
2019-11-13 05:35:24,129 [salt.minion      :1711][INFO    ][11475] Returning information for job: 20191113053524074078
2019-11-13 05:35:25,149 [salt.loaded.ext.module.maas:1023][INFO    ][11353] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1458.97818613s left)
2019-11-13 05:35:54,153 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113053554140370
2019-11-13 05:35:54,176 [salt.minion      :1432][INFO    ][11507] Starting a new job with PID 11507
2019-11-13 05:35:54,204 [salt.minion      :1711][INFO    ][11507] Returning information for job: 20191113053554140370
2019-11-13 05:35:58,748 [salt.loaded.ext.module.maas:1023][INFO    ][11353] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1425.37965703s left)
2019-11-13 05:36:24,207 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113053624194053
2019-11-13 05:36:24,230 [salt.minion      :1432][INFO    ][11548] Starting a new job with PID 11548
2019-11-13 05:36:24,254 [salt.minion      :1711][INFO    ][11548] Returning information for job: 20191113053624194053
2019-11-13 05:36:31,884 [salt.loaded.ext.module.maas:1023][INFO    ][11353] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1392.24328208s left)
2019-11-13 05:36:54,274 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113053654260806
2019-11-13 05:36:54,297 [salt.minion      :1432][INFO    ][11650] Starting a new job with PID 11650
2019-11-13 05:36:54,322 [salt.minion      :1711][INFO    ][11650] Returning information for job: 20191113053654260806
2019-11-13 05:37:05,472 [salt.loaded.ext.module.maas:1023][INFO    ][11353] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1358.654706s left)
2019-11-13 05:37:24,333 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113053724320919
2019-11-13 05:37:24,356 [salt.minion      :1432][INFO    ][11799] Starting a new job with PID 11799
2019-11-13 05:37:24,381 [salt.minion      :1711][INFO    ][11799] Returning information for job: 20191113053724320919
2019-11-13 05:37:38,479 [salt.loaded.ext.module.maas:1023][INFO    ][11353] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1325.64837098s left)
2019-11-13 05:37:54,393 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113053754381694
2019-11-13 05:37:54,412 [salt.minion      :1432][INFO    ][11869] Starting a new job with PID 11869
2019-11-13 05:37:54,436 [salt.minion      :1711][INFO    ][11869] Returning information for job: 20191113053754381694
2019-11-13 05:38:11,816 [salt.loaded.ext.module.maas:1023][INFO    ][11353] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1292.31068397s left)
2019-11-13 05:38:24,451 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113053824438152
2019-11-13 05:38:24,474 [salt.minion      :1432][INFO    ][11939] Starting a new job with PID 11939
2019-11-13 05:38:24,500 [salt.minion      :1711][INFO    ][11939] Returning information for job: 20191113053824438152
2019-11-13 05:38:45,385 [salt.loaded.ext.module.maas:1023][INFO    ][11353] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1258.74196696s left)
2019-11-13 05:38:54,516 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113053854503858
2019-11-13 05:38:54,539 [salt.minion      :1432][INFO    ][11985] Starting a new job with PID 11985
2019-11-13 05:38:54,564 [salt.minion      :1711][INFO    ][11985] Returning information for job: 20191113053854503858
2019-11-13 05:39:19,092 [salt.loaded.ext.module.maas:1023][INFO    ][11353] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1225.03557396s left)
2019-11-13 05:39:24,587 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113053924574140
2019-11-13 05:39:24,609 [salt.minion      :1432][INFO    ][12074] Starting a new job with PID 12074
2019-11-13 05:39:24,635 [salt.minion      :1711][INFO    ][12074] Returning information for job: 20191113053924574140
2019-11-13 05:39:52,785 [salt.loaded.ext.module.maas:1023][INFO    ][11353] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1191.34252715s left)
2019-11-13 05:39:54,661 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113053954648389
2019-11-13 05:39:54,682 [salt.minion      :1432][INFO    ][12114] Starting a new job with PID 12114
2019-11-13 05:39:54,710 [salt.minion      :1711][INFO    ][12114] Returning information for job: 20191113053954648389
2019-11-13 05:40:24,748 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113054024734080
2019-11-13 05:40:24,768 [salt.minion      :1432][INFO    ][12230] Starting a new job with PID 12230
2019-11-13 05:40:24,808 [salt.minion      :1711][INFO    ][12230] Returning information for job: 20191113054024734080
2019-11-13 05:40:26,361 [salt.loaded.ext.module.maas:1023][INFO    ][11353] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1157.76662517s left)
2019-11-13 05:40:54,842 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113054054829333
2019-11-13 05:40:54,865 [salt.minion      :1432][INFO    ][12262] Starting a new job with PID 12262
2019-11-13 05:40:54,896 [salt.minion      :1711][INFO    ][12262] Returning information for job: 20191113054054829333
2019-11-13 05:41:00,002 [salt.loaded.ext.module.maas:1023][INFO    ][11353] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1124.12545514s left)
2019-11-13 05:41:24,935 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113054124922117
2019-11-13 05:41:24,960 [salt.minion      :1432][INFO    ][12303] Starting a new job with PID 12303
2019-11-13 05:41:24,987 [salt.minion      :1711][INFO    ][12303] Returning information for job: 20191113054124922117
2019-11-13 05:41:33,705 [salt.loaded.ext.module.maas:1023][INFO    ][11353] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1090.421767s left)
2019-11-13 05:41:55,034 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command saltutil.find_job with jid 20191113054155021441
2019-11-13 05:41:55,057 [salt.minion      :1432][INFO    ][12365] Starting a new job with PID 12365
2019-11-13 05:41:55,084 [salt.minion      :1711][INFO    ][12365] Returning information for job: 20191113054155021441
2019-11-13 05:42:07,474 [salt.state       :300 ][INFO    ][11353] {'ret': True}
2019-11-13 05:42:07,475 [salt.state       :1951][INFO    ][11353] Completed state [maas.wait_for_machine_status] at time 05:42:07.475256 duration_in_ms=443362.889
2019-11-13 05:42:07,478 [salt.minion      :1711][INFO    ][11353] Returning information for job: 20191113053438965721
2019-11-13 05:42:08,156 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command state.apply with jid 20191113054208142952
2019-11-13 05:42:08,178 [salt.minion      :1432][INFO    ][12406] Starting a new job with PID 12406
2019-11-13 05:42:12,135 [salt.state       :915 ][INFO    ][12406] Loading fresh modules for state activity
2019-11-13 05:42:12,266 [salt.state       :1780][INFO    ][12406] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:42:12.266785
2019-11-13 05:42:12,267 [salt.state       :1813][INFO    ][12406] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-13 05:42:12,268 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12406] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-13 05:42:13,438 [salt.state       :300 ][INFO    ][12406] {'pid': 12469, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-13 05:42:13,439 [salt.state       :1951][INFO    ][12406] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:42:13.439086 duration_in_ms=1172.301
2019-11-13 05:42:13,442 [salt.state       :1780][INFO    ][12406] Running state [maas_machines_storage_cmp002_lvm] at time 05:42:13.442193
2019-11-13 05:42:13,442 [salt.state       :1813][INFO    ][12406] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-11-13 05:42:13,979 [salt.state       :300 ][INFO    ][12406] Machine cmp002 is not in Ready state.
2019-11-13 05:42:13,980 [salt.state       :1951][INFO    ][12406] Completed state [maas_machines_storage_cmp002_lvm] at time 05:42:13.980182 duration_in_ms=537.988
2019-11-13 05:42:13,980 [salt.state       :1780][INFO    ][12406] Running state [maas_machines_storage_cmp001_lvm] at time 05:42:13.980712
2019-11-13 05:42:13,981 [salt.state       :1813][INFO    ][12406] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-11-13 05:42:14,350 [salt.state       :300 ][INFO    ][12406] Machine cmp001 is not in Ready state.
2019-11-13 05:42:14,351 [salt.state       :1951][INFO    ][12406] Completed state [maas_machines_storage_cmp001_lvm] at time 05:42:14.351393 duration_in_ms=370.68
2019-11-13 05:42:14,355 [salt.minion      :1711][INFO    ][12406] Returning information for job: 20191113054208142952
2019-11-13 05:42:14,940 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command state.apply with jid 20191113054214927751
2019-11-13 05:42:14,962 [salt.minion      :1432][INFO    ][12480] Starting a new job with PID 12480
2019-11-13 05:42:15,757 [salt.state       :915 ][INFO    ][12480] Loading fresh modules for state activity
2019-11-13 05:42:15,842 [salt.state       :1780][INFO    ][12480] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:42:15.841978
2019-11-13 05:42:15,842 [salt.state       :1813][INFO    ][12480] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-13 05:42:15,844 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12480] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-13 05:42:17,295 [salt.state       :300 ][INFO    ][12480] {'pid': 12487, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-13 05:42:17,296 [salt.state       :1951][INFO    ][12480] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:42:17.296148 duration_in_ms=1454.171
2019-11-13 05:42:17,297 [salt.state       :1780][INFO    ][12480] Running state [maas.deploy_machines] at time 05:42:17.297298
2019-11-13 05:42:17,297 [salt.state       :1813][INFO    ][12480] Executing state module.run for [maas.deploy_machines]
2019-11-13 05:42:17,298 [salt.utils.decorators:613 ][WARNING ][12480] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-13 05:42:17,939 [salt.state       :300 ][INFO    ][12480] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-11-13 05:42:17,940 [salt.state       :1951][INFO    ][12480] Completed state [maas.deploy_machines] at time 05:42:17.940398 duration_in_ms=643.098
2019-11-13 05:42:17,944 [salt.minion      :1711][INFO    ][12480] Returning information for job: 20191113054214927751
2019-11-13 05:42:18,569 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command state.apply with jid 20191113054218553809
2019-11-13 05:42:18,592 [salt.minion      :1432][INFO    ][12504] Starting a new job with PID 12504
2019-11-13 05:42:19,385 [salt.state       :915 ][INFO    ][12504] Loading fresh modules for state activity
2019-11-13 05:42:19,476 [salt.state       :1780][INFO    ][12504] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:42:19.476061
2019-11-13 05:42:19,476 [salt.state       :1813][INFO    ][12504] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-13 05:42:19,478 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12504] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-13 05:42:20,942 [salt.state       :300 ][INFO    ][12504] {'pid': 12511, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-13 05:42:20,943 [salt.state       :1951][INFO    ][12504] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:42:20.943290 duration_in_ms=1467.231
2019-11-13 05:42:20,944 [salt.state       :1780][INFO    ][12504] Running state [maas.wait_for_machine_status] at time 05:42:20.944858
2019-11-13 05:42:20,945 [salt.state       :1813][INFO    ][12504] Executing state module.run for [maas.wait_for_machine_status]
2019-11-13 05:42:20,945 [salt.utils.decorators:613 ][WARNING ][12504] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-13 05:42:24,434 [salt.state       :300 ][INFO    ][12504] {'ret': True}
2019-11-13 05:42:24,434 [salt.state       :1951][INFO    ][12504] Completed state [maas.wait_for_machine_status] at time 05:42:24.434544 duration_in_ms=3489.682
2019-11-13 05:42:24,438 [salt.minion      :1711][INFO    ][12504] Returning information for job: 20191113054218553809
2019-11-13 06:09:05,393 [salt.utils.schedule:1377][INFO    ][3200] Running scheduled job: __mine_interval
2019-11-13 07:09:05,393 [salt.utils.schedule:1377][INFO    ][3200] Running scheduled job: __mine_interval
2019-11-13 07:22:53,134 [salt.minion      :1308][INFO    ][3200] User sudo_ubuntu Executing command cp.push_dir with jid 20191113072253121052
2019-11-13 07:22:53,157 [salt.minion      :1432][INFO    ][19468] Starting a new job with PID 19468
