2019-03-15 08:18:45,825 [salt.utils.decorators:613 ][WARNING ][2079] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-15 08:18:46,346 [salt.utils.decorators:613 ][WARNING ][2079] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-15 08:18:48,363 [salt.loaded.int.states.file:2298][WARNING ][2387] 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-03-15 08:19:16,866 [salt.state       :2022][WARNING ][3075] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-03-15 08:19:19,325 [salt.utils.decorators:613 ][WARNING ][3075] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-15 08:19:39,831 [salt.utils.decorators:613 ][WARNING ][3075] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-15 08:20:16,091 [salt.utils.decorators:613 ][WARNING ][3075] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-15 08:20:17,121 [salt.utils.decorators:613 ][WARNING ][3075] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-15 08:20:22,911 [salt.loaded.ext.module.maasng:1008][WARNING ][3075] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-03-15 08:20:22,911 [salt.loaded.ext.module.maasng:1011][WARNING ][3075] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-03-15 08:20:23,726 [salt.loaded.ext.module.maasng:1235][WARNING ][3075] Ignoring parameter vlan:0
2019-03-15 08:20:24,627 [salt.utils.decorators:613 ][WARNING ][3075] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-15 08:20:26,527 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6476] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-03-15 08:20:26,560 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6476] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-03-15 08:20:26,582 [salt.utils.parsers:1051][WARNING ][360] Minion received a SIGTERM. Exiting.
2019-03-15 08:20:27,566 [salt.cli.daemons :293 ][INFO    ][6545] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-03-15 08:20:27,646 [salt.cli.daemons :82  ][INFO    ][6545] Starting up the Salt Minion
2019-03-15 08:20:27,646 [salt.utils.event :1017][INFO    ][6545] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-03-15 08:20:28,420 [salt.minion      :976 ][INFO    ][6545] Creating minion process manager
2019-03-15 08:20:29,692 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][6545] Executing command ['date', '+%z'] in directory '/root'
2019-03-15 08:20:29,711 [salt.utils.schedule:568 ][INFO    ][6545] Updating job settings for scheduled job: __mine_interval
2019-03-15 08:20:29,751 [salt.minion      :1108][INFO    ][6545] Added mine.update to scheduler
2019-03-15 08:20:29,758 [salt.minion      :1975][INFO    ][6545] Minion is starting as user 'root'
2019-03-15 08:20:29,771 [salt.minion      :2336][INFO    ][6545] Minion is ready to receive requests!
2019-03-15 08:20:30,116 [salt.utils.decorators:613 ][WARNING ][6482] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-15 08:20:30,188 [salt.loaded.ext.module.maas:412 ][WARNING ][6482] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-15 08:20:31,745 [salt.loaded.ext.module.maas:412 ][WARNING ][6482] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-15 08:20:33,164 [salt.loaded.ext.module.maas:412 ][WARNING ][6482] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-15 08:20:34,613 [salt.loaded.ext.module.maas:412 ][WARNING ][6482] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-15 08:20:36,003 [salt.loaded.ext.module.maas:412 ][WARNING ][6482] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-15 08:21:08,542 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command state.apply with jid 20190315082108529447
2019-03-15 08:21:08,568 [salt.minion      :1432][INFO    ][6940] Starting a new job with PID 6940
2019-03-15 08:21:12,051 [salt.state       :915 ][INFO    ][6940] Loading fresh modules for state activity
2019-03-15 08:21:12,108 [salt.fileclient  :1219][INFO    ][6940] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-03-15 08:21:12,155 [salt.state       :1780][INFO    ][6940] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:21:12.155112
2019-03-15 08:21:12,155 [salt.state       :1813][INFO    ][6940] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-15 08:21:12,157 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6940] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-15 08:21:13,570 [salt.state       :300 ][INFO    ][6940] {'pid': 6960, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-15 08:21:13,571 [salt.state       :1951][INFO    ][6940] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:21:13.570989 duration_in_ms=1415.878
2019-03-15 08:21:13,572 [salt.state       :1780][INFO    ][6940] Running state [maas.wait_for_machine_status] at time 08:21:13.572134
2019-03-15 08:21:13,572 [salt.state       :1813][INFO    ][6940] Executing state module.run for [maas.wait_for_machine_status]
2019-03-15 08:21:13,572 [salt.utils.decorators:613 ][WARNING ][6940] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-15 08:21:14,422 [salt.loaded.ext.module.maas:1023][INFO    ][6940] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.15429306s left)
2019-03-15 08:21:23,670 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command saltutil.find_job with jid 20190315082123655630
2019-03-15 08:21:23,689 [salt.minion      :1432][INFO    ][6986] Starting a new job with PID 6986
2019-03-15 08:21:23,699 [salt.minion      :1711][INFO    ][6986] Returning information for job: 20190315082123655630
2019-03-15 08:21:45,481 [salt.loaded.ext.module.maas:1023][INFO    ][6940] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.09576607s left)
2019-03-15 08:21:53,698 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command saltutil.find_job with jid 20190315082153686041
2019-03-15 08:21:53,724 [salt.minion      :1432][INFO    ][7021] Starting a new job with PID 7021
2019-03-15 08:21:53,748 [salt.minion      :1711][INFO    ][7021] Returning information for job: 20190315082153686041
2019-03-15 08:22:16,769 [salt.loaded.ext.module.maas:1023][INFO    ][6940] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1436.80806708s left)
2019-03-15 08:22:23,756 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command saltutil.find_job with jid 20190315082223750517
2019-03-15 08:22:23,771 [salt.minion      :1432][INFO    ][7180] Starting a new job with PID 7180
2019-03-15 08:22:23,790 [salt.minion      :1711][INFO    ][7180] Returning information for job: 20190315082223750517
2019-03-15 08:22:48,140 [salt.loaded.ext.module.maas:1023][INFO    ][6940] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1405.43717313s left)
2019-03-15 08:22:53,789 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command saltutil.find_job with jid 20190315082253773198
2019-03-15 08:22:53,814 [salt.minion      :1432][INFO    ][7369] Starting a new job with PID 7369
2019-03-15 08:22:53,836 [salt.minion      :1711][INFO    ][7369] Returning information for job: 20190315082253773198
2019-03-15 08:23:19,644 [salt.loaded.ext.module.maas:1023][INFO    ][6940] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1373.93224597s left)
2019-03-15 08:23:23,858 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command saltutil.find_job with jid 20190315082323841127
2019-03-15 08:23:23,882 [salt.minion      :1432][INFO    ][7927] Starting a new job with PID 7927
2019-03-15 08:23:23,903 [salt.minion      :1711][INFO    ][7927] Returning information for job: 20190315082323841127
2019-03-15 08:23:51,451 [salt.loaded.ext.module.maas:1023][INFO    ][6940] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1342.12539506s left)
2019-03-15 08:23:53,913 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command saltutil.find_job with jid 20190315082353895983
2019-03-15 08:23:53,939 [salt.minion      :1432][INFO    ][8081] Starting a new job with PID 8081
2019-03-15 08:23:53,961 [salt.minion      :1711][INFO    ][8081] Returning information for job: 20190315082353895983
2019-03-15 08:24:23,977 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command saltutil.find_job with jid 20190315082423963748
2019-03-15 08:24:24,000 [salt.minion      :1432][INFO    ][8271] Starting a new job with PID 8271
2019-03-15 08:24:24,024 [salt.minion      :1711][INFO    ][8271] Returning information for job: 20190315082423963748
2019-03-15 08:24:24,607 [salt.loaded.ext.module.maas:1023][INFO    ][6940] Waiting status:Ready|Deployed for machines:['cmp002', 'kvm03']
sleep for:30s Timeout:1500s (1308.96981096s left)
2019-03-15 08:24:54,036 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command saltutil.find_job with jid 20190315082454023432
2019-03-15 08:24:54,061 [salt.minion      :1432][INFO    ][8373] Starting a new job with PID 8373
2019-03-15 08:24:54,083 [salt.minion      :1711][INFO    ][8373] Returning information for job: 20190315082454023432
2019-03-15 08:24:58,302 [salt.state       :300 ][INFO    ][6940] {'ret': True}
2019-03-15 08:24:58,303 [salt.state       :1951][INFO    ][6940] Completed state [maas.wait_for_machine_status] at time 08:24:58.303147 duration_in_ms=224731.01
2019-03-15 08:24:58,307 [salt.minion      :1711][INFO    ][6940] Returning information for job: 20190315082108529447
2019-03-15 08:24:58,943 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command state.apply with jid 20190315082458928724
2019-03-15 08:24:58,966 [salt.minion      :1432][INFO    ][8385] Starting a new job with PID 8385
2019-03-15 08:25:02,392 [salt.state       :915 ][INFO    ][8385] Loading fresh modules for state activity
2019-03-15 08:25:02,449 [salt.fileclient  :1219][INFO    ][8385] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-03-15 08:25:02,538 [salt.state       :1780][INFO    ][8385] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:25:02.538501
2019-03-15 08:25:02,538 [salt.state       :1813][INFO    ][8385] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-15 08:25:02,540 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8385] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-15 08:25:03,962 [salt.state       :300 ][INFO    ][8385] {'pid': 8393, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-15 08:25:03,963 [salt.state       :1951][INFO    ][8385] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:25:03.963288 duration_in_ms=1424.788
2019-03-15 08:25:03,964 [salt.state       :1780][INFO    ][8385] Running state [maas_machines_storage_cmp002_lvm] at time 08:25:03.964671
2019-03-15 08:25:03,964 [salt.state       :1813][INFO    ][8385] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-03-15 08:25:05,318 [salt.loaded.ext.module.maasng:610 ][INFO    ][8385] psm7wc
2019-03-15 08:25:05,319 [salt.loaded.ext.module.maasng:626 ][INFO    ][8385] sda
2019-03-15 08:25:05,959 [salt.loaded.ext.module.maasng:361 ][INFO    ][8385] psm7wc
2019-03-15 08:25:06,098 [salt.loaded.ext.module.maasng:367 ][INFO    ][8385] [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/psm7wc/blockdevices/2/', u'available_size': 0, u'uuid': None, u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'93e804df-b85c-40d6-850a-df0c70f357fe', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'psm7wc', u'device_id': 2, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'cc8ac598-4c8d-4011-b9a9-3a1f1ed025ef', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/psm7wc/blockdevices/2/partition/3', u'type': u'partition', u'id': 3, u'size': 2397992648704}], u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'psm7wc', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'type': u'physical', u'id': 2, u'name': u'sda'}, {u'size': 2397988454400, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/psm7wc/blockdevices/7/', u'available_size': 0, u'uuid': u'45f19910-4c92-4b4e-ad22-dac72d1b4bce', u'tags': [], u'used_size': 2397988454400, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'psm7wc', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'bc1441d6-93f1-4d4b-8edc-9b97b236e8ab', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 7, u'name': u'vgroot-lvroot'}]
2019-03-15 08:25:06,099 [salt.loaded.ext.module.maasng:632 ][INFO    ][8385] vgroot
2019-03-15 08:25:06,099 [salt.loaded.ext.module.maasng:635 ][INFO    ][8385] lvroot
2019-03-15 08:25:06,099 [salt.loaded.ext.module.maasng:639 ][INFO    ][8385] 107374182400
2019-03-15 08:25:06,836 [salt.loaded.ext.module.maasng:645 ][INFO    ][8385] {u'hwe_kernel': u'', u'swap_size': None, u'disable_ipv4': False, u'cpu_count': 16, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'description': u''}, u'resource_uri': u'/MAAS/api/2.0/machines/psm7wc/', u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'commissioning_status': 2, u'testing_status': 2, u'system_id': u'psm7wc', u'power_state': u'off', u'memory': 32768, u'commissioning_status_name': u'Passed', u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/psm7wc/blockdevices/11/', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'2a513db9-c6dc-488b-a694-b0fb08f98cc5', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'psm7wc', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 11, u'uuid': u'b88f987b-f7d8-41f6-81b3-9849500b6891'}], u'blockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/psm7wc/blockdevices/2/', u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'size': 2397992648704, u'uuid': u'b301a5b7-f1b1-4820-87cd-ce218e1eec0a', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'psm7wc', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'0f738608-f2c5-4c5c-a4e6-6fce54f49766', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 2, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/psm7wc/blockdevices/2/partition/6'}], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'psm7wc', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'type': u'physical', u'id': 2, u'uuid': None}, {u'size': 107374182400, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/psm7wc/blockdevices/11/', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'2a513db9-c6dc-488b-a694-b0fb08f98cc5', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'psm7wc', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 11, u'uuid': u'b88f987b-f7d8-41f6-81b3-9849500b6891'}], u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'psm7wc', u'id': 6}], u'current_installation_result_id': None, u'special_filesystems': [], u'current_commissioning_result_id': 2, u'boot_disk': {u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/psm7wc/blockdevices/2/', u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'size': 2397992648704, u'uuid': u'b301a5b7-f1b1-4820-87cd-ce218e1eec0a', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'psm7wc', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'0f738608-f2c5-4c5c-a4e6-6fce54f49766', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 2, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/psm7wc/blockdevices/2/partition/6'}], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'psm7wc', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'type': u'physical', u'id': 2, u'uuid': None}, u'interface_set': [{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'primary_rack': u'c67btq', 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'fabric': u'pxe_admin'}, 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'primary_rack': u'c67btq', 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'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'id': 22, u'mode': u'dhcp'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6a', u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'c67btq', 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'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'psm7wc', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/psm7wc/interfaces/4/'}, {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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'name': u'enp8s0', u'links': [{u'id': 23, u'mode': u'link_up'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6c', u'enabled': True, u'children': [], u'discovered': None, u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'psm7wc', u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/psm7wc/interfaces/15/'}, {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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'name': u'enp7s0', u'links': [{u'id': 25, u'mode': u'link_up'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6b', u'enabled': True, u'children': [], u'discovered': None, u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'psm7wc', u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/psm7wc/interfaces/16/'}, {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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'name': u'enp9s0', u'links': [{u'id': 27, u'mode': u'link_up'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6d', u'enabled': True, u'children': [], u'discovered': None, u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'psm7wc', u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/psm7wc/interfaces/17/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'bcaches': [], u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/psm7wc/blockdevices/2/', u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'size': 2397992648704, u'uuid': u'b301a5b7-f1b1-4820-87cd-ce218e1eec0a', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'psm7wc', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'0f738608-f2c5-4c5c-a4e6-6fce54f49766', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 2, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/psm7wc/blockdevices/2/partition/6'}], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'psm7wc', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'type': u'physical', u'id': 2, u'uuid': None}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'node_type': 0, u'ip_addresses': [u'192.168.11.42'], u'min_hwe_kernel': u'hwe-16.04', u'boot_interface': {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'primary_rack': u'c67btq', 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'fabric': u'pxe_admin'}, 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'primary_rack': u'c67btq', 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'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'id': 22, u'mode': u'dhcp'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6a', u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'c67btq', 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'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'psm7wc', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/psm7wc/interfaces/4/'}, u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-03-15 08:25:06,839 [salt.state       :300 ][INFO    ][8385] {'new': {'storage_layout': 'lvm'}}
2019-03-15 08:25:06,840 [salt.state       :1951][INFO    ][8385] Completed state [maas_machines_storage_cmp002_lvm] at time 08:25:06.840280 duration_in_ms=2875.605
2019-03-15 08:25:06,841 [salt.state       :1780][INFO    ][8385] Running state [maas_machines_storage_cmp001_lvm] at time 08:25:06.841117
2019-03-15 08:25:06,841 [salt.state       :1813][INFO    ][8385] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-03-15 08:25:08,295 [salt.loaded.ext.module.maasng:610 ][INFO    ][8385] fcgtm8
2019-03-15 08:25:08,296 [salt.loaded.ext.module.maasng:626 ][INFO    ][8385] sda
2019-03-15 08:25:08,966 [salt.loaded.ext.module.maasng:361 ][INFO    ][8385] fcgtm8
2019-03-15 08:25:09,104 [salt.loaded.ext.module.maasng:367 ][INFO    ][8385] [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/fcgtm8/blockdevices/4/', u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'size': 2397992648704, u'uuid': u'f6d04370-284a-4daf-b65e-3f9abf4a23b1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'fcgtm8', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'baa761b7-ede0-43c2-952f-e36b7e958559', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 4, u'type': u'partition', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/fcgtm8/blockdevices/4/partition/2'}], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'fcgtm8', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'type': u'physical', u'id': 4, u'uuid': None}, {u'size': 2397988454400, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/fcgtm8/blockdevices/8/', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 2397988454400, u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'0bc4954a-d6a3-4813-b10c-41c3a55e2fd4', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'fcgtm8', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 8, u'uuid': u'32f551e0-e6c3-4fe1-ae56-6bcdc698a365'}]
2019-03-15 08:25:09,104 [salt.loaded.ext.module.maasng:632 ][INFO    ][8385] vgroot
2019-03-15 08:25:09,104 [salt.loaded.ext.module.maasng:635 ][INFO    ][8385] lvroot
2019-03-15 08:25:09,104 [salt.loaded.ext.module.maasng:639 ][INFO    ][8385] 107374182400
2019-03-15 08:25:09,554 [salt.loaded.ext.module.maasng:645 ][INFO    ][8385] {u'hwe_kernel': u'', u'swap_size': None, u'disable_ipv4': False, u'cpu_count': 16, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'c67btq', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 24}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'c67btq', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'c67btq', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'00:25:b5:a0:00:5a', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'fcgtm8', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/fcgtm8/interfaces/5/'}, u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'boot_disk': {u'resource_uri': u'/MAAS/api/2.0/nodes/fcgtm8/blockdevices/4/', 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'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'system_id': u'fcgtm8', u'block_size': 4096, u'partition_table_type': u'GPT', u'available_size': 0, u'id': 4, u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'partitions': [{u'uuid': u'965c887c-11b8-4f61-94b7-f10a6d3432f6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'fcgtm8', u'device_id': 4, u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'2d4c4de9-45d0-4956-a7a5-47965d8fe275', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/fcgtm8/blockdevices/4/partition/7'}], u'used_size': 2397998940160, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}, u'zone': {u'description': u'', u'id': 1, u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/fcgtm8/', u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'fcgtm8', u'raids': [], u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'ip_addresses': [u'192.168.11.38'], u'blockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/fcgtm8/blockdevices/4/', u'uuid': None, u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'965c887c-11b8-4f61-94b7-f10a6d3432f6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'fcgtm8', u'device_id': 4, u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'2d4c4de9-45d0-4956-a7a5-47965d8fe275', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/fcgtm8/blockdevices/4/partition/7'}], u'filesystem': None, u'id': 4, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'fcgtm8', u'block_size': 4096, u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'size': 2397998940160, u'used_size': 2397998940160, u'serial': u'618e72837274f1901cc7889705aa1b02', u'name': u'sda'}, {u'resource_uri': u'/MAAS/api/2.0/nodes/fcgtm8/blockdevices/12/', u'uuid': u'3abf7670-49de-48b5-9c11-94bade7b118d', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'4a282a7e-fe06-4963-8284-44a19bce1bb0', u'label': u'root'}, u'id': 12, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'fcgtm8', u'block_size': 4096, u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'size': 107374182400, u'used_size': 107374182400, u'serial': None, u'name': u'vgroot-lvroot'}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'power_state': u'off', 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'fcgtm8', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'node_type_name': u'Machine', u'current_testing_result_id': 5, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'storage_test_status': 2, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/fcgtm8/blockdevices/4/', 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'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'system_id': u'fcgtm8', u'block_size': 4096, u'partition_table_type': u'GPT', u'available_size': 0, u'id': 4, u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'partitions': [{u'uuid': u'965c887c-11b8-4f61-94b7-f10a6d3432f6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'fcgtm8', u'device_id': 4, u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'2d4c4de9-45d0-4956-a7a5-47965d8fe275', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/fcgtm8/blockdevices/4/partition/7'}], u'used_size': 2397998940160, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'node_type': 0, u'virtualblockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/fcgtm8/blockdevices/12/', u'uuid': u'3abf7670-49de-48b5-9c11-94bade7b118d', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'4a282a7e-fe06-4963-8284-44a19bce1bb0', u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'id_path': None, u'system_id': u'fcgtm8', u'block_size': 4096, u'partition_table_type': None, u'available_size': 0, u'id': 12, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'partitions': [], u'used_size': 107374182400, u'serial': None, u'size': 107374182400}], u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'commissioning_status_name': u'Passed', u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'c67btq', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 24}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'c67btq', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'c67btq', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'00:25:b5:a0:00:5a', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'fcgtm8', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/fcgtm8/interfaces/5/'}, {u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 26}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'id': 12, 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'fcgtm8', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/fcgtm8/interfaces/12/'}, {u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 28}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'id': 13, 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'fcgtm8', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/fcgtm8/interfaces/13/'}, {u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 29}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'id': 14, 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'fcgtm8', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/fcgtm8/interfaces/14/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-03-15 08:25:09,555 [salt.state       :300 ][INFO    ][8385] {'new': {'storage_layout': 'lvm'}}
2019-03-15 08:25:09,555 [salt.state       :1951][INFO    ][8385] Completed state [maas_machines_storage_cmp001_lvm] at time 08:25:09.555883 duration_in_ms=2714.767
2019-03-15 08:25:09,558 [salt.minion      :1711][INFO    ][8385] Returning information for job: 20190315082458928724
2019-03-15 08:25:10,128 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command state.apply with jid 20190315082510115295
2019-03-15 08:25:10,153 [salt.minion      :1432][INFO    ][8423] Starting a new job with PID 8423
2019-03-15 08:25:10,813 [salt.state       :915 ][INFO    ][8423] Loading fresh modules for state activity
2019-03-15 08:25:10,868 [salt.fileclient  :1219][INFO    ][8423] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-03-15 08:25:10,907 [salt.state       :1780][INFO    ][8423] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:25:10.907406
2019-03-15 08:25:10,907 [salt.state       :1813][INFO    ][8423] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-15 08:25:10,909 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8423] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-15 08:25:12,293 [salt.state       :300 ][INFO    ][8423] {'pid': 8430, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-15 08:25:12,294 [salt.state       :1951][INFO    ][8423] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:25:12.293946 duration_in_ms=1386.541
2019-03-15 08:25:12,295 [salt.state       :1780][INFO    ][8423] Running state [maas.deploy_machines] at time 08:25:12.295089
2019-03-15 08:25:12,295 [salt.state       :1813][INFO    ][8423] Executing state module.run for [maas.deploy_machines]
2019-03-15 08:25:12,295 [salt.utils.decorators:613 ][WARNING ][8423] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-15 08:25:13,022 [salt.loaded.ext.module.maas:684 ][INFO    ][8423] deploymachines hwe_kernel=hwe-16.04 system_id=psm7wc distro_series=xenial
2019-03-15 08:25:15,602 [salt.loaded.ext.module.maas:684 ][INFO    ][8423] deploymachines hwe_kernel=hwe-16.04 system_id=fcgtm8 distro_series=xenial
2019-03-15 08:25:18,129 [salt.loaded.ext.module.maas:684 ][INFO    ][8423] deploymachines hwe_kernel=hwe-16.04 system_id=rsgbd6 distro_series=xenial
2019-03-15 08:25:20,896 [salt.loaded.ext.module.maas:684 ][INFO    ][8423] deploymachines hwe_kernel=hwe-16.04 system_id=bdgmqx distro_series=xenial
2019-03-15 08:25:23,249 [salt.loaded.ext.module.maas:684 ][INFO    ][8423] deploymachines hwe_kernel=hwe-16.04 system_id=r8msmy distro_series=xenial
2019-03-15 08:25:25,204 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command saltutil.find_job with jid 20190315082525192689
2019-03-15 08:25:25,228 [salt.minion      :1432][INFO    ][8726] Starting a new job with PID 8726
2019-03-15 08:25:25,248 [salt.minion      :1711][INFO    ][8726] Returning information for job: 20190315082525192689
2019-03-15 08:25:25,974 [salt.state       :300 ][INFO    ][8423] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-03-15 08:25:25,975 [salt.state       :1951][INFO    ][8423] Completed state [maas.deploy_machines] at time 08:25:25.975120 duration_in_ms=13680.027
2019-03-15 08:25:25,979 [salt.minion      :1711][INFO    ][8423] Returning information for job: 20190315082510115295
2019-03-15 08:25:26,490 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command state.apply with jid 20190315082526477763
2019-03-15 08:25:26,514 [salt.minion      :1432][INFO    ][8742] Starting a new job with PID 8742
2019-03-15 08:25:30,372 [salt.state       :915 ][INFO    ][8742] Loading fresh modules for state activity
2019-03-15 08:25:30,416 [salt.fileclient  :1219][INFO    ][8742] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-03-15 08:25:30,452 [salt.state       :1780][INFO    ][8742] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:25:30.452232
2019-03-15 08:25:30,452 [salt.state       :1813][INFO    ][8742] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-15 08:25:30,454 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8742] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-15 08:25:31,895 [salt.state       :300 ][INFO    ][8742] {'pid': 8756, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-15 08:25:31,896 [salt.state       :1951][INFO    ][8742] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:25:31.896460 duration_in_ms=1444.227
2019-03-15 08:25:31,899 [salt.state       :1780][INFO    ][8742] Running state [maas.wait_for_machine_status] at time 08:25:31.899131
2019-03-15 08:25:31,899 [salt.state       :1813][INFO    ][8742] Executing state module.run for [maas.wait_for_machine_status]
2019-03-15 08:25:31,900 [salt.utils.decorators:613 ][WARNING ][8742] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-15 08:25:35,380 [salt.loaded.ext.module.maas:1023][INFO    ][8742] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.53099012s left)
2019-03-15 08:25:41,546 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command saltutil.find_job with jid 20190315082541532413
2019-03-15 08:25:41,573 [salt.minion      :1432][INFO    ][8788] Starting a new job with PID 8788
2019-03-15 08:25:41,595 [salt.minion      :1711][INFO    ][8788] Returning information for job: 20190315082541532413
2019-03-15 08:26:08,948 [salt.loaded.ext.module.maas:1023][INFO    ][8742] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2212.96311712s left)
2019-03-15 08:26:11,593 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command saltutil.find_job with jid 20190315082611578962
2019-03-15 08:26:11,617 [salt.minion      :1432][INFO    ][8816] Starting a new job with PID 8816
2019-03-15 08:26:11,640 [salt.minion      :1711][INFO    ][8816] Returning information for job: 20190315082611578962
2019-03-15 08:26:41,640 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command saltutil.find_job with jid 20190315082641630941
2019-03-15 08:26:41,663 [salt.minion      :1432][INFO    ][8862] Starting a new job with PID 8862
2019-03-15 08:26:41,676 [salt.minion      :1711][INFO    ][8862] Returning information for job: 20190315082641630941
2019-03-15 08:26:42,175 [salt.loaded.ext.module.maas:1023][INFO    ][8742] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2179.73575616s left)
2019-03-15 08:27:11,669 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command saltutil.find_job with jid 20190315082711663370
2019-03-15 08:27:11,692 [salt.minion      :1432][INFO    ][8980] Starting a new job with PID 8980
2019-03-15 08:27:11,712 [salt.minion      :1711][INFO    ][8980] Returning information for job: 20190315082711663370
2019-03-15 08:27:15,111 [salt.loaded.ext.module.maas:1023][INFO    ][8742] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2146.7996552s left)
2019-03-15 08:27:41,727 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command saltutil.find_job with jid 20190315082741714976
2019-03-15 08:27:41,750 [salt.minion      :1432][INFO    ][9253] Starting a new job with PID 9253
2019-03-15 08:27:41,773 [salt.minion      :1711][INFO    ][9253] Returning information for job: 20190315082741714976
2019-03-15 08:27:47,940 [salt.loaded.ext.module.maas:1023][INFO    ][8742] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2113.97103119s left)
2019-03-15 08:28:11,783 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command saltutil.find_job with jid 20190315082811770953
2019-03-15 08:28:11,808 [salt.minion      :1432][INFO    ][9401] Starting a new job with PID 9401
2019-03-15 08:28:11,832 [salt.minion      :1711][INFO    ][9401] Returning information for job: 20190315082811770953
2019-03-15 08:28:20,713 [salt.loaded.ext.module.maas:1023][INFO    ][8742] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2081.1979022s left)
2019-03-15 08:28:41,848 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command saltutil.find_job with jid 20190315082841837403
2019-03-15 08:28:41,875 [salt.minion      :1432][INFO    ][9948] Starting a new job with PID 9948
2019-03-15 08:28:41,892 [salt.minion      :1711][INFO    ][9948] Returning information for job: 20190315082841837403
2019-03-15 08:28:53,460 [salt.loaded.ext.module.maas:1023][INFO    ][8742] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2048.45128417s left)
2019-03-15 08:29:11,904 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command saltutil.find_job with jid 20190315082911892033
2019-03-15 08:29:11,928 [salt.minion      :1432][INFO    ][10075] Starting a new job with PID 10075
2019-03-15 08:29:11,952 [salt.minion      :1711][INFO    ][10075] Returning information for job: 20190315082911892033
2019-03-15 08:29:27,127 [salt.loaded.ext.module.maas:1023][INFO    ][8742] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2014.78426003s left)
2019-03-15 08:29:41,979 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command saltutil.find_job with jid 20190315082941962880
2019-03-15 08:29:42,002 [salt.minion      :1432][INFO    ][10235] Starting a new job with PID 10235
2019-03-15 08:29:42,022 [salt.minion      :1711][INFO    ][10235] Returning information for job: 20190315082941962880
2019-03-15 08:30:00,363 [salt.loaded.ext.module.maas:1023][INFO    ][8742] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1981.54811215s left)
2019-03-15 08:30:12,046 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command saltutil.find_job with jid 20190315083012033846
2019-03-15 08:30:12,072 [salt.minion      :1432][INFO    ][10286] Starting a new job with PID 10286
2019-03-15 08:30:12,094 [salt.minion      :1711][INFO    ][10286] Returning information for job: 20190315083012033846
2019-03-15 08:30:33,679 [salt.loaded.ext.module.maas:1023][INFO    ][8742] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1948.23208022s left)
2019-03-15 08:30:42,126 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command saltutil.find_job with jid 20190315083042113287
2019-03-15 08:30:42,148 [salt.minion      :1432][INFO    ][10593] Starting a new job with PID 10593
2019-03-15 08:30:42,170 [salt.minion      :1711][INFO    ][10593] Returning information for job: 20190315083042113287
2019-03-15 08:31:07,016 [salt.loaded.ext.module.maas:1023][INFO    ][8742] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1914.89541817s left)
2019-03-15 08:31:12,190 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command saltutil.find_job with jid 20190315083112178583
2019-03-15 08:31:12,216 [salt.minion      :1432][INFO    ][10677] Starting a new job with PID 10677
2019-03-15 08:31:12,238 [salt.minion      :1711][INFO    ][10677] Returning information for job: 20190315083112178583
2019-03-15 08:31:40,699 [salt.loaded.ext.module.maas:1023][INFO    ][8742] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1881.21183014s left)
2019-03-15 08:31:42,275 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command saltutil.find_job with jid 20190315083142263056
2019-03-15 08:31:42,300 [salt.minion      :1432][INFO    ][10876] Starting a new job with PID 10876
2019-03-15 08:31:42,323 [salt.minion      :1711][INFO    ][10876] Returning information for job: 20190315083142263056
2019-03-15 08:32:12,361 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command saltutil.find_job with jid 20190315083212348914
2019-03-15 08:32:12,386 [salt.minion      :1432][INFO    ][10947] Starting a new job with PID 10947
2019-03-15 08:32:12,407 [salt.minion      :1711][INFO    ][10947] Returning information for job: 20190315083212348914
2019-03-15 08:32:14,005 [salt.loaded.ext.module.maas:1023][INFO    ][8742] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1847.90621114s left)
2019-03-15 08:32:42,503 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command saltutil.find_job with jid 20190315083242491939
2019-03-15 08:32:42,524 [salt.minion      :1432][INFO    ][11132] Starting a new job with PID 11132
2019-03-15 08:32:42,546 [salt.minion      :1711][INFO    ][11132] Returning information for job: 20190315083242491939
2019-03-15 08:32:47,623 [salt.loaded.ext.module.maas:1023][INFO    ][8742] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1814.28759503s left)
2019-03-15 08:33:12,598 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command saltutil.find_job with jid 20190315083312584977
2019-03-15 08:33:12,623 [salt.minion      :1432][INFO    ][11210] Starting a new job with PID 11210
2019-03-15 08:33:12,645 [salt.minion      :1711][INFO    ][11210] Returning information for job: 20190315083312584977
2019-03-15 08:33:20,357 [salt.loaded.ext.module.maas:1023][INFO    ][8742] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1781.55356812s left)
2019-03-15 08:33:42,703 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command saltutil.find_job with jid 20190315083342690024
2019-03-15 08:33:42,727 [salt.minion      :1432][INFO    ][11574] Starting a new job with PID 11574
2019-03-15 08:33:42,751 [salt.minion      :1711][INFO    ][11574] Returning information for job: 20190315083342690024
2019-03-15 08:33:53,989 [salt.loaded.ext.module.maas:1023][INFO    ][8742] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1747.92189121s left)
2019-03-15 08:34:12,814 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command saltutil.find_job with jid 20190315083412806443
2019-03-15 08:34:12,838 [salt.minion      :1432][INFO    ][11611] Starting a new job with PID 11611
2019-03-15 08:34:12,859 [salt.minion      :1711][INFO    ][11611] Returning information for job: 20190315083412806443
2019-03-15 08:34:27,271 [salt.loaded.ext.module.maas:1023][INFO    ][8742] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1714.63990021s left)
2019-03-15 08:34:42,930 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command saltutil.find_job with jid 20190315083442917096
2019-03-15 08:34:42,956 [salt.minion      :1432][INFO    ][11657] Starting a new job with PID 11657
2019-03-15 08:34:42,979 [salt.minion      :1711][INFO    ][11657] Returning information for job: 20190315083442917096
2019-03-15 08:35:00,612 [salt.loaded.ext.module.maas:1023][INFO    ][8742] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1681.29856205s left)
2019-03-15 08:35:13,059 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command saltutil.find_job with jid 20190315083513046309
2019-03-15 08:35:13,085 [salt.minion      :1432][INFO    ][11685] Starting a new job with PID 11685
2019-03-15 08:35:13,106 [salt.minion      :1711][INFO    ][11685] Returning information for job: 20190315083513046309
2019-03-15 08:35:34,349 [salt.loaded.ext.module.maas:1023][INFO    ][8742] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1647.56230521s left)
2019-03-15 08:35:43,194 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command saltutil.find_job with jid 20190315083543181143
2019-03-15 08:35:43,219 [salt.minion      :1432][INFO    ][11732] Starting a new job with PID 11732
2019-03-15 08:35:43,241 [salt.minion      :1711][INFO    ][11732] Returning information for job: 20190315083543181143
2019-03-15 08:36:07,546 [salt.loaded.ext.module.maas:1023][INFO    ][8742] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1614.36519217s left)
2019-03-15 08:36:13,346 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command saltutil.find_job with jid 20190315083613329799
2019-03-15 08:36:13,371 [salt.minion      :1432][INFO    ][11761] Starting a new job with PID 11761
2019-03-15 08:36:13,390 [salt.minion      :1711][INFO    ][11761] Returning information for job: 20190315083613329799
2019-03-15 08:36:41,063 [salt.loaded.ext.module.maas:1023][INFO    ][8742] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1580.84767222s left)
2019-03-15 08:36:43,496 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command saltutil.find_job with jid 20190315083643483577
2019-03-15 08:36:43,521 [salt.minion      :1432][INFO    ][11813] Starting a new job with PID 11813
2019-03-15 08:36:43,544 [salt.minion      :1711][INFO    ][11813] Returning information for job: 20190315083643483577
2019-03-15 08:37:13,664 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command saltutil.find_job with jid 20190315083713647554
2019-03-15 08:37:13,686 [salt.minion      :1432][INFO    ][11849] Starting a new job with PID 11849
2019-03-15 08:37:13,707 [salt.minion      :1711][INFO    ][11849] Returning information for job: 20190315083713647554
2019-03-15 08:37:14,318 [salt.loaded.ext.module.maas:1023][INFO    ][8742] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1547.59310222s left)
2019-03-15 08:37:43,830 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command saltutil.find_job with jid 20190315083743817441
2019-03-15 08:37:43,854 [salt.minion      :1432][INFO    ][11892] Starting a new job with PID 11892
2019-03-15 08:37:43,876 [salt.minion      :1711][INFO    ][11892] Returning information for job: 20190315083743817441
2019-03-15 08:37:47,871 [salt.loaded.ext.module.maas:1023][INFO    ][8742] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1514.0395422s left)
2019-03-15 08:38:14,007 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command saltutil.find_job with jid 20190315083813994147
2019-03-15 08:38:14,032 [salt.minion      :1432][INFO    ][11921] Starting a new job with PID 11921
2019-03-15 08:38:14,053 [salt.minion      :1711][INFO    ][11921] Returning information for job: 20190315083813994147
2019-03-15 08:38:21,444 [salt.loaded.ext.module.maas:1023][INFO    ][8742] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1480.46671009s left)
2019-03-15 08:38:44,196 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command saltutil.find_job with jid 20190315083844183149
2019-03-15 08:38:44,222 [salt.minion      :1432][INFO    ][11969] Starting a new job with PID 11969
2019-03-15 08:38:44,246 [salt.minion      :1711][INFO    ][11969] Returning information for job: 20190315083844183149
2019-03-15 08:38:54,633 [salt.loaded.ext.module.maas:1023][INFO    ][8742] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1447.27833104s left)
2019-03-15 08:39:14,401 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command saltutil.find_job with jid 20190315083914388555
2019-03-15 08:39:14,427 [salt.minion      :1432][INFO    ][12130] Starting a new job with PID 12130
2019-03-15 08:39:14,448 [salt.minion      :1711][INFO    ][12130] Returning information for job: 20190315083914388555
2019-03-15 08:39:28,106 [salt.loaded.ext.module.maas:1023][INFO    ][8742] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1413.8051672s left)
2019-03-15 08:39:44,611 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command saltutil.find_job with jid 20190315083944597917
2019-03-15 08:39:44,634 [salt.minion      :1432][INFO    ][12194] Starting a new job with PID 12194
2019-03-15 08:39:44,657 [salt.minion      :1711][INFO    ][12194] Returning information for job: 20190315083944597917
2019-03-15 08:40:01,403 [salt.loaded.ext.module.maas:1023][INFO    ][8742] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1380.50797415s left)
2019-03-15 08:40:14,831 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command saltutil.find_job with jid 20190315084014818005
2019-03-15 08:40:14,857 [salt.minion      :1432][INFO    ][12223] Starting a new job with PID 12223
2019-03-15 08:40:14,879 [salt.minion      :1711][INFO    ][12223] Returning information for job: 20190315084014818005
2019-03-15 08:40:34,942 [salt.loaded.ext.module.maas:1023][INFO    ][8742] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1346.96870112s left)
2019-03-15 08:40:45,059 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command saltutil.find_job with jid 20190315084045046133
2019-03-15 08:40:45,083 [salt.minion      :1432][INFO    ][12275] Starting a new job with PID 12275
2019-03-15 08:40:45,104 [salt.minion      :1711][INFO    ][12275] Returning information for job: 20190315084045046133
2019-03-15 08:41:07,418 [salt.loaded.ext.module.maas:993 ][INFO    ][8742] Machine bdgmqx mark broken
2019-03-15 08:41:08,180 [salt.loaded.ext.module.maas:996 ][INFO    ][8742] Machine bdgmqx mark fixed
2019-03-15 08:41:09,220 [salt.loaded.ext.module.maas:684 ][INFO    ][8742] deploymachines hwe_kernel=hwe-16.04 system_id=bdgmqx distro_series=xenial
2019-03-15 08:41:11,930 [salt.loaded.ext.module.maas:160 ][ERROR   ][8742] Failed for object kvm03 reason Unable to change power state to 'cycle' for node kvm03: another action is already in progress for that node.
2019-03-15 08:41:11,932 [salt.state       :302 ][ERROR   ][8742] Module function maas.wait_for_machine_status threw an exception. Exception: {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm02'], 'errors': {'kvm03': "Unable to change power state to 'cycle' for node kvm03: another action is already in progress for that node."}, 'success': []}
2019-03-15 08:41:11,932 [salt.state       :1951][INFO    ][8742] Completed state [maas.wait_for_machine_status] at time 08:41:11.932530 duration_in_ms=940033.393
2019-03-15 08:41:11,937 [salt.minion      :1711][INFO    ][8742] Returning information for job: 20190315082526477763
2019-03-15 08:41:22,658 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command pillar.get with jid 20190315084122646145
2019-03-15 08:41:22,682 [salt.minion      :1432][INFO    ][12394] Starting a new job with PID 12394
2019-03-15 08:41:22,692 [salt.minion      :1711][INFO    ][12394] Returning information for job: 20190315084122646145
2019-03-15 08:41:23,238 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command service.status with jid 20190315084123225389
2019-03-15 08:41:23,261 [salt.minion      :1432][INFO    ][12399] Starting a new job with PID 12399
2019-03-15 08:41:23,639 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12399] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-03-15 08:41:23,674 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12399] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-03-15 08:41:23,690 [salt.minion      :1711][INFO    ][12399] Returning information for job: 20190315084123225389
2019-03-15 08:41:24,244 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command state.apply with jid 20190315084124232541
2019-03-15 08:41:24,267 [salt.minion      :1432][INFO    ][12411] Starting a new job with PID 12411
2019-03-15 08:41:27,744 [salt.state       :915 ][INFO    ][12411] Loading fresh modules for state activity
2019-03-15 08:41:28,213 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12411] Executing command 'salt-minion --version' in directory '/root'
2019-03-15 08:41:28,535 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12411] Executing command 'salt-minion --version' in directory '/root'
2019-03-15 08:41:29,454 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12411] Executing command 'salt-minion --version' in directory '/root'
2019-03-15 08:41:29,822 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12411] Executing command 'salt-minion --version' in directory '/root'
2019-03-15 08:41:31,200 [salt.state       :1780][INFO    ][12411] Running state [salt-minion] at time 08:41:31.200340
2019-03-15 08:41:31,200 [salt.state       :1813][INFO    ][12411] Executing state pkg.installed for [salt-minion]
2019-03-15 08:41:31,201 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12411] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-03-15 08:41:31,288 [salt.state       :300 ][INFO    ][12411] All specified packages are already installed
2019-03-15 08:41:31,288 [salt.state       :1951][INFO    ][12411] Completed state [salt-minion] at time 08:41:31.288541 duration_in_ms=88.2
2019-03-15 08:41:31,288 [salt.state       :1780][INFO    ][12411] Running state [salt_minion_dependency_packages] at time 08:41:31.288875
2019-03-15 08:41:31,289 [salt.state       :1813][INFO    ][12411] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-03-15 08:41:31,295 [salt.state       :300 ][INFO    ][12411] All specified packages are already installed
2019-03-15 08:41:31,295 [salt.state       :1951][INFO    ][12411] Completed state [salt_minion_dependency_packages] at time 08:41:31.295844 duration_in_ms=6.968
2019-03-15 08:41:31,298 [salt.state       :1780][INFO    ][12411] Running state [/etc/salt/minion.d/minion.conf] at time 08:41:31.298662
2019-03-15 08:41:31,298 [salt.state       :1813][INFO    ][12411] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-03-15 08:41:31,531 [salt.state       :300 ][INFO    ][12411] File /etc/salt/minion.d/minion.conf is in the correct state
2019-03-15 08:41:31,531 [salt.state       :1951][INFO    ][12411] Completed state [/etc/salt/minion.d/minion.conf] at time 08:41:31.531286 duration_in_ms=232.624
2019-03-15 08:41:31,531 [salt.state       :1780][INFO    ][12411] Running state [python-netaddr] at time 08:41:31.531595
2019-03-15 08:41:31,531 [salt.state       :1813][INFO    ][12411] Executing state pkg.installed for [python-netaddr]
2019-03-15 08:41:31,539 [salt.state       :300 ][INFO    ][12411] All specified packages are already installed
2019-03-15 08:41:31,539 [salt.state       :1951][INFO    ][12411] Completed state [python-netaddr] at time 08:41:31.539908 duration_in_ms=8.314
2019-03-15 08:41:31,543 [salt.state       :1780][INFO    ][12411] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 08:41:31.543250
2019-03-15 08:41:31,543 [salt.state       :1813][INFO    ][12411] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-03-15 08:41:31,555 [salt.state       :300 ][INFO    ][12411] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-03-15 08:41:31,556 [salt.state       :1951][INFO    ][12411] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 08:41:31.556098 duration_in_ms=12.848
2019-03-15 08:41:31,557 [salt.state       :1780][INFO    ][12411] Running state [salt-minion] at time 08:41:31.557196
2019-03-15 08:41:31,557 [salt.state       :1813][INFO    ][12411] Executing state service.running for [salt-minion]
2019-03-15 08:41:31,558 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12411] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-03-15 08:41:31,595 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12411] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-03-15 08:41:31,613 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12411] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-03-15 08:41:31,630 [salt.state       :300 ][INFO    ][12411] The service salt-minion is already running
2019-03-15 08:41:31,630 [salt.state       :1951][INFO    ][12411] Completed state [salt-minion] at time 08:41:31.630551 duration_in_ms=73.355
2019-03-15 08:41:31,632 [salt.state       :1780][INFO    ][12411] Running state [/etc/salt/grains.d] at time 08:41:31.632437
2019-03-15 08:41:31,632 [salt.state       :1813][INFO    ][12411] Executing state file.directory for [/etc/salt/grains.d]
2019-03-15 08:41:31,634 [salt.state       :300 ][INFO    ][12411] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-03-15 08:41:31,634 [salt.state       :1951][INFO    ][12411] Completed state [/etc/salt/grains.d] at time 08:41:31.634271 duration_in_ms=1.833
2019-03-15 08:41:31,635 [salt.state       :1780][INFO    ][12411] Running state [/etc/salt/grains] at time 08:41:31.635108
2019-03-15 08:41:31,635 [salt.state       :1813][INFO    ][12411] Executing state file.managed for [/etc/salt/grains]
2019-03-15 08:41:31,636 [salt.state       :300 ][INFO    ][12411] File /etc/salt/grains exists with proper permissions. No changes made.
2019-03-15 08:41:31,636 [salt.state       :1951][INFO    ][12411] Completed state [/etc/salt/grains] at time 08:41:31.636426 duration_in_ms=1.318
2019-03-15 08:41:31,637 [salt.state       :1780][INFO    ][12411] Running state [/etc/salt/grains.d/placeholder] at time 08:41:31.637013
2019-03-15 08:41:31,637 [salt.state       :1813][INFO    ][12411] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-03-15 08:41:31,638 [salt.state       :300 ][INFO    ][12411] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-03-15 08:41:31,638 [salt.state       :1951][INFO    ][12411] Completed state [/etc/salt/grains.d/placeholder] at time 08:41:31.638272 duration_in_ms=1.26
2019-03-15 08:41:31,638 [salt.state       :1780][INFO    ][12411] Running state [/etc/salt/grains.d/sphinx] at time 08:41:31.638840
2019-03-15 08:41:31,639 [salt.state       :1813][INFO    ][12411] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-03-15 08:41:31,650 [salt.state       :300 ][INFO    ][12411] File /etc/salt/grains.d/sphinx is in the correct state
2019-03-15 08:41:31,651 [salt.state       :1951][INFO    ][12411] Completed state [/etc/salt/grains.d/sphinx] at time 08:41:31.651087 duration_in_ms=12.248
2019-03-15 08:41:31,653 [salt.state       :1780][INFO    ][12411] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 08:41:31.653710
2019-03-15 08:41:31,654 [salt.state       :1813][INFO    ][12411] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-03-15 08:41:31,654 [salt.state       :300 ][INFO    ][12411] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-03-15 08:41:31,654 [salt.state       :1951][INFO    ][12411] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 08:41:31.654764 duration_in_ms=1.053
2019-03-15 08:41:31,655 [salt.state       :1780][INFO    ][12411] Running state [/etc/salt/grains.d/dns_records] at time 08:41:31.655326
2019-03-15 08:41:31,655 [salt.state       :1813][INFO    ][12411] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-03-15 08:41:31,668 [salt.state       :300 ][INFO    ][12411] File /etc/salt/grains.d/dns_records is in the correct state
2019-03-15 08:41:31,669 [salt.state       :1951][INFO    ][12411] Completed state [/etc/salt/grains.d/dns_records] at time 08:41:31.669055 duration_in_ms=13.729
2019-03-15 08:41:31,670 [salt.state       :1780][INFO    ][12411] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 08:41:31.670155
2019-03-15 08:41:31,670 [salt.state       :1813][INFO    ][12411] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-03-15 08:41:31,670 [salt.state       :300 ][INFO    ][12411] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-03-15 08:41:31,671 [salt.state       :1951][INFO    ][12411] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 08:41:31.671186 duration_in_ms=1.031
2019-03-15 08:41:31,671 [salt.state       :1780][INFO    ][12411] Running state [/etc/salt/grains.d/salt] at time 08:41:31.671759
2019-03-15 08:41:31,672 [salt.state       :1813][INFO    ][12411] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-03-15 08:41:31,680 [salt.state       :300 ][INFO    ][12411] File /etc/salt/grains.d/salt is in the correct state
2019-03-15 08:41:31,681 [salt.state       :1951][INFO    ][12411] Completed state [/etc/salt/grains.d/salt] at time 08:41:31.680990 duration_in_ms=9.232
2019-03-15 08:41:31,682 [salt.state       :1780][INFO    ][12411] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 08:41:31.682046
2019-03-15 08:41:31,682 [salt.state       :1813][INFO    ][12411] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-03-15 08:41:31,682 [salt.state       :300 ][INFO    ][12411] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-03-15 08:41:31,683 [salt.state       :1951][INFO    ][12411] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 08:41:31.683079 duration_in_ms=1.034
2019-03-15 08:41:31,685 [salt.state       :1780][INFO    ][12411] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 08:41:31.685358
2019-03-15 08:41:31,685 [salt.state       :1813][INFO    ][12411] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-03-15 08:41:31,686 [salt.state       :300 ][INFO    ][12411] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-03-15 08:41:31,686 [salt.state       :1951][INFO    ][12411] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 08:41:31.686390 duration_in_ms=1.031
2019-03-15 08:41:31,687 [salt.state       :1780][INFO    ][12411] Running state [mine.update] at time 08:41:31.687220
2019-03-15 08:41:31,687 [salt.state       :1813][INFO    ][12411] Executing state module.wait for [mine.update]
2019-03-15 08:41:31,687 [salt.state       :300 ][INFO    ][12411] No changes made for mine.update
2019-03-15 08:41:31,688 [salt.state       :1951][INFO    ][12411] Completed state [mine.update] at time 08:41:31.688182 duration_in_ms=0.963
2019-03-15 08:41:31,688 [salt.state       :1780][INFO    ][12411] Running state [ca-certificates] at time 08:41:31.688476
2019-03-15 08:41:31,688 [salt.state       :1813][INFO    ][12411] Executing state pkg.installed for [ca-certificates]
2019-03-15 08:41:31,697 [salt.state       :300 ][INFO    ][12411] All specified packages are already installed
2019-03-15 08:41:31,697 [salt.state       :1951][INFO    ][12411] Completed state [ca-certificates] at time 08:41:31.697749 duration_in_ms=9.272
2019-03-15 08:41:31,698 [salt.state       :1780][INFO    ][12411] Running state [update-ca-certificates] at time 08:41:31.698564
2019-03-15 08:41:31,698 [salt.state       :1813][INFO    ][12411] Executing state cmd.wait for [update-ca-certificates]
2019-03-15 08:41:31,699 [salt.state       :300 ][INFO    ][12411] No changes made for update-ca-certificates
2019-03-15 08:41:31,699 [salt.state       :1951][INFO    ][12411] Completed state [update-ca-certificates] at time 08:41:31.699513 duration_in_ms=0.949
2019-03-15 08:41:31,699 [salt.state       :1780][INFO    ][12411] Running state [iptables] at time 08:41:31.699803
2019-03-15 08:41:31,700 [salt.state       :1813][INFO    ][12411] Executing state pkg.installed for [iptables]
2019-03-15 08:41:31,708 [salt.state       :300 ][INFO    ][12411] All specified packages are already installed
2019-03-15 08:41:31,708 [salt.state       :1951][INFO    ][12411] Completed state [iptables] at time 08:41:31.708230 duration_in_ms=8.427
2019-03-15 08:41:31,708 [salt.state       :1780][INFO    ][12411] Running state [iptables-persistent] at time 08:41:31.708525
2019-03-15 08:41:31,708 [salt.state       :1813][INFO    ][12411] Executing state pkg.installed for [iptables-persistent]
2019-03-15 08:41:31,716 [salt.state       :300 ][INFO    ][12411] All specified packages are already installed
2019-03-15 08:41:31,716 [salt.state       :1951][INFO    ][12411] Completed state [iptables-persistent] at time 08:41:31.716652 duration_in_ms=8.128
2019-03-15 08:41:31,717 [salt.state       :1780][INFO    ][12411] Running state [iptables_modules_v4_load] at time 08:41:31.717732
2019-03-15 08:41:31,718 [salt.state       :1813][INFO    ][12411] Executing state kmod.present for [iptables_modules_v4_load]
2019-03-15 08:41:31,718 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12411] Executing command 'lsmod' in directory '/root'
2019-03-15 08:41:31,740 [salt.state       :300 ][INFO    ][12411] Kernel modules iptable_filter, ip_tables are already present
2019-03-15 08:41:31,741 [salt.state       :1951][INFO    ][12411] Completed state [iptables_modules_v4_load] at time 08:41:31.741235 duration_in_ms=23.503
2019-03-15 08:41:31,742 [salt.state       :1780][INFO    ][12411] Running state [/etc/iptables/rules.v4] at time 08:41:31.741994
2019-03-15 08:41:31,742 [salt.state       :1813][INFO    ][12411] Executing state file.managed for [/etc/iptables/rules.v4]
2019-03-15 08:41:31,842 [salt.state       :300 ][INFO    ][12411] File /etc/iptables/rules.v4 is in the correct state
2019-03-15 08:41:31,842 [salt.state       :1951][INFO    ][12411] Completed state [/etc/iptables/rules.v4] at time 08:41:31.842851 duration_in_ms=100.857
2019-03-15 08:41:31,843 [salt.state       :1780][INFO    ][12411] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 08:41:31.843858
2019-03-15 08:41:31,844 [salt.state       :1813][INFO    ][12411] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-03-15 08:41:31,844 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12411] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-03-15 08:41:31,864 [salt.state       :300 ][INFO    ][12411] onlyif execution failed
2019-03-15 08:41:31,864 [salt.state       :1951][INFO    ][12411] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 08:41:31.864745 duration_in_ms=20.885
2019-03-15 08:41:31,866 [salt.state       :1780][INFO    ][12411] Running state [netfilter-persistent] at time 08:41:31.866527
2019-03-15 08:41:31,867 [salt.state       :1813][INFO    ][12411] Executing state service.running for [netfilter-persistent]
2019-03-15 08:41:31,868 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12411] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-03-15 08:41:31,889 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12411] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-03-15 08:41:31,908 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12411] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-03-15 08:41:31,926 [salt.state       :300 ][INFO    ][12411] The service netfilter-persistent is already running
2019-03-15 08:41:31,926 [salt.state       :1951][INFO    ][12411] Completed state [netfilter-persistent] at time 08:41:31.926501 duration_in_ms=59.974
2019-03-15 08:41:31,927 [salt.state       :1780][INFO    ][12411] Running state [iptables_extra.remove_stale_tables] at time 08:41:31.927908
2019-03-15 08:41:31,928 [salt.state       :1813][INFO    ][12411] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-03-15 08:41:31,929 [salt.state       :300 ][INFO    ][12411] No changes made for iptables_extra.remove_stale_tables
2019-03-15 08:41:31,929 [salt.state       :1951][INFO    ][12411] Completed state [iptables_extra.remove_stale_tables] at time 08:41:31.929328 duration_in_ms=1.42
2019-03-15 08:41:31,929 [salt.state       :1780][INFO    ][12411] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 08:41:31.929753
2019-03-15 08:41:31,930 [salt.state       :1813][INFO    ][12411] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-03-15 08:41:31,931 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12411] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-03-15 08:41:31,946 [salt.state       :300 ][INFO    ][12411] onlyif execution failed
2019-03-15 08:41:31,946 [salt.state       :1951][INFO    ][12411] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 08:41:31.946716 duration_in_ms=16.963
2019-03-15 08:41:31,948 [salt.state       :1780][INFO    ][12411] Running state [/etc/iptables/rules.v6] at time 08:41:31.948189
2019-03-15 08:41:31,948 [salt.state       :1813][INFO    ][12411] Executing state file.absent for [/etc/iptables/rules.v6]
2019-03-15 08:41:31,949 [salt.state       :300 ][INFO    ][12411] File /etc/iptables/rules.v6 is not present
2019-03-15 08:41:31,949 [salt.state       :1951][INFO    ][12411] Completed state [/etc/iptables/rules.v6] at time 08:41:31.949726 duration_in_ms=1.536
2019-03-15 08:41:31,950 [salt.state       :1780][INFO    ][12411] Running state [iptables_extra.flush_all] at time 08:41:31.950793
2019-03-15 08:41:31,951 [salt.state       :1813][INFO    ][12411] Executing state module.wait for [iptables_extra.flush_all]
2019-03-15 08:41:31,951 [salt.state       :300 ][INFO    ][12411] No changes made for iptables_extra.flush_all
2019-03-15 08:41:31,952 [salt.state       :1951][INFO    ][12411] Completed state [iptables_extra.flush_all] at time 08:41:31.951981 duration_in_ms=1.188
2019-03-15 08:41:31,956 [salt.minion      :1711][INFO    ][12411] Returning information for job: 20190315084124232541
2019-03-15 08:41:32,581 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command state.apply with jid 20190315084132568286
2019-03-15 08:41:32,605 [salt.minion      :1432][INFO    ][12486] Starting a new job with PID 12486
2019-03-15 08:41:33,232 [salt.state       :915 ][INFO    ][12486] Loading fresh modules for state activity
2019-03-15 08:41:33,857 [salt.state       :1780][INFO    ][12486] Running state [maas-rack-controller] at time 08:41:33.857855
2019-03-15 08:41:33,858 [salt.state       :1813][INFO    ][12486] Executing state pkg.installed for [maas-rack-controller]
2019-03-15 08:41:33,858 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12486] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-03-15 08:41:33,976 [salt.state       :300 ][INFO    ][12486] All specified packages are already installed
2019-03-15 08:41:33,976 [salt.state       :1951][INFO    ][12486] Completed state [maas-rack-controller] at time 08:41:33.976865 duration_in_ms=119.01
2019-03-15 08:41:33,977 [salt.state       :1780][INFO    ][12486] Running state [ipmitool] at time 08:41:33.977090
2019-03-15 08:41:33,977 [salt.state       :1813][INFO    ][12486] Executing state pkg.installed for [ipmitool]
2019-03-15 08:41:33,981 [salt.state       :300 ][INFO    ][12486] All specified packages are already installed
2019-03-15 08:41:33,981 [salt.state       :1951][INFO    ][12486] Completed state [ipmitool] at time 08:41:33.981870 duration_in_ms=4.78
2019-03-15 08:41:33,983 [salt.state       :1780][INFO    ][12486] Running state [/etc/maas/rackd.conf] at time 08:41:33.983700
2019-03-15 08:41:33,983 [salt.state       :1813][INFO    ][12486] Executing state file.line for [/etc/maas/rackd.conf]
2019-03-15 08:41:33,984 [salt.state       :300 ][INFO    ][12486] No changes needed to be made
2019-03-15 08:41:33,984 [salt.state       :1951][INFO    ][12486] Completed state [/etc/maas/rackd.conf] at time 08:41:33.984769 duration_in_ms=1.069
2019-03-15 08:41:33,984 [salt.state       :1780][INFO    ][12486] Running state [/etc/maas/rackd.conf] at time 08:41:33.984930
2019-03-15 08:41:33,985 [salt.state       :1813][INFO    ][12486] Executing state file.managed for [/etc/maas/rackd.conf]
2019-03-15 08:41:33,985 [salt.loaded.int.states.file:2298][WARNING ][12486] 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-03-15 08:41:33,985 [salt.state       :300 ][INFO    ][12486] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-03-15 08:41:33,985 [salt.state       :1951][INFO    ][12486] Completed state [/etc/maas/rackd.conf] at time 08:41:33.985808 duration_in_ms=0.877
2019-03-15 08:41:33,986 [salt.state       :1780][INFO    ][12486] Running state [maas-rackd] at time 08:41:33.986468
2019-03-15 08:41:33,986 [salt.state       :1813][INFO    ][12486] Executing state service.running for [maas-rackd]
2019-03-15 08:41:33,987 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12486] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-03-15 08:41:34,021 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12486] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-03-15 08:41:34,038 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12486] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-03-15 08:41:34,053 [salt.state       :300 ][INFO    ][12486] The service maas-rackd is already running
2019-03-15 08:41:34,054 [salt.state       :1951][INFO    ][12486] Completed state [maas-rackd] at time 08:41:34.053985 duration_in_ms=67.517
2019-03-15 08:41:34,055 [salt.minion      :1711][INFO    ][12486] Returning information for job: 20190315084132568286
2019-03-15 08:41:34,597 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command state.apply with jid 20190315084134583884
2019-03-15 08:41:34,621 [salt.minion      :1432][INFO    ][12509] Starting a new job with PID 12509
2019-03-15 08:41:35,252 [salt.state       :915 ][INFO    ][12509] Loading fresh modules for state activity
2019-03-15 08:41:35,997 [salt.state       :1780][INFO    ][12509] Running state [maas-region-controller] at time 08:41:35.997127
2019-03-15 08:41:35,997 [salt.state       :1813][INFO    ][12509] Executing state pkg.installed for [maas-region-controller]
2019-03-15 08:41:35,997 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12509] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-03-15 08:41:36,077 [salt.state       :300 ][INFO    ][12509] All specified packages are already installed
2019-03-15 08:41:36,078 [salt.state       :1951][INFO    ][12509] Completed state [maas-region-controller] at time 08:41:36.078142 duration_in_ms=81.015
2019-03-15 08:41:36,078 [salt.state       :1780][INFO    ][12509] Running state [python-oauth] at time 08:41:36.078429
2019-03-15 08:41:36,078 [salt.state       :1813][INFO    ][12509] Executing state pkg.installed for [python-oauth]
2019-03-15 08:41:36,085 [salt.state       :300 ][INFO    ][12509] All specified packages are already installed
2019-03-15 08:41:36,085 [salt.state       :1951][INFO    ][12509] Completed state [python-oauth] at time 08:41:36.085858 duration_in_ms=7.43
2019-03-15 08:41:36,088 [salt.state       :1780][INFO    ][12509] Running state [/etc/maas/regiond.conf] at time 08:41:36.088384
2019-03-15 08:41:36,088 [salt.state       :1813][INFO    ][12509] Executing state file.replace for [/etc/maas/regiond.conf]
2019-03-15 08:41:36,130 [salt.state       :300 ][INFO    ][12509] No changes needed to be made
2019-03-15 08:41:36,131 [salt.state       :1951][INFO    ][12509] Completed state [/etc/maas/regiond.conf] at time 08:41:36.130978 duration_in_ms=42.593
2019-03-15 08:41:36,131 [salt.state       :1780][INFO    ][12509] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 08:41:36.131505
2019-03-15 08:41:36,131 [salt.state       :1813][INFO    ][12509] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-03-15 08:41:36,196 [salt.state       :300 ][INFO    ][12509] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-03-15 08:41:36,196 [salt.state       :1951][INFO    ][12509] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 08:41:36.196240 duration_in_ms=64.735
2019-03-15 08:41:36,196 [salt.state       :1780][INFO    ][12509] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 08:41:36.196771
2019-03-15 08:41:36,197 [salt.state       :1813][INFO    ][12509] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-03-15 08:41:36,209 [salt.state       :300 ][INFO    ][12509] No changes needed to be made
2019-03-15 08:41:36,209 [salt.state       :1951][INFO    ][12509] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 08:41:36.209570 duration_in_ms=12.799
2019-03-15 08:41:36,210 [salt.state       :1780][INFO    ][12509] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 08:41:36.210077
2019-03-15 08:41:36,210 [salt.state       :1813][INFO    ][12509] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-03-15 08:41:36,239 [salt.state       :300 ][INFO    ][12509] No changes needed to be made
2019-03-15 08:41:36,239 [salt.state       :1951][INFO    ][12509] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 08:41:36.239605 duration_in_ms=29.527
2019-03-15 08:41:36,240 [salt.state       :1780][INFO    ][12509] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 08:41:36.240104
2019-03-15 08:41:36,240 [salt.state       :1813][INFO    ][12509] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-03-15 08:41:36,269 [salt.state       :300 ][INFO    ][12509] No changes needed to be made
2019-03-15 08:41:36,269 [salt.state       :1951][INFO    ][12509] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 08:41:36.269843 duration_in_ms=29.738
2019-03-15 08:41:36,270 [salt.state       :1780][INFO    ][12509] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 08:41:36.270532
2019-03-15 08:41:36,270 [salt.state       :1813][INFO    ][12509] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-03-15 08:41:36,284 [salt.state       :300 ][INFO    ][12509] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-03-15 08:41:36,284 [salt.state       :1951][INFO    ][12509] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 08:41:36.284292 duration_in_ms=13.76
2019-03-15 08:41:36,286 [salt.state       :1780][INFO    ][12509] Running state [a2enmod headers] at time 08:41:36.286100
2019-03-15 08:41:36,286 [salt.state       :1813][INFO    ][12509] Executing state cmd.run for [a2enmod headers]
2019-03-15 08:41:36,287 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12509] Executing command 'a2enmod headers' in directory '/root'
2019-03-15 08:41:36,361 [salt.state       :300 ][INFO    ][12509] {'pid': 12528, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-03-15 08:41:36,362 [salt.state       :1951][INFO    ][12509] Completed state [a2enmod headers] at time 08:41:36.361910 duration_in_ms=75.808
2019-03-15 08:41:36,362 [salt.state       :1780][INFO    ][12509] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 08:41:36.362463
2019-03-15 08:41:36,363 [salt.state       :1813][INFO    ][12509] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-03-15 08:41:36,382 [salt.state       :300 ][INFO    ][12509] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-03-15 08:41:36,383 [salt.state       :1951][INFO    ][12509] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 08:41:36.382938 duration_in_ms=20.475
2019-03-15 08:41:36,383 [salt.state       :1780][INFO    ][12509] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 08:41:36.383814
2019-03-15 08:41:36,384 [salt.state       :1813][INFO    ][12509] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-03-15 08:41:36,465 [salt.state       :300 ][INFO    ][12509] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-03-15 08:41:36,466 [salt.state       :1951][INFO    ][12509] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 08:41:36.466244 duration_in_ms=82.431
2019-03-15 08:41:36,467 [salt.state       :1780][INFO    ][12509] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 08:41:36.466957
2019-03-15 08:41:36,467 [salt.state       :1813][INFO    ][12509] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-03-15 08:41:36,531 [salt.state       :300 ][INFO    ][12509] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-03-15 08:41:36,531 [salt.state       :1951][INFO    ][12509] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 08:41:36.531890 duration_in_ms=64.932
2019-03-15 08:41:36,532 [salt.state       :1780][INFO    ][12509] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 08:41:36.532522
2019-03-15 08:41:36,532 [salt.state       :1813][INFO    ][12509] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-03-15 08:41:36,603 [salt.state       :300 ][INFO    ][12509] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-03-15 08:41:36,603 [salt.state       :1951][INFO    ][12509] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 08:41:36.603762 duration_in_ms=71.24
2019-03-15 08:41:36,604 [salt.state       :1780][INFO    ][12509] Running state [/root/.pgpass] at time 08:41:36.604076
2019-03-15 08:41:36,604 [salt.state       :1813][INFO    ][12509] Executing state file.managed for [/root/.pgpass]
2019-03-15 08:41:36,657 [salt.state       :300 ][INFO    ][12509] File /root/.pgpass is in the correct state
2019-03-15 08:41:36,657 [salt.state       :1951][INFO    ][12509] Completed state [/root/.pgpass] at time 08:41:36.657536 duration_in_ms=53.46
2019-03-15 08:41:36,663 [salt.state       :1780][INFO    ][12509] Running state [maas-region syncdb --noinput] at time 08:41:36.662970
2019-03-15 08:41:36,663 [salt.state       :1813][INFO    ][12509] Executing state cmd.run for [maas-region syncdb --noinput]
2019-03-15 08:41:36,664 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12509] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-03-15 08:41:38,658 [salt.state       :300 ][INFO    ][12509] {'pid': 12541, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: piston3, auth, metadataserver, maasserver, contenttypes, sites, sessions\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-03-15 08:41:38,658 [salt.state       :1951][INFO    ][12509] Completed state [maas-region syncdb --noinput] at time 08:41:38.658793 duration_in_ms=1995.821
2019-03-15 08:41:38,659 [salt.state       :2022][WARNING ][12509] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-03-15 08:41:38,661 [salt.state       :1780][INFO    ][12509] Running state [maas-regiond] at time 08:41:38.661863
2019-03-15 08:41:38,662 [salt.state       :1813][INFO    ][12509] Executing state service.running for [maas-regiond]
2019-03-15 08:41:38,663 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12509] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-03-15 08:41:38,695 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12509] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-03-15 08:41:38,709 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12509] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-03-15 08:41:38,723 [salt.state       :300 ][INFO    ][12509] The service maas-regiond is already running
2019-03-15 08:41:38,724 [salt.state       :1951][INFO    ][12509] Completed state [maas-regiond] at time 08:41:38.724341 duration_in_ms=62.478
2019-03-15 08:41:38,727 [salt.state       :1780][INFO    ][12509] Running state [bind9] at time 08:41:38.727053
2019-03-15 08:41:38,727 [salt.state       :1813][INFO    ][12509] Executing state service.running for [bind9]
2019-03-15 08:41:38,728 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12509] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-03-15 08:41:38,744 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12509] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-03-15 08:41:38,758 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12509] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-03-15 08:41:38,775 [salt.state       :300 ][INFO    ][12509] The service bind9 is already running
2019-03-15 08:41:38,776 [salt.state       :1951][INFO    ][12509] Completed state [bind9] at time 08:41:38.775926 duration_in_ms=48.873
2019-03-15 08:41:38,778 [salt.state       :1780][INFO    ][12509] Running state [apache2] at time 08:41:38.778447
2019-03-15 08:41:38,778 [salt.state       :1813][INFO    ][12509] Executing state service.running for [apache2]
2019-03-15 08:41:38,780 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12509] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-03-15 08:41:38,796 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12509] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-03-15 08:41:38,810 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12509] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-03-15 08:41:38,828 [salt.state       :300 ][INFO    ][12509] The service apache2 is already running
2019-03-15 08:41:38,828 [salt.state       :1951][INFO    ][12509] Completed state [apache2] at time 08:41:38.828624 duration_in_ms=50.177
2019-03-15 08:41:38,830 [salt.state       :1780][INFO    ][12509] Running state [maasng.wait_for_http_code] at time 08:41:38.830450
2019-03-15 08:41:38,830 [salt.state       :1813][INFO    ][12509] Executing state module.run for [maasng.wait_for_http_code]
2019-03-15 08:41:38,831 [salt.utils.decorators:613 ][WARNING ][12509] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-15 08:41:39,117 [salt.state       :300 ][INFO    ][12509] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-03-15 08:41:39,118 [salt.state       :1951][INFO    ][12509] Completed state [maasng.wait_for_http_code] at time 08:41:39.117967 duration_in_ms=287.517
2019-03-15 08:41:39,118 [salt.state       :1780][INFO    ][12509] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 08:41:39.118861
2019-03-15 08:41:39,119 [salt.state       :1813][INFO    ][12509] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-03-15 08:41:39,119 [salt.state       :300 ][INFO    ][12509] /var/lib/maas/.setup_admin exists
2019-03-15 08:41:39,119 [salt.state       :1951][INFO    ][12509] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 08:41:39.119817 duration_in_ms=0.956
2019-03-15 08:41:39,120 [salt.state       :1780][INFO    ][12509] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:41:39.120451
2019-03-15 08:41:39,120 [salt.state       :1813][INFO    ][12509] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-15 08:41:39,121 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12509] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-15 08:41:40,397 [salt.state       :300 ][INFO    ][12509] {'pid': 12566, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-15 08:41:40,398 [salt.state       :1951][INFO    ][12509] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:41:40.397946 duration_in_ms=1277.494
2019-03-15 08:41:40,405 [salt.state       :1780][INFO    ][12509] Running state [maas_region_boot_source_resources_mirror] at time 08:41:40.405871
2019-03-15 08:41:40,406 [salt.state       :1813][INFO    ][12509] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-03-15 08:41:40,497 [salt.state       :300 ][INFO    ][12509] {'changes': {}}
2019-03-15 08:41:40,498 [salt.state       :1951][INFO    ][12509] Completed state [maas_region_boot_source_resources_mirror] at time 08:41:40.498054 duration_in_ms=92.183
2019-03-15 08:41:40,499 [salt.state       :1780][INFO    ][12509] Running state [maasng.boot_resources_import] at time 08:41:40.499104
2019-03-15 08:41:40,499 [salt.state       :1813][INFO    ][12509] Executing state module.run for [maasng.boot_resources_import]
2019-03-15 08:41:40,500 [salt.utils.decorators:613 ][WARNING ][12509] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-15 08:41:40,593 [salt.loaded.ext.module.maasng:1600][INFO    ][12509] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-03-15 08:41:45,658 [salt.loaded.ext.module.maasng:1600][INFO    ][12509] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-03-15 08:41:49,631 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command saltutil.find_job with jid 20190315084149618704
2019-03-15 08:41:49,656 [salt.minion      :1432][INFO    ][12609] Starting a new job with PID 12609
2019-03-15 08:41:49,678 [salt.minion      :1711][INFO    ][12609] Returning information for job: 20190315084149618704
2019-03-15 08:41:50,728 [salt.loaded.ext.module.maasng:1600][INFO    ][12509] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-03-15 08:41:55,854 [salt.state       :300 ][INFO    ][12509] {'ret': True}
2019-03-15 08:41:55,855 [salt.state       :1951][INFO    ][12509] Completed state [maasng.boot_resources_import] at time 08:41:55.855309 duration_in_ms=15356.204
2019-03-15 08:41:55,856 [salt.state       :1780][INFO    ][12509] Running state [maas_region_boot_sources_selection_xenial] at time 08:41:55.856594
2019-03-15 08:41:55,857 [salt.state       :1813][INFO    ][12509] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-03-15 08:41:56,081 [salt.state       :300 ][INFO    ][12509] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-03-15 08:41:56,081 [salt.state       :1951][INFO    ][12509] Completed state [maas_region_boot_sources_selection_xenial] at time 08:41:56.081786 duration_in_ms=225.192
2019-03-15 08:41:56,083 [salt.state       :1780][INFO    ][12509] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 08:41:56.082985
2019-03-15 08:41:56,083 [salt.state       :1813][INFO    ][12509] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-03-15 08:41:56,083 [salt.utils.decorators:613 ][WARNING ][12509] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-15 08:41:56,084 [salt.loaded.ext.module.maasng:1771][INFO    ][12509] boot-sources sync initiated for ALL Rack's
2019-03-15 08:41:56,955 [salt.state       :300 ][INFO    ][12509] {'ret': True}
2019-03-15 08:41:56,956 [salt.state       :1951][INFO    ][12509] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 08:41:56.955921 duration_in_ms=872.935
2019-03-15 08:41:56,958 [salt.state       :1780][INFO    ][12509] Running state [maas.process_maas_config] at time 08:41:56.958030
2019-03-15 08:41:56,958 [salt.state       :1813][INFO    ][12509] Executing state module.run for [maas.process_maas_config]
2019-03-15 08:41:56,959 [salt.utils.decorators:613 ][WARNING ][12509] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-15 08:41:56,960 [salt.loaded.ext.module.maas:92  ][INFO    ][12509] maasconfig name=enable_http_proxy value=True
2019-03-15 08:41:57,016 [salt.loaded.ext.module.maas:92  ][INFO    ][12509] maasconfig name=upstream_dns value=8.8.8.8
2019-03-15 08:41:57,070 [salt.loaded.ext.module.maas:92  ][INFO    ][12509] maasconfig name=commissioning_distro_series value=xenial
2019-03-15 08:41:57,141 [salt.loaded.ext.module.maas:92  ][INFO    ][12509] maasconfig name=default_osystem value=ubuntu
2019-03-15 08:41:57,200 [salt.loaded.ext.module.maas:92  ][INFO    ][12509] maasconfig name=active_discovery_interval value=600
2019-03-15 08:41:57,261 [salt.loaded.ext.module.maas:92  ][INFO    ][12509] maasconfig name=dnssec_validation value=no
2019-03-15 08:41:57,314 [salt.loaded.ext.module.maas:92  ][INFO    ][12509] maasconfig name=maas_name value=mas01
2019-03-15 08:42:01,038 [salt.loaded.ext.module.maas:92  ][INFO    ][12509] maasconfig name=network_discovery value=enabled
2019-03-15 08:42:01,156 [salt.loaded.ext.module.maas:92  ][INFO    ][12509] maasconfig name=enable_third_party_drivers value=True
2019-03-15 08:42:01,210 [salt.loaded.ext.module.maas:92  ][INFO    ][12509] maasconfig name=default_storage_layout value=lvm
2019-03-15 08:42:01,264 [salt.loaded.ext.module.maas:92  ][INFO    ][12509] maasconfig name=ntp_external_only value=True
2019-03-15 08:42:01,320 [salt.loaded.ext.module.maas:92  ][INFO    ][12509] maasconfig name=disk_erase_with_secure_erase value=False
2019-03-15 08:42:01,390 [salt.loaded.ext.module.maas:92  ][INFO    ][12509] maasconfig name=default_distro_series value=xenial
2019-03-15 08:42:01,480 [salt.loaded.ext.module.maas:92  ][INFO    ][12509] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-03-15 08:42:01,615 [salt.state       :300 ][INFO    ][12509] {'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-03-15 08:42:01,615 [salt.state       :1951][INFO    ][12509] Completed state [maas.process_maas_config] at time 08:42:01.615563 duration_in_ms=4657.533
2019-03-15 08:42:01,616 [salt.state       :1780][INFO    ][12509] Running state [pxe_admin] at time 08:42:01.616481
2019-03-15 08:42:01,616 [salt.state       :1813][INFO    ][12509] Executing state maasng.fabric_present for [pxe_admin]
2019-03-15 08:42:01,674 [salt.loaded.ext.module.maasng:945 ][INFO    ][12509] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'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'fabric_id': 1, u'dhcp_on': False, 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'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'c67btq', 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-03-15 08:42:01,728 [salt.loaded.ext.module.maasng:1008][WARNING ][12509] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-03-15 08:42:01,728 [salt.loaded.ext.module.maasng:1011][WARNING ][12509] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-03-15 08:42:01,788 [salt.state       :300 ][INFO    ][12509] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-03-15 08:42:01,788 [salt.state       :1951][INFO    ][12509] Completed state [pxe_admin] at time 08:42:01.788540 duration_in_ms=172.059
2019-03-15 08:42:01,789 [salt.state       :1780][INFO    ][12509] Running state [vlan 0] at time 08:42:01.789052
2019-03-15 08:42:01,789 [salt.state       :1813][INFO    ][12509] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-03-15 08:42:01,859 [salt.loaded.ext.module.maasng:945 ][INFO    ][12509] [{u'id': 0, u'class_type': None, 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'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'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'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'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'c67btq', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-03-15 08:42:01,982 [salt.loaded.ext.module.maasng:945 ][INFO    ][12509] [{u'id': 0, u'class_type': None, 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'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'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'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'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'c67btq', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-03-15 08:42:02,269 [salt.loaded.ext.module.maasng:945 ][INFO    ][12509] [{u'class_type': None, 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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, 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'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'fabric': u'fabric-1'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'class_type': u'', 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'primary_rack': u'c67btq', 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'fabric': u'pxe_admin'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-03-15 08:42:02,385 [salt.state       :300 ][INFO    ][12509] {'new': 'Vlan untagged was updated'}
2019-03-15 08:42:02,386 [salt.state       :1951][INFO    ][12509] Completed state [vlan 0] at time 08:42:02.386316 duration_in_ms=597.263
2019-03-15 08:42:02,388 [salt.state       :1780][INFO    ][12509] Running state [192.168.11.0/24] at time 08:42:02.388298
2019-03-15 08:42:02,388 [salt.state       :1813][INFO    ][12509] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-03-15 08:42:02,632 [salt.loaded.ext.module.maasng:945 ][INFO    ][12509] [{u'class_type': None, 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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, 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'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'fabric': u'fabric-1'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'class_type': u'', 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'primary_rack': u'c67btq', 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'fabric': u'pxe_admin'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-03-15 08:42:02,633 [salt.loaded.ext.module.maasng:1235][WARNING ][12509] Ignoring parameter vlan:0
2019-03-15 08:42:02,705 [salt.state       :300 ][INFO    ][12509] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-03-15 08:42:02,705 [salt.state       :1951][INFO    ][12509] Completed state [192.168.11.0/24] at time 08:42:02.705861 duration_in_ms=317.561
2019-03-15 08:42:02,707 [salt.state       :1780][INFO    ][12509] Running state [maas_create_iprange_1] at time 08:42:02.707725
2019-03-15 08:42:02,708 [salt.state       :1813][INFO    ][12509] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-03-15 08:42:02,764 [salt.state       :300 ][INFO    ][12509] Iprange maas_create_iprange_1 already exist.
2019-03-15 08:42:02,765 [salt.state       :1951][INFO    ][12509] Completed state [maas_create_iprange_1] at time 08:42:02.765200 duration_in_ms=57.474
2019-03-15 08:42:02,765 [salt.state       :1780][INFO    ][12509] Running state [vlan 0] at time 08:42:02.765795
2019-03-15 08:42:02,766 [salt.state       :1813][INFO    ][12509] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-03-15 08:42:02,823 [salt.loaded.ext.module.maasng:945 ][INFO    ][12509] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'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'fabric_id': 1, u'dhcp_on': False, 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'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'c67btq', 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-03-15 08:42:02,949 [salt.loaded.ext.module.maasng:945 ][INFO    ][12509] [{u'class_type': None, 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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, 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'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'fabric': u'fabric-1'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'class_type': u'', 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'primary_rack': u'c67btq', 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'fabric': u'pxe_admin'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-03-15 08:42:03,214 [salt.loaded.ext.module.maasng:945 ][INFO    ][12509] [{u'id': 0, u'class_type': None, 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'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'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'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'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'c67btq', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-03-15 08:42:03,307 [salt.state       :300 ][INFO    ][12509] {'new': 'Vlan untagged was updated'}
2019-03-15 08:42:03,307 [salt.state       :1951][INFO    ][12509] Completed state [vlan 0] at time 08:42:03.307440 duration_in_ms=541.645
2019-03-15 08:42:03,308 [salt.state       :1780][INFO    ][12509] Running state [opnfv] at time 08:42:03.308417
2019-03-15 08:42:03,308 [salt.state       :1813][INFO    ][12509] Executing state maasng.sshkey_present for [opnfv]
2019-03-15 08:42:03,360 [salt.loaded.ext.module.maasng:1903][INFO    ][12509] [{u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP', u'keysource': u''}]
2019-03-15 08:42:03,361 [salt.state       :300 ][INFO    ][12509] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-03-15 08:42:03,361 [salt.state       :1951][INFO    ][12509] Completed state [opnfv] at time 08:42:03.361399 duration_in_ms=52.98
2019-03-15 08:42:03,362 [salt.state       :1780][INFO    ][12509] Running state [maas.process_tags] at time 08:42:03.362456
2019-03-15 08:42:03,363 [salt.state       :1813][INFO    ][12509] Executing state module.run for [maas.process_tags]
2019-03-15 08:42:03,363 [salt.utils.decorators:613 ][WARNING ][12509] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-15 08:42:03,407 [salt.loaded.ext.module.maas:92  ][INFO    ][12509] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-03-15 08:42:03,480 [salt.state       :300 ][INFO    ][12509] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-03-15 08:42:03,481 [salt.state       :1951][INFO    ][12509] Completed state [maas.process_tags] at time 08:42:03.481185 duration_in_ms=118.729
2019-03-15 08:42:03,486 [salt.minion      :1711][INFO    ][12509] Returning information for job: 20190315084134583884
2019-03-15 08:42:04,042 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command state.apply with jid 20190315084204034973
2019-03-15 08:42:04,062 [salt.minion      :1432][INFO    ][12974] Starting a new job with PID 12974
2019-03-15 08:42:07,541 [salt.state       :915 ][INFO    ][12974] Loading fresh modules for state activity
2019-03-15 08:42:07,634 [salt.state       :1780][INFO    ][12974] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:42:07.634784
2019-03-15 08:42:07,635 [salt.state       :1813][INFO    ][12974] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-15 08:42:07,637 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12974] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-15 08:42:09,075 [salt.state       :300 ][INFO    ][12974] {'pid': 12997, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-15 08:42:09,075 [salt.state       :1951][INFO    ][12974] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:42:09.075784 duration_in_ms=1441.0
2019-03-15 08:42:09,077 [salt.state       :1780][INFO    ][12974] Running state [maas.process_machines] at time 08:42:09.077377
2019-03-15 08:42:09,077 [salt.state       :1813][INFO    ][12974] Executing state module.run for [maas.process_machines]
2019-03-15 08:42:09,078 [salt.utils.decorators:613 ][WARNING ][12974] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-15 08:42:09,687 [salt.loaded.ext.module.maas:412 ][WARNING ][12974] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-15 08:42:09,688 [salt.loaded.ext.module.maas:92  ][INFO    ][12974] 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=psm7wc architecture=amd64/generic power_parameters_power_user=admin
2019-03-15 08:42:10,927 [salt.loaded.ext.module.maas:412 ][WARNING ][12974] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-15 08:42:10,927 [salt.loaded.ext.module.maas:92  ][INFO    ][12974] 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=fcgtm8 architecture=amd64/generic power_parameters_power_user=admin
2019-03-15 08:42:11,945 [salt.loaded.ext.module.maas:412 ][WARNING ][12974] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-15 08:42:11,946 [salt.loaded.ext.module.maas:92  ][INFO    ][12974] machine hostname=kvm01 power_type=ipmi mac_addresses=['00:25:b5:a0:00:2a'] power_parameters_power_address=172.30.8.75 power_parameters_power_pass=octopus system_id=rsgbd6 architecture=amd64/generic power_parameters_power_user=admin
2019-03-15 08:42:13,178 [salt.loaded.ext.module.maas:412 ][WARNING ][12974] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-15 08:42:13,179 [salt.loaded.ext.module.maas:92  ][INFO    ][12974] machine hostname=kvm03 power_type=ipmi mac_addresses=['00:25:b5:a0:00:4a'] power_parameters_power_address=172.30.8.74 power_parameters_power_pass=octopus system_id=bdgmqx architecture=amd64/generic power_parameters_power_user=admin
2019-03-15 08:42:14,370 [salt.loaded.ext.module.maas:412 ][WARNING ][12974] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-15 08:42:14,371 [salt.loaded.ext.module.maas:92  ][INFO    ][12974] machine hostname=kvm02 power_type=ipmi mac_addresses=['00:25:b5:a0:00:3a'] power_parameters_power_address=172.30.8.65 power_parameters_power_pass=octopus system_id=r8msmy architecture=amd64/generic power_parameters_power_user=admin
2019-03-15 08:42:15,629 [salt.state       :300 ][INFO    ][12974] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-03-15 08:42:15,630 [salt.state       :1951][INFO    ][12974] Completed state [maas.process_machines] at time 08:42:15.630506 duration_in_ms=6553.126
2019-03-15 08:42:15,634 [salt.minion      :1711][INFO    ][12974] Returning information for job: 20190315084204034973
2019-03-15 08:42:48,416 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command state.apply with jid 20190315084248372429
2019-03-15 08:42:48,442 [salt.minion      :1432][INFO    ][13272] Starting a new job with PID 13272
2019-03-15 08:42:51,980 [salt.state       :915 ][INFO    ][13272] Loading fresh modules for state activity
2019-03-15 08:42:52,068 [salt.state       :1780][INFO    ][13272] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:42:52.068659
2019-03-15 08:42:52,069 [salt.state       :1813][INFO    ][13272] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-15 08:42:52,071 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13272] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-15 08:42:53,466 [salt.state       :300 ][INFO    ][13272] {'pid': 13282, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-15 08:42:53,466 [salt.state       :1951][INFO    ][13272] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:42:53.466575 duration_in_ms=1397.916
2019-03-15 08:42:53,468 [salt.state       :1780][INFO    ][13272] Running state [maas.wait_for_machine_status] at time 08:42:53.468054
2019-03-15 08:42:53,468 [salt.state       :1813][INFO    ][13272] Executing state module.run for [maas.wait_for_machine_status]
2019-03-15 08:42:53,468 [salt.utils.decorators:613 ][WARNING ][13272] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-15 08:42:56,316 [salt.loaded.ext.module.maas:993 ][INFO    ][13272] Machine bdgmqx mark broken
2019-03-15 08:42:56,948 [salt.loaded.ext.module.maas:996 ][INFO    ][13272] Machine bdgmqx mark fixed
2019-03-15 08:42:58,175 [salt.loaded.ext.module.maas:684 ][INFO    ][13272] deploymachines hwe_kernel=hwe-16.04 system_id=bdgmqx distro_series=xenial
2019-03-15 08:43:01,481 [salt.loaded.ext.module.maas:1023][INFO    ][13272] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1491.99191809s left)
2019-03-15 08:43:03,490 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command saltutil.find_job with jid 20190315084303477876
2019-03-15 08:43:03,515 [salt.minion      :1432][INFO    ][13350] Starting a new job with PID 13350
2019-03-15 08:43:03,536 [salt.minion      :1711][INFO    ][13350] Returning information for job: 20190315084303477876
2019-03-15 08:43:33,545 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command saltutil.find_job with jid 20190315084333531860
2019-03-15 08:43:33,570 [salt.minion      :1432][INFO    ][13391] Starting a new job with PID 13391
2019-03-15 08:43:33,589 [salt.minion      :1711][INFO    ][13391] Returning information for job: 20190315084333531860
2019-03-15 08:43:35,103 [salt.loaded.ext.module.maas:1023][INFO    ][13272] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1458.36897516s left)
2019-03-15 08:44:03,779 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command saltutil.find_job with jid 20190315084403717518
2019-03-15 08:44:03,803 [salt.minion      :1432][INFO    ][13423] Starting a new job with PID 13423
2019-03-15 08:44:03,823 [salt.minion      :1711][INFO    ][13423] Returning information for job: 20190315084403717518
2019-03-15 08:44:08,536 [salt.loaded.ext.module.maas:1023][INFO    ][13272] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1424.936409s left)
2019-03-15 08:44:33,824 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command saltutil.find_job with jid 20190315084433810237
2019-03-15 08:44:33,850 [salt.minion      :1432][INFO    ][13464] Starting a new job with PID 13464
2019-03-15 08:44:33,872 [salt.minion      :1711][INFO    ][13464] Returning information for job: 20190315084433810237
2019-03-15 08:44:41,199 [salt.loaded.ext.module.maas:1023][INFO    ][13272] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1392.27368522s left)
2019-03-15 08:45:03,884 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command saltutil.find_job with jid 20190315084503870696
2019-03-15 08:45:03,911 [salt.minion      :1432][INFO    ][13549] Starting a new job with PID 13549
2019-03-15 08:45:03,933 [salt.minion      :1711][INFO    ][13549] Returning information for job: 20190315084503870696
2019-03-15 08:45:14,589 [salt.loaded.ext.module.maas:1023][INFO    ][13272] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1358.88358212s left)
2019-03-15 08:45:33,942 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command saltutil.find_job with jid 20190315084533928996
2019-03-15 08:45:33,967 [salt.minion      :1432][INFO    ][13673] Starting a new job with PID 13673
2019-03-15 08:45:33,991 [salt.minion      :1711][INFO    ][13673] Returning information for job: 20190315084533928996
2019-03-15 08:45:48,128 [salt.loaded.ext.module.maas:1023][INFO    ][13272] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1325.34428406s left)
2019-03-15 08:46:04,004 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command saltutil.find_job with jid 20190315084603991553
2019-03-15 08:46:04,029 [salt.minion      :1432][INFO    ][13748] Starting a new job with PID 13748
2019-03-15 08:46:04,057 [salt.minion      :1711][INFO    ][13748] Returning information for job: 20190315084603991553
2019-03-15 08:46:21,905 [salt.loaded.ext.module.maas:1023][INFO    ][13272] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1291.56750321s left)
2019-03-15 08:46:34,082 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command saltutil.find_job with jid 20190315084634069848
2019-03-15 08:46:34,107 [salt.minion      :1432][INFO    ][13831] Starting a new job with PID 13831
2019-03-15 08:46:34,129 [salt.minion      :1711][INFO    ][13831] Returning information for job: 20190315084634069848
2019-03-15 08:46:55,204 [salt.loaded.ext.module.maas:1023][INFO    ][13272] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1258.26802111s left)
2019-03-15 08:47:04,149 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command saltutil.find_job with jid 20190315084704136780
2019-03-15 08:47:04,175 [salt.minion      :1432][INFO    ][13874] Starting a new job with PID 13874
2019-03-15 08:47:04,198 [salt.minion      :1711][INFO    ][13874] Returning information for job: 20190315084704136780
2019-03-15 08:47:28,875 [salt.loaded.ext.module.maas:1023][INFO    ][13272] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1224.59701419s left)
2019-03-15 08:47:34,224 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command saltutil.find_job with jid 20190315084734210905
2019-03-15 08:47:34,249 [salt.minion      :1432][INFO    ][13952] Starting a new job with PID 13952
2019-03-15 08:47:34,273 [salt.minion      :1711][INFO    ][13952] Returning information for job: 20190315084734210905
2019-03-15 08:48:02,423 [salt.loaded.ext.module.maas:1023][INFO    ][13272] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1191.04914713s left)
2019-03-15 08:48:04,304 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command saltutil.find_job with jid 20190315084804292949
2019-03-15 08:48:04,329 [salt.minion      :1432][INFO    ][14011] Starting a new job with PID 14011
2019-03-15 08:48:04,352 [salt.minion      :1711][INFO    ][14011] Returning information for job: 20190315084804292949
2019-03-15 08:48:34,387 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command saltutil.find_job with jid 20190315084834374443
2019-03-15 08:48:34,413 [salt.minion      :1432][INFO    ][14099] Starting a new job with PID 14099
2019-03-15 08:48:34,435 [salt.minion      :1711][INFO    ][14099] Returning information for job: 20190315084834374443
2019-03-15 08:48:36,008 [salt.loaded.ext.module.maas:1023][INFO    ][13272] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1157.464571s left)
2019-03-15 08:49:04,475 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command saltutil.find_job with jid 20190315084904462788
2019-03-15 08:49:04,501 [salt.minion      :1432][INFO    ][14142] Starting a new job with PID 14142
2019-03-15 08:49:04,522 [salt.minion      :1711][INFO    ][14142] Returning information for job: 20190315084904462788
2019-03-15 08:49:09,089 [salt.loaded.ext.module.maas:1023][INFO    ][13272] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1124.38321114s left)
2019-03-15 08:49:34,567 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command saltutil.find_job with jid 20190315084934554308
2019-03-15 08:49:34,591 [salt.minion      :1432][INFO    ][14224] Starting a new job with PID 14224
2019-03-15 08:49:34,614 [salt.minion      :1711][INFO    ][14224] Returning information for job: 20190315084934554308
2019-03-15 08:49:42,386 [salt.loaded.ext.module.maas:1023][INFO    ][13272] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1091.08666921s left)
2019-03-15 08:50:04,761 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command saltutil.find_job with jid 20190315085004748285
2019-03-15 08:50:04,788 [salt.minion      :1432][INFO    ][14286] Starting a new job with PID 14286
2019-03-15 08:50:04,809 [salt.minion      :1711][INFO    ][14286] Returning information for job: 20190315085004748285
2019-03-15 08:50:15,316 [salt.state       :300 ][INFO    ][13272] {'ret': True}
2019-03-15 08:50:15,317 [salt.state       :1951][INFO    ][13272] Completed state [maas.wait_for_machine_status] at time 08:50:15.316955 duration_in_ms=441848.899
2019-03-15 08:50:15,320 [salt.minion      :1711][INFO    ][13272] Returning information for job: 20190315084248372429
2019-03-15 08:50:15,927 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command state.apply with jid 20190315085015914597
2019-03-15 08:50:15,950 [salt.minion      :1432][INFO    ][14328] Starting a new job with PID 14328
2019-03-15 08:50:19,322 [salt.state       :915 ][INFO    ][14328] Loading fresh modules for state activity
2019-03-15 08:50:19,445 [salt.state       :1780][INFO    ][14328] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:50:19.445148
2019-03-15 08:50:19,445 [salt.state       :1813][INFO    ][14328] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-15 08:50:19,447 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14328] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-15 08:50:20,682 [salt.state       :300 ][INFO    ][14328] {'pid': 14390, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-15 08:50:20,683 [salt.state       :1951][INFO    ][14328] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:50:20.683263 duration_in_ms=1238.115
2019-03-15 08:50:20,686 [salt.state       :1780][INFO    ][14328] Running state [maas_machines_storage_cmp002_lvm] at time 08:50:20.686438
2019-03-15 08:50:20,687 [salt.state       :1813][INFO    ][14328] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-03-15 08:50:21,466 [salt.state       :300 ][INFO    ][14328] Machine cmp002 is not in Ready state.
2019-03-15 08:50:21,467 [salt.state       :1951][INFO    ][14328] Completed state [maas_machines_storage_cmp002_lvm] at time 08:50:21.466888 duration_in_ms=780.448
2019-03-15 08:50:21,467 [salt.state       :1780][INFO    ][14328] Running state [maas_machines_storage_cmp001_lvm] at time 08:50:21.467666
2019-03-15 08:50:21,468 [salt.state       :1813][INFO    ][14328] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-03-15 08:50:22,118 [salt.state       :300 ][INFO    ][14328] Machine cmp001 is not in Ready state.
2019-03-15 08:50:22,118 [salt.state       :1951][INFO    ][14328] Completed state [maas_machines_storage_cmp001_lvm] at time 08:50:22.118821 duration_in_ms=651.154
2019-03-15 08:50:22,123 [salt.minion      :1711][INFO    ][14328] Returning information for job: 20190315085015914597
2019-03-15 08:50:22,749 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command state.apply with jid 20190315085022739692
2019-03-15 08:50:22,772 [salt.minion      :1432][INFO    ][14446] Starting a new job with PID 14446
2019-03-15 08:50:23,400 [salt.state       :915 ][INFO    ][14446] Loading fresh modules for state activity
2019-03-15 08:50:23,487 [salt.state       :1780][INFO    ][14446] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:50:23.487665
2019-03-15 08:50:23,488 [salt.state       :1813][INFO    ][14446] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-15 08:50:23,490 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14446] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-15 08:50:24,939 [salt.state       :300 ][INFO    ][14446] {'pid': 14453, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-15 08:50:24,940 [salt.state       :1951][INFO    ][14446] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:50:24.940067 duration_in_ms=1452.403
2019-03-15 08:50:24,941 [salt.state       :1780][INFO    ][14446] Running state [maas.deploy_machines] at time 08:50:24.941229
2019-03-15 08:50:24,941 [salt.state       :1813][INFO    ][14446] Executing state module.run for [maas.deploy_machines]
2019-03-15 08:50:24,941 [salt.utils.decorators:613 ][WARNING ][14446] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-15 08:50:25,593 [salt.state       :300 ][INFO    ][14446] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-03-15 08:50:25,593 [salt.state       :1951][INFO    ][14446] Completed state [maas.deploy_machines] at time 08:50:25.593595 duration_in_ms=652.363
2019-03-15 08:50:25,597 [salt.minion      :1711][INFO    ][14446] Returning information for job: 20190315085022739692
2019-03-15 08:50:26,219 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command state.apply with jid 20190315085026204314
2019-03-15 08:50:26,247 [salt.minion      :1432][INFO    ][14463] Starting a new job with PID 14463
2019-03-15 08:50:26,796 [salt.state       :915 ][INFO    ][14463] Loading fresh modules for state activity
2019-03-15 08:50:26,842 [salt.state       :1780][INFO    ][14463] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:50:26.842027
2019-03-15 08:50:26,842 [salt.state       :1813][INFO    ][14463] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-15 08:50:26,843 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14463] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-15 08:50:28,269 [salt.state       :300 ][INFO    ][14463] {'pid': 14470, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-15 08:50:28,270 [salt.state       :1951][INFO    ][14463] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:50:28.270099 duration_in_ms=1428.072
2019-03-15 08:50:28,271 [salt.state       :1780][INFO    ][14463] Running state [maas.wait_for_machine_status] at time 08:50:28.271264
2019-03-15 08:50:28,271 [salt.state       :1813][INFO    ][14463] Executing state module.run for [maas.wait_for_machine_status]
2019-03-15 08:50:28,271 [salt.utils.decorators:613 ][WARNING ][14463] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-15 08:50:31,814 [salt.state       :300 ][INFO    ][14463] {'ret': True}
2019-03-15 08:50:31,815 [salt.state       :1951][INFO    ][14463] Completed state [maas.wait_for_machine_status] at time 08:50:31.815254 duration_in_ms=3543.986
2019-03-15 08:50:31,819 [salt.minion      :1711][INFO    ][14463] Returning information for job: 20190315085026204314
2019-03-15 09:20:30,774 [salt.utils.schedule:1377][INFO    ][6545] Running scheduled job: __mine_interval
2019-03-15 10:20:30,774 [salt.utils.schedule:1377][INFO    ][6545] Running scheduled job: __mine_interval
2019-03-15 10:27:56,614 [salt.minion      :1308][INFO    ][6545] User sudo_ubuntu Executing command cp.push_dir with jid 20190315102756601791
2019-03-15 10:27:56,637 [salt.minion      :1432][INFO    ][21395] Starting a new job with PID 21395
