2019-11-26 22:23:42,274 [salt.utils.decorators:613 ][WARNING ][2131] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-26 22:23:42,851 [salt.utils.decorators:613 ][WARNING ][2131] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-26 22:23:44,839 [salt.loaded.int.states.file:2298][WARNING ][2352] 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-26 22:24:05,058 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2967] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-11-26 22:24:05,088 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2967] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-11-26 22:24:05,108 [salt.utils.parsers:1051][WARNING ][360] Minion received a SIGTERM. Exiting.
2019-11-26 22:24:06,059 [salt.cli.daemons :293 ][INFO    ][3081] Setting up the Salt Minion "mas01.mcp-odl-noha.local"
2019-11-26 22:24:06,136 [salt.cli.daemons :82  ][INFO    ][3081] Starting up the Salt Minion
2019-11-26 22:24:06,136 [salt.utils.event :1017][INFO    ][3081] Starting pull socket on /var/run/salt/minion/minion_event_0f3e0c7467_pull.ipc
2019-11-26 22:24:06,958 [salt.minion      :976 ][INFO    ][3081] Creating minion process manager
2019-11-26 22:24:08,212 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][3081] Executing command ['date', '+%z'] in directory '/root'
2019-11-26 22:24:08,247 [salt.utils.schedule:568 ][INFO    ][3081] Updating job settings for scheduled job: __mine_interval
2019-11-26 22:24:08,251 [salt.minion      :1108][INFO    ][3081] Added mine.update to scheduler
2019-11-26 22:24:08,258 [salt.minion      :1975][INFO    ][3081] Minion is starting as user 'root'
2019-11-26 22:24:08,272 [salt.minion      :2336][INFO    ][3081] Minion is ready to receive requests!
2019-11-26 22:24:10,436 [salt.state       :2022][WARNING ][2973] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-11-26 22:24:12,930 [salt.utils.decorators:613 ][WARNING ][2973] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-26 22:24:18,805 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126222418796085
2019-11-26 22:24:18,824 [salt.minion      :1432][INFO    ][3494] Starting a new job with PID 3494
2019-11-26 22:24:18,840 [salt.minion      :1711][INFO    ][3494] Returning information for job: 20191126222418796085
2019-11-26 22:24:44,508 [salt.utils.decorators:613 ][WARNING ][2973] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-26 22:24:48,867 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126222448853541
2019-11-26 22:24:48,893 [salt.minion      :1432][INFO    ][3821] Starting a new job with PID 3821
2019-11-26 22:24:48,909 [salt.minion      :1711][INFO    ][3821] Returning information for job: 20191126222448853541
2019-11-26 22:25:18,902 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126222518888752
2019-11-26 22:25:18,927 [salt.minion      :1432][INFO    ][3920] Starting a new job with PID 3920
2019-11-26 22:25:18,946 [salt.minion      :1711][INFO    ][3920] Returning information for job: 20191126222518888752
2019-11-26 22:25:24,231 [salt.utils.decorators:613 ][WARNING ][2973] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-26 22:25:25,608 [salt.utils.decorators:613 ][WARNING ][2973] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-26 22:25:29,564 [salt.loaded.ext.module.maasng:1008][WARNING ][2973] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-11-26 22:25:29,564 [salt.loaded.ext.module.maasng:1011][WARNING ][2973] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-11-26 22:25:30,379 [salt.loaded.ext.module.maasng:1235][WARNING ][2973] Ignoring parameter vlan:0
2019-11-26 22:25:31,387 [salt.utils.decorators:613 ][WARNING ][2973] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-26 22:25:32,048 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command state.apply with jid 20191126222532036913
2019-11-26 22:25:32,069 [salt.minion      :1432][INFO    ][4156] Starting a new job with PID 4156
2019-11-26 22:25:35,677 [salt.state       :915 ][INFO    ][4156] Loading fresh modules for state activity
2019-11-26 22:25:35,712 [salt.fileclient  :1219][INFO    ][4156] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-11-26 22:25:35,740 [salt.state       :1780][INFO    ][4156] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 22:25:35.740378
2019-11-26 22:25:35,740 [salt.state       :1813][INFO    ][4156] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-26 22:25:35,742 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4156] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-26 22:25:37,119 [salt.state       :300 ][INFO    ][4156] {'pid': 4183, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-26 22:25:37,120 [salt.state       :1951][INFO    ][4156] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 22:25:37.120208 duration_in_ms=1379.83
2019-11-26 22:25:37,121 [salt.state       :1780][INFO    ][4156] Running state [maas.process_machines] at time 22:25:37.121447
2019-11-26 22:25:37,121 [salt.state       :1813][INFO    ][4156] Executing state module.run for [maas.process_machines]
2019-11-26 22:25:37,121 [salt.utils.decorators:613 ][WARNING ][4156] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-26 22:25:37,188 [salt.loaded.ext.module.maas:412 ][WARNING ][4156] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-26 22:25:37,189 [salt.loaded.ext.module.maas:92  ][INFO    ][4156] machine hostname=gtw01 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-26 22:25:38,722 [salt.loaded.ext.module.maas:412 ][WARNING ][4156] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-26 22:25:38,723 [salt.loaded.ext.module.maas:92  ][INFO    ][4156] 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-26 22:25:40,087 [salt.loaded.ext.module.maas:412 ][WARNING ][4156] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-26 22:25:40,088 [salt.loaded.ext.module.maas:92  ][INFO    ][4156] machine hostname=odl01 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-26 22:25:41,554 [salt.loaded.ext.module.maas:412 ][WARNING ][4156] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-26 22:25:41,555 [salt.loaded.ext.module.maas:92  ][INFO    ][4156] 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-26 22:25:42,896 [salt.loaded.ext.module.maas:412 ][WARNING ][4156] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-26 22:25:42,896 [salt.loaded.ext.module.maas:92  ][INFO    ][4156] machine hostname=ctl01 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-26 22:25:44,303 [salt.state       :300 ][INFO    ][4156] {'ret': {'updated': [], 'errors': {}, 'success': ['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']}}
2019-11-26 22:25:44,304 [salt.state       :1951][INFO    ][4156] Completed state [maas.process_machines] at time 22:25:44.304341 duration_in_ms=7182.891
2019-11-26 22:25:44,316 [salt.minion      :1711][INFO    ][4156] Returning information for job: 20191126222532036913
2019-11-26 22:26:15,459 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command state.apply with jid 20191126222615443644
2019-11-26 22:26:15,483 [salt.minion      :1432][INFO    ][4512] Starting a new job with PID 4512
2019-11-26 22:26:19,247 [salt.state       :915 ][INFO    ][4512] Loading fresh modules for state activity
2019-11-26 22:26:19,299 [salt.fileclient  :1219][INFO    ][4512] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-11-26 22:26:19,340 [salt.state       :1780][INFO    ][4512] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 22:26:19.340530
2019-11-26 22:26:19,340 [salt.state       :1813][INFO    ][4512] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-26 22:26:19,342 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4512] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-26 22:26:20,638 [salt.state       :300 ][INFO    ][4512] {'pid': 4520, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-26 22:26:20,639 [salt.state       :1951][INFO    ][4512] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 22:26:20.639611 duration_in_ms=1299.081
2019-11-26 22:26:20,642 [salt.state       :1780][INFO    ][4512] Running state [maas.wait_for_machine_status] at time 22:26:20.641991
2019-11-26 22:26:20,642 [salt.state       :1813][INFO    ][4512] Executing state module.run for [maas.wait_for_machine_status]
2019-11-26 22:26:20,643 [salt.utils.decorators:613 ][WARNING ][4512] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-26 22:26:21,520 [salt.loaded.ext.module.maas:1023][INFO    ][4512] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1499.13270402s left)
2019-11-26 22:26:30,535 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126222630518886
2019-11-26 22:26:30,562 [salt.minion      :1432][INFO    ][4532] Starting a new job with PID 4532
2019-11-26 22:26:30,584 [salt.minion      :1711][INFO    ][4532] Returning information for job: 20191126222630518886
2019-11-26 22:26:52,485 [salt.loaded.ext.module.maas:1023][INFO    ][4512] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1468.16712618s left)
2019-11-26 22:27:00,591 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126222700578787
2019-11-26 22:27:00,616 [salt.minion      :1432][INFO    ][4567] Starting a new job with PID 4567
2019-11-26 22:27:00,638 [salt.minion      :1711][INFO    ][4567] Returning information for job: 20191126222700578787
2019-11-26 22:27:23,444 [salt.loaded.ext.module.maas:1023][INFO    ][4512] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1437.20865417s left)
2019-11-26 22:27:30,626 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126222730613708
2019-11-26 22:27:30,651 [salt.minion      :1432][INFO    ][4731] Starting a new job with PID 4731
2019-11-26 22:27:30,672 [salt.minion      :1711][INFO    ][4731] Returning information for job: 20191126222730613708
2019-11-26 22:27:54,734 [salt.loaded.ext.module.maas:1023][INFO    ][4512] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1405.91840196s left)
2019-11-26 22:28:00,681 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126222800668734
2019-11-26 22:28:00,706 [salt.minion      :1432][INFO    ][4857] Starting a new job with PID 4857
2019-11-26 22:28:00,728 [salt.minion      :1711][INFO    ][4857] Returning information for job: 20191126222800668734
2019-11-26 22:28:26,151 [salt.loaded.ext.module.maas:1023][INFO    ][4512] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1374.50183797s left)
2019-11-26 22:28:30,738 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126222830725992
2019-11-26 22:28:30,764 [salt.minion      :1432][INFO    ][5462] Starting a new job with PID 5462
2019-11-26 22:28:30,785 [salt.minion      :1711][INFO    ][5462] Returning information for job: 20191126222830725992
2019-11-26 22:28:57,723 [salt.loaded.ext.module.maas:1023][INFO    ][4512] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1342.92933702s left)
2019-11-26 22:29:00,795 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126222900781993
2019-11-26 22:29:00,819 [salt.minion      :1432][INFO    ][5636] Starting a new job with PID 5636
2019-11-26 22:29:00,841 [salt.minion      :1711][INFO    ][5636] Returning information for job: 20191126222900781993
2019-11-26 22:29:30,852 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126222930839542
2019-11-26 22:29:30,875 [salt.minion      :1432][INFO    ][5798] Starting a new job with PID 5798
2019-11-26 22:29:30,898 [salt.minion      :1711][INFO    ][5798] Returning information for job: 20191126222930839542
2019-11-26 22:29:31,174 [salt.state       :300 ][INFO    ][4512] {'ret': True}
2019-11-26 22:29:31,175 [salt.state       :1951][INFO    ][4512] Completed state [maas.wait_for_machine_status] at time 22:29:31.175215 duration_in_ms=190533.224
2019-11-26 22:29:31,178 [salt.minion      :1711][INFO    ][4512] Returning information for job: 20191126222615443644
2019-11-26 22:29:31,693 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command state.apply with jid 20191126222931675185
2019-11-26 22:29:31,715 [salt.minion      :1432][INFO    ][5805] Starting a new job with PID 5805
2019-11-26 22:29:35,294 [salt.state       :915 ][INFO    ][5805] Loading fresh modules for state activity
2019-11-26 22:29:35,345 [salt.fileclient  :1219][INFO    ][5805] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-11-26 22:29:35,434 [salt.state       :1780][INFO    ][5805] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 22:29:35.434568
2019-11-26 22:29:35,434 [salt.state       :1813][INFO    ][5805] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-26 22:29:35,436 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5805] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-26 22:29:36,846 [salt.state       :300 ][INFO    ][5805] {'pid': 5816, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-26 22:29:36,846 [salt.state       :1951][INFO    ][5805] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 22:29:36.846842 duration_in_ms=1412.274
2019-11-26 22:29:36,848 [salt.state       :1780][INFO    ][5805] Running state [maas_machines_storage_cmp002_lvm] at time 22:29:36.848266
2019-11-26 22:29:36,848 [salt.state       :1813][INFO    ][5805] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-11-26 22:29:38,160 [salt.loaded.ext.module.maasng:610 ][INFO    ][5805] bnt6wd
2019-11-26 22:29:38,160 [salt.loaded.ext.module.maasng:626 ][INFO    ][5805] sda
2019-11-26 22:29:38,599 [salt.loaded.ext.module.maasng:361 ][INFO    ][5805] bnt6wd
2019-11-26 22:29:38,727 [salt.loaded.ext.module.maasng:367 ][INFO    ][5805] [{u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/bnt6wd/blockdevices/4/', u'uuid': None, u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'used_size': 2397998940160, u'partitions': [{u'uuid': u'463315c4-8d0a-4057-a292-c41ff68ac993', u'resource_uri': u'/MAAS/api/2.0/nodes/bnt6wd/blockdevices/4/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'bnt6wd', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'6cc5a631-522f-44b3-a7ac-af2ae743b444', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 4, u'type': u'partition', u'id': 7, u'size': 2397992648704}], u'id': 4, u'system_id': u'bnt6wd', u'block_size': 4096, u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'path': u'/dev/disk/by-dname/sda', u'size': 2397998940160, u'type': u'physical', u'serial': u'618e728372755980239b15112698bc66', u'name': u'sda'}, {u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/bnt6wd/blockdevices/12/', u'uuid': u'317b7e05-1037-4e63-81a7-2b0238193241', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 2397988454400, u'partitions': [], u'id': 12, u'system_id': u'bnt6wd', u'block_size': 4096, u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'c3aa9767-73bd-4088-98c1-914cf0bbaa64', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'path': u'/dev/disk/by-dname/lvroot', u'size': 2397988454400, u'type': u'virtual', u'serial': None, u'name': u'vgroot-lvroot'}]
2019-11-26 22:29:38,728 [salt.loaded.ext.module.maasng:632 ][INFO    ][5805] vgroot
2019-11-26 22:29:38,729 [salt.loaded.ext.module.maasng:635 ][INFO    ][5805] lvroot
2019-11-26 22:29:38,729 [salt.loaded.ext.module.maasng:639 ][INFO    ][5805] 107374182400
2019-11-26 22:29:39,583 [salt.loaded.ext.module.maasng:645 ][INFO    ][5805] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'ip_addresses': [u'192.168.11.42'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'boot_interface': {u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'bs347y', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'bs347y', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'mode': u'dhcp', u'id': 43}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'bs347y', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'parents': [], u'mac_address': u'00:25:b5:a0:00:6a', u'params': u'', u'system_id': u'bnt6wd', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/bnt6wd/interfaces/5/'}, u'fqdn': u'cmp002.maas', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'blockdevice_set': [{u'size': 2397998940160, u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'bnt6wd', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/bnt6wd/blockdevices/4/', u'used_size': 2397998940160, u'id': 4, u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'uuid': u'145e278e-d8cc-4448-82f0-566a8df97ead', u'resource_uri': u'/MAAS/api/2.0/nodes/bnt6wd/blockdevices/4/partition/10', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'bnt6wd', u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'0d3cc85b-7c21-4cda-a803-c9ddf8ac0236', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 4, u'type': u'partition', u'id': 10, u'size': 2397992648704}]}, {u'size': 107374182400, u'block_size': 4096, u'uuid': u'c086bbf4-efee-48ae-a0dd-d1528caee261', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'bnt6wd', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'uuid': u'f4abecdd-3121-4994-8689-a2e23ebf483f', u'fstype': u'ext4', u'mount_options': None}, u'id_path': None, u'available_size': 0, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/bnt6wd/blockdevices/15/', u'used_size': 107374182400, u'id': 15, u'serial': None, u'partitions': []}], u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/bnt6wd/', u'current_commissioning_result_id': 4, u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'bnt6wd', u'raids': [], u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u"From 'Testing' to 'Ready'", u'virtualblockdevice_set': [{u'size': 107374182400, u'block_size': 4096, u'uuid': u'c086bbf4-efee-48ae-a0dd-d1528caee261', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'filesystem': {u'label': u'root', u'mount_point': u'/', u'uuid': u'f4abecdd-3121-4994-8689-a2e23ebf483f', u'fstype': u'ext4', u'mount_options': None}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'bnt6wd', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/bnt6wd/blockdevices/15/', u'used_size': 107374182400, u'id': 15, u'serial': None, u'partitions': []}], u'min_hwe_kernel': u'hwe-16.04', u'status': 4, u'bcaches': [], u'cpu_count': 16, u'power_state': u'on', u'owner_data': {}, u'memory_test_status_name': u'Unknown', u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'bnt6wd', u'id': 10}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'node_type_name': u'Machine', u'current_testing_result_id': 5, u'cpu_test_status': -1, u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 2397998940160, u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'bnt6wd', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/bnt6wd/blockdevices/4/', u'used_size': 2397998940160, u'id': 4, u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'uuid': u'145e278e-d8cc-4448-82f0-566a8df97ead', u'resource_uri': u'/MAAS/api/2.0/nodes/bnt6wd/blockdevices/4/partition/10', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'bnt6wd', u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'0d3cc85b-7c21-4cda-a803-c9ddf8ac0236', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 4, u'type': u'partition', u'id': 10, u'size': 2397992648704}]}], u'netboot': True, u'osystem': u'', u'node_type': 0, u'disable_ipv4': False, u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'interface_set': [{u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'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'bs347y', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'bs347y', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'mode': u'dhcp', u'id': 43}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'bs347y', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'parents': [], u'mac_address': u'00:25:b5:a0:00:6a', u'params': u'', u'system_id': u'bnt6wd', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/bnt6wd/interfaces/5/'}, {u'discovered': None, u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 44}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'parents': [], u'mac_address': u'00:25:b5:a0:00:6b', u'params': u'', u'system_id': u'bnt6wd', u'type': u'physical', u'id': 9, u'resource_uri': u'/MAAS/api/2.0/nodes/bnt6wd/interfaces/9/'}, {u'discovered': None, u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 46}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'parents': [], u'mac_address': u'00:25:b5:a0:00:6c', u'params': u'', u'system_id': u'bnt6wd', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/bnt6wd/interfaces/12/'}, {u'discovered': None, u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 49}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'parents': [], u'mac_address': u'00:25:b5:a0:00:6d', u'params': u'', u'system_id': u'bnt6wd', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/bnt6wd/interfaces/13/'}], u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'boot_disk': {u'size': 2397998940160, u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'bnt6wd', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/bnt6wd/blockdevices/4/', u'used_size': 2397998940160, u'id': 4, u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'uuid': u'145e278e-d8cc-4448-82f0-566a8df97ead', u'resource_uri': u'/MAAS/api/2.0/nodes/bnt6wd/blockdevices/4/partition/10', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'bnt6wd', u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'0d3cc85b-7c21-4cda-a803-c9ddf8ac0236', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 4, u'type': u'partition', u'id': 10, u'size': 2397992648704}]}}
2019-11-26 22:29:39,586 [salt.state       :300 ][INFO    ][5805] {'new': {'storage_layout': 'lvm'}}
2019-11-26 22:29:39,586 [salt.state       :1951][INFO    ][5805] Completed state [maas_machines_storage_cmp002_lvm] at time 22:29:39.586392 duration_in_ms=2738.124
2019-11-26 22:29:39,587 [salt.state       :1780][INFO    ][5805] Running state [maas_machines_storage_cmp001_lvm] at time 22:29:39.586994
2019-11-26 22:29:39,587 [salt.state       :1813][INFO    ][5805] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-11-26 22:29:40,988 [salt.loaded.ext.module.maasng:610 ][INFO    ][5805] hm6xsh
2019-11-26 22:29:40,988 [salt.loaded.ext.module.maasng:626 ][INFO    ][5805] sda
2019-11-26 22:29:41,726 [salt.loaded.ext.module.maasng:361 ][INFO    ][5805] hm6xsh
2019-11-26 22:29:41,845 [salt.loaded.ext.module.maasng:367 ][INFO    ][5805] [{u'size': 2397998940160, u'block_size': 4096, u'uuid': None, u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'type': u'physical', u'filesystem': None, u'name': u'sda', u'system_id': u'hm6xsh', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/hm6xsh/blockdevices/1/', u'used_size': 2397998940160, u'id': 1, u'serial': u'618e72837274f1901cc7889705aa1b02', u'partitions': [{u'uuid': u'ca163ed3-6a6a-4e4f-84ec-9bcd199bba10', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hm6xsh', u'device_id': 1, u'filesystem': {u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'uuid': u'09a44e33-a836-4655-a0cf-6d6d16b1245e'}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 9, u'resource_uri': u'/MAAS/api/2.0/nodes/hm6xsh/blockdevices/1/partition/9'}]}, {u'size': 2397988454400, u'block_size': 4096, u'uuid': u'ea9db977-79a1-4373-a0e1-94b1c531bdb0', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'filesystem': {u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'uuid': u'f9ca6400-416e-4041-8399-7a69772da159'}, u'name': u'vgroot-lvroot', u'system_id': u'hm6xsh', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/hm6xsh/blockdevices/14/', u'used_size': 2397988454400, u'id': 14, u'serial': None, u'partitions': []}]
2019-11-26 22:29:41,846 [salt.loaded.ext.module.maasng:632 ][INFO    ][5805] vgroot
2019-11-26 22:29:41,846 [salt.loaded.ext.module.maasng:635 ][INFO    ][5805] lvroot
2019-11-26 22:29:41,846 [salt.loaded.ext.module.maasng:639 ][INFO    ][5805] 107374182400
2019-11-26 22:29:42,585 [salt.loaded.ext.module.maasng:645 ][INFO    ][5805] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'testing_status_name': u'Passed', u'memory_test_status': -1, u'disable_ipv4': False, u'cpu_count': 16, u'power_type': u'ipmi', u'hwe_kernel': u'', 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'bs347y', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40', u'id': 48, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'bs347y', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'id': 7, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'bs347y', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40'}], u'mac_address': u'00:25:b5:a0:00:5a', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'hm6xsh', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/hm6xsh/interfaces/7/'}, u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'blockdevice_set': [{u'size': 2397998940160, u'resource_uri': u'/MAAS/api/2.0/nodes/hm6xsh/blockdevices/1/', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'uuid': None, u'tags': [u'rotary'], u'used_size': 2397998940160, u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'hm6xsh', u'partition_table_type': u'GPT', u'filesystem': None, u'block_size': 4096, u'available_size': 0, u'model': u'UCSB-MRAID12G', u'partitions': [{u'size': 2397992648704, u'uuid': u'610e2bd7-8420-4df9-b23e-2872868379d1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hm6xsh', u'filesystem': {u'mount_options': None, u'uuid': u'788839ad-885d-4efa-ae57-3cdae314ccd9', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 1, u'type': u'partition', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/hm6xsh/blockdevices/1/partition/11'}], u'type': u'physical', u'id': 1, u'serial': u'618e72837274f1901cc7889705aa1b02', u'name': u'sda'}, {u'size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/hm6xsh/blockdevices/16/', u'id_path': None, u'uuid': u'35542213-bd6f-4a22-9bb6-1478f38719ef', u'tags': [], u'used_size': 107374182400, u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'hm6xsh', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'd4f3c022-d52a-4377-827c-8547d1bbc3f5', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, u'block_size': 4096, u'available_size': 0, u'model': None, u'partitions': [], u'type': u'virtual', u'id': 16, u'serial': None, u'name': u'vgroot-lvroot'}], u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'description': u''}, 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'hm6xsh', u'power_state': u'on', u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u"From 'Testing' to 'Ready'", u'ip_addresses': [u'192.168.11.40'], u'min_hwe_kernel': u'hwe-16.04', u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'hm6xsh', u'id': 11}], u'special_filesystems': [], u'current_commissioning_result_id': 8, u'boot_disk': {u'resource_uri': u'/MAAS/api/2.0/nodes/hm6xsh/blockdevices/1/', u'uuid': None, u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'size': 2397992648704, u'uuid': u'610e2bd7-8420-4df9-b23e-2872868379d1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hm6xsh', u'filesystem': {u'mount_options': None, u'uuid': u'788839ad-885d-4efa-ae57-3cdae314ccd9', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 1, u'type': u'partition', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/hm6xsh/blockdevices/1/partition/11'}], u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'hm6xsh', u'block_size': 4096, u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'size': 2397998940160, u'type': u'physical', u'id': 1, u'serial': u'618e72837274f1901cc7889705aa1b02', u'name': u'sda'}, 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'bs347y', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40', u'id': 48, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'bs347y', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'id': 7, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'bs347y', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40'}], u'mac_address': u'00:25:b5:a0:00:5a', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'hm6xsh', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/hm6xsh/interfaces/7/'}, {u'name': u'enp7s0', u'links': [{u'id': 50, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'id': 16, u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5b', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'hm6xsh', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/hm6xsh/interfaces/16/'}, {u'name': u'enp8s0', u'links': [{u'id': 53, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'id': 22, u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5c', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'hm6xsh', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/hm6xsh/interfaces/22/'}, {u'name': u'enp9s0', u'links': [{u'id': 54, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'id': 23, u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5d', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'hm6xsh', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/hm6xsh/interfaces/23/'}], u'current_testing_result_id': 9, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/hm6xsh/blockdevices/1/', u'uuid': None, u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'size': 2397992648704, u'uuid': u'610e2bd7-8420-4df9-b23e-2872868379d1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hm6xsh', u'filesystem': {u'mount_options': None, u'uuid': u'788839ad-885d-4efa-ae57-3cdae314ccd9', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 1, u'type': u'partition', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/hm6xsh/blockdevices/1/partition/11'}], u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'hm6xsh', u'block_size': 4096, u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'size': 2397998940160, u'type': u'physical', u'id': 1, u'serial': u'618e72837274f1901cc7889705aa1b02', u'name': u'sda'}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/hm6xsh/blockdevices/16/', u'uuid': u'35542213-bd6f-4a22-9bb6-1478f38719ef', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'hm6xsh', u'block_size': 4096, u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'd4f3c022-d52a-4377-827c-8547d1bbc3f5', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'size': 107374182400, u'type': u'virtual', u'id': 16, u'serial': None, u'name': u'vgroot-lvroot'}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/hm6xsh/'}
2019-11-26 22:29:42,587 [salt.state       :300 ][INFO    ][5805] {'new': {'storage_layout': 'lvm'}}
2019-11-26 22:29:42,588 [salt.state       :1951][INFO    ][5805] Completed state [maas_machines_storage_cmp001_lvm] at time 22:29:42.588180 duration_in_ms=3001.185
2019-11-26 22:29:42,592 [salt.minion      :1711][INFO    ][5805] Returning information for job: 20191126222931675185
2019-11-26 22:29:43,108 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command state.apply with jid 20191126222943100537
2019-11-26 22:29:43,123 [salt.minion      :1432][INFO    ][5900] Starting a new job with PID 5900
2019-11-26 22:29:43,814 [salt.state       :915 ][INFO    ][5900] Loading fresh modules for state activity
2019-11-26 22:29:43,866 [salt.fileclient  :1219][INFO    ][5900] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-11-26 22:29:43,911 [salt.state       :1780][INFO    ][5900] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 22:29:43.910943
2019-11-26 22:29:43,911 [salt.state       :1813][INFO    ][5900] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-26 22:29:43,913 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5900] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-26 22:29:45,248 [salt.state       :300 ][INFO    ][5900] {'pid': 5907, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-26 22:29:45,249 [salt.state       :1951][INFO    ][5900] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 22:29:45.249666 duration_in_ms=1338.722
2019-11-26 22:29:45,252 [salt.state       :1780][INFO    ][5900] Running state [maas.deploy_machines] at time 22:29:45.252059
2019-11-26 22:29:45,252 [salt.state       :1813][INFO    ][5900] Executing state module.run for [maas.deploy_machines]
2019-11-26 22:29:45,253 [salt.utils.decorators:613 ][WARNING ][5900] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-26 22:29:46,004 [salt.loaded.ext.module.maas:684 ][INFO    ][5900] deploymachines hwe_kernel=hwe-16.04 system_id=c3c7f8 distro_series=xenial
2019-11-26 22:29:48,542 [salt.loaded.ext.module.maas:684 ][INFO    ][5900] deploymachines hwe_kernel=hwe-16.04 system_id=bnt6wd distro_series=xenial
2019-11-26 22:29:51,225 [salt.loaded.ext.module.maas:684 ][INFO    ][5900] deploymachines hwe_kernel=hwe-16.04 system_id=gd8ca6 distro_series=xenial
2019-11-26 22:29:53,980 [salt.loaded.ext.module.maas:684 ][INFO    ][5900] deploymachines hwe_kernel=hwe-16.04 system_id=hm6xsh distro_series=xenial
2019-11-26 22:29:56,736 [salt.loaded.ext.module.maas:684 ][INFO    ][5900] deploymachines hwe_kernel=hwe-16.04 system_id=eqcqsp distro_series=xenial
2019-11-26 22:29:58,199 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126222958185970
2019-11-26 22:29:58,224 [salt.minion      :1432][INFO    ][6158] Starting a new job with PID 6158
2019-11-26 22:29:58,248 [salt.minion      :1711][INFO    ][6158] Returning information for job: 20191126222958185970
2019-11-26 22:29:59,503 [salt.state       :300 ][INFO    ][5900] {'ret': {'updated': [], 'errors': {}, 'success': ['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']}}
2019-11-26 22:29:59,503 [salt.state       :1951][INFO    ][5900] Completed state [maas.deploy_machines] at time 22:29:59.503603 duration_in_ms=14251.543
2019-11-26 22:29:59,507 [salt.minion      :1711][INFO    ][5900] Returning information for job: 20191126222943100537
2019-11-26 22:30:00,172 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command state.apply with jid 20191126223000156011
2019-11-26 22:30:00,198 [salt.minion      :1432][INFO    ][6175] Starting a new job with PID 6175
2019-11-26 22:30:03,981 [salt.state       :915 ][INFO    ][6175] Loading fresh modules for state activity
2019-11-26 22:30:04,033 [salt.fileclient  :1219][INFO    ][6175] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-11-26 22:30:04,074 [salt.state       :1780][INFO    ][6175] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 22:30:04.074004
2019-11-26 22:30:04,074 [salt.state       :1813][INFO    ][6175] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-26 22:30:04,076 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6175] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-26 22:30:05,486 [salt.state       :300 ][INFO    ][6175] {'pid': 6190, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-26 22:30:05,486 [salt.state       :1951][INFO    ][6175] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 22:30:05.486884 duration_in_ms=1412.88
2019-11-26 22:30:05,488 [salt.state       :1780][INFO    ][6175] Running state [maas.wait_for_machine_status] at time 22:30:05.488305
2019-11-26 22:30:05,488 [salt.state       :1813][INFO    ][6175] Executing state module.run for [maas.wait_for_machine_status]
2019-11-26 22:30:05,488 [salt.utils.decorators:613 ][WARNING ][6175] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-26 22:30:09,251 [salt.loaded.ext.module.maas:1023][INFO    ][6175] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2246.24114394s left)
2019-11-26 22:30:15,277 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126223015261035
2019-11-26 22:30:15,302 [salt.minion      :1432][INFO    ][6230] Starting a new job with PID 6230
2019-11-26 22:30:15,325 [salt.minion      :1711][INFO    ][6230] Returning information for job: 20191126223015261035
2019-11-26 22:30:42,703 [salt.loaded.ext.module.maas:1023][INFO    ][6175] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2212.78933096s left)
2019-11-26 22:30:45,338 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126223045325064
2019-11-26 22:30:45,363 [salt.minion      :1432][INFO    ][6266] Starting a new job with PID 6266
2019-11-26 22:30:45,386 [salt.minion      :1711][INFO    ][6266] Returning information for job: 20191126223045325064
2019-11-26 22:31:15,385 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126223115373605
2019-11-26 22:31:15,412 [salt.minion      :1432][INFO    ][6305] Starting a new job with PID 6305
2019-11-26 22:31:15,433 [salt.minion      :1711][INFO    ][6305] Returning information for job: 20191126223115373605
2019-11-26 22:31:16,166 [salt.loaded.ext.module.maas:1023][INFO    ][6175] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2179.32676387s left)
2019-11-26 22:31:45,431 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126223145420102
2019-11-26 22:31:45,453 [salt.minion      :1432][INFO    ][6457] Starting a new job with PID 6457
2019-11-26 22:31:45,475 [salt.minion      :1711][INFO    ][6457] Returning information for job: 20191126223145420102
2019-11-26 22:31:49,219 [salt.loaded.ext.module.maas:1023][INFO    ][6175] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2146.2735498s left)
2019-11-26 22:32:15,484 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126223215473392
2019-11-26 22:32:15,506 [salt.minion      :1432][INFO    ][6828] Starting a new job with PID 6828
2019-11-26 22:32:15,527 [salt.minion      :1711][INFO    ][6828] Returning information for job: 20191126223215473392
2019-11-26 22:32:22,217 [salt.loaded.ext.module.maas:1023][INFO    ][6175] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2113.27520585s left)
2019-11-26 22:32:45,538 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126223245525314
2019-11-26 22:32:45,559 [salt.minion      :1432][INFO    ][7172] Starting a new job with PID 7172
2019-11-26 22:32:45,579 [salt.minion      :1711][INFO    ][7172] Returning information for job: 20191126223245525314
2019-11-26 22:32:55,440 [salt.loaded.ext.module.maas:1023][INFO    ][6175] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2080.05215502s left)
2019-11-26 22:33:15,591 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126223315578620
2019-11-26 22:33:15,614 [salt.minion      :1432][INFO    ][7484] Starting a new job with PID 7484
2019-11-26 22:33:15,637 [salt.minion      :1711][INFO    ][7484] Returning information for job: 20191126223315578620
2019-11-26 22:33:29,147 [salt.loaded.ext.module.maas:1023][INFO    ][6175] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2046.34569097s left)
2019-11-26 22:33:45,654 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126223345641190
2019-11-26 22:33:45,677 [salt.minion      :1432][INFO    ][7558] Starting a new job with PID 7558
2019-11-26 22:33:45,699 [salt.minion      :1711][INFO    ][7558] Returning information for job: 20191126223345641190
2019-11-26 22:34:02,776 [salt.loaded.ext.module.maas:1023][INFO    ][6175] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2012.71687198s left)
2019-11-26 22:34:15,719 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126223415706667
2019-11-26 22:34:15,742 [salt.minion      :1432][INFO    ][7804] Starting a new job with PID 7804
2019-11-26 22:34:15,762 [salt.minion      :1711][INFO    ][7804] Returning information for job: 20191126223415706667
2019-11-26 22:34:36,405 [salt.loaded.ext.module.maas:1023][INFO    ][6175] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1979.08710384s left)
2019-11-26 22:34:45,791 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126223445775356
2019-11-26 22:34:45,816 [salt.minion      :1432][INFO    ][8007] Starting a new job with PID 8007
2019-11-26 22:34:45,837 [salt.minion      :1711][INFO    ][8007] Returning information for job: 20191126223445775356
2019-11-26 22:35:10,048 [salt.loaded.ext.module.maas:1023][INFO    ][6175] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1945.44414687s left)
2019-11-26 22:35:15,868 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126223515854925
2019-11-26 22:35:15,891 [salt.minion      :1432][INFO    ][8360] Starting a new job with PID 8360
2019-11-26 22:35:15,911 [salt.minion      :1711][INFO    ][8360] Returning information for job: 20191126223515854925
2019-11-26 22:35:43,511 [salt.loaded.ext.module.maas:1023][INFO    ][6175] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1911.9812429s left)
2019-11-26 22:35:45,948 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126223545935050
2019-11-26 22:35:45,972 [salt.minion      :1432][INFO    ][8480] Starting a new job with PID 8480
2019-11-26 22:35:45,993 [salt.minion      :1711][INFO    ][8480] Returning information for job: 20191126223545935050
2019-11-26 22:36:16,029 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126223616016564
2019-11-26 22:36:16,049 [salt.minion      :1432][INFO    ][8695] Starting a new job with PID 8695
2019-11-26 22:36:16,073 [salt.minion      :1711][INFO    ][8695] Returning information for job: 20191126223616016564
2019-11-26 22:36:16,393 [salt.loaded.ext.module.maas:1023][INFO    ][6175] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1879.09984279s left)
2019-11-26 22:36:46,113 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126223646100717
2019-11-26 22:36:46,136 [salt.minion      :1432][INFO    ][8724] Starting a new job with PID 8724
2019-11-26 22:36:46,158 [salt.minion      :1711][INFO    ][8724] Returning information for job: 20191126223646100717
2019-11-26 22:36:49,782 [salt.loaded.ext.module.maas:1023][INFO    ][6175] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1845.71084595s left)
2019-11-26 22:37:16,205 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126223716197280
2019-11-26 22:37:16,226 [salt.minion      :1432][INFO    ][8869] Starting a new job with PID 8869
2019-11-26 22:37:16,246 [salt.minion      :1711][INFO    ][8869] Returning information for job: 20191126223716197280
2019-11-26 22:37:22,818 [salt.loaded.ext.module.maas:1023][INFO    ][6175] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'ctl01']
sleep for:30s Timeout:2250s (1812.67446899s left)
2019-11-26 22:37:46,398 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126223746385239
2019-11-26 22:37:46,421 [salt.minion      :1432][INFO    ][9101] Starting a new job with PID 9101
2019-11-26 22:37:46,443 [salt.minion      :1711][INFO    ][9101] Returning information for job: 20191126223746385239
2019-11-26 22:37:56,344 [salt.loaded.ext.module.maas:1023][INFO    ][6175] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1779.14872599s left)
2019-11-26 22:38:16,505 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126223816492814
2019-11-26 22:38:16,527 [salt.minion      :1432][INFO    ][9506] Starting a new job with PID 9506
2019-11-26 22:38:16,549 [salt.minion      :1711][INFO    ][9506] Returning information for job: 20191126223816492814
2019-11-26 22:38:29,991 [salt.loaded.ext.module.maas:1023][INFO    ][6175] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1745.50128198s left)
2019-11-26 22:38:46,618 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126223846604965
2019-11-26 22:38:46,642 [salt.minion      :1432][INFO    ][9555] Starting a new job with PID 9555
2019-11-26 22:38:46,664 [salt.minion      :1711][INFO    ][9555] Returning information for job: 20191126223846604965
2019-11-26 22:39:03,536 [salt.loaded.ext.module.maas:1023][INFO    ][6175] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1711.95692682s left)
2019-11-26 22:39:16,731 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126223916719300
2019-11-26 22:39:16,755 [salt.minion      :1432][INFO    ][9595] Starting a new job with PID 9595
2019-11-26 22:39:16,778 [salt.minion      :1711][INFO    ][9595] Returning information for job: 20191126223916719300
2019-11-26 22:39:37,172 [salt.loaded.ext.module.maas:1023][INFO    ][6175] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1678.32085085s left)
2019-11-26 22:39:46,858 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126223946845420
2019-11-26 22:39:46,882 [salt.minion      :1432][INFO    ][9631] Starting a new job with PID 9631
2019-11-26 22:39:46,904 [salt.minion      :1711][INFO    ][9631] Returning information for job: 20191126223946845420
2019-11-26 22:40:10,788 [salt.loaded.ext.module.maas:1023][INFO    ][6175] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1644.70471096s left)
2019-11-26 22:40:16,994 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126224016982181
2019-11-26 22:40:17,017 [salt.minion      :1432][INFO    ][9671] Starting a new job with PID 9671
2019-11-26 22:40:17,041 [salt.minion      :1711][INFO    ][9671] Returning information for job: 20191126224016982181
2019-11-26 22:40:43,825 [salt.loaded.ext.module.maas:1023][INFO    ][6175] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1611.66769791s left)
2019-11-26 22:40:47,140 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126224047127289
2019-11-26 22:40:47,164 [salt.minion      :1432][INFO    ][9706] Starting a new job with PID 9706
2019-11-26 22:40:47,186 [salt.minion      :1711][INFO    ][9706] Returning information for job: 20191126224047127289
2019-11-26 22:41:17,296 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126224117285011
2019-11-26 22:41:17,316 [salt.minion      :1432][INFO    ][9747] Starting a new job with PID 9747
2019-11-26 22:41:17,316 [salt.loaded.ext.module.maas:1023][INFO    ][6175] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1578.17707682s left)
2019-11-26 22:41:17,338 [salt.minion      :1711][INFO    ][9747] Returning information for job: 20191126224117285011
2019-11-26 22:41:47,450 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126224147437432
2019-11-26 22:41:47,473 [salt.minion      :1432][INFO    ][9792] Starting a new job with PID 9792
2019-11-26 22:41:47,495 [salt.minion      :1711][INFO    ][9792] Returning information for job: 20191126224147437432
2019-11-26 22:41:50,885 [salt.loaded.ext.module.maas:1023][INFO    ][6175] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1544.60745692s left)
2019-11-26 22:42:17,624 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126224217608730
2019-11-26 22:42:17,649 [salt.minion      :1432][INFO    ][9832] Starting a new job with PID 9832
2019-11-26 22:42:17,670 [salt.minion      :1711][INFO    ][9832] Returning information for job: 20191126224217608730
2019-11-26 22:42:24,328 [salt.loaded.ext.module.maas:1023][INFO    ][6175] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1511.16439795s left)
2019-11-26 22:42:47,803 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126224247790039
2019-11-26 22:42:47,827 [salt.minion      :1432][INFO    ][9870] Starting a new job with PID 9870
2019-11-26 22:42:47,850 [salt.minion      :1711][INFO    ][9870] Returning information for job: 20191126224247790039
2019-11-26 22:42:57,550 [salt.loaded.ext.module.maas:1023][INFO    ][6175] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1477.94264698s left)
2019-11-26 22:43:17,992 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126224317978997
2019-11-26 22:43:18,016 [salt.minion      :1432][INFO    ][9911] Starting a new job with PID 9911
2019-11-26 22:43:18,037 [salt.minion      :1711][INFO    ][9911] Returning information for job: 20191126224317978997
2019-11-26 22:43:30,570 [salt.loaded.ext.module.maas:1023][INFO    ][6175] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1444.92227793s left)
2019-11-26 22:43:48,189 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126224348177233
2019-11-26 22:43:48,214 [salt.minion      :1432][INFO    ][9947] Starting a new job with PID 9947
2019-11-26 22:43:48,235 [salt.minion      :1711][INFO    ][9947] Returning information for job: 20191126224348177233
2019-11-26 22:44:04,063 [salt.loaded.ext.module.maas:1023][INFO    ][6175] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1411.4296329s left)
2019-11-26 22:44:18,402 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126224418389148
2019-11-26 22:44:18,425 [salt.minion      :1432][INFO    ][10126] Starting a new job with PID 10126
2019-11-26 22:44:18,449 [salt.minion      :1711][INFO    ][10126] Returning information for job: 20191126224418389148
2019-11-26 22:44:37,588 [salt.loaded.ext.module.maas:1023][INFO    ][6175] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1377.90457702s left)
2019-11-26 22:44:48,622 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126224448609980
2019-11-26 22:44:48,647 [salt.minion      :1432][INFO    ][10172] Starting a new job with PID 10172
2019-11-26 22:44:48,668 [salt.minion      :1711][INFO    ][10172] Returning information for job: 20191126224448609980
2019-11-26 22:45:11,147 [salt.loaded.ext.module.maas:1023][INFO    ][6175] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1344.3451159s left)
2019-11-26 22:45:18,645 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126224518631666
2019-11-26 22:45:18,669 [salt.minion      :1432][INFO    ][10217] Starting a new job with PID 10217
2019-11-26 22:45:18,692 [salt.minion      :1711][INFO    ][10217] Returning information for job: 20191126224518631666
2019-11-26 22:45:41,893 [salt.loaded.ext.module.maas:993 ][INFO    ][6175] Machine c3c7f8 mark broken
2019-11-26 22:45:42,656 [salt.loaded.ext.module.maas:996 ][INFO    ][6175] Machine c3c7f8 mark fixed
2019-11-26 22:45:43,856 [salt.loaded.ext.module.maas:684 ][INFO    ][6175] deploymachines hwe_kernel=hwe-16.04 system_id=c3c7f8 distro_series=xenial
2019-11-26 22:45:46,628 [salt.loaded.ext.module.maas:160 ][ERROR   ][6175] Failed for object gtw01 reason Unable to change power state to 'cycle' for node gtw01: another action is already in progress for that node.
2019-11-26 22:45:46,630 [salt.state       :302 ][ERROR   ][6175] Module function maas.wait_for_machine_status threw an exception. Exception: {'updated': ['cmp002', 'odl01', 'cmp001', 'ctl01'], 'errors': {'gtw01': "Unable to change power state to 'cycle' for node gtw01: another action is already in progress for that node."}, 'success': []}
2019-11-26 22:45:46,631 [salt.state       :1951][INFO    ][6175] Completed state [maas.wait_for_machine_status] at time 22:45:46.630934 duration_in_ms=941142.622
2019-11-26 22:45:46,641 [salt.minion      :1711][INFO    ][6175] Returning information for job: 20191126223000156011
2019-11-26 22:45:57,442 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command pillar.get with jid 20191126224557429805
2019-11-26 22:45:57,467 [salt.minion      :1432][INFO    ][10314] Starting a new job with PID 10314
2019-11-26 22:45:57,476 [salt.minion      :1711][INFO    ][10314] Returning information for job: 20191126224557429805
2019-11-26 22:45:57,972 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command service.status with jid 20191126224557959583
2019-11-26 22:45:57,994 [salt.minion      :1432][INFO    ][10319] Starting a new job with PID 10319
2019-11-26 22:45:58,379 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][10319] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-11-26 22:45:58,412 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][10319] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-11-26 22:45:58,428 [salt.minion      :1711][INFO    ][10319] Returning information for job: 20191126224557959583
2019-11-26 22:45:58,963 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command state.apply with jid 20191126224558955762
2019-11-26 22:45:58,985 [salt.minion      :1432][INFO    ][10337] Starting a new job with PID 10337
2019-11-26 22:46:02,419 [salt.state       :915 ][INFO    ][10337] Loading fresh modules for state activity
2019-11-26 22:46:02,860 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10337] Executing command 'salt-minion --version' in directory '/root'
2019-11-26 22:46:03,160 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10337] Executing command 'salt-minion --version' in directory '/root'
2019-11-26 22:46:04,009 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10337] Executing command 'salt-minion --version' in directory '/root'
2019-11-26 22:46:04,329 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10337] Executing command 'salt-minion --version' in directory '/root'
2019-11-26 22:46:05,630 [salt.state       :1780][INFO    ][10337] Running state [salt-minion] at time 22:46:05.630555
2019-11-26 22:46:05,630 [salt.state       :1813][INFO    ][10337] Executing state pkg.installed for [salt-minion]
2019-11-26 22:46:05,631 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10337] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-11-26 22:46:05,711 [salt.state       :300 ][INFO    ][10337] All specified packages are already installed
2019-11-26 22:46:05,711 [salt.state       :1951][INFO    ][10337] Completed state [salt-minion] at time 22:46:05.711569 duration_in_ms=81.013
2019-11-26 22:46:05,711 [salt.state       :1780][INFO    ][10337] Running state [salt_minion_dependency_packages] at time 22:46:05.711843
2019-11-26 22:46:05,712 [salt.state       :1813][INFO    ][10337] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-11-26 22:46:05,716 [salt.state       :300 ][INFO    ][10337] All specified packages are already installed
2019-11-26 22:46:05,717 [salt.state       :1951][INFO    ][10337] Completed state [salt_minion_dependency_packages] at time 22:46:05.717017 duration_in_ms=5.174
2019-11-26 22:46:05,719 [salt.state       :1780][INFO    ][10337] Running state [/etc/salt/minion.d/minion.conf] at time 22:46:05.719314
2019-11-26 22:46:05,719 [salt.state       :1813][INFO    ][10337] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-11-26 22:46:05,908 [salt.state       :300 ][INFO    ][10337] File /etc/salt/minion.d/minion.conf is in the correct state
2019-11-26 22:46:05,908 [salt.state       :1951][INFO    ][10337] Completed state [/etc/salt/minion.d/minion.conf] at time 22:46:05.908821 duration_in_ms=189.507
2019-11-26 22:46:05,911 [salt.state       :1780][INFO    ][10337] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 22:46:05.911757
2019-11-26 22:46:05,912 [salt.state       :1813][INFO    ][10337] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-11-26 22:46:05,923 [salt.state       :300 ][INFO    ][10337] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-11-26 22:46:05,923 [salt.state       :1951][INFO    ][10337] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 22:46:05.923333 duration_in_ms=11.576
2019-11-26 22:46:05,924 [salt.state       :1780][INFO    ][10337] Running state [salt-minion] at time 22:46:05.924248
2019-11-26 22:46:05,924 [salt.state       :1813][INFO    ][10337] Executing state service.running for [salt-minion]
2019-11-26 22:46:05,925 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10337] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-11-26 22:46:05,961 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10337] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-11-26 22:46:05,978 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10337] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-11-26 22:46:05,996 [salt.state       :300 ][INFO    ][10337] The service salt-minion is already running
2019-11-26 22:46:05,996 [salt.state       :1951][INFO    ][10337] Completed state [salt-minion] at time 22:46:05.996852 duration_in_ms=72.604
2019-11-26 22:46:05,998 [salt.state       :1780][INFO    ][10337] Running state [/etc/salt/grains.d] at time 22:46:05.998443
2019-11-26 22:46:05,998 [salt.state       :1813][INFO    ][10337] Executing state file.directory for [/etc/salt/grains.d]
2019-11-26 22:46:05,999 [salt.state       :300 ][INFO    ][10337] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-11-26 22:46:06,000 [salt.state       :1951][INFO    ][10337] Completed state [/etc/salt/grains.d] at time 22:46:06.000087 duration_in_ms=1.644
2019-11-26 22:46:06,000 [salt.state       :1780][INFO    ][10337] Running state [/etc/salt/grains] at time 22:46:06.000812
2019-11-26 22:46:06,001 [salt.state       :1813][INFO    ][10337] Executing state file.managed for [/etc/salt/grains]
2019-11-26 22:46:06,001 [salt.state       :300 ][INFO    ][10337] File /etc/salt/grains exists with proper permissions. No changes made.
2019-11-26 22:46:06,002 [salt.state       :1951][INFO    ][10337] Completed state [/etc/salt/grains] at time 22:46:06.001994 duration_in_ms=1.183
2019-11-26 22:46:06,002 [salt.state       :1780][INFO    ][10337] Running state [/etc/salt/grains.d/placeholder] at time 22:46:06.002472
2019-11-26 22:46:06,002 [salt.state       :1813][INFO    ][10337] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-11-26 22:46:06,003 [salt.state       :300 ][INFO    ][10337] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-11-26 22:46:06,003 [salt.state       :1951][INFO    ][10337] Completed state [/etc/salt/grains.d/placeholder] at time 22:46:06.003610 duration_in_ms=1.138
2019-11-26 22:46:06,004 [salt.state       :1780][INFO    ][10337] Running state [/etc/salt/grains.d/sphinx] at time 22:46:06.004094
2019-11-26 22:46:06,004 [salt.state       :1813][INFO    ][10337] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-11-26 22:46:06,016 [salt.state       :300 ][INFO    ][10337] File /etc/salt/grains.d/sphinx is in the correct state
2019-11-26 22:46:06,016 [salt.state       :1951][INFO    ][10337] Completed state [/etc/salt/grains.d/sphinx] at time 22:46:06.016720 duration_in_ms=12.626
2019-11-26 22:46:06,019 [salt.state       :1780][INFO    ][10337] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 22:46:06.019030
2019-11-26 22:46:06,019 [salt.state       :1813][INFO    ][10337] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-11-26 22:46:06,019 [salt.state       :300 ][INFO    ][10337] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-11-26 22:46:06,020 [salt.state       :1951][INFO    ][10337] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 22:46:06.019979 duration_in_ms=0.949
2019-11-26 22:46:06,020 [salt.state       :1780][INFO    ][10337] Running state [/etc/salt/grains.d/dns_records] at time 22:46:06.020468
2019-11-26 22:46:06,020 [salt.state       :1813][INFO    ][10337] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-11-26 22:46:06,034 [salt.state       :300 ][INFO    ][10337] File /etc/salt/grains.d/dns_records is in the correct state
2019-11-26 22:46:06,034 [salt.state       :1951][INFO    ][10337] Completed state [/etc/salt/grains.d/dns_records] at time 22:46:06.034548 duration_in_ms=14.079
2019-11-26 22:46:06,035 [salt.state       :1780][INFO    ][10337] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 22:46:06.035476
2019-11-26 22:46:06,035 [salt.state       :1813][INFO    ][10337] 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-26 22:46:06,036 [salt.state       :300 ][INFO    ][10337] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-11-26 22:46:06,036 [salt.state       :1951][INFO    ][10337] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 22:46:06.036394 duration_in_ms=0.918
2019-11-26 22:46:06,036 [salt.state       :1780][INFO    ][10337] Running state [/etc/salt/grains.d/salt] at time 22:46:06.036900
2019-11-26 22:46:06,037 [salt.state       :1813][INFO    ][10337] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-11-26 22:46:06,052 [salt.state       :300 ][INFO    ][10337] File /etc/salt/grains.d/salt is in the correct state
2019-11-26 22:46:06,052 [salt.state       :1951][INFO    ][10337] Completed state [/etc/salt/grains.d/salt] at time 22:46:06.052531 duration_in_ms=15.632
2019-11-26 22:46:06,053 [salt.state       :1780][INFO    ][10337] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 22:46:06.053430
2019-11-26 22:46:06,053 [salt.state       :1813][INFO    ][10337] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-11-26 22:46:06,054 [salt.state       :300 ][INFO    ][10337] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-11-26 22:46:06,054 [salt.state       :1951][INFO    ][10337] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 22:46:06.054367 duration_in_ms=0.936
2019-11-26 22:46:06,056 [salt.state       :1780][INFO    ][10337] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 22:46:06.056310
2019-11-26 22:46:06,056 [salt.state       :1813][INFO    ][10337] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-11-26 22:46:06,057 [salt.state       :300 ][INFO    ][10337] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-11-26 22:46:06,057 [salt.state       :1951][INFO    ][10337] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 22:46:06.057246 duration_in_ms=0.935
2019-11-26 22:46:06,058 [salt.state       :1780][INFO    ][10337] Running state [mine.update] at time 22:46:06.057949
2019-11-26 22:46:06,058 [salt.state       :1813][INFO    ][10337] Executing state module.wait for [mine.update]
2019-11-26 22:46:06,058 [salt.state       :300 ][INFO    ][10337] No changes made for mine.update
2019-11-26 22:46:06,058 [salt.state       :1951][INFO    ][10337] Completed state [mine.update] at time 22:46:06.058807 duration_in_ms=0.858
2019-11-26 22:46:06,059 [salt.state       :1780][INFO    ][10337] Running state [ca-certificates] at time 22:46:06.059069
2019-11-26 22:46:06,059 [salt.state       :1813][INFO    ][10337] Executing state pkg.installed for [ca-certificates]
2019-11-26 22:46:06,067 [salt.state       :300 ][INFO    ][10337] All specified packages are already installed
2019-11-26 22:46:06,067 [salt.state       :1951][INFO    ][10337] Completed state [ca-certificates] at time 22:46:06.067301 duration_in_ms=8.232
2019-11-26 22:46:06,068 [salt.state       :1780][INFO    ][10337] Running state [update-ca-certificates] at time 22:46:06.068310
2019-11-26 22:46:06,068 [salt.state       :1813][INFO    ][10337] Executing state cmd.wait for [update-ca-certificates]
2019-11-26 22:46:06,068 [salt.state       :300 ][INFO    ][10337] No changes made for update-ca-certificates
2019-11-26 22:46:06,069 [salt.state       :1951][INFO    ][10337] Completed state [update-ca-certificates] at time 22:46:06.069154 duration_in_ms=0.844
2019-11-26 22:46:06,069 [salt.state       :1780][INFO    ][10337] Running state [iptables] at time 22:46:06.069404
2019-11-26 22:46:06,069 [salt.state       :1813][INFO    ][10337] Executing state pkg.installed for [iptables]
2019-11-26 22:46:06,076 [salt.state       :300 ][INFO    ][10337] All specified packages are already installed
2019-11-26 22:46:06,076 [salt.state       :1951][INFO    ][10337] Completed state [iptables] at time 22:46:06.076619 duration_in_ms=7.214
2019-11-26 22:46:06,076 [salt.state       :1780][INFO    ][10337] Running state [iptables-persistent] at time 22:46:06.076860
2019-11-26 22:46:06,077 [salt.state       :1813][INFO    ][10337] Executing state pkg.installed for [iptables-persistent]
2019-11-26 22:46:06,083 [salt.state       :300 ][INFO    ][10337] All specified packages are already installed
2019-11-26 22:46:06,083 [salt.state       :1951][INFO    ][10337] Completed state [iptables-persistent] at time 22:46:06.083924 duration_in_ms=7.064
2019-11-26 22:46:06,084 [salt.state       :1780][INFO    ][10337] Running state [iptables_modules_v4_load] at time 22:46:06.084895
2019-11-26 22:46:06,085 [salt.state       :1813][INFO    ][10337] Executing state kmod.present for [iptables_modules_v4_load]
2019-11-26 22:46:06,085 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10337] Executing command 'lsmod' in directory '/root'
2019-11-26 22:46:06,107 [salt.state       :300 ][INFO    ][10337] Kernel modules iptable_filter, ip_tables are already present
2019-11-26 22:46:06,108 [salt.state       :1951][INFO    ][10337] Completed state [iptables_modules_v4_load] at time 22:46:06.108129 duration_in_ms=23.234
2019-11-26 22:46:06,108 [salt.state       :1780][INFO    ][10337] Running state [/etc/iptables/rules.v4] at time 22:46:06.108739
2019-11-26 22:46:06,109 [salt.state       :1813][INFO    ][10337] Executing state file.managed for [/etc/iptables/rules.v4]
2019-11-26 22:46:06,196 [salt.state       :300 ][INFO    ][10337] File /etc/iptables/rules.v4 is in the correct state
2019-11-26 22:46:06,196 [salt.state       :1951][INFO    ][10337] Completed state [/etc/iptables/rules.v4] at time 22:46:06.196430 duration_in_ms=87.691
2019-11-26 22:46:06,197 [salt.state       :1780][INFO    ][10337] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 22:46:06.197296
2019-11-26 22:46:06,197 [salt.state       :1813][INFO    ][10337] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-11-26 22:46:06,198 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10337] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-11-26 22:46:06,215 [salt.state       :300 ][INFO    ][10337] onlyif execution failed
2019-11-26 22:46:06,215 [salt.state       :1951][INFO    ][10337] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 22:46:06.215245 duration_in_ms=17.949
2019-11-26 22:46:06,216 [salt.state       :1780][INFO    ][10337] Running state [netfilter-persistent] at time 22:46:06.216052
2019-11-26 22:46:06,216 [salt.state       :1813][INFO    ][10337] Executing state service.running for [netfilter-persistent]
2019-11-26 22:46:06,217 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10337] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-11-26 22:46:06,235 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10337] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-11-26 22:46:06,253 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10337] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-11-26 22:46:06,269 [salt.state       :300 ][INFO    ][10337] The service netfilter-persistent is already running
2019-11-26 22:46:06,270 [salt.state       :1951][INFO    ][10337] Completed state [netfilter-persistent] at time 22:46:06.270276 duration_in_ms=54.224
2019-11-26 22:46:06,271 [salt.state       :1780][INFO    ][10337] Running state [iptables_extra.remove_stale_tables] at time 22:46:06.271075
2019-11-26 22:46:06,271 [salt.state       :1813][INFO    ][10337] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-11-26 22:46:06,271 [salt.state       :300 ][INFO    ][10337] No changes made for iptables_extra.remove_stale_tables
2019-11-26 22:46:06,272 [salt.state       :1951][INFO    ][10337] Completed state [iptables_extra.remove_stale_tables] at time 22:46:06.271989 duration_in_ms=0.913
2019-11-26 22:46:06,272 [salt.state       :1780][INFO    ][10337] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 22:46:06.272237
2019-11-26 22:46:06,272 [salt.state       :1813][INFO    ][10337] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-11-26 22:46:06,273 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10337] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-11-26 22:46:06,287 [salt.state       :300 ][INFO    ][10337] onlyif execution failed
2019-11-26 22:46:06,287 [salt.state       :1951][INFO    ][10337] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 22:46:06.287915 duration_in_ms=15.677
2019-11-26 22:46:06,289 [salt.state       :1780][INFO    ][10337] Running state [/etc/iptables/rules.v6] at time 22:46:06.288982
2019-11-26 22:46:06,289 [salt.state       :1813][INFO    ][10337] Executing state file.absent for [/etc/iptables/rules.v6]
2019-11-26 22:46:06,289 [salt.state       :300 ][INFO    ][10337] File /etc/iptables/rules.v6 is not present
2019-11-26 22:46:06,290 [salt.state       :1951][INFO    ][10337] Completed state [/etc/iptables/rules.v6] at time 22:46:06.290149 duration_in_ms=1.167
2019-11-26 22:46:06,290 [salt.state       :1780][INFO    ][10337] Running state [iptables_extra.flush_all] at time 22:46:06.290919
2019-11-26 22:46:06,291 [salt.state       :1813][INFO    ][10337] Executing state module.wait for [iptables_extra.flush_all]
2019-11-26 22:46:06,291 [salt.state       :300 ][INFO    ][10337] No changes made for iptables_extra.flush_all
2019-11-26 22:46:06,291 [salt.state       :1951][INFO    ][10337] Completed state [iptables_extra.flush_all] at time 22:46:06.291860 duration_in_ms=0.942
2019-11-26 22:46:06,294 [salt.minion      :1711][INFO    ][10337] Returning information for job: 20191126224558955762
2019-11-26 22:46:06,907 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command state.apply with jid 20191126224606893858
2019-11-26 22:46:06,931 [salt.minion      :1432][INFO    ][10427] Starting a new job with PID 10427
2019-11-26 22:46:07,625 [salt.state       :915 ][INFO    ][10427] Loading fresh modules for state activity
2019-11-26 22:46:08,326 [salt.state       :1780][INFO    ][10427] Running state [maas-rack-controller] at time 22:46:08.326917
2019-11-26 22:46:08,327 [salt.state       :1813][INFO    ][10427] Executing state pkg.installed for [maas-rack-controller]
2019-11-26 22:46:08,327 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10427] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-11-26 22:46:08,415 [salt.state       :300 ][INFO    ][10427] All specified packages are already installed
2019-11-26 22:46:08,415 [salt.state       :1951][INFO    ][10427] Completed state [maas-rack-controller] at time 22:46:08.415514 duration_in_ms=88.597
2019-11-26 22:46:08,415 [salt.state       :1780][INFO    ][10427] Running state [ipmitool] at time 22:46:08.415792
2019-11-26 22:46:08,416 [salt.state       :1813][INFO    ][10427] Executing state pkg.installed for [ipmitool]
2019-11-26 22:46:08,421 [salt.state       :300 ][INFO    ][10427] All specified packages are already installed
2019-11-26 22:46:08,421 [salt.state       :1951][INFO    ][10427] Completed state [ipmitool] at time 22:46:08.421514 duration_in_ms=5.722
2019-11-26 22:46:08,424 [salt.state       :1780][INFO    ][10427] Running state [/etc/maas/rackd.conf] at time 22:46:08.423988
2019-11-26 22:46:08,424 [salt.state       :1813][INFO    ][10427] Executing state file.line for [/etc/maas/rackd.conf]
2019-11-26 22:46:08,425 [salt.state       :300 ][INFO    ][10427] No changes needed to be made
2019-11-26 22:46:08,425 [salt.state       :1951][INFO    ][10427] Completed state [/etc/maas/rackd.conf] at time 22:46:08.425262 duration_in_ms=1.275
2019-11-26 22:46:08,425 [salt.state       :1780][INFO    ][10427] Running state [/etc/maas/rackd.conf] at time 22:46:08.425449
2019-11-26 22:46:08,425 [salt.state       :1813][INFO    ][10427] Executing state file.managed for [/etc/maas/rackd.conf]
2019-11-26 22:46:08,425 [salt.loaded.int.states.file:2298][WARNING ][10427] 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-26 22:46:08,426 [salt.state       :300 ][INFO    ][10427] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-11-26 22:46:08,426 [salt.state       :1951][INFO    ][10427] Completed state [/etc/maas/rackd.conf] at time 22:46:08.426473 duration_in_ms=1.023
2019-11-26 22:46:08,427 [salt.state       :1780][INFO    ][10427] Running state [maas-rackd] at time 22:46:08.427251
2019-11-26 22:46:08,427 [salt.state       :1813][INFO    ][10427] Executing state service.running for [maas-rackd]
2019-11-26 22:46:08,428 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10427] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-11-26 22:46:08,461 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10427] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-11-26 22:46:08,477 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10427] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-11-26 22:46:08,494 [salt.state       :300 ][INFO    ][10427] The service maas-rackd is already running
2019-11-26 22:46:08,494 [salt.state       :1951][INFO    ][10427] Completed state [maas-rackd] at time 22:46:08.494630 duration_in_ms=67.378
2019-11-26 22:46:08,496 [salt.minion      :1711][INFO    ][10427] Returning information for job: 20191126224606893858
2019-11-26 22:46:08,974 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command state.apply with jid 20191126224608962293
2019-11-26 22:46:08,996 [salt.minion      :1432][INFO    ][10450] Starting a new job with PID 10450
2019-11-26 22:46:09,712 [salt.state       :915 ][INFO    ][10450] Loading fresh modules for state activity
2019-11-26 22:46:10,472 [salt.state       :1780][INFO    ][10450] Running state [maas-region-controller] at time 22:46:10.471983
2019-11-26 22:46:10,472 [salt.state       :1813][INFO    ][10450] Executing state pkg.installed for [maas-region-controller]
2019-11-26 22:46:10,472 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10450] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-11-26 22:46:10,546 [salt.state       :300 ][INFO    ][10450] All specified packages are already installed
2019-11-26 22:46:10,546 [salt.state       :1951][INFO    ][10450] Completed state [maas-region-controller] at time 22:46:10.546637 duration_in_ms=74.653
2019-11-26 22:46:10,546 [salt.state       :1780][INFO    ][10450] Running state [python-oauth] at time 22:46:10.546873
2019-11-26 22:46:10,547 [salt.state       :1813][INFO    ][10450] Executing state pkg.installed for [python-oauth]
2019-11-26 22:46:10,551 [salt.state       :300 ][INFO    ][10450] All specified packages are already installed
2019-11-26 22:46:10,551 [salt.state       :1951][INFO    ][10450] Completed state [python-oauth] at time 22:46:10.551636 duration_in_ms=4.762
2019-11-26 22:46:10,553 [salt.state       :1780][INFO    ][10450] Running state [/etc/maas/regiond.conf] at time 22:46:10.553846
2019-11-26 22:46:10,554 [salt.state       :1813][INFO    ][10450] Executing state file.replace for [/etc/maas/regiond.conf]
2019-11-26 22:46:10,591 [salt.state       :300 ][INFO    ][10450] No changes needed to be made
2019-11-26 22:46:10,591 [salt.state       :1951][INFO    ][10450] Completed state [/etc/maas/regiond.conf] at time 22:46:10.591803 duration_in_ms=37.958
2019-11-26 22:46:10,592 [salt.state       :1780][INFO    ][10450] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 22:46:10.592121
2019-11-26 22:46:10,592 [salt.state       :1813][INFO    ][10450] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-11-26 22:46:10,651 [salt.state       :300 ][INFO    ][10450] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-11-26 22:46:10,651 [salt.state       :1951][INFO    ][10450] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 22:46:10.651393 duration_in_ms=59.272
2019-11-26 22:46:10,651 [salt.state       :1780][INFO    ][10450] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 22:46:10.651769
2019-11-26 22:46:10,651 [salt.state       :1813][INFO    ][10450] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-11-26 22:46:10,664 [salt.state       :300 ][INFO    ][10450] No changes needed to be made
2019-11-26 22:46:10,664 [salt.state       :1951][INFO    ][10450] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 22:46:10.664430 duration_in_ms=12.662
2019-11-26 22:46:10,664 [salt.state       :1780][INFO    ][10450] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 22:46:10.664794
2019-11-26 22:46:10,665 [salt.state       :1813][INFO    ][10450] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-11-26 22:46:10,724 [salt.state       :300 ][INFO    ][10450] No changes needed to be made
2019-11-26 22:46:10,724 [salt.state       :1951][INFO    ][10450] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 22:46:10.724315 duration_in_ms=59.52
2019-11-26 22:46:10,725 [salt.state       :1780][INFO    ][10450] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 22:46:10.724959
2019-11-26 22:46:10,725 [salt.state       :1813][INFO    ][10450] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-11-26 22:46:10,761 [salt.state       :300 ][INFO    ][10450] No changes needed to be made
2019-11-26 22:46:10,762 [salt.state       :1951][INFO    ][10450] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 22:46:10.762233 duration_in_ms=37.265
2019-11-26 22:46:10,763 [salt.state       :1780][INFO    ][10450] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 22:46:10.763180
2019-11-26 22:46:10,763 [salt.state       :1813][INFO    ][10450] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-11-26 22:46:10,778 [salt.state       :300 ][INFO    ][10450] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-11-26 22:46:10,778 [salt.state       :1951][INFO    ][10450] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 22:46:10.778818 duration_in_ms=15.639
2019-11-26 22:46:10,781 [salt.state       :1780][INFO    ][10450] Running state [a2enmod headers] at time 22:46:10.781104
2019-11-26 22:46:10,781 [salt.state       :1813][INFO    ][10450] Executing state cmd.run for [a2enmod headers]
2019-11-26 22:46:10,782 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10450] Executing command 'a2enmod headers' in directory '/root'
2019-11-26 22:46:10,854 [salt.state       :300 ][INFO    ][10450] {'pid': 10469, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-11-26 22:46:10,855 [salt.state       :1951][INFO    ][10450] Completed state [a2enmod headers] at time 22:46:10.855157 duration_in_ms=74.053
2019-11-26 22:46:10,855 [salt.state       :1780][INFO    ][10450] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 22:46:10.855778
2019-11-26 22:46:10,856 [salt.state       :1813][INFO    ][10450] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-11-26 22:46:10,876 [salt.state       :300 ][INFO    ][10450] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-11-26 22:46:10,876 [salt.state       :1951][INFO    ][10450] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 22:46:10.876382 duration_in_ms=20.603
2019-11-26 22:46:10,877 [salt.state       :1780][INFO    ][10450] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 22:46:10.877276
2019-11-26 22:46:10,877 [salt.state       :1813][INFO    ][10450] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-11-26 22:46:10,962 [salt.state       :300 ][INFO    ][10450] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-11-26 22:46:10,962 [salt.state       :1951][INFO    ][10450] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 22:46:10.962704 duration_in_ms=85.428
2019-11-26 22:46:10,963 [salt.state       :1780][INFO    ][10450] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 22:46:10.963345
2019-11-26 22:46:10,963 [salt.state       :1813][INFO    ][10450] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-11-26 22:46:11,028 [salt.state       :300 ][INFO    ][10450] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-11-26 22:46:11,028 [salt.state       :1951][INFO    ][10450] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 22:46:11.028356 duration_in_ms=65.01
2019-11-26 22:46:11,029 [salt.state       :1780][INFO    ][10450] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 22:46:11.028959
2019-11-26 22:46:11,029 [salt.state       :1813][INFO    ][10450] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-11-26 22:46:11,099 [salt.state       :300 ][INFO    ][10450] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-11-26 22:46:11,100 [salt.state       :1951][INFO    ][10450] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 22:46:11.100192 duration_in_ms=71.233
2019-11-26 22:46:11,100 [salt.state       :1780][INFO    ][10450] Running state [/root/.pgpass] at time 22:46:11.100516
2019-11-26 22:46:11,100 [salt.state       :1813][INFO    ][10450] Executing state file.managed for [/root/.pgpass]
2019-11-26 22:46:11,153 [salt.state       :300 ][INFO    ][10450] File /root/.pgpass is in the correct state
2019-11-26 22:46:11,154 [salt.state       :1951][INFO    ][10450] Completed state [/root/.pgpass] at time 22:46:11.154065 duration_in_ms=53.548
2019-11-26 22:46:11,159 [salt.state       :1780][INFO    ][10450] Running state [maas-region syncdb --noinput] at time 22:46:11.159806
2019-11-26 22:46:11,160 [salt.state       :1813][INFO    ][10450] Executing state cmd.run for [maas-region syncdb --noinput]
2019-11-26 22:46:11,160 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10450] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-11-26 22:46:13,206 [salt.state       :300 ][INFO    ][10450] {'pid': 10482, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: piston3, metadataserver, sessions, contenttypes, maasserver, auth, sites\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-11-26 22:46:13,207 [salt.state       :1951][INFO    ][10450] Completed state [maas-region syncdb --noinput] at time 22:46:13.207536 duration_in_ms=2047.729
2019-11-26 22:46:13,208 [salt.state       :2022][WARNING ][10450] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-11-26 22:46:13,210 [salt.state       :1780][INFO    ][10450] Running state [maas-regiond] at time 22:46:13.210775
2019-11-26 22:46:13,211 [salt.state       :1813][INFO    ][10450] Executing state service.running for [maas-regiond]
2019-11-26 22:46:13,212 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10450] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-11-26 22:46:13,253 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10450] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-11-26 22:46:13,273 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10450] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-11-26 22:46:13,290 [salt.state       :300 ][INFO    ][10450] The service maas-regiond is already running
2019-11-26 22:46:13,291 [salt.state       :1951][INFO    ][10450] Completed state [maas-regiond] at time 22:46:13.290987 duration_in_ms=80.212
2019-11-26 22:46:13,293 [salt.state       :1780][INFO    ][10450] Running state [bind9] at time 22:46:13.293388
2019-11-26 22:46:13,293 [salt.state       :1813][INFO    ][10450] Executing state service.running for [bind9]
2019-11-26 22:46:13,294 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10450] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-11-26 22:46:13,313 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10450] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-11-26 22:46:13,329 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10450] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-11-26 22:46:13,346 [salt.state       :300 ][INFO    ][10450] The service bind9 is already running
2019-11-26 22:46:13,346 [salt.state       :1951][INFO    ][10450] Completed state [bind9] at time 22:46:13.346815 duration_in_ms=53.428
2019-11-26 22:46:13,348 [salt.state       :1780][INFO    ][10450] Running state [apache2] at time 22:46:13.348883
2019-11-26 22:46:13,349 [salt.state       :1813][INFO    ][10450] Executing state service.running for [apache2]
2019-11-26 22:46:13,350 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10450] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-11-26 22:46:13,368 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10450] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-11-26 22:46:13,384 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10450] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-11-26 22:46:13,405 [salt.state       :300 ][INFO    ][10450] The service apache2 is already running
2019-11-26 22:46:13,405 [salt.state       :1951][INFO    ][10450] Completed state [apache2] at time 22:46:13.405643 duration_in_ms=56.76
2019-11-26 22:46:13,407 [salt.state       :1780][INFO    ][10450] Running state [maasng.wait_for_http_code] at time 22:46:13.407449
2019-11-26 22:46:13,407 [salt.state       :1813][INFO    ][10450] Executing state module.run for [maasng.wait_for_http_code]
2019-11-26 22:46:13,408 [salt.utils.decorators:613 ][WARNING ][10450] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-26 22:46:13,418 [salt.state       :300 ][INFO    ][10450] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-11-26 22:46:13,419 [salt.state       :1951][INFO    ][10450] Completed state [maasng.wait_for_http_code] at time 22:46:13.418928 duration_in_ms=11.479
2019-11-26 22:46:13,420 [salt.state       :1780][INFO    ][10450] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 22:46:13.419935
2019-11-26 22:46:13,420 [salt.state       :1813][INFO    ][10450] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-11-26 22:46:13,420 [salt.state       :300 ][INFO    ][10450] /var/lib/maas/.setup_admin exists
2019-11-26 22:46:13,421 [salt.state       :1951][INFO    ][10450] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 22:46:13.421186 duration_in_ms=1.25
2019-11-26 22:46:13,422 [salt.state       :1780][INFO    ][10450] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 22:46:13.422087
2019-11-26 22:46:13,422 [salt.state       :1813][INFO    ][10450] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-26 22:46:13,423 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10450] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-26 22:46:14,859 [salt.state       :300 ][INFO    ][10450] {'pid': 10515, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-26 22:46:14,860 [salt.state       :1951][INFO    ][10450] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 22:46:14.860137 duration_in_ms=1438.05
2019-11-26 22:46:14,868 [salt.state       :1780][INFO    ][10450] Running state [maas_region_boot_source_resources_mirror] at time 22:46:14.868219
2019-11-26 22:46:14,868 [salt.state       :1813][INFO    ][10450] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-11-26 22:46:14,981 [salt.state       :300 ][INFO    ][10450] {'changes': {}}
2019-11-26 22:46:14,981 [salt.state       :1951][INFO    ][10450] Completed state [maas_region_boot_source_resources_mirror] at time 22:46:14.981710 duration_in_ms=113.491
2019-11-26 22:46:14,982 [salt.state       :1780][INFO    ][10450] Running state [maasng.boot_resources_import] at time 22:46:14.982735
2019-11-26 22:46:14,983 [salt.state       :1813][INFO    ][10450] Executing state module.run for [maasng.boot_resources_import]
2019-11-26 22:46:14,983 [salt.utils.decorators:613 ][WARNING ][10450] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-26 22:46:15,116 [salt.loaded.ext.module.maasng:1600][INFO    ][10450] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-11-26 22:46:20,180 [salt.loaded.ext.module.maasng:1600][INFO    ][10450] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-11-26 22:46:24,097 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126224624084169
2019-11-26 22:46:24,121 [salt.minion      :1432][INFO    ][10542] Starting a new job with PID 10542
2019-11-26 22:46:24,143 [salt.minion      :1711][INFO    ][10542] Returning information for job: 20191126224624084169
2019-11-26 22:46:25,241 [salt.loaded.ext.module.maasng:1600][INFO    ][10450] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-11-26 22:46:30,358 [salt.state       :300 ][INFO    ][10450] {'ret': True}
2019-11-26 22:46:30,358 [salt.state       :1951][INFO    ][10450] Completed state [maasng.boot_resources_import] at time 22:46:30.358442 duration_in_ms=15375.706
2019-11-26 22:46:30,359 [salt.state       :1780][INFO    ][10450] Running state [maas_region_boot_sources_selection_xenial] at time 22:46:30.359508
2019-11-26 22:46:30,359 [salt.state       :1813][INFO    ][10450] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-11-26 22:46:30,567 [salt.state       :300 ][INFO    ][10450] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-11-26 22:46:30,567 [salt.state       :1951][INFO    ][10450] Completed state [maas_region_boot_sources_selection_xenial] at time 22:46:30.567639 duration_in_ms=208.13
2019-11-26 22:46:30,569 [salt.state       :1780][INFO    ][10450] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 22:46:30.568953
2019-11-26 22:46:30,569 [salt.state       :1813][INFO    ][10450] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-11-26 22:46:30,570 [salt.utils.decorators:613 ][WARNING ][10450] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-26 22:46:30,570 [salt.loaded.ext.module.maasng:1771][INFO    ][10450] boot-sources sync initiated for ALL Rack's
2019-11-26 22:46:31,836 [salt.state       :300 ][INFO    ][10450] {'ret': True}
2019-11-26 22:46:31,837 [salt.state       :1951][INFO    ][10450] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 22:46:31.837125 duration_in_ms=1268.172
2019-11-26 22:46:31,839 [salt.state       :1780][INFO    ][10450] Running state [maas.process_maas_config] at time 22:46:31.839125
2019-11-26 22:46:31,839 [salt.state       :1813][INFO    ][10450] Executing state module.run for [maas.process_maas_config]
2019-11-26 22:46:31,840 [salt.utils.decorators:613 ][WARNING ][10450] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-26 22:46:31,841 [salt.loaded.ext.module.maas:92  ][INFO    ][10450] maasconfig name=enable_http_proxy value=True
2019-11-26 22:46:31,904 [salt.loaded.ext.module.maas:92  ][INFO    ][10450] maasconfig name=upstream_dns value=8.8.8.8
2019-11-26 22:46:31,968 [salt.loaded.ext.module.maas:92  ][INFO    ][10450] maasconfig name=commissioning_distro_series value=xenial
2019-11-26 22:46:32,034 [salt.loaded.ext.module.maas:92  ][INFO    ][10450] maasconfig name=default_osystem value=ubuntu
2019-11-26 22:46:32,094 [salt.loaded.ext.module.maas:92  ][INFO    ][10450] maasconfig name=active_discovery_interval value=600
2019-11-26 22:46:35,028 [salt.loaded.ext.module.maas:92  ][INFO    ][10450] maasconfig name=dnssec_validation value=no
2019-11-26 22:46:35,086 [salt.loaded.ext.module.maas:92  ][INFO    ][10450] maasconfig name=maas_name value=mas01
2019-11-26 22:46:35,146 [salt.loaded.ext.module.maas:92  ][INFO    ][10450] maasconfig name=network_discovery value=enabled
2019-11-26 22:46:35,260 [salt.loaded.ext.module.maas:92  ][INFO    ][10450] maasconfig name=enable_third_party_drivers value=True
2019-11-26 22:46:35,314 [salt.loaded.ext.module.maas:92  ][INFO    ][10450] maasconfig name=default_storage_layout value=lvm
2019-11-26 22:46:35,370 [salt.loaded.ext.module.maas:92  ][INFO    ][10450] maasconfig name=ntp_external_only value=True
2019-11-26 22:46:35,427 [salt.loaded.ext.module.maas:92  ][INFO    ][10450] maasconfig name=disk_erase_with_secure_erase value=False
2019-11-26 22:46:35,484 [salt.loaded.ext.module.maas:92  ][INFO    ][10450] maasconfig name=default_distro_series value=xenial
2019-11-26 22:46:35,549 [salt.loaded.ext.module.maas:92  ][INFO    ][10450] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-11-26 22:46:35,694 [salt.state       :300 ][INFO    ][10450] {'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-26 22:46:35,694 [salt.state       :1951][INFO    ][10450] Completed state [maas.process_maas_config] at time 22:46:35.694618 duration_in_ms=3855.491
2019-11-26 22:46:35,695 [salt.state       :1780][INFO    ][10450] Running state [pxe_admin] at time 22:46:35.695777
2019-11-26 22:46:35,696 [salt.state       :1813][INFO    ][10450] Executing state maasng.fabric_present for [pxe_admin]
2019-11-26 22:46:35,765 [salt.loaded.ext.module.maasng:945 ][INFO    ][10450] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0', u'class_type': None}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1', u'class_type': None}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'bs347y', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin', u'class_type': u''}]
2019-11-26 22:46:35,855 [salt.loaded.ext.module.maasng:1008][WARNING ][10450] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-11-26 22:46:35,856 [salt.loaded.ext.module.maasng:1011][WARNING ][10450] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-11-26 22:46:35,924 [salt.state       :300 ][INFO    ][10450] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-11-26 22:46:35,924 [salt.state       :1951][INFO    ][10450] Completed state [pxe_admin] at time 22:46:35.924830 duration_in_ms=229.053
2019-11-26 22:46:35,925 [salt.state       :1780][INFO    ][10450] Running state [vlan 0] at time 22:46:35.925289
2019-11-26 22:46:35,925 [salt.state       :1813][INFO    ][10450] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-11-26 22:46:36,002 [salt.loaded.ext.module.maasng:945 ][INFO    ][10450] [{u'class_type': None, 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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'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'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'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'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'bs347y', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-11-26 22:46:36,115 [salt.loaded.ext.module.maasng:945 ][INFO    ][10450] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'bs347y', 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'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-11-26 22:46:36,400 [salt.loaded.ext.module.maasng:945 ][INFO    ][10450] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'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'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'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'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'bs347y', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-11-26 22:46:36,548 [salt.state       :300 ][INFO    ][10450] {'new': 'Vlan untagged was updated'}
2019-11-26 22:46:36,549 [salt.state       :1951][INFO    ][10450] Completed state [vlan 0] at time 22:46:36.549325 duration_in_ms=624.035
2019-11-26 22:46:36,550 [salt.state       :1780][INFO    ][10450] Running state [192.168.11.0/24] at time 22:46:36.550887
2019-11-26 22:46:36,551 [salt.state       :1813][INFO    ][10450] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-11-26 22:46:36,795 [salt.loaded.ext.module.maasng:945 ][INFO    ][10450] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0', u'class_type': None}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1', u'class_type': None}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'bs347y', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin', u'class_type': u''}]
2019-11-26 22:46:36,796 [salt.loaded.ext.module.maasng:1235][WARNING ][10450] Ignoring parameter vlan:0
2019-11-26 22:46:36,880 [salt.state       :300 ][INFO    ][10450] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-11-26 22:46:36,880 [salt.state       :1951][INFO    ][10450] Completed state [192.168.11.0/24] at time 22:46:36.880889 duration_in_ms=330.001
2019-11-26 22:46:36,882 [salt.state       :1780][INFO    ][10450] Running state [maas_create_iprange_1] at time 22:46:36.882609
2019-11-26 22:46:36,883 [salt.state       :1813][INFO    ][10450] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-11-26 22:46:36,945 [salt.state       :300 ][INFO    ][10450] Iprange maas_create_iprange_1 already exist.
2019-11-26 22:46:36,945 [salt.state       :1951][INFO    ][10450] Completed state [maas_create_iprange_1] at time 22:46:36.945508 duration_in_ms=62.899
2019-11-26 22:46:36,945 [salt.state       :1780][INFO    ][10450] Running state [vlan 0] at time 22:46:36.945856
2019-11-26 22:46:36,946 [salt.state       :1813][INFO    ][10450] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-11-26 22:46:37,026 [salt.loaded.ext.module.maasng:945 ][INFO    ][10450] [{u'class_type': None, 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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'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'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'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'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'bs347y', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-11-26 22:46:37,116 [salt.loaded.ext.module.maasng:945 ][INFO    ][10450] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0', u'class_type': None}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1', u'class_type': None}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'bs347y', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin', u'class_type': u''}]
2019-11-26 22:46:37,311 [salt.loaded.ext.module.maasng:945 ][INFO    ][10450] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'id': 2, 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'bs347y', 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'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-11-26 22:46:37,402 [salt.state       :300 ][INFO    ][10450] {'new': 'Vlan untagged was updated'}
2019-11-26 22:46:37,403 [salt.state       :1951][INFO    ][10450] Completed state [vlan 0] at time 22:46:37.403009 duration_in_ms=457.152
2019-11-26 22:46:37,403 [salt.state       :1780][INFO    ][10450] Running state [opnfv] at time 22:46:37.403794
2019-11-26 22:46:37,404 [salt.state       :1813][INFO    ][10450] Executing state maasng.sshkey_present for [opnfv]
2019-11-26 22:46:37,459 [salt.loaded.ext.module.maasng:1903][INFO    ][10450] [{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-26 22:46:37,460 [salt.state       :300 ][INFO    ][10450] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-11-26 22:46:37,460 [salt.state       :1951][INFO    ][10450] Completed state [opnfv] at time 22:46:37.460396 duration_in_ms=56.601
2019-11-26 22:46:37,461 [salt.state       :1780][INFO    ][10450] Running state [maas.process_tags] at time 22:46:37.461008
2019-11-26 22:46:37,461 [salt.state       :1813][INFO    ][10450] Executing state module.run for [maas.process_tags]
2019-11-26 22:46:37,461 [salt.utils.decorators:613 ][WARNING ][10450] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-26 22:46:37,501 [salt.loaded.ext.module.maas:92  ][INFO    ][10450] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-11-26 22:46:37,544 [salt.state       :300 ][INFO    ][10450] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-11-26 22:46:37,544 [salt.state       :1951][INFO    ][10450] Completed state [maas.process_tags] at time 22:46:37.544467 duration_in_ms=83.457
2019-11-26 22:46:37,548 [salt.minion      :1711][INFO    ][10450] Returning information for job: 20191126224608962293
2019-11-26 22:46:38,093 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command state.apply with jid 20191126224638081957
2019-11-26 22:46:38,113 [salt.minion      :1432][INFO    ][10910] Starting a new job with PID 10910
2019-11-26 22:46:41,701 [salt.state       :915 ][INFO    ][10910] Loading fresh modules for state activity
2019-11-26 22:46:41,797 [salt.state       :1780][INFO    ][10910] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 22:46:41.797763
2019-11-26 22:46:41,798 [salt.state       :1813][INFO    ][10910] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-26 22:46:41,800 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10910] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-26 22:46:43,300 [salt.state       :300 ][INFO    ][10910] {'pid': 10950, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-26 22:46:43,301 [salt.state       :1951][INFO    ][10910] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 22:46:43.301293 duration_in_ms=1503.529
2019-11-26 22:46:43,303 [salt.state       :1780][INFO    ][10910] Running state [maas.process_machines] at time 22:46:43.303818
2019-11-26 22:46:43,304 [salt.state       :1813][INFO    ][10910] Executing state module.run for [maas.process_machines]
2019-11-26 22:46:43,305 [salt.utils.decorators:613 ][WARNING ][10910] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-26 22:46:44,014 [salt.loaded.ext.module.maas:412 ][WARNING ][10910] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-26 22:46:44,015 [salt.loaded.ext.module.maas:92  ][INFO    ][10910] machine hostname=gtw01 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=c3c7f8 architecture=amd64/generic power_parameters_power_user=admin
2019-11-26 22:46:45,171 [salt.loaded.ext.module.maas:412 ][WARNING ][10910] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-26 22:46:45,173 [salt.loaded.ext.module.maas:92  ][INFO    ][10910] 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=bnt6wd architecture=amd64/generic power_parameters_power_user=admin
2019-11-26 22:46:46,411 [salt.loaded.ext.module.maas:412 ][WARNING ][10910] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-26 22:46:46,412 [salt.loaded.ext.module.maas:92  ][INFO    ][10910] machine hostname=odl01 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=gd8ca6 architecture=amd64/generic power_parameters_power_user=admin
2019-11-26 22:46:47,692 [salt.loaded.ext.module.maas:412 ][WARNING ][10910] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-26 22:46:47,693 [salt.loaded.ext.module.maas:92  ][INFO    ][10910] 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=hm6xsh architecture=amd64/generic power_parameters_power_user=admin
2019-11-26 22:46:48,812 [salt.loaded.ext.module.maas:412 ][WARNING ][10910] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-26 22:46:48,814 [salt.loaded.ext.module.maas:92  ][INFO    ][10910] machine hostname=ctl01 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=eqcqsp architecture=amd64/generic power_parameters_power_user=admin
2019-11-26 22:46:50,125 [salt.state       :300 ][INFO    ][10910] {'ret': {'updated': ['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01'], 'errors': {}, 'success': []}}
2019-11-26 22:46:50,125 [salt.state       :1951][INFO    ][10910] Completed state [maas.process_machines] at time 22:46:50.125643 duration_in_ms=6821.823
2019-11-26 22:46:50,129 [salt.minion      :1711][INFO    ][10910] Returning information for job: 20191126224638081957
2019-11-26 22:47:23,348 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command state.apply with jid 20191126224723335608
2019-11-26 22:47:23,372 [salt.minion      :1432][INFO    ][11202] Starting a new job with PID 11202
2019-11-26 22:47:27,043 [salt.state       :915 ][INFO    ][11202] Loading fresh modules for state activity
2019-11-26 22:47:27,132 [salt.state       :1780][INFO    ][11202] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 22:47:27.132214
2019-11-26 22:47:27,132 [salt.state       :1813][INFO    ][11202] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-26 22:47:27,134 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11202] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-26 22:47:28,538 [salt.state       :300 ][INFO    ][11202] {'pid': 11209, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-26 22:47:28,538 [salt.state       :1951][INFO    ][11202] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 22:47:28.538735 duration_in_ms=1406.522
2019-11-26 22:47:28,540 [salt.state       :1780][INFO    ][11202] Running state [maas.wait_for_machine_status] at time 22:47:28.540245
2019-11-26 22:47:28,540 [salt.state       :1813][INFO    ][11202] Executing state module.run for [maas.wait_for_machine_status]
2019-11-26 22:47:28,540 [salt.utils.decorators:613 ][WARNING ][11202] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-26 22:47:29,073 [salt.loaded.ext.module.maas:993 ][INFO    ][11202] Machine c3c7f8 mark broken
2019-11-26 22:47:29,731 [salt.loaded.ext.module.maas:996 ][INFO    ][11202] Machine c3c7f8 mark fixed
2019-11-26 22:47:30,923 [salt.loaded.ext.module.maas:684 ][INFO    ][11202] deploymachines hwe_kernel=hwe-16.04 system_id=c3c7f8 distro_series=xenial
2019-11-26 22:47:36,359 [salt.loaded.ext.module.maas:1023][INFO    ][11202] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1492.18612409s left)
2019-11-26 22:47:38,445 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126224738429802
2019-11-26 22:47:38,467 [salt.minion      :1432][INFO    ][11289] Starting a new job with PID 11289
2019-11-26 22:47:38,487 [salt.minion      :1711][INFO    ][11289] Returning information for job: 20191126224738429802
2019-11-26 22:48:08,702 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126224808651972
2019-11-26 22:48:08,720 [salt.minion      :1432][INFO    ][11326] Starting a new job with PID 11326
2019-11-26 22:48:08,737 [salt.minion      :1711][INFO    ][11326] Returning information for job: 20191126224808651972
2019-11-26 22:48:09,533 [salt.loaded.ext.module.maas:1023][INFO    ][11202] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1459.01213002s left)
2019-11-26 22:48:38,797 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126224838786330
2019-11-26 22:48:38,821 [salt.minion      :1432][INFO    ][11374] Starting a new job with PID 11374
2019-11-26 22:48:38,845 [salt.minion      :1711][INFO    ][11374] Returning information for job: 20191126224838786330
2019-11-26 22:48:42,970 [salt.loaded.ext.module.maas:1023][INFO    ][11202] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1425.5742681s left)
2019-11-26 22:49:08,850 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126224908836406
2019-11-26 22:49:08,874 [salt.minion      :1432][INFO    ][11402] Starting a new job with PID 11402
2019-11-26 22:49:08,898 [salt.minion      :1711][INFO    ][11402] Returning information for job: 20191126224908836406
2019-11-26 22:49:16,408 [salt.loaded.ext.module.maas:1023][INFO    ][11202] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1392.13648605s left)
2019-11-26 22:49:38,906 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126224938892593
2019-11-26 22:49:38,930 [salt.minion      :1432][INFO    ][11474] Starting a new job with PID 11474
2019-11-26 22:49:38,955 [salt.minion      :1711][INFO    ][11474] Returning information for job: 20191126224938892593
2019-11-26 22:49:50,047 [salt.loaded.ext.module.maas:1023][INFO    ][11202] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1358.49731493s left)
2019-11-26 22:50:08,963 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126225008950175
2019-11-26 22:50:08,986 [salt.minion      :1432][INFO    ][11537] Starting a new job with PID 11537
2019-11-26 22:50:09,010 [salt.minion      :1711][INFO    ][11537] Returning information for job: 20191126225008950175
2019-11-26 22:50:23,688 [salt.loaded.ext.module.maas:1023][INFO    ][11202] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1324.85654497s left)
2019-11-26 22:50:39,023 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126225039010532
2019-11-26 22:50:39,046 [salt.minion      :1432][INFO    ][11709] Starting a new job with PID 11709
2019-11-26 22:50:39,072 [salt.minion      :1711][INFO    ][11709] Returning information for job: 20191126225039010532
2019-11-26 22:50:56,730 [salt.loaded.ext.module.maas:1023][INFO    ][11202] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1291.81447697s left)
2019-11-26 22:51:09,086 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126225109074418
2019-11-26 22:51:09,110 [salt.minion      :1432][INFO    ][11758] Starting a new job with PID 11758
2019-11-26 22:51:09,134 [salt.minion      :1711][INFO    ][11758] Returning information for job: 20191126225109074418
2019-11-26 22:51:30,563 [salt.loaded.ext.module.maas:1023][INFO    ][11202] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1257.98140407s left)
2019-11-26 22:51:39,153 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126225139140601
2019-11-26 22:51:39,177 [salt.minion      :1432][INFO    ][11840] Starting a new job with PID 11840
2019-11-26 22:51:39,202 [salt.minion      :1711][INFO    ][11840] Returning information for job: 20191126225139140601
2019-11-26 22:52:03,895 [salt.loaded.ext.module.maas:1023][INFO    ][11202] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1224.65013909s left)
2019-11-26 22:52:09,222 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126225209209170
2019-11-26 22:52:09,247 [salt.minion      :1432][INFO    ][11870] Starting a new job with PID 11870
2019-11-26 22:52:09,271 [salt.minion      :1711][INFO    ][11870] Returning information for job: 20191126225209209170
2019-11-26 22:52:37,499 [salt.loaded.ext.module.maas:1023][INFO    ][11202] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1191.04548597s left)
2019-11-26 22:52:39,303 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126225239290785
2019-11-26 22:52:39,327 [salt.minion      :1432][INFO    ][12013] Starting a new job with PID 12013
2019-11-26 22:52:39,350 [salt.minion      :1711][INFO    ][12013] Returning information for job: 20191126225239290785
2019-11-26 22:53:09,382 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126225309369543
2019-11-26 22:53:09,406 [salt.minion      :1432][INFO    ][12049] Starting a new job with PID 12049
2019-11-26 22:53:09,432 [salt.minion      :1711][INFO    ][12049] Returning information for job: 20191126225309369543
2019-11-26 22:53:11,129 [salt.loaded.ext.module.maas:1023][INFO    ][11202] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1157.41578388s left)
2019-11-26 22:53:39,478 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126225339465452
2019-11-26 22:53:39,503 [salt.minion      :1432][INFO    ][12174] Starting a new job with PID 12174
2019-11-26 22:53:39,526 [salt.minion      :1711][INFO    ][12174] Returning information for job: 20191126225339465452
2019-11-26 22:53:44,487 [salt.loaded.ext.module.maas:1023][INFO    ][11202] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1124.05753493s left)
2019-11-26 22:54:09,563 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126225409550360
2019-11-26 22:54:09,585 [salt.minion      :1432][INFO    ][12211] Starting a new job with PID 12211
2019-11-26 22:54:09,608 [salt.minion      :1711][INFO    ][12211] Returning information for job: 20191126225409550360
2019-11-26 22:54:18,189 [salt.loaded.ext.module.maas:1023][INFO    ][11202] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1090.35553694s left)
2019-11-26 22:54:39,656 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126225439643588
2019-11-26 22:54:39,680 [salt.minion      :1432][INFO    ][12277] Starting a new job with PID 12277
2019-11-26 22:54:39,706 [salt.minion      :1711][INFO    ][12277] Returning information for job: 20191126225439643588
2019-11-26 22:54:51,368 [salt.loaded.ext.module.maas:1023][INFO    ][11202] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1057.17617607s left)
2019-11-26 22:55:09,758 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command saltutil.find_job with jid 20191126225509745365
2019-11-26 22:55:09,783 [salt.minion      :1432][INFO    ][12352] Starting a new job with PID 12352
2019-11-26 22:55:09,809 [salt.minion      :1711][INFO    ][12352] Returning information for job: 20191126225509745365
2019-11-26 22:55:24,980 [salt.state       :300 ][INFO    ][11202] {'ret': True}
2019-11-26 22:55:24,980 [salt.state       :1951][INFO    ][11202] Completed state [maas.wait_for_machine_status] at time 22:55:24.980707 duration_in_ms=476440.459
2019-11-26 22:55:24,984 [salt.minion      :1711][INFO    ][11202] Returning information for job: 20191126224723335608
2019-11-26 22:55:25,635 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command state.apply with jid 20191126225525622181
2019-11-26 22:55:25,658 [salt.minion      :1432][INFO    ][12434] Starting a new job with PID 12434
2019-11-26 22:55:29,447 [salt.state       :915 ][INFO    ][12434] Loading fresh modules for state activity
2019-11-26 22:55:29,583 [salt.state       :1780][INFO    ][12434] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 22:55:29.583126
2019-11-26 22:55:29,583 [salt.state       :1813][INFO    ][12434] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-26 22:55:29,585 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12434] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-26 22:55:31,092 [salt.state       :300 ][INFO    ][12434] {'pid': 12441, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-26 22:55:31,093 [salt.state       :1951][INFO    ][12434] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 22:55:31.093076 duration_in_ms=1509.949
2019-11-26 22:55:31,096 [salt.state       :1780][INFO    ][12434] Running state [maas_machines_storage_cmp002_lvm] at time 22:55:31.096134
2019-11-26 22:55:31,096 [salt.state       :1813][INFO    ][12434] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-11-26 22:55:31,872 [salt.state       :300 ][INFO    ][12434] Machine cmp002 is not in Ready state.
2019-11-26 22:55:31,872 [salt.state       :1951][INFO    ][12434] Completed state [maas_machines_storage_cmp002_lvm] at time 22:55:31.872593 duration_in_ms=776.458
2019-11-26 22:55:31,873 [salt.state       :1780][INFO    ][12434] Running state [maas_machines_storage_cmp001_lvm] at time 22:55:31.873087
2019-11-26 22:55:31,873 [salt.state       :1813][INFO    ][12434] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-11-26 22:55:32,590 [salt.state       :300 ][INFO    ][12434] Machine cmp001 is not in Ready state.
2019-11-26 22:55:32,591 [salt.state       :1951][INFO    ][12434] Completed state [maas_machines_storage_cmp001_lvm] at time 22:55:32.591322 duration_in_ms=718.234
2019-11-26 22:55:32,595 [salt.minion      :1711][INFO    ][12434] Returning information for job: 20191126225525622181
2019-11-26 22:55:33,230 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command state.apply with jid 20191126225533221248
2019-11-26 22:55:33,254 [salt.minion      :1432][INFO    ][12453] Starting a new job with PID 12453
2019-11-26 22:55:33,961 [salt.state       :915 ][INFO    ][12453] Loading fresh modules for state activity
2019-11-26 22:55:34,051 [salt.state       :1780][INFO    ][12453] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 22:55:34.051390
2019-11-26 22:55:34,051 [salt.state       :1813][INFO    ][12453] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-26 22:55:34,054 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12453] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-26 22:55:35,507 [salt.state       :300 ][INFO    ][12453] {'pid': 12460, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-26 22:55:35,507 [salt.state       :1951][INFO    ][12453] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 22:55:35.507661 duration_in_ms=1456.272
2019-11-26 22:55:35,508 [salt.state       :1780][INFO    ][12453] Running state [maas.deploy_machines] at time 22:55:35.508858
2019-11-26 22:55:35,509 [salt.state       :1813][INFO    ][12453] Executing state module.run for [maas.deploy_machines]
2019-11-26 22:55:35,509 [salt.utils.decorators:613 ][WARNING ][12453] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-26 22:55:36,244 [salt.state       :300 ][INFO    ][12453] {'ret': {'updated': ['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01'], 'errors': {}, 'success': []}}
2019-11-26 22:55:36,244 [salt.state       :1951][INFO    ][12453] Completed state [maas.deploy_machines] at time 22:55:36.244814 duration_in_ms=735.955
2019-11-26 22:55:36,248 [salt.minion      :1711][INFO    ][12453] Returning information for job: 20191126225533221248
2019-11-26 22:55:36,882 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command state.apply with jid 20191126225536869118
2019-11-26 22:55:36,905 [salt.minion      :1432][INFO    ][12473] Starting a new job with PID 12473
2019-11-26 22:55:37,621 [salt.state       :915 ][INFO    ][12473] Loading fresh modules for state activity
2019-11-26 22:55:37,710 [salt.state       :1780][INFO    ][12473] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 22:55:37.710823
2019-11-26 22:55:37,711 [salt.state       :1813][INFO    ][12473] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-26 22:55:37,713 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12473] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-26 22:55:39,308 [salt.state       :300 ][INFO    ][12473] {'pid': 12480, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-26 22:55:39,309 [salt.state       :1951][INFO    ][12473] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 22:55:39.309087 duration_in_ms=1598.263
2019-11-26 22:55:39,311 [salt.state       :1780][INFO    ][12473] Running state [maas.wait_for_machine_status] at time 22:55:39.311522
2019-11-26 22:55:39,312 [salt.state       :1813][INFO    ][12473] Executing state module.run for [maas.wait_for_machine_status]
2019-11-26 22:55:39,312 [salt.utils.decorators:613 ][WARNING ][12473] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-26 22:55:43,041 [salt.state       :300 ][INFO    ][12473] {'ret': True}
2019-11-26 22:55:43,042 [salt.state       :1951][INFO    ][12473] Completed state [maas.wait_for_machine_status] at time 22:55:43.041997 duration_in_ms=3730.474
2019-11-26 22:55:43,045 [salt.minion      :1711][INFO    ][12473] Returning information for job: 20191126225536869118
2019-11-26 23:24:09,275 [salt.utils.schedule:1377][INFO    ][3081] Running scheduled job: __mine_interval
2019-11-26 23:48:14,306 [salt.minion      :1308][INFO    ][3081] User sudo_ubuntu Executing command cp.push_dir with jid 20191126234814294136
2019-11-26 23:48:14,323 [salt.minion      :1432][INFO    ][16639] Starting a new job with PID 16639
