2019-08-09 05:08:09,711 [salt.minion      :870 ][ERROR   ][359] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-08-09 05:08:59,758 [salt.minion      :870 ][ERROR   ][359] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-08-09 05:09:49,812 [salt.minion      :870 ][ERROR   ][359] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-08-09 05:10:39,854 [salt.minion      :870 ][ERROR   ][359] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-08-09 05:11:29,903 [salt.minion      :870 ][ERROR   ][359] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-08-09 05:13:37,593 [salt.utils.decorators:613 ][WARNING ][2702] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-09 05:13:38,162 [salt.utils.decorators:613 ][WARNING ][2702] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-09 05:13:40,019 [salt.loaded.int.states.file:2298][WARNING ][2838] 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-08-09 05:13:45,992 [salt.state       :2022][WARNING ][2954] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-08-09 05:13:48,579 [salt.utils.decorators:613 ][WARNING ][2954] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-09 05:14:00,355 [salt.utils.decorators:613 ][WARNING ][2954] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-09 05:14:34,537 [salt.utils.decorators:613 ][WARNING ][2954] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-09 05:14:35,856 [salt.utils.decorators:613 ][WARNING ][2954] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-09 05:14:40,285 [salt.loaded.ext.module.maasng:1008][WARNING ][2954] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-08-09 05:14:40,285 [salt.loaded.ext.module.maasng:1011][WARNING ][2954] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-08-09 05:14:41,081 [salt.loaded.ext.module.maasng:1235][WARNING ][2954] Ignoring parameter vlan:0
2019-08-09 05:14:42,085 [salt.utils.decorators:613 ][WARNING ][2954] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-09 05:14:47,824 [salt.utils.decorators:613 ][WARNING ][5759] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-09 05:14:47,901 [salt.loaded.ext.module.maas:412 ][WARNING ][5759] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-09 05:14:49,120 [salt.loaded.ext.module.maas:412 ][WARNING ][5759] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-09 05:14:50,324 [salt.loaded.ext.module.maas:412 ][WARNING ][5759] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-09 05:14:51,425 [salt.loaded.ext.module.maas:412 ][WARNING ][5759] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-09 05:14:52,526 [salt.loaded.ext.module.maas:412 ][WARNING ][5759] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-09 05:14:55,950 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6351] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-08-09 05:14:55,983 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6351] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-08-09 05:14:56,007 [salt.utils.parsers:1051][WARNING ][359] Minion received a SIGTERM. Exiting.
2019-08-09 05:14:57,066 [salt.cli.daemons :293 ][INFO    ][6407] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-08-09 05:14:57,163 [salt.cli.daemons :82  ][INFO    ][6407] Starting up the Salt Minion
2019-08-09 05:14:57,164 [salt.utils.event :1017][INFO    ][6407] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-08-09 05:14:58,097 [salt.minion      :976 ][INFO    ][6407] Creating minion process manager
2019-08-09 05:14:59,610 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][6407] Executing command ['date', '+%z'] in directory '/root'
2019-08-09 05:14:59,630 [salt.utils.schedule:568 ][INFO    ][6407] Updating job settings for scheduled job: __mine_interval
2019-08-09 05:14:59,631 [salt.minion      :1108][INFO    ][6407] Added mine.update to scheduler
2019-08-09 05:14:59,635 [salt.minion      :1975][INFO    ][6407] Minion is starting as user 'root'
2019-08-09 05:14:59,647 [salt.minion      :2336][INFO    ][6407] Minion is ready to receive requests!
2019-08-09 05:15:24,981 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command state.apply with jid 20190809051524967404
2019-08-09 05:15:25,002 [salt.minion      :1432][INFO    ][6498] Starting a new job with PID 6498
2019-08-09 05:15:28,763 [salt.state       :915 ][INFO    ][6498] Loading fresh modules for state activity
2019-08-09 05:15:28,816 [salt.fileclient  :1219][INFO    ][6498] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-08-09 05:15:28,859 [salt.state       :1780][INFO    ][6498] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:15:28.859125
2019-08-09 05:15:28,859 [salt.state       :1813][INFO    ][6498] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-09 05:15:28,861 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6498] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-09 05:15:30,421 [salt.state       :300 ][INFO    ][6498] {'pid': 6506, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-09 05:15:30,422 [salt.state       :1951][INFO    ][6498] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:15:30.422139 duration_in_ms=1563.013
2019-08-09 05:15:30,425 [salt.state       :1780][INFO    ][6498] Running state [maas.wait_for_machine_status] at time 05:15:30.425505
2019-08-09 05:15:30,426 [salt.state       :1813][INFO    ][6498] Executing state module.run for [maas.wait_for_machine_status]
2019-08-09 05:15:30,426 [salt.utils.decorators:613 ][WARNING ][6498] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-09 05:15:31,562 [salt.loaded.ext.module.maas:1023][INFO    ][6498] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1498.87509894s left)
2019-08-09 05:15:40,055 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command saltutil.find_job with jid 20190809051540040128
2019-08-09 05:15:40,078 [salt.minion      :1432][INFO    ][6518] Starting a new job with PID 6518
2019-08-09 05:15:40,102 [salt.minion      :1711][INFO    ][6518] Returning information for job: 20190809051540040128
2019-08-09 05:16:02,524 [salt.loaded.ext.module.maas:1023][INFO    ][6498] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1467.91253114s left)
2019-08-09 05:16:10,118 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command saltutil.find_job with jid 20190809051610102137
2019-08-09 05:16:10,141 [salt.minion      :1432][INFO    ][6573] Starting a new job with PID 6573
2019-08-09 05:16:10,164 [salt.minion      :1711][INFO    ][6573] Returning information for job: 20190809051610102137
2019-08-09 05:16:33,706 [salt.loaded.ext.module.maas:1023][INFO    ][6498] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1436.73038507s left)
2019-08-09 05:16:40,158 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command saltutil.find_job with jid 20190809051640144752
2019-08-09 05:16:40,181 [salt.minion      :1432][INFO    ][6685] Starting a new job with PID 6685
2019-08-09 05:16:40,205 [salt.minion      :1711][INFO    ][6685] Returning information for job: 20190809051640144752
2019-08-09 05:17:05,084 [salt.loaded.ext.module.maas:1023][INFO    ][6498] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1405.35325909s left)
2019-08-09 05:17:10,214 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command saltutil.find_job with jid 20190809051710199269
2019-08-09 05:17:10,237 [salt.minion      :1432][INFO    ][6893] Starting a new job with PID 6893
2019-08-09 05:17:10,261 [salt.minion      :1711][INFO    ][6893] Returning information for job: 20190809051710199269
2019-08-09 05:17:36,785 [salt.loaded.ext.module.maas:1023][INFO    ][6498] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1373.65191412s left)
2019-08-09 05:17:40,275 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command saltutil.find_job with jid 20190809051740263146
2019-08-09 05:17:40,298 [salt.minion      :1432][INFO    ][7045] Starting a new job with PID 7045
2019-08-09 05:17:40,321 [salt.minion      :1711][INFO    ][7045] Returning information for job: 20190809051740263146
2019-08-09 05:18:08,844 [salt.loaded.ext.module.maas:1023][INFO    ][6498] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1341.59254503s left)
2019-08-09 05:18:10,332 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command saltutil.find_job with jid 20190809051810319142
2019-08-09 05:18:10,354 [salt.minion      :1432][INFO    ][7616] Starting a new job with PID 7616
2019-08-09 05:18:10,378 [salt.minion      :1711][INFO    ][7616] Returning information for job: 20190809051810319142
2019-08-09 05:18:40,404 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command saltutil.find_job with jid 20190809051840389228
2019-08-09 05:18:40,424 [salt.minion      :1432][INFO    ][7833] Starting a new job with PID 7833
2019-08-09 05:18:40,445 [salt.minion      :1711][INFO    ][7833] Returning information for job: 20190809051840389228
2019-08-09 05:18:41,918 [salt.loaded.ext.module.maas:1023][INFO    ][6498] Waiting status:Ready|Deployed for machines:['cmp002']
sleep for:30s Timeout:1500s (1308.51930904s left)
2019-08-09 05:19:10,463 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command saltutil.find_job with jid 20190809051910449313
2019-08-09 05:19:10,486 [salt.minion      :1432][INFO    ][7936] Starting a new job with PID 7936
2019-08-09 05:19:10,508 [salt.minion      :1711][INFO    ][7936] Returning information for job: 20190809051910449313
2019-08-09 05:19:15,460 [salt.state       :300 ][INFO    ][6498] {'ret': True}
2019-08-09 05:19:15,460 [salt.state       :1951][INFO    ][6498] Completed state [maas.wait_for_machine_status] at time 05:19:15.460423 duration_in_ms=225034.916
2019-08-09 05:19:15,464 [salt.minion      :1711][INFO    ][6498] Returning information for job: 20190809051524967404
2019-08-09 05:19:16,132 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command state.apply with jid 20190809051916119121
2019-08-09 05:19:16,154 [salt.minion      :1432][INFO    ][7955] Starting a new job with PID 7955
2019-08-09 05:19:19,927 [salt.state       :915 ][INFO    ][7955] Loading fresh modules for state activity
2019-08-09 05:19:19,979 [salt.fileclient  :1219][INFO    ][7955] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-08-09 05:19:20,070 [salt.state       :1780][INFO    ][7955] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:19:20.070822
2019-08-09 05:19:20,071 [salt.state       :1813][INFO    ][7955] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-09 05:19:20,072 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7955] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-09 05:19:21,576 [salt.state       :300 ][INFO    ][7955] {'pid': 7963, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-09 05:19:21,576 [salt.state       :1951][INFO    ][7955] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:19:21.576818 duration_in_ms=1505.996
2019-08-09 05:19:21,580 [salt.state       :1780][INFO    ][7955] Running state [maas_machines_storage_cmp002_lvm] at time 05:19:21.579955
2019-08-09 05:19:21,580 [salt.state       :1813][INFO    ][7955] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-08-09 05:19:23,063 [salt.loaded.ext.module.maasng:610 ][INFO    ][7955] x7g4x4
2019-08-09 05:19:23,063 [salt.loaded.ext.module.maasng:626 ][INFO    ][7955] sda
2019-08-09 05:19:23,821 [salt.loaded.ext.module.maasng:361 ][INFO    ][7955] x7g4x4
2019-08-09 05:19:23,952 [salt.loaded.ext.module.maasng:367 ][INFO    ][7955] [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'id': 5, u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'x7g4x4', 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'used_size': 2397998940160, u'resource_uri': u'/MAAS/api/2.0/nodes/x7g4x4/blockdevices/5/', u'partitions': [{u'uuid': u'a56d4eef-6242-4427-bc99-2a8b96bd4230', u'resource_uri': u'/MAAS/api/2.0/nodes/x7g4x4/blockdevices/5/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'x7g4x4', u'filesystem': {u'mount_options': None, u'uuid': u'676952e1-c3a5-4e10-8b7c-228f977cf49c', u'fstype': u'lvm-pv', u'mount_point': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 5, u'type': u'partition', u'id': 6, u'size': 2397992648704}]}, {u'size': 2397988454400, u'model': None, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'id': 11, u'filesystem': {u'mount_options': None, u'uuid': u'cb054483-065a-4215-9cec-3325a465440e', u'fstype': u'ext4', u'mount_point': u'/', u'label': u'root'}, u'uuid': u'1954a412-9f1f-4da2-930e-7e089159f736', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'x7g4x4', 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'used_size': 2397988454400, u'resource_uri': u'/MAAS/api/2.0/nodes/x7g4x4/blockdevices/11/', u'partitions': []}]
2019-08-09 05:19:23,953 [salt.loaded.ext.module.maasng:632 ][INFO    ][7955] vgroot
2019-08-09 05:19:23,953 [salt.loaded.ext.module.maasng:635 ][INFO    ][7955] lvroot
2019-08-09 05:19:23,954 [salt.loaded.ext.module.maasng:639 ][INFO    ][7955] 107374182400
2019-08-09 05:19:24,655 [salt.loaded.ext.module.maasng:645 ][INFO    ][7955] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'swap_size': None, u'memory_test_status': -1, u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'fqdn': u'cmp002.maas', u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 2397998940160, u'uuid': None, u'resource_uri': u'/MAAS/api/2.0/nodes/x7g4x4/blockdevices/5/', u'type': u'physical', u'partitions': [{u'uuid': u'76c77911-2472-4bb1-bcdf-d02f68829124', u'resource_uri': u'/MAAS/api/2.0/nodes/x7g4x4/blockdevices/5/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'x7g4x4', u'filesystem': {u'mount_options': None, u'uuid': u'fcc30f97-8ddf-43ea-9edf-842fc158a84c', u'fstype': u'lvm-pv', u'mount_point': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 5, u'type': u'partition', u'id': 7, u'size': 2397992648704}], u'tags': [u'rotary'], u'filesystem': None, u'id': 5, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'x7g4x4', 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'used_size': 2397998940160, u'model': u'UCSB-MRAID12G', u'name': u'sda'}, u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default'}, u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'x7g4x4', 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'virtualblockdevice_set': [{u'size': 107374182400, u'uuid': u'f06e73ec-7128-42f1-b497-08fc11ecc18d', u'resource_uri': u'/MAAS/api/2.0/nodes/x7g4x4/blockdevices/12/', u'type': u'virtual', u'partitions': [], u'tags': [], u'filesystem': {u'mount_options': None, u'uuid': u'cac005d5-fd94-47b9-bcf6-d7bb4388fd34', u'fstype': u'ext4', u'mount_point': u'/', u'label': u'root'}, u'id': 12, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'x7g4x4', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'model': None, u'name': u'vgroot-lvroot'}], u'blockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'id': 5, u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'x7g4x4', 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'used_size': 2397998940160, u'resource_uri': u'/MAAS/api/2.0/nodes/x7g4x4/blockdevices/5/', u'partitions': [{u'uuid': u'76c77911-2472-4bb1-bcdf-d02f68829124', u'resource_uri': u'/MAAS/api/2.0/nodes/x7g4x4/blockdevices/5/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'x7g4x4', u'filesystem': {u'mount_options': None, u'uuid': u'fcc30f97-8ddf-43ea-9edf-842fc158a84c', u'fstype': u'lvm-pv', u'mount_point': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 5, u'type': u'partition', u'id': 7, u'size': 2397992648704}]}, {u'size': 107374182400, u'model': None, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'id': 12, u'filesystem': {u'mount_options': None, u'uuid': u'cac005d5-fd94-47b9-bcf6-d7bb4388fd34', u'fstype': u'ext4', u'mount_point': u'/', u'label': u'root'}, u'uuid': u'f06e73ec-7128-42f1-b497-08fc11ecc18d', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'x7g4x4', 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'used_size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/x7g4x4/blockdevices/12/', u'partitions': []}], u'status': 4, u'bcaches': [], u'cpu_count': 16, u'power_state': u'off', u'physicalblockdevice_set': [{u'size': 2397998940160, u'uuid': None, u'resource_uri': u'/MAAS/api/2.0/nodes/x7g4x4/blockdevices/5/', u'type': u'physical', u'partitions': [{u'uuid': u'76c77911-2472-4bb1-bcdf-d02f68829124', u'resource_uri': u'/MAAS/api/2.0/nodes/x7g4x4/blockdevices/5/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'x7g4x4', u'filesystem': {u'mount_options': None, u'uuid': u'fcc30f97-8ddf-43ea-9edf-842fc158a84c', u'fstype': u'lvm-pv', u'mount_point': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 5, u'type': u'partition', u'id': 7, u'size': 2397992648704}], u'tags': [u'rotary'], u'filesystem': None, u'id': 5, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'x7g4x4', 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'used_size': 2397998940160, u'model': u'UCSB-MRAID12G', u'name': u'sda'}], u'ip_addresses': [u'192.168.11.42'], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'x7g4x4', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', 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'7ad878', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'id': 39, u'mode': u'dhcp'}], 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'7ad878', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'7ad878', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'mac_address': u'00:25:b5:a0:00:6a', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'x7g4x4', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/x7g4x4/interfaces/4/'}, {u'name': u'enp9s0', u'links': [{u'id': 40, u'mode': u'link_up'}], 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'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:6d', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'x7g4x4', u'type': u'physical', u'id': 21, u'resource_uri': u'/MAAS/api/2.0/nodes/x7g4x4/interfaces/21/'}, {u'name': u'enp7s0', u'links': [{u'id': 41, u'mode': u'link_up'}], 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'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:6b', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'x7g4x4', u'type': u'physical', u'id': 22, u'resource_uri': u'/MAAS/api/2.0/nodes/x7g4x4/interfaces/22/'}, {u'name': u'enp8s0', u'links': [{u'id': 42, u'mode': u'link_up'}], 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'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:6c', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'x7g4x4', u'type': u'physical', u'id': 23, u'resource_uri': u'/MAAS/api/2.0/nodes/x7g4x4/interfaces/23/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'status_action': u'', u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', 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'7ad878', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'id': 39, u'mode': u'dhcp'}], 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'7ad878', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'7ad878', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'mac_address': u'00:25:b5:a0:00:6a', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'x7g4x4', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/x7g4x4/interfaces/4/'}, u'current_commissioning_result_id': 2, u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/x7g4x4/', u'distro_series': u'', u'node_type_name': u'Machine'}
2019-08-09 05:19:24,657 [salt.state       :300 ][INFO    ][7955] {'new': {'storage_layout': 'lvm'}}
2019-08-09 05:19:24,658 [salt.state       :1951][INFO    ][7955] Completed state [maas_machines_storage_cmp002_lvm] at time 05:19:24.658115 duration_in_ms=3078.159
2019-08-09 05:19:24,658 [salt.state       :1780][INFO    ][7955] Running state [maas_machines_storage_cmp001_lvm] at time 05:19:24.658645
2019-08-09 05:19:24,659 [salt.state       :1813][INFO    ][7955] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-08-09 05:19:26,131 [salt.loaded.ext.module.maasng:610 ][INFO    ][7955] xgw4h8
2019-08-09 05:19:26,131 [salt.loaded.ext.module.maasng:626 ][INFO    ][7955] sda
2019-08-09 05:19:26,833 [salt.loaded.ext.module.maasng:361 ][INFO    ][7955] xgw4h8
2019-08-09 05:19:26,953 [salt.loaded.ext.module.maasng:367 ][INFO    ][7955] [{u'size': 2397998940160, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'9801dd0a-a2e4-4f3d-87a3-63c380d62361', u'resource_uri': u'/MAAS/api/2.0/nodes/xgw4h8/blockdevices/1/partition/1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xgw4h8', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'060344fd-0da7-4f73-8468-9622019042e0', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 1, u'type': u'partition', u'id': 1, u'size': 2397992648704}], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'xgw4h8', 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'block_size': 4096, u'type': u'physical', u'id': 1, u'serial': u'618e72837274f1901cc7889705aa1b02', u'resource_uri': u'/MAAS/api/2.0/nodes/xgw4h8/blockdevices/1/'}, {u'size': 2397988454400, u'uuid': u'8623dd12-0a06-496d-934d-40c558a3da1e', 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'7ace1ca5-9a3f-4a49-abbb-e94e0edc06ef', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'xgw4h8', 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'block_size': 4096, u'type': u'virtual', u'id': 6, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/xgw4h8/blockdevices/6/'}]
2019-08-09 05:19:26,954 [salt.loaded.ext.module.maasng:632 ][INFO    ][7955] vgroot
2019-08-09 05:19:26,954 [salt.loaded.ext.module.maasng:635 ][INFO    ][7955] lvroot
2019-08-09 05:19:26,954 [salt.loaded.ext.module.maasng:639 ][INFO    ][7955] 107374182400
2019-08-09 05:19:27,678 [salt.loaded.ext.module.maasng:645 ][INFO    ][7955] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'swap_size': None, u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.38'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'fqdn': u'cmp001.maas', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'uuid': None, u'resource_uri': u'/MAAS/api/2.0/nodes/xgw4h8/blockdevices/1/', u'used_for': u'GPT partitioned with 1 partition', u'used_size': 2397998940160, u'partitions': [{u'size': 2397992648704, u'uuid': u'fc5b4990-d6bb-4306-b78b-898f7f7e2bef', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xgw4h8', u'filesystem': {u'uuid': u'17468bab-622e-42f1-a3eb-db8e8cb40a46', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/xgw4h8/blockdevices/1/partition/8', u'type': u'partition', u'id': 8, u'device_id': 1}], u'filesystem': None, u'name': u'sda', u'tags': [u'rotary'], u'system_id': u'xgw4h8', 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'block_size': 4096, u'type': u'physical', u'id': 1, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}, u'zone': {u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default', u'id': 1}, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'xgw4h8', 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'physicalblockdevice_set': [{u'uuid': None, u'resource_uri': u'/MAAS/api/2.0/nodes/xgw4h8/blockdevices/1/', u'used_for': u'GPT partitioned with 1 partition', u'used_size': 2397998940160, u'partitions': [{u'size': 2397992648704, u'uuid': u'fc5b4990-d6bb-4306-b78b-898f7f7e2bef', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xgw4h8', u'filesystem': {u'uuid': u'17468bab-622e-42f1-a3eb-db8e8cb40a46', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/xgw4h8/blockdevices/1/partition/8', u'type': u'partition', u'id': 8, u'device_id': 1}], u'filesystem': None, u'name': u'sda', u'tags': [u'rotary'], u'system_id': u'xgw4h8', 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'block_size': 4096, u'type': u'physical', u'id': 1, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}], u'blockdevice_set': [{u'size': 2397998940160, u'uuid': None, u'resource_uri': u'/MAAS/api/2.0/nodes/xgw4h8/blockdevices/1/', u'used_size': 2397998940160, u'partitions': [{u'size': 2397992648704, u'uuid': u'fc5b4990-d6bb-4306-b78b-898f7f7e2bef', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xgw4h8', u'filesystem': {u'uuid': u'17468bab-622e-42f1-a3eb-db8e8cb40a46', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/xgw4h8/blockdevices/1/partition/8', u'type': u'partition', u'id': 8, u'device_id': 1}], u'tags': [u'rotary'], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'xgw4h8', 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'block_size': 4096, u'type': u'physical', u'id': 1, u'serial': u'618e72837274f1901cc7889705aa1b02', u'name': u'sda'}, {u'size': 107374182400, u'uuid': u'fc9c795b-26a0-4427-83f2-57f3b6ea3f80', u'resource_uri': u'/MAAS/api/2.0/nodes/xgw4h8/blockdevices/13/', u'used_size': 107374182400, u'partitions': [], u'tags': [], u'filesystem': {u'uuid': u'752ba2ea-2533-40dc-aa8b-da725020ce22', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'xgw4h8', 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'block_size': 4096, u'type': u'virtual', u'id': 13, u'serial': None, u'name': u'vgroot-lvroot'}], u'status': 4, u'storage_test_status': 2, u'cpu_count': 16, u'power_state': u'off', u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'xgw4h8', u'id': 8}], u'special_filesystems': [], u'current_commissioning_result_id': 4, 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'7ad878', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 16, u'mode': u'dhcp'}], 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'7ad878', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'parents': [], u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'7ad878', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'00:25:b5:a0:00:5a', u'system_id': u'xgw4h8', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/xgw4h8/interfaces/5/'}, {u'name': u'enp8s0', u'links': [{u'id': 17, u'mode': u'link_up'}], 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'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'parents': [], u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5c', u'system_id': u'xgw4h8', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 9, u'resource_uri': u'/MAAS/api/2.0/nodes/xgw4h8/interfaces/9/'}, {u'name': u'enp9s0', u'links': [{u'id': 18, u'mode': u'link_up'}], 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'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'parents': [], u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5d', u'system_id': u'xgw4h8', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 10, u'resource_uri': u'/MAAS/api/2.0/nodes/xgw4h8/interfaces/10/'}, {u'name': u'enp7s0', u'links': [{u'id': 19, u'mode': u'link_up'}], 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'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'parents': [], u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5b', u'system_id': u'xgw4h8', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/xgw4h8/interfaces/11/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'bcaches': [], u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'node_type': 0, u'disable_ipv4': False, u'virtualblockdevice_set': [{u'uuid': u'fc9c795b-26a0-4427-83f2-57f3b6ea3f80', u'resource_uri': u'/MAAS/api/2.0/nodes/xgw4h8/blockdevices/13/', u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'uuid': u'752ba2ea-2533-40dc-aa8b-da725020ce22', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'name': u'vgroot-lvroot', u'tags': [], u'system_id': u'xgw4h8', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 13, u'serial': None, u'size': 107374182400}], u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', 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'7ad878', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 16, u'mode': u'dhcp'}], 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'7ad878', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'parents': [], u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'7ad878', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'00:25:b5:a0:00:5a', u'system_id': u'xgw4h8', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/xgw4h8/interfaces/5/'}, u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/xgw4h8/'}
2019-08-09 05:19:27,680 [salt.state       :300 ][INFO    ][7955] {'new': {'storage_layout': 'lvm'}}
2019-08-09 05:19:27,681 [salt.state       :1951][INFO    ][7955] Completed state [maas_machines_storage_cmp001_lvm] at time 05:19:27.680974 duration_in_ms=3022.328
2019-08-09 05:19:27,685 [salt.minion      :1711][INFO    ][7955] Returning information for job: 20190809051916119121
2019-08-09 05:19:28,315 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command state.apply with jid 20190809051928299741
2019-08-09 05:19:28,336 [salt.minion      :1432][INFO    ][7982] Starting a new job with PID 7982
2019-08-09 05:19:29,099 [salt.state       :915 ][INFO    ][7982] Loading fresh modules for state activity
2019-08-09 05:19:29,150 [salt.fileclient  :1219][INFO    ][7982] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-08-09 05:19:29,187 [salt.state       :1780][INFO    ][7982] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:19:29.187473
2019-08-09 05:19:29,187 [salt.state       :1813][INFO    ][7982] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-09 05:19:29,189 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7982] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-09 05:19:30,425 [salt.state       :300 ][INFO    ][7982] {'pid': 7989, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-09 05:19:30,426 [salt.state       :1951][INFO    ][7982] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:19:30.426203 duration_in_ms=1238.731
2019-08-09 05:19:30,427 [salt.state       :1780][INFO    ][7982] Running state [maas.deploy_machines] at time 05:19:30.427242
2019-08-09 05:19:30,427 [salt.state       :1813][INFO    ][7982] Executing state module.run for [maas.deploy_machines]
2019-08-09 05:19:30,427 [salt.utils.decorators:613 ][WARNING ][7982] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-09 05:19:30,992 [salt.loaded.ext.module.maas:684 ][INFO    ][7982] deploymachines hwe_kernel=hwe-16.04 system_id=x7g4x4 distro_series=xenial
2019-08-09 05:19:33,475 [salt.loaded.ext.module.maas:684 ][INFO    ][7982] deploymachines hwe_kernel=hwe-16.04 system_id=xgw4h8 distro_series=xenial
2019-08-09 05:19:36,064 [salt.loaded.ext.module.maas:684 ][INFO    ][7982] deploymachines hwe_kernel=hwe-16.04 system_id=mpwkyw distro_series=xenial
2019-08-09 05:19:38,873 [salt.loaded.ext.module.maas:684 ][INFO    ][7982] deploymachines hwe_kernel=hwe-16.04 system_id=b4r7dk distro_series=xenial
2019-08-09 05:19:41,873 [salt.loaded.ext.module.maas:684 ][INFO    ][7982] deploymachines hwe_kernel=hwe-16.04 system_id=4a4t64 distro_series=xenial
2019-08-09 05:19:43,371 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command saltutil.find_job with jid 20190809051943359939
2019-08-09 05:19:43,392 [salt.minion      :1432][INFO    ][8266] Starting a new job with PID 8266
2019-08-09 05:19:43,416 [salt.minion      :1711][INFO    ][8266] Returning information for job: 20190809051943359939
2019-08-09 05:19:44,630 [salt.state       :300 ][INFO    ][7982] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-08-09 05:19:44,630 [salt.state       :1951][INFO    ][7982] Completed state [maas.deploy_machines] at time 05:19:44.630649 duration_in_ms=14203.404
2019-08-09 05:19:44,634 [salt.minion      :1711][INFO    ][7982] Returning information for job: 20190809051928299741
2019-08-09 05:19:45,274 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command state.apply with jid 20190809051945261261
2019-08-09 05:19:45,295 [salt.minion      :1432][INFO    ][8279] Starting a new job with PID 8279
2019-08-09 05:19:49,152 [salt.state       :915 ][INFO    ][8279] Loading fresh modules for state activity
2019-08-09 05:19:49,202 [salt.fileclient  :1219][INFO    ][8279] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-08-09 05:19:49,244 [salt.state       :1780][INFO    ][8279] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:19:49.244623
2019-08-09 05:19:49,245 [salt.state       :1813][INFO    ][8279] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-09 05:19:49,246 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8279] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-09 05:19:50,656 [salt.state       :300 ][INFO    ][8279] {'pid': 8321, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-09 05:19:50,657 [salt.state       :1951][INFO    ][8279] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:19:50.657049 duration_in_ms=1412.427
2019-08-09 05:19:50,658 [salt.state       :1780][INFO    ][8279] Running state [maas.wait_for_machine_status] at time 05:19:50.658481
2019-08-09 05:19:50,658 [salt.state       :1813][INFO    ][8279] Executing state module.run for [maas.wait_for_machine_status]
2019-08-09 05:19:50,658 [salt.utils.decorators:613 ][WARNING ][8279] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-09 05:19:54,107 [salt.loaded.ext.module.maas:1023][INFO    ][8279] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.55581999s left)
2019-08-09 05:20:00,330 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command saltutil.find_job with jid 20190809052000315768
2019-08-09 05:20:00,353 [salt.minion      :1432][INFO    ][8334] Starting a new job with PID 8334
2019-08-09 05:20:00,377 [salt.minion      :1711][INFO    ][8334] Returning information for job: 20190809052000315768
2019-08-09 05:20:27,825 [salt.loaded.ext.module.maas:1023][INFO    ][8279] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2212.83825779s left)
2019-08-09 05:20:30,386 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command saltutil.find_job with jid 20190809052030373317
2019-08-09 05:20:30,409 [salt.minion      :1432][INFO    ][8354] Starting a new job with PID 8354
2019-08-09 05:20:30,431 [salt.minion      :1711][INFO    ][8354] Returning information for job: 20190809052030373317
2019-08-09 05:21:00,429 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command saltutil.find_job with jid 20190809052100416754
2019-08-09 05:21:00,450 [salt.minion      :1432][INFO    ][8409] Starting a new job with PID 8409
2019-08-09 05:21:00,474 [salt.minion      :1711][INFO    ][8409] Returning information for job: 20190809052100416754
2019-08-09 05:21:01,449 [salt.loaded.ext.module.maas:1023][INFO    ][8279] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2179.21403384s left)
2019-08-09 05:21:30,482 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command saltutil.find_job with jid 20190809052130464210
2019-08-09 05:21:30,500 [salt.minion      :1432][INFO    ][8517] Starting a new job with PID 8517
2019-08-09 05:21:30,523 [salt.minion      :1711][INFO    ][8517] Returning information for job: 20190809052130464210
2019-08-09 05:21:34,156 [salt.loaded.ext.module.maas:1023][INFO    ][8279] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2146.50735092s left)
2019-08-09 05:22:00,534 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command saltutil.find_job with jid 20190809052200521327
2019-08-09 05:22:00,556 [salt.minion      :1432][INFO    ][8984] Starting a new job with PID 8984
2019-08-09 05:22:00,581 [salt.minion      :1711][INFO    ][8984] Returning information for job: 20190809052200521327
2019-08-09 05:22:07,608 [salt.loaded.ext.module.maas:1023][INFO    ][8279] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2113.05501294s left)
2019-08-09 05:22:30,594 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command saltutil.find_job with jid 20190809052230578819
2019-08-09 05:22:30,614 [salt.minion      :1432][INFO    ][9028] Starting a new job with PID 9028
2019-08-09 05:22:30,633 [salt.minion      :1711][INFO    ][9028] Returning information for job: 20190809052230578819
2019-08-09 05:22:41,178 [salt.loaded.ext.module.maas:1023][INFO    ][8279] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2079.48546696s left)
2019-08-09 05:23:00,642 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command saltutil.find_job with jid 20190809052300629659
2019-08-09 05:23:00,664 [salt.minion      :1432][INFO    ][9626] Starting a new job with PID 9626
2019-08-09 05:23:00,688 [salt.minion      :1711][INFO    ][9626] Returning information for job: 20190809052300629659
2019-08-09 05:23:14,759 [salt.loaded.ext.module.maas:1023][INFO    ][8279] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2045.90425777s left)
2019-08-09 05:23:30,707 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command saltutil.find_job with jid 20190809052330694079
2019-08-09 05:23:30,729 [salt.minion      :1432][INFO    ][9648] Starting a new job with PID 9648
2019-08-09 05:23:30,752 [salt.minion      :1711][INFO    ][9648] Returning information for job: 20190809052330694079
2019-08-09 05:23:48,342 [salt.loaded.ext.module.maas:1023][INFO    ][8279] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2012.321105s left)
2019-08-09 05:24:00,773 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command saltutil.find_job with jid 20190809052400757213
2019-08-09 05:24:00,795 [salt.minion      :1432][INFO    ][9869] Starting a new job with PID 9869
2019-08-09 05:24:00,820 [salt.minion      :1711][INFO    ][9869] Returning information for job: 20190809052400757213
2019-08-09 05:24:21,957 [salt.loaded.ext.module.maas:1023][INFO    ][8279] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1978.70596981s left)
2019-08-09 05:24:30,848 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command saltutil.find_job with jid 20190809052430832958
2019-08-09 05:24:30,871 [salt.minion      :1432][INFO    ][9901] Starting a new job with PID 9901
2019-08-09 05:24:30,895 [salt.minion      :1711][INFO    ][9901] Returning information for job: 20190809052430832958
2019-08-09 05:24:55,299 [salt.loaded.ext.module.maas:1023][INFO    ][8279] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1945.364223s left)
2019-08-09 05:25:00,924 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command saltutil.find_job with jid 20190809052500910821
2019-08-09 05:25:00,946 [salt.minion      :1432][INFO    ][10439] Starting a new job with PID 10439
2019-08-09 05:25:00,972 [salt.minion      :1711][INFO    ][10439] Returning information for job: 20190809052500910821
2019-08-09 05:25:28,869 [salt.loaded.ext.module.maas:1023][INFO    ][8279] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1911.79414797s left)
2019-08-09 05:25:31,003 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command saltutil.find_job with jid 20190809052530990603
2019-08-09 05:25:31,026 [salt.minion      :1432][INFO    ][10483] Starting a new job with PID 10483
2019-08-09 05:25:31,050 [salt.minion      :1711][INFO    ][10483] Returning information for job: 20190809052530990603
2019-08-09 05:26:01,083 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command saltutil.find_job with jid 20190809052601071912
2019-08-09 05:26:01,103 [salt.minion      :1432][INFO    ][10810] Starting a new job with PID 10810
2019-08-09 05:26:01,126 [salt.minion      :1711][INFO    ][10810] Returning information for job: 20190809052601071912
2019-08-09 05:26:02,408 [salt.loaded.ext.module.maas:1023][INFO    ][8279] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1878.25487781s left)
2019-08-09 05:26:31,168 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command saltutil.find_job with jid 20190809052631155084
2019-08-09 05:26:31,191 [salt.minion      :1432][INFO    ][10826] Starting a new job with PID 10826
2019-08-09 05:26:31,215 [salt.minion      :1711][INFO    ][10826] Returning information for job: 20190809052631155084
2019-08-09 05:26:35,881 [salt.loaded.ext.module.maas:1023][INFO    ][8279] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1844.78191996s left)
2019-08-09 05:27:01,262 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command saltutil.find_job with jid 20190809052701252841
2019-08-09 05:27:01,282 [salt.minion      :1432][INFO    ][10964] Starting a new job with PID 10964
2019-08-09 05:27:01,305 [salt.minion      :1711][INFO    ][10964] Returning information for job: 20190809052701252841
2019-08-09 05:27:09,566 [salt.loaded.ext.module.maas:1023][INFO    ][8279] Waiting status:Deployed for machines:['cmp002', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1811.09692287s left)
2019-08-09 05:27:31,464 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command saltutil.find_job with jid 20190809052731452062
2019-08-09 05:27:31,487 [salt.minion      :1432][INFO    ][11096] Starting a new job with PID 11096
2019-08-09 05:27:31,511 [salt.minion      :1711][INFO    ][11096] Returning information for job: 20190809052731452062
2019-08-09 05:27:43,136 [salt.loaded.ext.module.maas:1023][INFO    ][8279] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1777.52704692s left)
2019-08-09 05:28:01,572 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command saltutil.find_job with jid 20190809052801560263
2019-08-09 05:28:01,592 [salt.minion      :1432][INFO    ][11553] Starting a new job with PID 11553
2019-08-09 05:28:01,614 [salt.minion      :1711][INFO    ][11553] Returning information for job: 20190809052801560263
2019-08-09 05:28:16,782 [salt.loaded.ext.module.maas:1023][INFO    ][8279] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1743.88092279s left)
2019-08-09 05:28:31,679 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command saltutil.find_job with jid 20190809052831665900
2019-08-09 05:28:31,701 [salt.minion      :1432][INFO    ][11572] Starting a new job with PID 11572
2019-08-09 05:28:31,724 [salt.minion      :1711][INFO    ][11572] Returning information for job: 20190809052831665900
2019-08-09 05:28:50,071 [salt.loaded.ext.module.maas:1023][INFO    ][8279] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1710.59255791s left)
2019-08-09 05:29:01,800 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command saltutil.find_job with jid 20190809052901785201
2019-08-09 05:29:01,822 [salt.minion      :1432][INFO    ][11629] Starting a new job with PID 11629
2019-08-09 05:29:01,847 [salt.minion      :1711][INFO    ][11629] Returning information for job: 20190809052901785201
2019-08-09 05:29:23,622 [salt.loaded.ext.module.maas:1023][INFO    ][8279] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1677.04126191s left)
2019-08-09 05:29:31,930 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command saltutil.find_job with jid 20190809052931914510
2019-08-09 05:29:31,953 [salt.minion      :1432][INFO    ][11648] Starting a new job with PID 11648
2019-08-09 05:29:31,978 [salt.minion      :1711][INFO    ][11648] Returning information for job: 20190809052931914510
2019-08-09 05:29:57,262 [salt.loaded.ext.module.maas:1023][INFO    ][8279] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1643.40103698s left)
2019-08-09 05:30:02,067 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command saltutil.find_job with jid 20190809053002054479
2019-08-09 05:30:02,090 [salt.minion      :1432][INFO    ][11705] Starting a new job with PID 11705
2019-08-09 05:30:02,113 [salt.minion      :1711][INFO    ][11705] Returning information for job: 20190809053002054479
2019-08-09 05:30:30,933 [salt.loaded.ext.module.maas:1023][INFO    ][8279] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1609.72981691s left)
2019-08-09 05:30:32,206 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command saltutil.find_job with jid 20190809053032193308
2019-08-09 05:30:32,229 [salt.minion      :1432][INFO    ][11725] Starting a new job with PID 11725
2019-08-09 05:30:32,253 [salt.minion      :1711][INFO    ][11725] Returning information for job: 20190809053032193308
2019-08-09 05:31:02,363 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command saltutil.find_job with jid 20190809053102348506
2019-08-09 05:31:02,387 [salt.minion      :1432][INFO    ][11780] Starting a new job with PID 11780
2019-08-09 05:31:02,410 [salt.minion      :1711][INFO    ][11780] Returning information for job: 20190809053102348506
2019-08-09 05:31:04,486 [salt.loaded.ext.module.maas:1023][INFO    ][8279] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1576.177104s left)
2019-08-09 05:31:32,525 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command saltutil.find_job with jid 20190809053132509297
2019-08-09 05:31:32,547 [salt.minion      :1432][INFO    ][11801] Starting a new job with PID 11801
2019-08-09 05:31:32,571 [salt.minion      :1711][INFO    ][11801] Returning information for job: 20190809053132509297
2019-08-09 05:31:37,750 [salt.loaded.ext.module.maas:1023][INFO    ][8279] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1542.91315985s left)
2019-08-09 05:32:02,695 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command saltutil.find_job with jid 20190809053202682312
2019-08-09 05:32:02,718 [salt.minion      :1432][INFO    ][11862] Starting a new job with PID 11862
2019-08-09 05:32:02,742 [salt.minion      :1711][INFO    ][11862] Returning information for job: 20190809053202682312
2019-08-09 05:32:11,289 [salt.loaded.ext.module.maas:1023][INFO    ][8279] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1509.37408996s left)
2019-08-09 05:32:32,878 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command saltutil.find_job with jid 20190809053232865307
2019-08-09 05:32:32,901 [salt.minion      :1432][INFO    ][11889] Starting a new job with PID 11889
2019-08-09 05:32:32,926 [salt.minion      :1711][INFO    ][11889] Returning information for job: 20190809053232865307
2019-08-09 05:32:44,632 [salt.loaded.ext.module.maas:1023][INFO    ][8279] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1476.03157091s left)
2019-08-09 05:33:03,068 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command saltutil.find_job with jid 20190809053303055289
2019-08-09 05:33:03,090 [salt.minion      :1432][INFO    ][11948] Starting a new job with PID 11948
2019-08-09 05:33:03,115 [salt.minion      :1711][INFO    ][11948] Returning information for job: 20190809053303055289
2019-08-09 05:33:18,428 [salt.loaded.ext.module.maas:1023][INFO    ][8279] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1442.2353909s left)
2019-08-09 05:33:33,265 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command saltutil.find_job with jid 20190809053333252590
2019-08-09 05:33:33,287 [salt.minion      :1432][INFO    ][11969] Starting a new job with PID 11969
2019-08-09 05:33:33,309 [salt.minion      :1711][INFO    ][11969] Returning information for job: 20190809053333252590
2019-08-09 05:33:52,059 [salt.loaded.ext.module.maas:1023][INFO    ][8279] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1408.60368991s left)
2019-08-09 05:34:03,473 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command saltutil.find_job with jid 20190809053403460539
2019-08-09 05:34:03,495 [salt.minion      :1432][INFO    ][12169] Starting a new job with PID 12169
2019-08-09 05:34:03,519 [salt.minion      :1711][INFO    ][12169] Returning information for job: 20190809053403460539
2019-08-09 05:34:25,640 [salt.loaded.ext.module.maas:1023][INFO    ][8279] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1375.023175s left)
2019-08-09 05:34:33,699 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command saltutil.find_job with jid 20190809053433685527
2019-08-09 05:34:33,721 [salt.minion      :1432][INFO    ][12194] Starting a new job with PID 12194
2019-08-09 05:34:33,744 [salt.minion      :1711][INFO    ][12194] Returning information for job: 20190809053433685527
2019-08-09 05:34:59,195 [salt.loaded.ext.module.maas:993 ][INFO    ][8279] Machine 4a4t64 mark broken
2019-08-09 05:34:59,987 [salt.loaded.ext.module.maas:996 ][INFO    ][8279] Machine 4a4t64 mark fixed
2019-08-09 05:35:01,261 [salt.loaded.ext.module.maas:684 ][INFO    ][8279] deploymachines hwe_kernel=hwe-16.04 system_id=4a4t64 distro_series=xenial
2019-08-09 05:35:03,724 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command saltutil.find_job with jid 20190809053503711251
2019-08-09 05:35:03,745 [salt.minion      :1432][INFO    ][12305] Starting a new job with PID 12305
2019-08-09 05:35:03,773 [salt.minion      :1711][INFO    ][12305] Returning information for job: 20190809053503711251
2019-08-09 05:35:04,068 [salt.loaded.ext.module.maas:160 ][ERROR   ][8279] Failed for object kvm02 reason Unable to change power state to 'cycle' for node kvm02: another action is already in progress for that node.
2019-08-09 05:35:04,070 [salt.state       :302 ][ERROR   ][8279] Module function maas.wait_for_machine_status threw an exception. Exception: {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {'kvm02': "Unable to change power state to 'cycle' for node kvm02: another action is already in progress for that node."}, 'success': []}
2019-08-09 05:35:04,070 [salt.state       :1951][INFO    ][8279] Completed state [maas.wait_for_machine_status] at time 05:35:04.070742 duration_in_ms=913412.255
2019-08-09 05:35:04,080 [salt.minion      :1711][INFO    ][8279] Returning information for job: 20190809051945261261
2019-08-09 05:35:14,841 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command pillar.get with jid 20190809053514828124
2019-08-09 05:35:14,862 [salt.minion      :1432][INFO    ][12339] Starting a new job with PID 12339
2019-08-09 05:35:14,868 [salt.minion      :1711][INFO    ][12339] Returning information for job: 20190809053514828124
2019-08-09 05:35:15,377 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command service.status with jid 20190809053515365250
2019-08-09 05:35:15,397 [salt.minion      :1432][INFO    ][12344] Starting a new job with PID 12344
2019-08-09 05:35:15,787 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12344] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-08-09 05:35:15,819 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12344] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-08-09 05:35:15,834 [salt.minion      :1711][INFO    ][12344] Returning information for job: 20190809053515365250
2019-08-09 05:35:16,330 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command state.apply with jid 20190809053516317159
2019-08-09 05:35:16,351 [salt.minion      :1432][INFO    ][12355] Starting a new job with PID 12355
2019-08-09 05:35:20,192 [salt.state       :915 ][INFO    ][12355] Loading fresh modules for state activity
2019-08-09 05:35:20,631 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12355] Executing command 'salt-minion --version' in directory '/root'
2019-08-09 05:35:20,941 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12355] Executing command 'salt-minion --version' in directory '/root'
2019-08-09 05:35:21,720 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12355] Executing command 'salt-minion --version' in directory '/root'
2019-08-09 05:35:22,056 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12355] Executing command 'salt-minion --version' in directory '/root'
2019-08-09 05:35:23,431 [salt.state       :1780][INFO    ][12355] Running state [salt-minion] at time 05:35:23.431620
2019-08-09 05:35:23,432 [salt.state       :1813][INFO    ][12355] Executing state pkg.installed for [salt-minion]
2019-08-09 05:35:23,433 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12355] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-08-09 05:35:23,542 [salt.state       :300 ][INFO    ][12355] All specified packages are already installed
2019-08-09 05:35:23,542 [salt.state       :1951][INFO    ][12355] Completed state [salt-minion] at time 05:35:23.542735 duration_in_ms=111.116
2019-08-09 05:35:23,543 [salt.state       :1780][INFO    ][12355] Running state [salt_minion_dependency_packages] at time 05:35:23.543158
2019-08-09 05:35:23,543 [salt.state       :1813][INFO    ][12355] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-08-09 05:35:23,552 [salt.state       :300 ][INFO    ][12355] All specified packages are already installed
2019-08-09 05:35:23,552 [salt.state       :1951][INFO    ][12355] Completed state [salt_minion_dependency_packages] at time 05:35:23.552354 duration_in_ms=9.195
2019-08-09 05:35:23,556 [salt.state       :1780][INFO    ][12355] Running state [/etc/salt/minion.d/minion.conf] at time 05:35:23.556321
2019-08-09 05:35:23,556 [salt.state       :1813][INFO    ][12355] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-08-09 05:35:23,783 [salt.state       :300 ][INFO    ][12355] File /etc/salt/minion.d/minion.conf is in the correct state
2019-08-09 05:35:23,784 [salt.state       :1951][INFO    ][12355] Completed state [/etc/salt/minion.d/minion.conf] at time 05:35:23.784103 duration_in_ms=227.784
2019-08-09 05:35:23,784 [salt.state       :1780][INFO    ][12355] Running state [python-netaddr] at time 05:35:23.784301
2019-08-09 05:35:23,784 [salt.state       :1813][INFO    ][12355] Executing state pkg.installed for [python-netaddr]
2019-08-09 05:35:23,789 [salt.state       :300 ][INFO    ][12355] All specified packages are already installed
2019-08-09 05:35:23,789 [salt.state       :1951][INFO    ][12355] Completed state [python-netaddr] at time 05:35:23.789301 duration_in_ms=5.0
2019-08-09 05:35:23,791 [salt.state       :1780][INFO    ][12355] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 05:35:23.791557
2019-08-09 05:35:23,791 [salt.state       :1813][INFO    ][12355] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-08-09 05:35:23,801 [salt.state       :300 ][INFO    ][12355] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-08-09 05:35:23,801 [salt.state       :1951][INFO    ][12355] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 05:35:23.801841 duration_in_ms=10.284
2019-08-09 05:35:23,802 [salt.state       :1780][INFO    ][12355] Running state [salt-minion] at time 05:35:23.802518
2019-08-09 05:35:23,802 [salt.state       :1813][INFO    ][12355] Executing state service.running for [salt-minion]
2019-08-09 05:35:23,803 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12355] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-08-09 05:35:23,839 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12355] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-08-09 05:35:23,855 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12355] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-08-09 05:35:23,872 [salt.state       :300 ][INFO    ][12355] The service salt-minion is already running
2019-08-09 05:35:23,873 [salt.state       :1951][INFO    ][12355] Completed state [salt-minion] at time 05:35:23.872901 duration_in_ms=70.382
2019-08-09 05:35:23,875 [salt.state       :1780][INFO    ][12355] Running state [/etc/salt/grains.d] at time 05:35:23.875343
2019-08-09 05:35:23,875 [salt.state       :1813][INFO    ][12355] Executing state file.directory for [/etc/salt/grains.d]
2019-08-09 05:35:23,877 [salt.state       :300 ][INFO    ][12355] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-08-09 05:35:23,877 [salt.state       :1951][INFO    ][12355] Completed state [/etc/salt/grains.d] at time 05:35:23.877621 duration_in_ms=2.279
2019-08-09 05:35:23,878 [salt.state       :1780][INFO    ][12355] Running state [/etc/salt/grains] at time 05:35:23.878686
2019-08-09 05:35:23,879 [salt.state       :1813][INFO    ][12355] Executing state file.managed for [/etc/salt/grains]
2019-08-09 05:35:23,879 [salt.state       :300 ][INFO    ][12355] File /etc/salt/grains exists with proper permissions. No changes made.
2019-08-09 05:35:23,880 [salt.state       :1951][INFO    ][12355] Completed state [/etc/salt/grains] at time 05:35:23.880248 duration_in_ms=1.561
2019-08-09 05:35:23,881 [salt.state       :1780][INFO    ][12355] Running state [/etc/salt/grains.d/placeholder] at time 05:35:23.880942
2019-08-09 05:35:23,881 [salt.state       :1813][INFO    ][12355] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-08-09 05:35:23,882 [salt.state       :300 ][INFO    ][12355] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-08-09 05:35:23,882 [salt.state       :1951][INFO    ][12355] Completed state [/etc/salt/grains.d/placeholder] at time 05:35:23.882411 duration_in_ms=1.469
2019-08-09 05:35:23,883 [salt.state       :1780][INFO    ][12355] Running state [/etc/salt/grains.d/sphinx] at time 05:35:23.883110
2019-08-09 05:35:23,883 [salt.state       :1813][INFO    ][12355] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-08-09 05:35:23,891 [salt.state       :300 ][INFO    ][12355] File /etc/salt/grains.d/sphinx is in the correct state
2019-08-09 05:35:23,892 [salt.state       :1951][INFO    ][12355] Completed state [/etc/salt/grains.d/sphinx] at time 05:35:23.892088 duration_in_ms=8.977
2019-08-09 05:35:23,895 [salt.state       :1780][INFO    ][12355] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 05:35:23.895400
2019-08-09 05:35:23,895 [salt.state       :1813][INFO    ][12355] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-08-09 05:35:23,896 [salt.state       :300 ][INFO    ][12355] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-08-09 05:35:23,896 [salt.state       :1951][INFO    ][12355] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 05:35:23.896677 duration_in_ms=1.277
2019-08-09 05:35:23,897 [salt.state       :1780][INFO    ][12355] Running state [/etc/salt/grains.d/dns_records] at time 05:35:23.897381
2019-08-09 05:35:23,897 [salt.state       :1813][INFO    ][12355] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-08-09 05:35:23,909 [salt.state       :300 ][INFO    ][12355] File /etc/salt/grains.d/dns_records is in the correct state
2019-08-09 05:35:23,910 [salt.state       :1951][INFO    ][12355] Completed state [/etc/salt/grains.d/dns_records] at time 05:35:23.909917 duration_in_ms=12.537
2019-08-09 05:35:23,911 [salt.state       :1780][INFO    ][12355] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 05:35:23.911228
2019-08-09 05:35:23,911 [salt.state       :1813][INFO    ][12355] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-08-09 05:35:23,912 [salt.state       :300 ][INFO    ][12355] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-08-09 05:35:23,912 [salt.state       :1951][INFO    ][12355] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 05:35:23.912401 duration_in_ms=1.173
2019-08-09 05:35:23,913 [salt.state       :1780][INFO    ][12355] Running state [/etc/salt/grains.d/salt] at time 05:35:23.913093
2019-08-09 05:35:23,913 [salt.state       :1813][INFO    ][12355] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-08-09 05:35:23,927 [salt.state       :300 ][INFO    ][12355] File /etc/salt/grains.d/salt is in the correct state
2019-08-09 05:35:23,927 [salt.state       :1951][INFO    ][12355] Completed state [/etc/salt/grains.d/salt] at time 05:35:23.927891 duration_in_ms=14.797
2019-08-09 05:35:23,929 [salt.state       :1780][INFO    ][12355] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 05:35:23.929128
2019-08-09 05:35:23,929 [salt.state       :1813][INFO    ][12355] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-08-09 05:35:23,930 [salt.state       :300 ][INFO    ][12355] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-08-09 05:35:23,930 [salt.state       :1951][INFO    ][12355] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 05:35:23.930306 duration_in_ms=1.179
2019-08-09 05:35:23,933 [salt.state       :1780][INFO    ][12355] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 05:35:23.933028
2019-08-09 05:35:23,933 [salt.state       :1813][INFO    ][12355] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-08-09 05:35:23,933 [salt.state       :300 ][INFO    ][12355] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-08-09 05:35:23,934 [salt.state       :1951][INFO    ][12355] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 05:35:23.934212 duration_in_ms=1.183
2019-08-09 05:35:23,935 [salt.state       :1780][INFO    ][12355] Running state [mine.update] at time 05:35:23.935153
2019-08-09 05:35:23,935 [salt.state       :1813][INFO    ][12355] Executing state module.wait for [mine.update]
2019-08-09 05:35:23,936 [salt.state       :300 ][INFO    ][12355] No changes made for mine.update
2019-08-09 05:35:23,936 [salt.state       :1951][INFO    ][12355] Completed state [mine.update] at time 05:35:23.936250 duration_in_ms=1.097
2019-08-09 05:35:23,936 [salt.state       :1780][INFO    ][12355] Running state [ca-certificates] at time 05:35:23.936602
2019-08-09 05:35:23,936 [salt.state       :1813][INFO    ][12355] Executing state pkg.installed for [ca-certificates]
2019-08-09 05:35:23,947 [salt.state       :300 ][INFO    ][12355] All specified packages are already installed
2019-08-09 05:35:23,947 [salt.state       :1951][INFO    ][12355] Completed state [ca-certificates] at time 05:35:23.947300 duration_in_ms=10.698
2019-08-09 05:35:23,948 [salt.state       :1780][INFO    ][12355] Running state [update-ca-certificates] at time 05:35:23.948250
2019-08-09 05:35:23,948 [salt.state       :1813][INFO    ][12355] Executing state cmd.wait for [update-ca-certificates]
2019-08-09 05:35:23,949 [salt.state       :300 ][INFO    ][12355] No changes made for update-ca-certificates
2019-08-09 05:35:23,949 [salt.state       :1951][INFO    ][12355] Completed state [update-ca-certificates] at time 05:35:23.949326 duration_in_ms=1.077
2019-08-09 05:35:23,949 [salt.state       :1780][INFO    ][12355] Running state [iptables] at time 05:35:23.949653
2019-08-09 05:35:23,949 [salt.state       :1813][INFO    ][12355] Executing state pkg.installed for [iptables]
2019-08-09 05:35:23,958 [salt.state       :300 ][INFO    ][12355] All specified packages are already installed
2019-08-09 05:35:23,959 [salt.state       :1951][INFO    ][12355] Completed state [iptables] at time 05:35:23.959111 duration_in_ms=9.459
2019-08-09 05:35:23,959 [salt.state       :1780][INFO    ][12355] Running state [iptables-persistent] at time 05:35:23.959419
2019-08-09 05:35:23,959 [salt.state       :1813][INFO    ][12355] Executing state pkg.installed for [iptables-persistent]
2019-08-09 05:35:23,968 [salt.state       :300 ][INFO    ][12355] All specified packages are already installed
2019-08-09 05:35:23,968 [salt.state       :1951][INFO    ][12355] Completed state [iptables-persistent] at time 05:35:23.968526 duration_in_ms=9.106
2019-08-09 05:35:23,969 [salt.state       :1780][INFO    ][12355] Running state [iptables_modules_v4_load] at time 05:35:23.969781
2019-08-09 05:35:23,970 [salt.state       :1813][INFO    ][12355] Executing state kmod.present for [iptables_modules_v4_load]
2019-08-09 05:35:23,970 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12355] Executing command 'lsmod' in directory '/root'
2019-08-09 05:35:23,995 [salt.state       :300 ][INFO    ][12355] Kernel modules iptable_filter, ip_tables are already present
2019-08-09 05:35:23,995 [salt.state       :1951][INFO    ][12355] Completed state [iptables_modules_v4_load] at time 05:35:23.995668 duration_in_ms=25.886
2019-08-09 05:35:23,996 [salt.state       :1780][INFO    ][12355] Running state [/etc/iptables/rules.v4] at time 05:35:23.996613
2019-08-09 05:35:23,997 [salt.state       :1813][INFO    ][12355] Executing state file.managed for [/etc/iptables/rules.v4]
2019-08-09 05:35:24,101 [salt.state       :300 ][INFO    ][12355] File /etc/iptables/rules.v4 is in the correct state
2019-08-09 05:35:24,101 [salt.state       :1951][INFO    ][12355] Completed state [/etc/iptables/rules.v4] at time 05:35:24.101742 duration_in_ms=105.129
2019-08-09 05:35:24,102 [salt.state       :1780][INFO    ][12355] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 05:35:24.102856
2019-08-09 05:35:24,103 [salt.state       :1813][INFO    ][12355] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-08-09 05:35:24,103 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12355] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-08-09 05:35:24,123 [salt.state       :300 ][INFO    ][12355] onlyif execution failed
2019-08-09 05:35:24,123 [salt.state       :1951][INFO    ][12355] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 05:35:24.123669 duration_in_ms=20.813
2019-08-09 05:35:24,124 [salt.state       :1780][INFO    ][12355] Running state [netfilter-persistent] at time 05:35:24.124727
2019-08-09 05:35:24,125 [salt.state       :1813][INFO    ][12355] Executing state service.running for [netfilter-persistent]
2019-08-09 05:35:24,125 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12355] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-08-09 05:35:24,145 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12355] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-08-09 05:35:24,163 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12355] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-08-09 05:35:24,180 [salt.state       :300 ][INFO    ][12355] The service netfilter-persistent is already running
2019-08-09 05:35:24,181 [salt.state       :1951][INFO    ][12355] Completed state [netfilter-persistent] at time 05:35:24.181129 duration_in_ms=56.401
2019-08-09 05:35:24,182 [salt.state       :1780][INFO    ][12355] Running state [iptables_extra.remove_stale_tables] at time 05:35:24.182286
2019-08-09 05:35:24,182 [salt.state       :1813][INFO    ][12355] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-08-09 05:35:24,183 [salt.state       :300 ][INFO    ][12355] No changes made for iptables_extra.remove_stale_tables
2019-08-09 05:35:24,183 [salt.state       :1951][INFO    ][12355] Completed state [iptables_extra.remove_stale_tables] at time 05:35:24.183533 duration_in_ms=1.247
2019-08-09 05:35:24,183 [salt.state       :1780][INFO    ][12355] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 05:35:24.183882
2019-08-09 05:35:24,184 [salt.state       :1813][INFO    ][12355] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-08-09 05:35:24,185 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12355] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-08-09 05:35:24,199 [salt.state       :300 ][INFO    ][12355] onlyif execution failed
2019-08-09 05:35:24,200 [salt.state       :1951][INFO    ][12355] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 05:35:24.200230 duration_in_ms=16.348
2019-08-09 05:35:24,201 [salt.state       :1780][INFO    ][12355] Running state [/etc/iptables/rules.v6] at time 05:35:24.201570
2019-08-09 05:35:24,202 [salt.state       :1813][INFO    ][12355] Executing state file.absent for [/etc/iptables/rules.v6]
2019-08-09 05:35:24,202 [salt.state       :300 ][INFO    ][12355] File /etc/iptables/rules.v6 is not present
2019-08-09 05:35:24,203 [salt.state       :1951][INFO    ][12355] Completed state [/etc/iptables/rules.v6] at time 05:35:24.202945 duration_in_ms=1.374
2019-08-09 05:35:24,203 [salt.state       :1780][INFO    ][12355] Running state [iptables_extra.flush_all] at time 05:35:24.203872
2019-08-09 05:35:24,204 [salt.state       :1813][INFO    ][12355] Executing state module.wait for [iptables_extra.flush_all]
2019-08-09 05:35:24,204 [salt.state       :300 ][INFO    ][12355] No changes made for iptables_extra.flush_all
2019-08-09 05:35:24,205 [salt.state       :1951][INFO    ][12355] Completed state [iptables_extra.flush_all] at time 05:35:24.204961 duration_in_ms=1.089
2019-08-09 05:35:24,209 [salt.minion      :1711][INFO    ][12355] Returning information for job: 20190809053516317159
2019-08-09 05:35:24,843 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command state.apply with jid 20190809053524830070
2019-08-09 05:35:24,864 [salt.minion      :1432][INFO    ][12431] Starting a new job with PID 12431
2019-08-09 05:35:25,613 [salt.state       :915 ][INFO    ][12431] Loading fresh modules for state activity
2019-08-09 05:35:26,275 [salt.state       :1780][INFO    ][12431] Running state [maas-rack-controller] at time 05:35:26.275257
2019-08-09 05:35:26,275 [salt.state       :1813][INFO    ][12431] Executing state pkg.installed for [maas-rack-controller]
2019-08-09 05:35:26,276 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12431] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-08-09 05:35:26,361 [salt.state       :300 ][INFO    ][12431] All specified packages are already installed
2019-08-09 05:35:26,361 [salt.state       :1951][INFO    ][12431] Completed state [maas-rack-controller] at time 05:35:26.361814 duration_in_ms=86.557
2019-08-09 05:35:26,362 [salt.state       :1780][INFO    ][12431] Running state [ipmitool] at time 05:35:26.362121
2019-08-09 05:35:26,362 [salt.state       :1813][INFO    ][12431] Executing state pkg.installed for [ipmitool]
2019-08-09 05:35:26,368 [salt.state       :300 ][INFO    ][12431] All specified packages are already installed
2019-08-09 05:35:26,368 [salt.state       :1951][INFO    ][12431] Completed state [ipmitool] at time 05:35:26.368322 duration_in_ms=6.202
2019-08-09 05:35:26,371 [salt.state       :1780][INFO    ][12431] Running state [/etc/maas/rackd.conf] at time 05:35:26.371171
2019-08-09 05:35:26,371 [salt.state       :1813][INFO    ][12431] Executing state file.line for [/etc/maas/rackd.conf]
2019-08-09 05:35:26,372 [salt.state       :300 ][INFO    ][12431] No changes needed to be made
2019-08-09 05:35:26,372 [salt.state       :1951][INFO    ][12431] Completed state [/etc/maas/rackd.conf] at time 05:35:26.372567 duration_in_ms=1.396
2019-08-09 05:35:26,372 [salt.state       :1780][INFO    ][12431] Running state [/etc/maas/rackd.conf] at time 05:35:26.372770
2019-08-09 05:35:26,372 [salt.state       :1813][INFO    ][12431] Executing state file.managed for [/etc/maas/rackd.conf]
2019-08-09 05:35:26,373 [salt.loaded.int.states.file:2298][WARNING ][12431] 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-08-09 05:35:26,373 [salt.state       :300 ][INFO    ][12431] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-08-09 05:35:26,373 [salt.state       :1951][INFO    ][12431] Completed state [/etc/maas/rackd.conf] at time 05:35:26.373865 duration_in_ms=1.095
2019-08-09 05:35:26,374 [salt.state       :1780][INFO    ][12431] Running state [maas-rackd] at time 05:35:26.374709
2019-08-09 05:35:26,374 [salt.state       :1813][INFO    ][12431] Executing state service.running for [maas-rackd]
2019-08-09 05:35:26,375 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12431] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-08-09 05:35:26,410 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12431] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-08-09 05:35:26,428 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12431] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-08-09 05:35:26,448 [salt.state       :300 ][INFO    ][12431] The service maas-rackd is already running
2019-08-09 05:35:26,449 [salt.state       :1951][INFO    ][12431] Completed state [maas-rackd] at time 05:35:26.449297 duration_in_ms=74.587
2019-08-09 05:35:26,451 [salt.minion      :1711][INFO    ][12431] Returning information for job: 20190809053524830070
2019-08-09 05:35:27,071 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command state.apply with jid 20190809053527060854
2019-08-09 05:35:27,093 [salt.minion      :1432][INFO    ][12455] Starting a new job with PID 12455
2019-08-09 05:35:27,835 [salt.state       :915 ][INFO    ][12455] Loading fresh modules for state activity
2019-08-09 05:35:28,509 [salt.state       :1780][INFO    ][12455] Running state [maas-region-controller] at time 05:35:28.509126
2019-08-09 05:35:28,509 [salt.state       :1813][INFO    ][12455] Executing state pkg.installed for [maas-region-controller]
2019-08-09 05:35:28,509 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12455] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-08-09 05:35:28,607 [salt.state       :300 ][INFO    ][12455] All specified packages are already installed
2019-08-09 05:35:28,607 [salt.state       :1951][INFO    ][12455] Completed state [maas-region-controller] at time 05:35:28.607872 duration_in_ms=98.746
2019-08-09 05:35:28,608 [salt.state       :1780][INFO    ][12455] Running state [python-oauth] at time 05:35:28.608232
2019-08-09 05:35:28,608 [salt.state       :1813][INFO    ][12455] Executing state pkg.installed for [python-oauth]
2019-08-09 05:35:28,615 [salt.state       :300 ][INFO    ][12455] All specified packages are already installed
2019-08-09 05:35:28,615 [salt.state       :1951][INFO    ][12455] Completed state [python-oauth] at time 05:35:28.615772 duration_in_ms=7.539
2019-08-09 05:35:28,619 [salt.state       :1780][INFO    ][12455] Running state [/etc/maas/regiond.conf] at time 05:35:28.619197
2019-08-09 05:35:28,619 [salt.state       :1813][INFO    ][12455] Executing state file.replace for [/etc/maas/regiond.conf]
2019-08-09 05:35:28,701 [salt.state       :300 ][INFO    ][12455] No changes needed to be made
2019-08-09 05:35:28,701 [salt.state       :1951][INFO    ][12455] Completed state [/etc/maas/regiond.conf] at time 05:35:28.701820 duration_in_ms=82.622
2019-08-09 05:35:28,702 [salt.state       :1780][INFO    ][12455] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:35:28.702626
2019-08-09 05:35:28,703 [salt.state       :1813][INFO    ][12455] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-08-09 05:35:28,778 [salt.state       :300 ][INFO    ][12455] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-08-09 05:35:28,778 [salt.state       :1951][INFO    ][12455] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:35:28.778458 duration_in_ms=75.832
2019-08-09 05:35:28,779 [salt.state       :1780][INFO    ][12455] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:35:28.779070
2019-08-09 05:35:28,779 [salt.state       :1813][INFO    ][12455] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-08-09 05:35:28,797 [salt.state       :300 ][INFO    ][12455] No changes needed to be made
2019-08-09 05:35:28,798 [salt.state       :1951][INFO    ][12455] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:35:28.798165 duration_in_ms=19.094
2019-08-09 05:35:28,798 [salt.state       :1780][INFO    ][12455] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:35:28.798747
2019-08-09 05:35:28,799 [salt.state       :1813][INFO    ][12455] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-08-09 05:35:28,864 [salt.state       :300 ][INFO    ][12455] No changes needed to be made
2019-08-09 05:35:28,864 [salt.state       :1951][INFO    ][12455] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:35:28.864907 duration_in_ms=66.159
2019-08-09 05:35:28,865 [salt.state       :1780][INFO    ][12455] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:35:28.865719
2019-08-09 05:35:28,866 [salt.state       :1813][INFO    ][12455] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-08-09 05:35:28,904 [salt.state       :300 ][INFO    ][12455] No changes needed to be made
2019-08-09 05:35:28,904 [salt.state       :1951][INFO    ][12455] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:35:28.904357 duration_in_ms=38.638
2019-08-09 05:35:28,905 [salt.state       :1780][INFO    ][12455] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:35:28.905081
2019-08-09 05:35:28,905 [salt.state       :1813][INFO    ][12455] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-08-09 05:35:28,926 [salt.state       :300 ][INFO    ][12455] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-08-09 05:35:28,927 [salt.state       :1951][INFO    ][12455] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:35:28.927123 duration_in_ms=22.04
2019-08-09 05:35:28,930 [salt.state       :1780][INFO    ][12455] Running state [a2enmod headers] at time 05:35:28.930409
2019-08-09 05:35:28,930 [salt.state       :1813][INFO    ][12455] Executing state cmd.run for [a2enmod headers]
2019-08-09 05:35:28,931 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12455] Executing command 'a2enmod headers' in directory '/root'
2019-08-09 05:35:29,008 [salt.state       :300 ][INFO    ][12455] {'pid': 12474, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-08-09 05:35:29,008 [salt.state       :1951][INFO    ][12455] Completed state [a2enmod headers] at time 05:35:29.008700 duration_in_ms=78.291
2019-08-09 05:35:29,009 [salt.state       :1780][INFO    ][12455] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:35:29.009259
2019-08-09 05:35:29,009 [salt.state       :1813][INFO    ][12455] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-08-09 05:35:29,028 [salt.state       :300 ][INFO    ][12455] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-08-09 05:35:29,029 [salt.state       :1951][INFO    ][12455] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:35:29.029138 duration_in_ms=19.88
2019-08-09 05:35:29,030 [salt.state       :1780][INFO    ][12455] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:35:29.029997
2019-08-09 05:35:29,030 [salt.state       :1813][INFO    ][12455] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-08-09 05:35:29,114 [salt.state       :300 ][INFO    ][12455] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-08-09 05:35:29,114 [salt.state       :1951][INFO    ][12455] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:35:29.114466 duration_in_ms=84.468
2019-08-09 05:35:29,115 [salt.state       :1780][INFO    ][12455] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:35:29.115266
2019-08-09 05:35:29,115 [salt.state       :1813][INFO    ][12455] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-08-09 05:35:29,185 [salt.state       :300 ][INFO    ][12455] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-08-09 05:35:29,186 [salt.state       :1951][INFO    ][12455] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:35:29.186053 duration_in_ms=70.787
2019-08-09 05:35:29,186 [salt.state       :1780][INFO    ][12455] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:35:29.186600
2019-08-09 05:35:29,186 [salt.state       :1813][INFO    ][12455] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-08-09 05:35:29,251 [salt.state       :300 ][INFO    ][12455] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-08-09 05:35:29,251 [salt.state       :1951][INFO    ][12455] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:35:29.251783 duration_in_ms=65.182
2019-08-09 05:35:29,252 [salt.state       :1780][INFO    ][12455] Running state [/root/.pgpass] at time 05:35:29.252064
2019-08-09 05:35:29,252 [salt.state       :1813][INFO    ][12455] Executing state file.managed for [/root/.pgpass]
2019-08-09 05:35:29,299 [salt.state       :300 ][INFO    ][12455] File /root/.pgpass is in the correct state
2019-08-09 05:35:29,299 [salt.state       :1951][INFO    ][12455] Completed state [/root/.pgpass] at time 05:35:29.299482 duration_in_ms=47.418
2019-08-09 05:35:29,304 [salt.state       :1780][INFO    ][12455] Running state [maas-region syncdb --noinput] at time 05:35:29.304117
2019-08-09 05:35:29,304 [salt.state       :1813][INFO    ][12455] Executing state cmd.run for [maas-region syncdb --noinput]
2019-08-09 05:35:29,305 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12455] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-08-09 05:35:31,227 [salt.state       :300 ][INFO    ][12455] {'pid': 12487, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: sites, metadataserver, contenttypes, auth, sessions, piston3, maasserver\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-08-09 05:35:31,228 [salt.state       :1951][INFO    ][12455] Completed state [maas-region syncdb --noinput] at time 05:35:31.228092 duration_in_ms=1923.976
2019-08-09 05:35:31,228 [salt.state       :2022][WARNING ][12455] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-08-09 05:35:31,229 [salt.state       :1780][INFO    ][12455] Running state [maas-regiond] at time 05:35:31.229258
2019-08-09 05:35:31,229 [salt.state       :1813][INFO    ][12455] Executing state service.running for [maas-regiond]
2019-08-09 05:35:31,230 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12455] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-08-09 05:35:31,262 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12455] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-08-09 05:35:31,277 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12455] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-08-09 05:35:31,292 [salt.state       :300 ][INFO    ][12455] The service maas-regiond is already running
2019-08-09 05:35:31,292 [salt.state       :1951][INFO    ][12455] Completed state [maas-regiond] at time 05:35:31.292431 duration_in_ms=63.173
2019-08-09 05:35:31,293 [salt.state       :1780][INFO    ][12455] Running state [bind9] at time 05:35:31.293521
2019-08-09 05:35:31,293 [salt.state       :1813][INFO    ][12455] Executing state service.running for [bind9]
2019-08-09 05:35:31,294 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12455] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-08-09 05:35:31,309 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12455] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-08-09 05:35:31,323 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12455] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-08-09 05:35:31,337 [salt.state       :300 ][INFO    ][12455] The service bind9 is already running
2019-08-09 05:35:31,337 [salt.state       :1951][INFO    ][12455] Completed state [bind9] at time 05:35:31.337261 duration_in_ms=43.74
2019-08-09 05:35:31,338 [salt.state       :1780][INFO    ][12455] Running state [apache2] at time 05:35:31.338361
2019-08-09 05:35:31,338 [salt.state       :1813][INFO    ][12455] Executing state service.running for [apache2]
2019-08-09 05:35:31,339 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12455] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-08-09 05:35:31,353 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12455] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-08-09 05:35:31,366 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12455] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-08-09 05:35:31,383 [salt.state       :300 ][INFO    ][12455] The service apache2 is already running
2019-08-09 05:35:31,384 [salt.state       :1951][INFO    ][12455] Completed state [apache2] at time 05:35:31.384033 duration_in_ms=45.671
2019-08-09 05:35:31,385 [salt.state       :1780][INFO    ][12455] Running state [maasng.wait_for_http_code] at time 05:35:31.385094
2019-08-09 05:35:31,385 [salt.state       :1813][INFO    ][12455] Executing state module.run for [maasng.wait_for_http_code]
2019-08-09 05:35:31,385 [salt.utils.decorators:613 ][WARNING ][12455] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-09 05:35:31,484 [salt.state       :300 ][INFO    ][12455] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-08-09 05:35:31,484 [salt.state       :1951][INFO    ][12455] Completed state [maasng.wait_for_http_code] at time 05:35:31.484803 duration_in_ms=99.707
2019-08-09 05:35:31,486 [salt.state       :1780][INFO    ][12455] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:35:31.486244
2019-08-09 05:35:31,486 [salt.state       :1813][INFO    ][12455] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-08-09 05:35:31,487 [salt.state       :300 ][INFO    ][12455] /var/lib/maas/.setup_admin exists
2019-08-09 05:35:31,487 [salt.state       :1951][INFO    ][12455] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:35:31.487820 duration_in_ms=1.576
2019-08-09 05:35:31,488 [salt.state       :1780][INFO    ][12455] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:35:31.488899
2019-08-09 05:35:31,489 [salt.state       :1813][INFO    ][12455] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-09 05:35:31,490 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12455] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-09 05:35:32,778 [salt.state       :300 ][INFO    ][12455] {'pid': 12508, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-09 05:35:32,779 [salt.state       :1951][INFO    ][12455] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:35:32.779022 duration_in_ms=1290.124
2019-08-09 05:35:32,782 [salt.state       :1780][INFO    ][12455] Running state [maas_region_boot_source_resources_mirror] at time 05:35:32.782153
2019-08-09 05:35:32,782 [salt.state       :1813][INFO    ][12455] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-08-09 05:35:32,856 [salt.state       :300 ][INFO    ][12455] {'changes': {}}
2019-08-09 05:35:32,856 [salt.state       :1951][INFO    ][12455] Completed state [maas_region_boot_source_resources_mirror] at time 05:35:32.856926 duration_in_ms=74.772
2019-08-09 05:35:32,857 [salt.state       :1780][INFO    ][12455] Running state [maasng.boot_resources_import] at time 05:35:32.857649
2019-08-09 05:35:32,857 [salt.state       :1813][INFO    ][12455] Executing state module.run for [maasng.boot_resources_import]
2019-08-09 05:35:32,858 [salt.utils.decorators:613 ][WARNING ][12455] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-09 05:35:32,953 [salt.loaded.ext.module.maasng:1600][INFO    ][12455] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-08-09 05:35:38,019 [salt.loaded.ext.module.maasng:1600][INFO    ][12455] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-08-09 05:35:42,107 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command saltutil.find_job with jid 20190809053542094607
2019-08-09 05:35:42,128 [salt.minion      :1432][INFO    ][12550] Starting a new job with PID 12550
2019-08-09 05:35:42,152 [salt.minion      :1711][INFO    ][12550] Returning information for job: 20190809053542094607
2019-08-09 05:35:43,086 [salt.loaded.ext.module.maasng:1600][INFO    ][12455] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-08-09 05:35:48,207 [salt.state       :300 ][INFO    ][12455] {'ret': True}
2019-08-09 05:35:48,207 [salt.state       :1951][INFO    ][12455] Completed state [maasng.boot_resources_import] at time 05:35:48.207401 duration_in_ms=15349.751
2019-08-09 05:35:48,208 [salt.state       :1780][INFO    ][12455] Running state [maas_region_boot_sources_selection_xenial] at time 05:35:48.208357
2019-08-09 05:35:48,208 [salt.state       :1813][INFO    ][12455] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-08-09 05:35:48,468 [salt.state       :300 ][INFO    ][12455] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-08-09 05:35:48,468 [salt.state       :1951][INFO    ][12455] Completed state [maas_region_boot_sources_selection_xenial] at time 05:35:48.468718 duration_in_ms=260.361
2019-08-09 05:35:48,469 [salt.state       :1780][INFO    ][12455] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 05:35:48.469689
2019-08-09 05:35:48,470 [salt.state       :1813][INFO    ][12455] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-08-09 05:35:48,470 [salt.utils.decorators:613 ][WARNING ][12455] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-09 05:35:48,470 [salt.loaded.ext.module.maasng:1771][INFO    ][12455] boot-sources sync initiated for ALL Rack's
2019-08-09 05:35:49,510 [salt.state       :300 ][INFO    ][12455] {'ret': True}
2019-08-09 05:35:49,510 [salt.state       :1951][INFO    ][12455] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 05:35:49.510853 duration_in_ms=1041.163
2019-08-09 05:35:49,513 [salt.state       :1780][INFO    ][12455] Running state [maas.process_maas_config] at time 05:35:49.513009
2019-08-09 05:35:49,513 [salt.state       :1813][INFO    ][12455] Executing state module.run for [maas.process_maas_config]
2019-08-09 05:35:49,514 [salt.utils.decorators:613 ][WARNING ][12455] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-09 05:35:49,515 [salt.loaded.ext.module.maas:92  ][INFO    ][12455] maasconfig name=enable_http_proxy value=True
2019-08-09 05:35:49,578 [salt.loaded.ext.module.maas:92  ][INFO    ][12455] maasconfig name=upstream_dns value=8.8.8.8
2019-08-09 05:35:49,642 [salt.loaded.ext.module.maas:92  ][INFO    ][12455] maasconfig name=commissioning_distro_series value=xenial
2019-08-09 05:35:49,708 [salt.loaded.ext.module.maas:92  ][INFO    ][12455] maasconfig name=default_osystem value=ubuntu
2019-08-09 05:35:49,774 [salt.loaded.ext.module.maas:92  ][INFO    ][12455] maasconfig name=active_discovery_interval value=600
2019-08-09 05:35:53,552 [salt.loaded.ext.module.maas:92  ][INFO    ][12455] maasconfig name=dnssec_validation value=no
2019-08-09 05:35:53,602 [salt.loaded.ext.module.maas:92  ][INFO    ][12455] maasconfig name=maas_name value=mas01
2019-08-09 05:35:53,655 [salt.loaded.ext.module.maas:92  ][INFO    ][12455] maasconfig name=network_discovery value=enabled
2019-08-09 05:35:53,807 [salt.loaded.ext.module.maas:92  ][INFO    ][12455] maasconfig name=enable_third_party_drivers value=True
2019-08-09 05:35:53,859 [salt.loaded.ext.module.maas:92  ][INFO    ][12455] maasconfig name=default_storage_layout value=lvm
2019-08-09 05:35:53,922 [salt.loaded.ext.module.maas:92  ][INFO    ][12455] maasconfig name=ntp_external_only value=True
2019-08-09 05:35:53,981 [salt.loaded.ext.module.maas:92  ][INFO    ][12455] maasconfig name=disk_erase_with_secure_erase value=False
2019-08-09 05:35:54,055 [salt.loaded.ext.module.maas:92  ][INFO    ][12455] maasconfig name=default_distro_series value=xenial
2019-08-09 05:35:54,119 [salt.loaded.ext.module.maas:92  ][INFO    ][12455] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-08-09 05:35:54,245 [salt.state       :300 ][INFO    ][12455] {'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-08-09 05:35:54,245 [salt.state       :1951][INFO    ][12455] Completed state [maas.process_maas_config] at time 05:35:54.245404 duration_in_ms=4732.395
2019-08-09 05:35:54,246 [salt.state       :1780][INFO    ][12455] Running state [pxe_admin] at time 05:35:54.246300
2019-08-09 05:35:54,246 [salt.state       :1813][INFO    ][12455] Executing state maasng.fabric_present for [pxe_admin]
2019-08-09 05:35:54,310 [salt.loaded.ext.module.maasng:945 ][INFO    ][12455] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'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'name': u'untagged', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'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'name': u'untagged', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'7ad878', u'relay_vlan': None, u'external_dhcp': None, 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-08-09 05:35:54,394 [salt.loaded.ext.module.maasng:1008][WARNING ][12455] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-08-09 05:35:54,395 [salt.loaded.ext.module.maasng:1011][WARNING ][12455] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-08-09 05:35:54,466 [salt.state       :300 ][INFO    ][12455] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-08-09 05:35:54,466 [salt.state       :1951][INFO    ][12455] Completed state [pxe_admin] at time 05:35:54.466633 duration_in_ms=220.333
2019-08-09 05:35:54,467 [salt.state       :1780][INFO    ][12455] Running state [vlan 0] at time 05:35:54.467079
2019-08-09 05:35:54,467 [salt.state       :1813][INFO    ][12455] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-08-09 05:35:54,529 [salt.loaded.ext.module.maasng:945 ][INFO    ][12455] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'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'relay_vlan': None, u'primary_rack': u'7ad878', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-08-09 05:35:54,655 [salt.loaded.ext.module.maasng:945 ][INFO    ][12455] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'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'relay_vlan': None, u'primary_rack': u'7ad878', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-08-09 05:35:54,847 [salt.loaded.ext.module.maasng:945 ][INFO    ][12455] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'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'relay_vlan': None, u'primary_rack': u'7ad878', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-08-09 05:35:54,925 [salt.state       :300 ][INFO    ][12455] {'new': 'Vlan untagged was updated'}
2019-08-09 05:35:54,925 [salt.state       :1951][INFO    ][12455] Completed state [vlan 0] at time 05:35:54.925509 duration_in_ms=458.429
2019-08-09 05:35:54,926 [salt.state       :1780][INFO    ][12455] Running state [192.168.11.0/24] at time 05:35:54.926765
2019-08-09 05:35:54,927 [salt.state       :1813][INFO    ][12455] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-08-09 05:35:55,107 [salt.loaded.ext.module.maasng:945 ][INFO    ][12455] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-1', u'id': 1}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'7ad878', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'name': u'pxe_admin', u'id': 2}]
2019-08-09 05:35:55,108 [salt.loaded.ext.module.maasng:1235][WARNING ][12455] Ignoring parameter vlan:0
2019-08-09 05:35:55,191 [salt.state       :300 ][INFO    ][12455] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-08-09 05:35:55,191 [salt.state       :1951][INFO    ][12455] Completed state [192.168.11.0/24] at time 05:35:55.191549 duration_in_ms=264.784
2019-08-09 05:35:55,192 [salt.state       :1780][INFO    ][12455] Running state [maas_create_iprange_1] at time 05:35:55.192761
2019-08-09 05:35:55,193 [salt.state       :1813][INFO    ][12455] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-08-09 05:35:55,244 [salt.state       :300 ][INFO    ][12455] Iprange maas_create_iprange_1 already exist.
2019-08-09 05:35:55,244 [salt.state       :1951][INFO    ][12455] Completed state [maas_create_iprange_1] at time 05:35:55.244840 duration_in_ms=52.079
2019-08-09 05:35:55,245 [salt.state       :1780][INFO    ][12455] Running state [vlan 0] at time 05:35:55.245229
2019-08-09 05:35:55,245 [salt.state       :1813][INFO    ][12455] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-08-09 05:35:55,286 [salt.loaded.ext.module.maasng:945 ][INFO    ][12455] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-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'7ad878', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-08-09 05:35:55,394 [salt.loaded.ext.module.maasng:945 ][INFO    ][12455] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-1', u'id': 1}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'7ad878', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'name': u'pxe_admin', u'id': 2}]
2019-08-09 05:35:55,615 [salt.loaded.ext.module.maasng:945 ][INFO    ][12455] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'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'relay_vlan': None, u'primary_rack': u'7ad878', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-08-09 05:35:55,704 [salt.state       :300 ][INFO    ][12455] {'new': 'Vlan untagged was updated'}
2019-08-09 05:35:55,705 [salt.state       :1951][INFO    ][12455] Completed state [vlan 0] at time 05:35:55.704969 duration_in_ms=459.739
2019-08-09 05:35:55,705 [salt.state       :1780][INFO    ][12455] Running state [opnfv] at time 05:35:55.705902
2019-08-09 05:35:55,706 [salt.state       :1813][INFO    ][12455] Executing state maasng.sshkey_present for [opnfv]
2019-08-09 05:35:55,764 [salt.loaded.ext.module.maasng:1903][INFO    ][12455] [{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-08-09 05:35:55,765 [salt.state       :300 ][INFO    ][12455] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-08-09 05:35:55,765 [salt.state       :1951][INFO    ][12455] Completed state [opnfv] at time 05:35:55.765779 duration_in_ms=59.876
2019-08-09 05:35:55,766 [salt.state       :1780][INFO    ][12455] Running state [maas.process_tags] at time 05:35:55.766652
2019-08-09 05:35:55,767 [salt.state       :1813][INFO    ][12455] Executing state module.run for [maas.process_tags]
2019-08-09 05:35:55,767 [salt.utils.decorators:613 ][WARNING ][12455] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-09 05:35:55,812 [salt.loaded.ext.module.maas:92  ][INFO    ][12455] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-08-09 05:35:55,871 [salt.state       :300 ][INFO    ][12455] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-08-09 05:35:55,872 [salt.state       :1951][INFO    ][12455] Completed state [maas.process_tags] at time 05:35:55.872351 duration_in_ms=105.699
2019-08-09 05:35:55,876 [salt.minion      :1711][INFO    ][12455] Returning information for job: 20190809053527060854
2019-08-09 05:35:56,435 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command state.apply with jid 20190809053556426759
2019-08-09 05:35:56,454 [salt.minion      :1432][INFO    ][12934] Starting a new job with PID 12934
2019-08-09 05:36:00,194 [salt.state       :915 ][INFO    ][12934] Loading fresh modules for state activity
2019-08-09 05:36:00,290 [salt.state       :1780][INFO    ][12934] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:36:00.290542
2019-08-09 05:36:00,291 [salt.state       :1813][INFO    ][12934] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-09 05:36:00,293 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12934] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-09 05:36:01,726 [salt.state       :300 ][INFO    ][12934] {'pid': 12957, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-09 05:36:01,726 [salt.state       :1951][INFO    ][12934] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:36:01.726508 duration_in_ms=1435.957
2019-08-09 05:36:01,727 [salt.state       :1780][INFO    ][12934] Running state [maas.process_machines] at time 05:36:01.727625
2019-08-09 05:36:01,727 [salt.state       :1813][INFO    ][12934] Executing state module.run for [maas.process_machines]
2019-08-09 05:36:01,728 [salt.utils.decorators:613 ][WARNING ][12934] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-09 05:36:02,416 [salt.loaded.ext.module.maas:412 ][WARNING ][12934] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-09 05:36:02,417 [salt.loaded.ext.module.maas:92  ][INFO    ][12934] 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=x7g4x4 architecture=amd64/generic power_parameters_power_user=admin
2019-08-09 05:36:03,453 [salt.loaded.ext.module.maas:412 ][WARNING ][12934] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-09 05:36:03,454 [salt.loaded.ext.module.maas:92  ][INFO    ][12934] 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=xgw4h8 architecture=amd64/generic power_parameters_power_user=admin
2019-08-09 05:36:04,665 [salt.loaded.ext.module.maas:412 ][WARNING ][12934] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-09 05:36:04,666 [salt.loaded.ext.module.maas:92  ][INFO    ][12934] 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=mpwkyw architecture=amd64/generic power_parameters_power_user=admin
2019-08-09 05:36:05,890 [salt.loaded.ext.module.maas:412 ][WARNING ][12934] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-09 05:36:05,891 [salt.loaded.ext.module.maas:92  ][INFO    ][12934] 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=b4r7dk architecture=amd64/generic power_parameters_power_user=admin
2019-08-09 05:36:07,010 [salt.loaded.ext.module.maas:412 ][WARNING ][12934] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-09 05:36:07,011 [salt.loaded.ext.module.maas:92  ][INFO    ][12934] 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=4a4t64 architecture=amd64/generic power_parameters_power_user=admin
2019-08-09 05:36:08,418 [salt.state       :300 ][INFO    ][12934] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-08-09 05:36:08,419 [salt.state       :1951][INFO    ][12934] Completed state [maas.process_machines] at time 05:36:08.419061 duration_in_ms=6691.434
2019-08-09 05:36:08,422 [salt.minion      :1711][INFO    ][12934] Returning information for job: 20190809053556426759
2019-08-09 05:36:41,601 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command state.apply with jid 20190809053641587918
2019-08-09 05:36:41,624 [salt.minion      :1432][INFO    ][13203] Starting a new job with PID 13203
2019-08-09 05:36:45,215 [salt.state       :915 ][INFO    ][13203] Loading fresh modules for state activity
2019-08-09 05:36:45,266 [salt.state       :1780][INFO    ][13203] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:36:45.266181
2019-08-09 05:36:45,266 [salt.state       :1813][INFO    ][13203] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-09 05:36:45,267 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13203] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-09 05:36:46,718 [salt.state       :300 ][INFO    ][13203] {'pid': 13210, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-09 05:36:46,719 [salt.state       :1951][INFO    ][13203] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:36:46.719282 duration_in_ms=1453.101
2019-08-09 05:36:46,720 [salt.state       :1780][INFO    ][13203] Running state [maas.wait_for_machine_status] at time 05:36:46.720809
2019-08-09 05:36:46,721 [salt.state       :1813][INFO    ][13203] Executing state module.run for [maas.wait_for_machine_status]
2019-08-09 05:36:46,721 [salt.utils.decorators:613 ][WARNING ][13203] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-09 05:36:49,801 [salt.loaded.ext.module.maas:993 ][INFO    ][13203] Machine 4a4t64 mark broken
2019-08-09 05:36:50,425 [salt.loaded.ext.module.maas:996 ][INFO    ][13203] Machine 4a4t64 mark fixed
2019-08-09 05:36:51,580 [salt.loaded.ext.module.maas:684 ][INFO    ][13203] deploymachines hwe_kernel=hwe-16.04 system_id=4a4t64 distro_series=xenial
2019-08-09 05:36:54,116 [salt.loaded.ext.module.maas:1023][INFO    ][13203] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1492.6098671s left)
2019-08-09 05:36:56,623 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command saltutil.find_job with jid 20190809053656610324
2019-08-09 05:36:56,646 [salt.minion      :1432][INFO    ][13311] Starting a new job with PID 13311
2019-08-09 05:36:56,669 [salt.minion      :1711][INFO    ][13311] Returning information for job: 20190809053656610324
2019-08-09 05:37:26,677 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command saltutil.find_job with jid 20190809053726667246
2019-08-09 05:37:26,700 [salt.minion      :1432][INFO    ][13339] Starting a new job with PID 13339
2019-08-09 05:37:26,722 [salt.minion      :1711][INFO    ][13339] Returning information for job: 20190809053726667246
2019-08-09 05:37:27,575 [salt.loaded.ext.module.maas:1023][INFO    ][13203] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1459.15088987s left)
2019-08-09 05:37:56,778 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command saltutil.find_job with jid 20190809053756728528
2019-08-09 05:37:56,799 [salt.minion      :1432][INFO    ][13387] Starting a new job with PID 13387
2019-08-09 05:37:56,825 [salt.minion      :1711][INFO    ][13387] Returning information for job: 20190809053756728528
2019-08-09 05:38:00,795 [salt.loaded.ext.module.maas:1023][INFO    ][13203] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1425.93091393s left)
2019-08-09 05:38:26,825 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command saltutil.find_job with jid 20190809053826810311
2019-08-09 05:38:26,847 [salt.minion      :1432][INFO    ][13407] Starting a new job with PID 13407
2019-08-09 05:38:26,873 [salt.minion      :1711][INFO    ][13407] Returning information for job: 20190809053826810311
2019-08-09 05:38:33,840 [salt.loaded.ext.module.maas:1023][INFO    ][13203] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1392.88607907s left)
2019-08-09 05:38:56,857 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command saltutil.find_job with jid 20190809053856845002
2019-08-09 05:38:56,880 [salt.minion      :1432][INFO    ][13556] Starting a new job with PID 13556
2019-08-09 05:38:56,908 [salt.minion      :1711][INFO    ][13556] Returning information for job: 20190809053856845002
2019-08-09 05:39:06,725 [salt.loaded.ext.module.maas:1023][INFO    ][13203] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1360.00042605s left)
2019-08-09 05:39:26,914 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command saltutil.find_job with jid 20190809053926901646
2019-08-09 05:39:26,936 [salt.minion      :1432][INFO    ][13578] Starting a new job with PID 13578
2019-08-09 05:39:26,962 [salt.minion      :1711][INFO    ][13578] Returning information for job: 20190809053926901646
2019-08-09 05:39:40,481 [salt.loaded.ext.module.maas:1023][INFO    ][13203] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1326.24415708s left)
2019-08-09 05:39:56,970 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command saltutil.find_job with jid 20190809053956957108
2019-08-09 05:39:56,992 [salt.minion      :1432][INFO    ][13722] Starting a new job with PID 13722
2019-08-09 05:39:57,020 [salt.minion      :1711][INFO    ][13722] Returning information for job: 20190809053956957108
2019-08-09 05:40:13,899 [salt.loaded.ext.module.maas:1023][INFO    ][13203] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1292.82644701s left)
2019-08-09 05:40:27,038 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command saltutil.find_job with jid 20190809054027025137
2019-08-09 05:40:27,060 [salt.minion      :1432][INFO    ][13744] Starting a new job with PID 13744
2019-08-09 05:40:27,086 [salt.minion      :1711][INFO    ][13744] Returning information for job: 20190809054027025137
2019-08-09 05:40:47,739 [salt.loaded.ext.module.maas:1023][INFO    ][13203] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1258.98680592s left)
2019-08-09 05:40:57,108 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command saltutil.find_job with jid 20190809054057095800
2019-08-09 05:40:57,131 [salt.minion      :1432][INFO    ][13828] Starting a new job with PID 13828
2019-08-09 05:40:57,157 [salt.minion      :1711][INFO    ][13828] Returning information for job: 20190809054057095800
2019-08-09 05:41:21,008 [salt.loaded.ext.module.maas:1023][INFO    ][13203] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1225.71724296s left)
2019-08-09 05:41:27,178 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command saltutil.find_job with jid 20190809054127165286
2019-08-09 05:41:27,202 [salt.minion      :1432][INFO    ][13848] Starting a new job with PID 13848
2019-08-09 05:41:27,228 [salt.minion      :1711][INFO    ][13848] Returning information for job: 20190809054127165286
2019-08-09 05:41:54,649 [salt.loaded.ext.module.maas:1023][INFO    ][13203] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1192.07683706s left)
2019-08-09 05:41:57,249 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command saltutil.find_job with jid 20190809054157238044
2019-08-09 05:41:57,268 [salt.minion      :1432][INFO    ][13984] Starting a new job with PID 13984
2019-08-09 05:41:57,294 [salt.minion      :1711][INFO    ][13984] Returning information for job: 20190809054157238044
2019-08-09 05:42:27,320 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command saltutil.find_job with jid 20190809054227307288
2019-08-09 05:42:27,343 [salt.minion      :1432][INFO    ][14009] Starting a new job with PID 14009
2019-08-09 05:42:27,367 [salt.minion      :1711][INFO    ][14009] Returning information for job: 20190809054227307288
2019-08-09 05:42:28,082 [salt.loaded.ext.module.maas:1023][INFO    ][13203] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1158.644135s left)
2019-08-09 05:42:57,401 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command saltutil.find_job with jid 20190809054257388418
2019-08-09 05:42:57,424 [salt.minion      :1432][INFO    ][14129] Starting a new job with PID 14129
2019-08-09 05:42:57,450 [salt.minion      :1711][INFO    ][14129] Returning information for job: 20190809054257388418
2019-08-09 05:43:00,918 [salt.loaded.ext.module.maas:1023][INFO    ][13203] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1125.80723596s left)
2019-08-09 05:43:27,487 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command saltutil.find_job with jid 20190809054327473824
2019-08-09 05:43:27,510 [salt.minion      :1432][INFO    ][14149] Starting a new job with PID 14149
2019-08-09 05:43:27,536 [salt.minion      :1711][INFO    ][14149] Returning information for job: 20190809054327473824
2019-08-09 05:43:34,470 [salt.loaded.ext.module.maas:1023][INFO    ][13203] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1092.25564003s left)
2019-08-09 05:43:57,582 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command saltutil.find_job with jid 20190809054357569527
2019-08-09 05:43:57,605 [salt.minion      :1432][INFO    ][14243] Starting a new job with PID 14243
2019-08-09 05:43:57,633 [salt.minion      :1711][INFO    ][14243] Returning information for job: 20190809054357569527
2019-08-09 05:44:07,871 [salt.loaded.ext.module.maas:1023][INFO    ][13203] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1058.854496s left)
2019-08-09 05:44:27,814 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command saltutil.find_job with jid 20190809054427801206
2019-08-09 05:44:27,837 [salt.minion      :1432][INFO    ][14295] Starting a new job with PID 14295
2019-08-09 05:44:27,864 [salt.minion      :1711][INFO    ][14295] Returning information for job: 20190809054427801206
2019-08-09 05:44:41,584 [salt.state       :300 ][INFO    ][13203] {'ret': True}
2019-08-09 05:44:41,585 [salt.state       :1951][INFO    ][13203] Completed state [maas.wait_for_machine_status] at time 05:44:41.585384 duration_in_ms=474864.572
2019-08-09 05:44:41,589 [salt.minion      :1711][INFO    ][13203] Returning information for job: 20190809053641587918
2019-08-09 05:44:42,229 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command state.apply with jid 20190809054442218026
2019-08-09 05:44:42,249 [salt.minion      :1432][INFO    ][14332] Starting a new job with PID 14332
2019-08-09 05:44:46,000 [salt.state       :915 ][INFO    ][14332] Loading fresh modules for state activity
2019-08-09 05:44:46,142 [salt.state       :1780][INFO    ][14332] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:44:46.142879
2019-08-09 05:44:46,143 [salt.state       :1813][INFO    ][14332] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-09 05:44:46,144 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14332] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-09 05:44:47,610 [salt.state       :300 ][INFO    ][14332] {'pid': 14339, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-09 05:44:47,611 [salt.state       :1951][INFO    ][14332] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:44:47.611041 duration_in_ms=1468.162
2019-08-09 05:44:47,612 [salt.state       :1780][INFO    ][14332] Running state [maas_machines_storage_cmp002_lvm] at time 05:44:47.612638
2019-08-09 05:44:47,612 [salt.state       :1813][INFO    ][14332] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-08-09 05:44:48,217 [salt.state       :300 ][INFO    ][14332] Machine cmp002 is not in Ready state.
2019-08-09 05:44:48,217 [salt.state       :1951][INFO    ][14332] Completed state [maas_machines_storage_cmp002_lvm] at time 05:44:48.217439 duration_in_ms=604.8
2019-08-09 05:44:48,217 [salt.state       :1780][INFO    ][14332] Running state [maas_machines_storage_cmp001_lvm] at time 05:44:48.217726
2019-08-09 05:44:48,217 [salt.state       :1813][INFO    ][14332] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-08-09 05:44:48,773 [salt.state       :300 ][INFO    ][14332] Machine cmp001 is not in Ready state.
2019-08-09 05:44:48,773 [salt.state       :1951][INFO    ][14332] Completed state [maas_machines_storage_cmp001_lvm] at time 05:44:48.773647 duration_in_ms=555.92
2019-08-09 05:44:48,775 [salt.minion      :1711][INFO    ][14332] Returning information for job: 20190809054442218026
2019-08-09 05:44:49,312 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command state.apply with jid 20190809054449297221
2019-08-09 05:44:49,332 [salt.minion      :1432][INFO    ][14496] Starting a new job with PID 14496
2019-08-09 05:44:50,154 [salt.state       :915 ][INFO    ][14496] Loading fresh modules for state activity
2019-08-09 05:44:50,242 [salt.state       :1780][INFO    ][14496] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:44:50.242173
2019-08-09 05:44:50,242 [salt.state       :1813][INFO    ][14496] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-09 05:44:50,244 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14496] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-09 05:44:51,593 [salt.state       :300 ][INFO    ][14496] {'pid': 14505, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-09 05:44:51,594 [salt.state       :1951][INFO    ][14496] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:44:51.594150 duration_in_ms=1351.976
2019-08-09 05:44:51,596 [salt.state       :1780][INFO    ][14496] Running state [maas.deploy_machines] at time 05:44:51.596671
2019-08-09 05:44:51,597 [salt.state       :1813][INFO    ][14496] Executing state module.run for [maas.deploy_machines]
2019-08-09 05:44:51,598 [salt.utils.decorators:613 ][WARNING ][14496] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-09 05:44:52,348 [salt.state       :300 ][INFO    ][14496] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-08-09 05:44:52,349 [salt.state       :1951][INFO    ][14496] Completed state [maas.deploy_machines] at time 05:44:52.349375 duration_in_ms=752.703
2019-08-09 05:44:52,353 [salt.minion      :1711][INFO    ][14496] Returning information for job: 20190809054449297221
2019-08-09 05:44:52,929 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command state.apply with jid 20190809054452917532
2019-08-09 05:44:52,953 [salt.minion      :1432][INFO    ][14514] Starting a new job with PID 14514
2019-08-09 05:44:53,693 [salt.state       :915 ][INFO    ][14514] Loading fresh modules for state activity
2019-08-09 05:44:53,786 [salt.state       :1780][INFO    ][14514] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:44:53.786247
2019-08-09 05:44:53,786 [salt.state       :1813][INFO    ][14514] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-09 05:44:53,789 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14514] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-09 05:44:55,233 [salt.state       :300 ][INFO    ][14514] {'pid': 14522, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-09 05:44:55,234 [salt.state       :1951][INFO    ][14514] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:44:55.234358 duration_in_ms=1448.111
2019-08-09 05:44:55,237 [salt.state       :1780][INFO    ][14514] Running state [maas.wait_for_machine_status] at time 05:44:55.237678
2019-08-09 05:44:55,238 [salt.state       :1813][INFO    ][14514] Executing state module.run for [maas.wait_for_machine_status]
2019-08-09 05:44:55,238 [salt.utils.decorators:613 ][WARNING ][14514] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-09 05:44:58,867 [salt.state       :300 ][INFO    ][14514] {'ret': True}
2019-08-09 05:44:58,867 [salt.state       :1951][INFO    ][14514] Completed state [maas.wait_for_machine_status] at time 05:44:58.867489 duration_in_ms=3629.797
2019-08-09 05:44:58,871 [salt.minion      :1711][INFO    ][14514] Returning information for job: 20190809054452917532
2019-08-09 06:15:00,649 [salt.utils.schedule:1377][INFO    ][6407] Running scheduled job: __mine_interval
2019-08-09 07:15:00,649 [salt.utils.schedule:1377][INFO    ][6407] Running scheduled job: __mine_interval
2019-08-09 07:23:59,505 [salt.minion      :1308][INFO    ][6407] User sudo_ubuntu Executing command cp.push_dir with jid 20190809072359493234
2019-08-09 07:23:59,526 [salt.minion      :1432][INFO    ][21507] Starting a new job with PID 21507
