2019-05-24 08:21:10,885 [salt.utils.decorators:613 ][WARNING ][2128] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-24 08:21:11,875 [salt.utils.decorators:613 ][WARNING ][2128] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-24 08:21:14,910 [salt.loaded.int.states.file:2298][WARNING ][2442] 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-05-24 08:21:42,780 [salt.state       :2022][WARNING ][2887] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-24 08:21:46,017 [salt.utils.decorators:613 ][WARNING ][2887] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-24 08:21:59,816 [salt.utils.decorators:613 ][WARNING ][2887] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-24 08:22:26,890 [salt.utils.decorators:613 ][WARNING ][2887] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-24 08:22:27,784 [salt.utils.decorators:613 ][WARNING ][2887] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-24 08:22:30,140 [salt.loaded.ext.module.maasng:1008][WARNING ][2887] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-05-24 08:22:30,140 [salt.loaded.ext.module.maasng:1011][WARNING ][2887] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-05-24 08:22:30,841 [salt.loaded.ext.module.maasng:1235][WARNING ][2887] Ignoring parameter vlan:0
2019-05-24 08:22:31,722 [salt.utils.decorators:613 ][WARNING ][2887] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-24 08:22:41,284 [salt.utils.decorators:613 ][WARNING ][5420] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-24 08:22:41,349 [salt.loaded.ext.module.maas:412 ][WARNING ][5420] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-24 08:22:42,828 [salt.loaded.ext.module.maas:412 ][WARNING ][5420] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-24 08:22:43,964 [salt.loaded.ext.module.maas:412 ][WARNING ][5420] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-24 08:22:45,288 [salt.loaded.ext.module.maas:412 ][WARNING ][5420] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-24 08:22:46,534 [salt.loaded.ext.module.maas:412 ][WARNING ][5420] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-24 08:22:51,927 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5976] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-24 08:22:51,969 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5976] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-05-24 08:22:52,018 [salt.utils.parsers:1051][WARNING ][382] Minion received a SIGTERM. Exiting.
2019-05-24 08:22:53,205 [salt.cli.daemons :293 ][INFO    ][6035] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-05-24 08:22:53,405 [salt.cli.daemons :82  ][INFO    ][6035] Starting up the Salt Minion
2019-05-24 08:22:53,409 [salt.utils.event :1017][INFO    ][6035] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-05-24 08:22:54,805 [salt.minion      :976 ][INFO    ][6035] Creating minion process manager
2019-05-24 08:22:56,829 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][6035] Executing command ['date', '+%z'] in directory '/root'
2019-05-24 08:22:56,862 [salt.utils.schedule:568 ][INFO    ][6035] Updating job settings for scheduled job: __mine_interval
2019-05-24 08:22:56,866 [salt.minion      :1108][INFO    ][6035] Added mine.update to scheduler
2019-05-24 08:22:56,874 [salt.minion      :1975][INFO    ][6035] Minion is starting as user 'root'
2019-05-24 08:22:56,891 [salt.minion      :2336][INFO    ][6035] Minion is ready to receive requests!
2019-05-24 08:23:19,220 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command state.apply with jid 20190524082319207900
2019-05-24 08:23:19,251 [salt.minion      :1432][INFO    ][6125] Starting a new job with PID 6125
2019-05-24 08:23:25,182 [salt.state       :915 ][INFO    ][6125] Loading fresh modules for state activity
2019-05-24 08:23:25,248 [salt.fileclient  :1219][INFO    ][6125] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-05-24 08:23:25,313 [salt.state       :1780][INFO    ][6125] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:23:25.313608
2019-05-24 08:23:25,314 [salt.state       :1813][INFO    ][6125] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-24 08:23:25,315 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6125] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-24 08:23:27,260 [salt.state       :300 ][INFO    ][6125] {'pid': 6132, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-24 08:23:27,261 [salt.state       :1951][INFO    ][6125] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:23:27.261049 duration_in_ms=1947.44
2019-05-24 08:23:27,265 [salt.state       :1780][INFO    ][6125] Running state [maas.wait_for_machine_status] at time 08:23:27.265600
2019-05-24 08:23:27,266 [salt.state       :1813][INFO    ][6125] Executing state module.run for [maas.wait_for_machine_status]
2019-05-24 08:23:27,267 [salt.utils.decorators:613 ][WARNING ][6125] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-24 08:23:28,141 [salt.loaded.ext.module.maas:1023][INFO    ][6125] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.139709s left)
2019-05-24 08:23:34,260 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524082334249305
2019-05-24 08:23:34,285 [salt.minion      :1432][INFO    ][6155] Starting a new job with PID 6155
2019-05-24 08:23:34,319 [salt.minion      :1711][INFO    ][6155] Returning information for job: 20190524082334249305
2019-05-24 08:23:59,040 [salt.loaded.ext.module.maas:1023][INFO    ][6125] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.24032187s left)
2019-05-24 08:24:04,342 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524082404329452
2019-05-24 08:24:04,369 [salt.minion      :1432][INFO    ][6201] Starting a new job with PID 6201
2019-05-24 08:24:04,405 [salt.minion      :1711][INFO    ][6201] Returning information for job: 20190524082404329452
2019-05-24 08:24:30,016 [salt.loaded.ext.module.maas:1023][INFO    ][6125] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.264256s left)
2019-05-24 08:24:34,436 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524082434425551
2019-05-24 08:24:34,468 [salt.minion      :1432][INFO    ][6230] Starting a new job with PID 6230
2019-05-24 08:24:34,504 [salt.minion      :1711][INFO    ][6230] Returning information for job: 20190524082434425551
2019-05-24 08:25:00,897 [salt.loaded.ext.module.maas:1023][INFO    ][6125] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.38375878s left)
2019-05-24 08:25:04,519 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524082504504187
2019-05-24 08:25:04,546 [salt.minion      :1432][INFO    ][6280] Starting a new job with PID 6280
2019-05-24 08:25:04,578 [salt.minion      :1711][INFO    ][6280] Returning information for job: 20190524082504504187
2019-05-24 08:25:32,073 [salt.loaded.ext.module.maas:1023][INFO    ][6125] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.20734286s left)
2019-05-24 08:25:34,608 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524082534594277
2019-05-24 08:25:34,637 [salt.minion      :1432][INFO    ][6342] Starting a new job with PID 6342
2019-05-24 08:25:34,667 [salt.minion      :1711][INFO    ][6342] Returning information for job: 20190524082534594277
2019-05-24 08:26:03,181 [salt.loaded.ext.module.maas:1023][INFO    ][6125] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.09944797s left)
2019-05-24 08:26:04,723 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524082604706951
2019-05-24 08:26:04,745 [salt.minion      :1432][INFO    ][6496] Starting a new job with PID 6496
2019-05-24 08:26:04,774 [salt.minion      :1711][INFO    ][6496] Returning information for job: 20190524082604706951
2019-05-24 08:26:34,344 [salt.loaded.ext.module.maas:1023][INFO    ][6125] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1312.93625593s left)
2019-05-24 08:26:34,823 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524082634809421
2019-05-24 08:26:34,848 [salt.minion      :1432][INFO    ][6577] Starting a new job with PID 6577
2019-05-24 08:26:34,876 [salt.minion      :1711][INFO    ][6577] Returning information for job: 20190524082634809421
2019-05-24 08:27:04,950 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524082704938358
2019-05-24 08:27:04,973 [salt.minion      :1432][INFO    ][6883] Starting a new job with PID 6883
2019-05-24 08:27:05,002 [salt.minion      :1711][INFO    ][6883] Returning information for job: 20190524082704938358
2019-05-24 08:27:05,716 [salt.loaded.ext.module.maas:1023][INFO    ][6125] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1281.56466389s left)
2019-05-24 08:27:35,050 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524082735036972
2019-05-24 08:27:35,075 [salt.minion      :1432][INFO    ][7005] Starting a new job with PID 7005
2019-05-24 08:27:35,110 [salt.minion      :1711][INFO    ][7005] Returning information for job: 20190524082735036972
2019-05-24 08:27:37,676 [salt.loaded.ext.module.maas:1023][INFO    ][6125] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1249.60474396s left)
2019-05-24 08:28:05,228 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524082805211368
2019-05-24 08:28:05,255 [salt.minion      :1432][INFO    ][7342] Starting a new job with PID 7342
2019-05-24 08:28:05,286 [salt.minion      :1711][INFO    ][7342] Returning information for job: 20190524082805211368
2019-05-24 08:28:09,888 [salt.loaded.ext.module.maas:1023][INFO    ][6125] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1217.39200997s left)
2019-05-24 08:28:35,345 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524082835328056
2019-05-24 08:28:35,374 [salt.minion      :1432][INFO    ][7506] Starting a new job with PID 7506
2019-05-24 08:28:35,405 [salt.minion      :1711][INFO    ][7506] Returning information for job: 20190524082835328056
2019-05-24 08:28:42,048 [salt.loaded.ext.module.maas:1023][INFO    ][6125] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1185.2326479s left)
2019-05-24 08:29:05,488 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524082905475294
2019-05-24 08:29:05,513 [salt.minion      :1432][INFO    ][7680] Starting a new job with PID 7680
2019-05-24 08:29:05,547 [salt.minion      :1711][INFO    ][7680] Returning information for job: 20190524082905475294
2019-05-24 08:29:14,187 [salt.loaded.ext.module.maas:1023][INFO    ][6125] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1153.09327483s left)
2019-05-24 08:29:35,631 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524082935617692
2019-05-24 08:29:35,660 [salt.minion      :1432][INFO    ][7829] Starting a new job with PID 7829
2019-05-24 08:29:35,689 [salt.minion      :1711][INFO    ][7829] Returning information for job: 20190524082935617692
2019-05-24 08:29:47,484 [salt.state       :300 ][INFO    ][6125] {'ret': True}
2019-05-24 08:29:47,485 [salt.state       :1951][INFO    ][6125] Completed state [maas.wait_for_machine_status] at time 08:29:47.485062 duration_in_ms=380219.461
2019-05-24 08:29:47,492 [salt.minion      :1711][INFO    ][6125] Returning information for job: 20190524082319207900
2019-05-24 08:29:48,260 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command state.apply with jid 20190524082948250514
2019-05-24 08:29:48,286 [salt.minion      :1432][INFO    ][7922] Starting a new job with PID 7922
2019-05-24 08:29:54,295 [salt.state       :915 ][INFO    ][7922] Loading fresh modules for state activity
2019-05-24 08:29:54,360 [salt.fileclient  :1219][INFO    ][7922] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-05-24 08:29:54,464 [salt.state       :1780][INFO    ][7922] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:29:54.464429
2019-05-24 08:29:54,464 [salt.state       :1813][INFO    ][7922] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-24 08:29:54,466 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7922] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-24 08:29:56,418 [salt.state       :300 ][INFO    ][7922] {'pid': 7943, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-24 08:29:56,419 [salt.state       :1951][INFO    ][7922] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:29:56.419257 duration_in_ms=1954.829
2019-05-24 08:29:56,422 [salt.state       :1780][INFO    ][7922] Running state [maas_machines_storage_cmp002_lvm] at time 08:29:56.422759
2019-05-24 08:29:56,423 [salt.state       :1813][INFO    ][7922] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-24 08:29:57,764 [salt.loaded.ext.module.maasng:610 ][INFO    ][7922] 8ekr6p
2019-05-24 08:29:57,764 [salt.loaded.ext.module.maasng:626 ][INFO    ][7922] sda
2019-05-24 08:29:58,448 [salt.loaded.ext.module.maasng:361 ][INFO    ][7922] 8ekr6p
2019-05-24 08:29:58,547 [salt.loaded.ext.module.maasng:367 ][INFO    ][7922] [{u'size': 800109715456, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'a482e047-d73a-427b-bf60-cd0187b4e078', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'8ekr6p', u'filesystem': {u'label': None, u'uuid': u'db895287-ff73-43df-8c18-d15b5a55ac58', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/8ekr6p/blockdevices/1/partition/1', u'type': u'partition', u'id': 1, u'device_id': 1}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'8ekr6p', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/8ekr6p/blockdevices/1/'}, {u'size': 800097042432, u'uuid': u'415ab1a8-ff68-45cf-985f-99a3457ccac5', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'label': u'root', u'uuid': u'b9da120c-619c-450f-863e-2262b653eeae', 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'8ekr6p', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 800097042432, u'id': 3, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/8ekr6p/blockdevices/3/'}]
2019-05-24 08:29:58,547 [salt.loaded.ext.module.maasng:632 ][INFO    ][7922] vgroot
2019-05-24 08:29:58,548 [salt.loaded.ext.module.maasng:635 ][INFO    ][7922] lvroot
2019-05-24 08:29:58,548 [salt.loaded.ext.module.maasng:639 ][INFO    ][7922] 107374182400
2019-05-24 08:29:59,161 [salt.loaded.ext.module.maasng:645 ][INFO    ][7922] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'testing_status_name': u'Passed', u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/8ekr6p/blockdevices/1/', u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'8ekr6p', u'block_size': 4096, u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456, u'used_size': 800106479616, u'id': 1, u'partitions': [{u'uuid': u'd93b0a5d-500f-42f1-ba14-e88f86523a11', u'resource_uri': u'/MAAS/api/2.0/nodes/8ekr6p/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'8ekr6p', u'filesystem': {u'label': None, u'uuid': u'3e360e34-839f-4809-9d30-d5a942295b7b', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'device_id': 1}]}, u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/8ekr6p/', u'current_commissioning_result_id': 2, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'8ekr6p', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'blockdevice_set': [{u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/8ekr6p/blockdevices/1/', u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'8ekr6p', u'block_size': 4096, u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456, u'used_size': 800106479616, u'id': 1, u'partitions': [{u'uuid': u'd93b0a5d-500f-42f1-ba14-e88f86523a11', u'resource_uri': u'/MAAS/api/2.0/nodes/8ekr6p/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'8ekr6p', u'filesystem': {u'label': None, u'uuid': u'3e360e34-839f-4809-9d30-d5a942295b7b', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'device_id': 1}]}, {u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/8ekr6p/blockdevices/11/', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'filesystem': {u'label': u'root', u'uuid': u'c0d9c4cf-1c72-426a-8549-f315edebbf63', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'uuid': u'ee153118-8946-4216-aa66-5df0b57874a9', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'8ekr6p', u'block_size': 4096, u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'size': 107374182400, u'used_size': 107374182400, u'id': 11, u'partitions': []}], u'status': 4, u'bcaches': [], u'cpu_count': 40, u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'8ekr6p', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'76dn3y', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 18}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'76dn3y', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 4, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'76dn3y', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'8ekr6p', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/8ekr6p/interfaces/4/'}, u'current_testing_result_id': 3, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'storage_test_status': 2, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/8ekr6p/blockdevices/1/', u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'8ekr6p', u'block_size': 4096, u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456, u'used_size': 800106479616, u'id': 1, u'partitions': [{u'uuid': u'd93b0a5d-500f-42f1-ba14-e88f86523a11', u'resource_uri': u'/MAAS/api/2.0/nodes/8ekr6p/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'8ekr6p', u'filesystem': {u'label': None, u'uuid': u'3e360e34-839f-4809-9d30-d5a942295b7b', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'device_id': 1}]}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'node_type': 0, u'virtualblockdevice_set': [{u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/8ekr6p/blockdevices/11/', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'filesystem': {u'label': u'root', u'uuid': u'c0d9c4cf-1c72-426a-8549-f315edebbf63', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'uuid': u'ee153118-8946-4216-aa66-5df0b57874a9', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'8ekr6p', u'block_size': 4096, 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'size': 107374182400, u'used_size': 107374182400, u'id': 11, u'partitions': []}], u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'commissioning_status_name': u'Passed', u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'76dn3y', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 18}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'76dn3y', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 4, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'76dn3y', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'8ekr6p', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/8ekr6p/interfaces/4/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'id': 13, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:07:51', u'system_id': u'8ekr6p', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/8ekr6p/interfaces/13/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'id': 15, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:07:50', u'system_id': u'8ekr6p', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/8ekr6p/interfaces/15/'}, {u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 19}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 14, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:12:49', u'system_id': u'8ekr6p', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/8ekr6p/interfaces/14/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'76dn3y', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'mode': u'link_up', u'id': 20}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'76dn3y', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 11, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'76dn3y', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.40'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:1c', u'system_id': u'8ekr6p', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/8ekr6p/interfaces/11/'}, {u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 21}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 12, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:12:48', u'system_id': u'8ekr6p', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/8ekr6p/interfaces/12/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-05-24 08:29:59,165 [salt.state       :300 ][INFO    ][7922] {'new': {'storage_layout': 'lvm'}}
2019-05-24 08:29:59,166 [salt.state       :1951][INFO    ][7922] Completed state [maas_machines_storage_cmp002_lvm] at time 08:29:59.166376 duration_in_ms=2743.616
2019-05-24 08:29:59,167 [salt.state       :1780][INFO    ][7922] Running state [maas_machines_storage_cmp001_lvm] at time 08:29:59.167098
2019-05-24 08:29:59,167 [salt.state       :1813][INFO    ][7922] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-24 08:30:00,311 [salt.loaded.ext.module.maasng:610 ][INFO    ][7922] 74nw77
2019-05-24 08:30:00,312 [salt.loaded.ext.module.maasng:626 ][INFO    ][7922] sda
2019-05-24 08:30:00,864 [salt.loaded.ext.module.maasng:361 ][INFO    ][7922] 74nw77
2019-05-24 08:30:00,958 [salt.loaded.ext.module.maasng:367 ][INFO    ][7922] [{u'size': 800109715456, u'partition_table_type': u'MBR', u'uuid': None, u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'9c7569be-0ff3-4c73-ad47-f0f7a363a698', u'resource_uri': u'/MAAS/api/2.0/nodes/74nw77/blockdevices/2/partition/2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'74nw77', u'filesystem': {u'uuid': u'd0748922-b39b-4906-9bc4-0e2fc68d3f03', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 2, u'device_id': 2}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'74nw77', u'resource_uri': u'/MAAS/api/2.0/nodes/74nw77/blockdevices/2/', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'name': u'sda'}, {u'size': 800097042432, u'partition_table_type': None, u'uuid': u'005f24b6-d9cc-4da3-b6fd-914ddbad6958', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'uuid': u'c448223a-1592-4ea8-b8a5-e92b55e57c2b', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'74nw77', u'resource_uri': u'/MAAS/api/2.0/nodes/74nw77/blockdevices/4/', u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 800097042432, u'id': 4, u'serial': None, u'name': u'vgroot-lvroot'}]
2019-05-24 08:30:00,958 [salt.loaded.ext.module.maasng:632 ][INFO    ][7922] vgroot
2019-05-24 08:30:00,959 [salt.loaded.ext.module.maasng:635 ][INFO    ][7922] lvroot
2019-05-24 08:30:00,959 [salt.loaded.ext.module.maasng:639 ][INFO    ][7922] 107374182400
2019-05-24 08:30:01,601 [salt.loaded.ext.module.maasng:645 ][INFO    ][7922] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'testing_status_name': u'Passed', u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.39', u'192.168.11.44'], u'cpu_count': 40, u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'commissioning_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'blockdevice_set': [{u'size': 800109715456, u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'uuid': None, u'id': 2, u'path': u'/dev/disk/by-dname/sda', u'system_id': u'74nw77', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'partitions': [{u'size': 800101236736, u'uuid': u'fa9d31d1-4fd0-4a25-9279-ed220b1ac5b5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'74nw77', u'filesystem': {u'label': None, u'uuid': u'49ec589c-6ac4-43f0-a6b6-1decbd3f4f76', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/74nw77/blockdevices/2/partition/7'}], u'used_size': 800106479616, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/74nw77/blockdevices/2/'}, {u'size': 107374182400, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'uuid': u'1be3bc04-3e81-4db0-8b43-1298304b94f6', u'id': 12, u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'74nw77', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'uuid': u'abdb1a24-a0eb-4eda-b4be-121e99b6f781', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'partitions': [], u'used_size': 107374182400, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/74nw77/blockdevices/12/'}], u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/74nw77/', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'74nw77', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'disable_ipv4': False, u'architecture': u'amd64/generic', u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'74nw77', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'boot_disk': {u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'74nw77', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'size': 800101236736, u'uuid': u'fa9d31d1-4fd0-4a25-9279-ed220b1ac5b5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'74nw77', u'filesystem': {u'label': None, u'uuid': u'49ec589c-6ac4-43f0-a6b6-1decbd3f4f76', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/74nw77/blockdevices/2/partition/7'}], u'used_size': 800106479616, u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/74nw77/blockdevices/2/'}, u'current_testing_result_id': 5, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'74nw77', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'size': 800101236736, u'uuid': u'fa9d31d1-4fd0-4a25-9279-ed220b1ac5b5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'74nw77', u'filesystem': {u'label': None, u'uuid': u'49ec589c-6ac4-43f0-a6b6-1decbd3f4f76', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/74nw77/blockdevices/2/partition/7'}], u'used_size': 800106479616, u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/74nw77/blockdevices/2/'}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'node_type': 0, u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'uuid': u'1be3bc04-3e81-4db0-8b43-1298304b94f6', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'74nw77', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'uuid': u'abdb1a24-a0eb-4eda-b4be-121e99b6f781', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'partitions': [], u'used_size': 107374182400, u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/74nw77/blockdevices/12/'}], u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'76dn3y', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 24}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'76dn3y', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'76dn3y', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'74nw77', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/74nw77/interfaces/5/'}, u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'76dn3y', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 24}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'76dn3y', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'76dn3y', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'74nw77', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/74nw77/interfaces/5/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'id': 18, u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d5', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'74nw77', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/74nw77/interfaces/18/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'id': 20, u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d4', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'74nw77', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/74nw77/interfaces/20/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'76dn3y', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'mode': u'link_up', u'id': 25}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'76dn3y', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'id': 16, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'76dn3y', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.44'}], u'mac_address': u'9c:b6:54:8a:95:a4', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'74nw77', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/74nw77/interfaces/16/'}, {u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 26}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'id': 17, u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cc', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'74nw77', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/74nw77/interfaces/17/'}, {u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 27}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'id': 19, u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cd', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'74nw77', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/74nw77/interfaces/19/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'node_type_name': u'Machine'}
2019-05-24 08:30:01,604 [salt.state       :300 ][INFO    ][7922] {'new': {'storage_layout': 'lvm'}}
2019-05-24 08:30:01,604 [salt.state       :1951][INFO    ][7922] Completed state [maas_machines_storage_cmp001_lvm] at time 08:30:01.604305 duration_in_ms=2437.207
2019-05-24 08:30:01,608 [salt.minion      :1711][INFO    ][7922] Returning information for job: 20190524082948250514
2019-05-24 08:30:02,362 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command state.apply with jid 20190524083002347411
2019-05-24 08:30:02,387 [salt.minion      :1432][INFO    ][7965] Starting a new job with PID 7965
2019-05-24 08:30:03,396 [salt.state       :915 ][INFO    ][7965] Loading fresh modules for state activity
2019-05-24 08:30:03,465 [salt.fileclient  :1219][INFO    ][7965] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-05-24 08:30:03,517 [salt.state       :1780][INFO    ][7965] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:30:03.517317
2019-05-24 08:30:03,518 [salt.state       :1813][INFO    ][7965] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-24 08:30:03,520 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7965] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-24 08:30:05,530 [salt.state       :300 ][INFO    ][7965] {'pid': 7972, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-24 08:30:05,531 [salt.state       :1951][INFO    ][7965] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:30:05.530951 duration_in_ms=2013.634
2019-05-24 08:30:05,535 [salt.state       :1780][INFO    ][7965] Running state [maas.deploy_machines] at time 08:30:05.534994
2019-05-24 08:30:05,535 [salt.state       :1813][INFO    ][7965] Executing state module.run for [maas.deploy_machines]
2019-05-24 08:30:05,536 [salt.utils.decorators:613 ][WARNING ][7965] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-24 08:30:06,115 [salt.loaded.ext.module.maas:684 ][INFO    ][7965] deploymachines hwe_kernel=hwe-16.04 system_id=8ekr6p distro_series=xenial
2019-05-24 08:30:08,601 [salt.loaded.ext.module.maas:684 ][INFO    ][7965] deploymachines hwe_kernel=hwe-16.04 system_id=74nw77 distro_series=xenial
2019-05-24 08:30:11,169 [salt.loaded.ext.module.maas:684 ][INFO    ][7965] deploymachines hwe_kernel=hwe-16.04 system_id=ehgd4g distro_series=xenial
2019-05-24 08:30:13,752 [salt.loaded.ext.module.maas:684 ][INFO    ][7965] deploymachines hwe_kernel=hwe-16.04 system_id=shwwp4 distro_series=xenial
2019-05-24 08:30:16,363 [salt.loaded.ext.module.maas:684 ][INFO    ][7965] deploymachines hwe_kernel=hwe-16.04 system_id=whexg8 distro_series=xenial
2019-05-24 08:30:17,467 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524083017455957
2019-05-24 08:30:17,490 [salt.minion      :1432][INFO    ][8221] Starting a new job with PID 8221
2019-05-24 08:30:17,519 [salt.minion      :1711][INFO    ][8221] Returning information for job: 20190524083017455957
2019-05-24 08:30:18,908 [salt.state       :300 ][INFO    ][7965] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-05-24 08:30:18,909 [salt.state       :1951][INFO    ][7965] Completed state [maas.deploy_machines] at time 08:30:18.909198 duration_in_ms=13374.202
2019-05-24 08:30:18,917 [salt.minion      :1711][INFO    ][7965] Returning information for job: 20190524083002347411
2019-05-24 08:30:19,672 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command state.apply with jid 20190524083019659738
2019-05-24 08:30:19,693 [salt.minion      :1432][INFO    ][8253] Starting a new job with PID 8253
2019-05-24 08:30:25,730 [salt.state       :915 ][INFO    ][8253] Loading fresh modules for state activity
2019-05-24 08:30:25,789 [salt.fileclient  :1219][INFO    ][8253] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-05-24 08:30:25,840 [salt.state       :1780][INFO    ][8253] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:30:25.840196
2019-05-24 08:30:25,840 [salt.state       :1813][INFO    ][8253] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-24 08:30:25,842 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8253] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-24 08:30:27,704 [salt.state       :300 ][INFO    ][8253] {'pid': 8271, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-24 08:30:27,705 [salt.state       :1951][INFO    ][8253] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:30:27.705241 duration_in_ms=1865.043
2019-05-24 08:30:27,709 [salt.state       :1780][INFO    ][8253] Running state [maas.wait_for_machine_status] at time 08:30:27.709420
2019-05-24 08:30:27,711 [salt.state       :1813][INFO    ][8253] Executing state module.run for [maas.wait_for_machine_status]
2019-05-24 08:30:27,712 [salt.utils.decorators:613 ][WARNING ][8253] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-24 08:30:30,529 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.1966579s left)
2019-05-24 08:30:34,795 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524083034782103
2019-05-24 08:30:34,819 [salt.minion      :1432][INFO    ][8298] Starting a new job with PID 8298
2019-05-24 08:30:34,848 [salt.minion      :1711][INFO    ][8298] Returning information for job: 20190524083034782103
2019-05-24 08:31:03,452 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.2728219s left)
2019-05-24 08:31:04,888 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524083104876323
2019-05-24 08:31:04,914 [salt.minion      :1432][INFO    ][8345] Starting a new job with PID 8345
2019-05-24 08:31:04,943 [salt.minion      :1711][INFO    ][8345] Returning information for job: 20190524083104876323
2019-05-24 08:31:34,956 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524083134943985
2019-05-24 08:31:34,975 [salt.minion      :1432][INFO    ][8377] Starting a new job with PID 8377
2019-05-24 08:31:35,003 [salt.minion      :1711][INFO    ][8377] Returning information for job: 20190524083134943985
2019-05-24 08:31:36,527 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.19787908s left)
2019-05-24 08:32:05,035 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524083205025018
2019-05-24 08:32:05,058 [salt.minion      :1432][INFO    ][8440] Starting a new job with PID 8440
2019-05-24 08:32:05,084 [salt.minion      :1711][INFO    ][8440] Returning information for job: 20190524083205025018
2019-05-24 08:32:09,376 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2148.34947109s left)
2019-05-24 08:32:35,106 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524083235097212
2019-05-24 08:32:35,130 [salt.minion      :1432][INFO    ][8467] Starting a new job with PID 8467
2019-05-24 08:32:35,159 [salt.minion      :1711][INFO    ][8467] Returning information for job: 20190524083235097212
2019-05-24 08:32:42,208 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2115.51701403s left)
2019-05-24 08:33:05,167 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524083305154654
2019-05-24 08:33:05,195 [salt.minion      :1432][INFO    ][8551] Starting a new job with PID 8551
2019-05-24 08:33:05,231 [salt.minion      :1711][INFO    ][8551] Returning information for job: 20190524083305154654
2019-05-24 08:33:15,116 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2082.60868692s left)
2019-05-24 08:33:35,252 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524083335238397
2019-05-24 08:33:35,288 [salt.minion      :1432][INFO    ][8611] Starting a new job with PID 8611
2019-05-24 08:33:35,329 [salt.minion      :1711][INFO    ][8611] Returning information for job: 20190524083335238397
2019-05-24 08:33:49,081 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2048.64465189s left)
2019-05-24 08:34:05,422 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524083405402780
2019-05-24 08:34:05,444 [salt.minion      :1432][INFO    ][8878] Starting a new job with PID 8878
2019-05-24 08:34:05,480 [salt.minion      :1711][INFO    ][8878] Returning information for job: 20190524083405402780
2019-05-24 08:34:21,972 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2015.75290895s left)
2019-05-24 08:34:35,502 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524083435492996
2019-05-24 08:34:35,526 [salt.minion      :1432][INFO    ][8949] Starting a new job with PID 8949
2019-05-24 08:34:35,560 [salt.minion      :1711][INFO    ][8949] Returning information for job: 20190524083435492996
2019-05-24 08:34:55,003 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1982.72224903s left)
2019-05-24 08:35:05,612 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524083505599063
2019-05-24 08:35:05,638 [salt.minion      :1432][INFO    ][9212] Starting a new job with PID 9212
2019-05-24 08:35:05,669 [salt.minion      :1711][INFO    ][9212] Returning information for job: 20190524083505599063
2019-05-24 08:35:28,078 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1949.64678192s left)
2019-05-24 08:35:35,753 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524083535737130
2019-05-24 08:35:35,781 [salt.minion      :1432][INFO    ][9274] Starting a new job with PID 9274
2019-05-24 08:35:35,816 [salt.minion      :1711][INFO    ][9274] Returning information for job: 20190524083535737130
2019-05-24 08:36:01,077 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1916.64857697s left)
2019-05-24 08:36:05,967 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524083605955088
2019-05-24 08:36:05,995 [salt.minion      :1432][INFO    ][9762] Starting a new job with PID 9762
2019-05-24 08:36:06,031 [salt.minion      :1711][INFO    ][9762] Returning information for job: 20190524083605955088
2019-05-24 08:36:34,292 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1883.43359303s left)
2019-05-24 08:36:36,117 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524083636104590
2019-05-24 08:36:36,145 [salt.minion      :1432][INFO    ][9797] Starting a new job with PID 9797
2019-05-24 08:36:36,172 [salt.minion      :1711][INFO    ][9797] Returning information for job: 20190524083636104590
2019-05-24 08:37:06,270 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524083706257336
2019-05-24 08:37:06,296 [salt.minion      :1432][INFO    ][10113] Starting a new job with PID 10113
2019-05-24 08:37:06,328 [salt.minion      :1711][INFO    ][10113] Returning information for job: 20190524083706257336
2019-05-24 08:37:07,393 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1850.33258104s left)
2019-05-24 08:37:36,414 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524083736399165
2019-05-24 08:37:36,442 [salt.minion      :1432][INFO    ][10152] Starting a new job with PID 10152
2019-05-24 08:37:36,476 [salt.minion      :1711][INFO    ][10152] Returning information for job: 20190524083736399165
2019-05-24 08:37:40,301 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1817.42424202s left)
2019-05-24 08:38:06,596 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524083806583111
2019-05-24 08:38:06,618 [salt.minion      :1432][INFO    ][10453] Starting a new job with PID 10453
2019-05-24 08:38:06,644 [salt.minion      :1711][INFO    ][10453] Returning information for job: 20190524083806583111
2019-05-24 08:38:13,324 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1784.40109301s left)
2019-05-24 08:38:36,731 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524083836719346
2019-05-24 08:38:36,754 [salt.minion      :1432][INFO    ][10511] Starting a new job with PID 10511
2019-05-24 08:38:36,783 [salt.minion      :1711][INFO    ][10511] Returning information for job: 20190524083836719346
2019-05-24 08:38:46,164 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1751.56082702s left)
2019-05-24 08:39:06,918 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524083906903246
2019-05-24 08:39:06,948 [salt.minion      :1432][INFO    ][10745] Starting a new job with PID 10745
2019-05-24 08:39:06,978 [salt.minion      :1711][INFO    ][10745] Returning information for job: 20190524083906903246
2019-05-24 08:39:19,053 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1718.67257309s left)
2019-05-24 08:39:37,084 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524083937070754
2019-05-24 08:39:37,108 [salt.minion      :1432][INFO    ][10799] Starting a new job with PID 10799
2019-05-24 08:39:37,135 [salt.minion      :1711][INFO    ][10799] Returning information for job: 20190524083937070754
2019-05-24 08:39:52,027 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1685.69843888s left)
2019-05-24 08:40:07,216 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524084007206372
2019-05-24 08:40:07,233 [salt.minion      :1432][INFO    ][10937] Starting a new job with PID 10937
2019-05-24 08:40:07,264 [salt.minion      :1711][INFO    ][10937] Returning information for job: 20190524084007206372
2019-05-24 08:40:25,120 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1652.60496092s left)
2019-05-24 08:40:37,367 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524084037355791
2019-05-24 08:40:37,391 [salt.minion      :1432][INFO    ][10989] Starting a new job with PID 10989
2019-05-24 08:40:37,423 [salt.minion      :1711][INFO    ][10989] Returning information for job: 20190524084037355791
2019-05-24 08:40:58,125 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1619.60039687s left)
2019-05-24 08:41:07,560 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524084107546330
2019-05-24 08:41:07,584 [salt.minion      :1432][INFO    ][11176] Starting a new job with PID 11176
2019-05-24 08:41:07,614 [salt.minion      :1711][INFO    ][11176] Returning information for job: 20190524084107546330
2019-05-24 08:41:31,067 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1586.65783501s left)
2019-05-24 08:41:37,756 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524084137739506
2019-05-24 08:41:37,786 [salt.minion      :1432][INFO    ][11338] Starting a new job with PID 11338
2019-05-24 08:41:37,822 [salt.minion      :1711][INFO    ][11338] Returning information for job: 20190524084137739506
2019-05-24 08:42:04,258 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1553.46743989s left)
2019-05-24 08:42:07,952 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524084207940282
2019-05-24 08:42:07,979 [salt.minion      :1432][INFO    ][11398] Starting a new job with PID 11398
2019-05-24 08:42:08,012 [salt.minion      :1711][INFO    ][11398] Returning information for job: 20190524084207940282
2019-05-24 08:42:37,216 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1520.50865293s left)
2019-05-24 08:42:38,144 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524084238131379
2019-05-24 08:42:38,172 [salt.minion      :1432][INFO    ][11449] Starting a new job with PID 11449
2019-05-24 08:42:38,207 [salt.minion      :1711][INFO    ][11449] Returning information for job: 20190524084238131379
2019-05-24 08:43:08,263 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524084308252905
2019-05-24 08:43:08,280 [salt.minion      :1432][INFO    ][11538] Starting a new job with PID 11538
2019-05-24 08:43:08,309 [salt.minion      :1711][INFO    ][11538] Returning information for job: 20190524084308252905
2019-05-24 08:43:10,350 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1487.37474108s left)
2019-05-24 08:43:38,472 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524084338462519
2019-05-24 08:43:38,494 [salt.minion      :1432][INFO    ][11595] Starting a new job with PID 11595
2019-05-24 08:43:38,523 [salt.minion      :1711][INFO    ][11595] Returning information for job: 20190524084338462519
2019-05-24 08:43:43,192 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1454.53341007s left)
2019-05-24 08:44:08,517 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524084408500897
2019-05-24 08:44:08,544 [salt.minion      :1432][INFO    ][11823] Starting a new job with PID 11823
2019-05-24 08:44:08,573 [salt.minion      :1711][INFO    ][11823] Returning information for job: 20190524084408500897
2019-05-24 08:44:16,169 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1421.55645609s left)
2019-05-24 08:44:38,562 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524084438545126
2019-05-24 08:44:38,591 [salt.minion      :1432][INFO    ][11855] Starting a new job with PID 11855
2019-05-24 08:44:38,628 [salt.minion      :1711][INFO    ][11855] Returning information for job: 20190524084438545126
2019-05-24 08:44:49,280 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1388.44488096s left)
2019-05-24 08:45:08,611 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524084508595644
2019-05-24 08:45:08,640 [salt.minion      :1432][INFO    ][11905] Starting a new job with PID 11905
2019-05-24 08:45:08,671 [salt.minion      :1711][INFO    ][11905] Returning information for job: 20190524084508595644
2019-05-24 08:45:22,024 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1355.70134091s left)
2019-05-24 08:45:38,671 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524084538662887
2019-05-24 08:45:38,698 [salt.minion      :1432][INFO    ][11937] Starting a new job with PID 11937
2019-05-24 08:45:38,727 [salt.minion      :1711][INFO    ][11937] Returning information for job: 20190524084538662887
2019-05-24 08:45:54,931 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1322.79396892s left)
2019-05-24 08:46:08,720 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524084608706609
2019-05-24 08:46:08,742 [salt.minion      :1432][INFO    ][11983] Starting a new job with PID 11983
2019-05-24 08:46:08,772 [salt.minion      :1711][INFO    ][11983] Returning information for job: 20190524084608706609
2019-05-24 08:46:27,693 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1290.03166008s left)
2019-05-24 08:46:38,789 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524084638779061
2019-05-24 08:46:38,815 [salt.minion      :1432][INFO    ][12014] Starting a new job with PID 12014
2019-05-24 08:46:38,843 [salt.minion      :1711][INFO    ][12014] Returning information for job: 20190524084638779061
2019-05-24 08:47:00,558 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1257.16661692s left)
2019-05-24 08:47:08,851 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524084708841146
2019-05-24 08:47:08,878 [salt.minion      :1432][INFO    ][12065] Starting a new job with PID 12065
2019-05-24 08:47:08,909 [salt.minion      :1711][INFO    ][12065] Returning information for job: 20190524084708841146
2019-05-24 08:47:33,335 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1224.39003396s left)
2019-05-24 08:47:38,962 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524084738945629
2019-05-24 08:47:38,989 [salt.minion      :1432][INFO    ][12095] Starting a new job with PID 12095
2019-05-24 08:47:39,023 [salt.minion      :1711][INFO    ][12095] Returning information for job: 20190524084738945629
2019-05-24 08:48:06,232 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1191.49315691s left)
2019-05-24 08:48:09,086 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524084809071643
2019-05-24 08:48:09,115 [salt.minion      :1432][INFO    ][12143] Starting a new job with PID 12143
2019-05-24 08:48:09,144 [salt.minion      :1711][INFO    ][12143] Returning information for job: 20190524084809071643
2019-05-24 08:48:39,216 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524084839204962
2019-05-24 08:48:39,236 [salt.minion      :1432][INFO    ][12171] Starting a new job with PID 12171
2019-05-24 08:48:39,264 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1158.4607749s left)
2019-05-24 08:48:39,271 [salt.minion      :1711][INFO    ][12171] Returning information for job: 20190524084839204962
2019-05-24 08:49:09,358 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524084909345674
2019-05-24 08:49:09,381 [salt.minion      :1432][INFO    ][12216] Starting a new job with PID 12216
2019-05-24 08:49:09,412 [salt.minion      :1711][INFO    ][12216] Returning information for job: 20190524084909345674
2019-05-24 08:49:12,051 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1125.67428994s left)
2019-05-24 08:49:39,522 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524084939507968
2019-05-24 08:49:39,540 [salt.minion      :1432][INFO    ][12242] Starting a new job with PID 12242
2019-05-24 08:49:39,572 [salt.minion      :1711][INFO    ][12242] Returning information for job: 20190524084939507968
2019-05-24 08:49:44,789 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1092.9364121s left)
2019-05-24 08:50:09,681 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524085009674146
2019-05-24 08:50:09,701 [salt.minion      :1432][INFO    ][12289] Starting a new job with PID 12289
2019-05-24 08:50:09,727 [salt.minion      :1711][INFO    ][12289] Returning information for job: 20190524085009674146
2019-05-24 08:50:17,635 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1060.090101s left)
2019-05-24 08:50:39,822 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524085039807453
2019-05-24 08:50:39,843 [salt.minion      :1432][INFO    ][12317] Starting a new job with PID 12317
2019-05-24 08:50:39,872 [salt.minion      :1711][INFO    ][12317] Returning information for job: 20190524085039807453
2019-05-24 08:50:50,553 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1027.17211795s left)
2019-05-24 08:51:10,015 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524085110003149
2019-05-24 08:51:10,043 [salt.minion      :1432][INFO    ][12361] Starting a new job with PID 12361
2019-05-24 08:51:10,075 [salt.minion      :1711][INFO    ][12361] Returning information for job: 20190524085110003149
2019-05-24 08:51:23,411 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (994.31368494s left)
2019-05-24 08:51:40,245 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524085140229553
2019-05-24 08:51:40,277 [salt.minion      :1432][INFO    ][12398] Starting a new job with PID 12398
2019-05-24 08:51:40,305 [salt.minion      :1711][INFO    ][12398] Returning information for job: 20190524085140229553
2019-05-24 08:51:56,226 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (961.499473095s left)
2019-05-24 08:52:10,293 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524085210278250
2019-05-24 08:52:10,325 [salt.minion      :1432][INFO    ][12460] Starting a new job with PID 12460
2019-05-24 08:52:10,360 [salt.minion      :1711][INFO    ][12460] Returning information for job: 20190524085210278250
2019-05-24 08:52:29,104 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (928.621440887s left)
2019-05-24 08:52:40,339 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524085240323135
2019-05-24 08:52:40,367 [salt.minion      :1432][INFO    ][12492] Starting a new job with PID 12492
2019-05-24 08:52:40,416 [salt.minion      :1711][INFO    ][12492] Returning information for job: 20190524085240323135
2019-05-24 08:53:01,876 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (895.849050999s left)
2019-05-24 08:53:10,412 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524085310395929
2019-05-24 08:53:10,440 [salt.minion      :1432][INFO    ][12536] Starting a new job with PID 12536
2019-05-24 08:53:10,470 [salt.minion      :1711][INFO    ][12536] Returning information for job: 20190524085310395929
2019-05-24 08:53:34,597 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (863.128479004s left)
2019-05-24 08:53:40,460 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524085340443982
2019-05-24 08:53:40,489 [salt.minion      :1432][INFO    ][12566] Starting a new job with PID 12566
2019-05-24 08:53:40,523 [salt.minion      :1711][INFO    ][12566] Returning information for job: 20190524085340443982
2019-05-24 08:54:07,625 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (830.100697041s left)
2019-05-24 08:54:10,555 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524085410540857
2019-05-24 08:54:10,582 [salt.minion      :1432][INFO    ][12611] Starting a new job with PID 12611
2019-05-24 08:54:10,620 [salt.minion      :1711][INFO    ][12611] Returning information for job: 20190524085410540857
2019-05-24 08:54:40,379 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (797.346364021s left)
2019-05-24 08:54:40,617 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524085440602729
2019-05-24 08:54:40,648 [salt.minion      :1432][INFO    ][12638] Starting a new job with PID 12638
2019-05-24 08:54:40,678 [salt.minion      :1711][INFO    ][12638] Returning information for job: 20190524085440602729
2019-05-24 08:55:10,752 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524085510739434
2019-05-24 08:55:10,775 [salt.minion      :1432][INFO    ][12679] Starting a new job with PID 12679
2019-05-24 08:55:10,804 [salt.minion      :1711][INFO    ][12679] Returning information for job: 20190524085510739434
2019-05-24 08:55:13,259 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (764.465882063s left)
2019-05-24 08:55:40,879 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524085540869595
2019-05-24 08:55:40,902 [salt.minion      :1432][INFO    ][12710] Starting a new job with PID 12710
2019-05-24 08:55:40,928 [salt.minion      :1711][INFO    ][12710] Returning information for job: 20190524085540869595
2019-05-24 08:55:45,946 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (731.779299021s left)
2019-05-24 08:56:11,009 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524085610999747
2019-05-24 08:56:11,039 [salt.minion      :1432][INFO    ][12757] Starting a new job with PID 12757
2019-05-24 08:56:11,072 [salt.minion      :1711][INFO    ][12757] Returning information for job: 20190524085610999747
2019-05-24 08:56:18,700 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (699.025455952s left)
2019-05-24 08:56:41,203 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524085641189232
2019-05-24 08:56:41,232 [salt.minion      :1432][INFO    ][12787] Starting a new job with PID 12787
2019-05-24 08:56:41,267 [salt.minion      :1711][INFO    ][12787] Returning information for job: 20190524085641189232
2019-05-24 08:56:51,652 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (666.073494911s left)
2019-05-24 08:57:11,362 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524085711353495
2019-05-24 08:57:11,384 [salt.minion      :1432][INFO    ][12834] Starting a new job with PID 12834
2019-05-24 08:57:11,416 [salt.minion      :1711][INFO    ][12834] Returning information for job: 20190524085711353495
2019-05-24 08:57:24,516 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (633.209522963s left)
2019-05-24 08:57:41,567 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524085741557633
2019-05-24 08:57:41,599 [salt.minion      :1432][INFO    ][12865] Starting a new job with PID 12865
2019-05-24 08:57:41,631 [salt.minion      :1711][INFO    ][12865] Returning information for job: 20190524085741557633
2019-05-24 08:57:57,244 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (600.480803967s left)
2019-05-24 08:58:11,607 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524085811598258
2019-05-24 08:58:11,636 [salt.minion      :1432][INFO    ][12915] Starting a new job with PID 12915
2019-05-24 08:58:11,669 [salt.minion      :1711][INFO    ][12915] Returning information for job: 20190524085811598258
2019-05-24 08:58:30,103 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (567.621773005s left)
2019-05-24 08:58:41,646 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524085841629120
2019-05-24 08:58:41,673 [salt.minion      :1432][INFO    ][12946] Starting a new job with PID 12946
2019-05-24 08:58:41,704 [salt.minion      :1711][INFO    ][12946] Returning information for job: 20190524085841629120
2019-05-24 08:59:02,926 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (534.79903698s left)
2019-05-24 08:59:11,672 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524085911657166
2019-05-24 08:59:11,700 [salt.minion      :1432][INFO    ][12990] Starting a new job with PID 12990
2019-05-24 08:59:11,729 [salt.minion      :1711][INFO    ][12990] Returning information for job: 20190524085911657166
2019-05-24 08:59:35,793 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (501.932547092s left)
2019-05-24 08:59:41,782 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524085941769157
2019-05-24 08:59:41,811 [salt.minion      :1432][INFO    ][13019] Starting a new job with PID 13019
2019-05-24 08:59:41,842 [salt.minion      :1711][INFO    ][13019] Returning information for job: 20190524085941769157
2019-05-24 09:00:08,808 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (468.917282104s left)
2019-05-24 09:00:11,859 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524090011844561
2019-05-24 09:00:11,889 [salt.minion      :1432][INFO    ][13064] Starting a new job with PID 13064
2019-05-24 09:00:11,921 [salt.minion      :1711][INFO    ][13064] Returning information for job: 20190524090011844561
2019-05-24 09:00:41,529 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (436.196108103s left)
2019-05-24 09:00:41,997 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524090041987796
2019-05-24 09:00:42,023 [salt.minion      :1432][INFO    ][13095] Starting a new job with PID 13095
2019-05-24 09:00:42,054 [salt.minion      :1711][INFO    ][13095] Returning information for job: 20190524090041987796
2019-05-24 09:01:12,080 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524090112070296
2019-05-24 09:01:12,104 [salt.minion      :1432][INFO    ][13137] Starting a new job with PID 13137
2019-05-24 09:01:12,136 [salt.minion      :1711][INFO    ][13137] Returning information for job: 20190524090112070296
2019-05-24 09:01:14,496 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (403.229523897s left)
2019-05-24 09:01:42,261 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524090142243329
2019-05-24 09:01:42,288 [salt.minion      :1432][INFO    ][13306] Starting a new job with PID 13306
2019-05-24 09:01:42,323 [salt.minion      :1711][INFO    ][13306] Returning information for job: 20190524090142243329
2019-05-24 09:01:47,321 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (370.404438019s left)
2019-05-24 09:02:12,460 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524090212446801
2019-05-24 09:02:12,480 [salt.minion      :1432][INFO    ][13363] Starting a new job with PID 13363
2019-05-24 09:02:12,512 [salt.minion      :1711][INFO    ][13363] Returning information for job: 20190524090212446801
2019-05-24 09:02:20,179 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (337.546485901s left)
2019-05-24 09:02:42,672 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524090242659029
2019-05-24 09:02:42,703 [salt.minion      :1432][INFO    ][13398] Starting a new job with PID 13398
2019-05-24 09:02:42,732 [salt.minion      :1711][INFO    ][13398] Returning information for job: 20190524090242659029
2019-05-24 09:02:52,977 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (304.748362064s left)
2019-05-24 09:03:12,847 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524090312835244
2019-05-24 09:03:12,875 [salt.minion      :1432][INFO    ][13442] Starting a new job with PID 13442
2019-05-24 09:03:12,905 [salt.minion      :1711][INFO    ][13442] Returning information for job: 20190524090312835244
2019-05-24 09:03:25,708 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (272.016973972s left)
2019-05-24 09:03:43,014 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524090342997907
2019-05-24 09:03:43,042 [salt.minion      :1432][INFO    ][13470] Starting a new job with PID 13470
2019-05-24 09:03:43,075 [salt.minion      :1711][INFO    ][13470] Returning information for job: 20190524090342997907
2019-05-24 09:03:58,703 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (239.021933079s left)
2019-05-24 09:04:13,083 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524090413066968
2019-05-24 09:04:13,111 [salt.minion      :1432][INFO    ][13519] Starting a new job with PID 13519
2019-05-24 09:04:13,141 [salt.minion      :1711][INFO    ][13519] Returning information for job: 20190524090413066968
2019-05-24 09:04:31,551 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (206.174118042s left)
2019-05-24 09:04:43,161 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524090443144915
2019-05-24 09:04:43,189 [salt.minion      :1432][INFO    ][13549] Starting a new job with PID 13549
2019-05-24 09:04:43,222 [salt.minion      :1711][INFO    ][13549] Returning information for job: 20190524090443144915
2019-05-24 09:05:04,307 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (173.417952061s left)
2019-05-24 09:05:13,268 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524090513256650
2019-05-24 09:05:13,295 [salt.minion      :1432][INFO    ][13599] Starting a new job with PID 13599
2019-05-24 09:05:13,326 [salt.minion      :1711][INFO    ][13599] Returning information for job: 20190524090513256650
2019-05-24 09:05:37,041 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (140.683765888s left)
2019-05-24 09:05:43,390 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524090543377420
2019-05-24 09:05:43,420 [salt.minion      :1432][INFO    ][13633] Starting a new job with PID 13633
2019-05-24 09:05:43,452 [salt.minion      :1711][INFO    ][13633] Returning information for job: 20190524090543377420
2019-05-24 09:06:09,828 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (107.897471905s left)
2019-05-24 09:06:13,488 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524090613472840
2019-05-24 09:06:13,515 [salt.minion      :1432][INFO    ][13682] Starting a new job with PID 13682
2019-05-24 09:06:13,547 [salt.minion      :1711][INFO    ][13682] Returning information for job: 20190524090613472840
2019-05-24 09:06:42,812 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (74.91305089s left)
2019-05-24 09:06:43,568 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524090643555636
2019-05-24 09:06:43,594 [salt.minion      :1432][INFO    ][13714] Starting a new job with PID 13714
2019-05-24 09:06:43,627 [salt.minion      :1711][INFO    ][13714] Returning information for job: 20190524090643555636
2019-05-24 09:07:13,759 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524090713748423
2019-05-24 09:07:13,783 [salt.minion      :1432][INFO    ][13761] Starting a new job with PID 13761
2019-05-24 09:07:13,817 [salt.minion      :1711][INFO    ][13761] Returning information for job: 20190524090713748423
2019-05-24 09:07:15,755 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (41.970236063s left)
2019-05-24 09:07:43,902 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524090743892427
2019-05-24 09:07:43,924 [salt.minion      :1432][INFO    ][13789] Starting a new job with PID 13789
2019-05-24 09:07:43,953 [salt.minion      :1711][INFO    ][13789] Returning information for job: 20190524090743892427
2019-05-24 09:07:48,624 [salt.loaded.ext.module.maas:1023][INFO    ][8253] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (9.10122799873s left)
2019-05-24 09:08:14,057 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524090814042717
2019-05-24 09:08:14,083 [salt.minion      :1432][INFO    ][13837] Starting a new job with PID 13837
2019-05-24 09:08:14,112 [salt.minion      :1711][INFO    ][13837] Returning information for job: 20190524090814042717
2019-05-24 09:08:21,391 [salt.state       :302 ][ERROR   ][8253] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-05-24 09:08:21,392 [salt.state       :1951][INFO    ][8253] Completed state [maas.wait_for_machine_status] at time 09:08:21.392301 duration_in_ms=2273682.877
2019-05-24 09:08:21,404 [salt.minion      :1711][INFO    ][8253] Returning information for job: 20190524083019659738
2019-05-24 09:08:32,417 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command pillar.get with jid 20190524090832407427
2019-05-24 09:08:32,443 [salt.minion      :1432][INFO    ][13869] Starting a new job with PID 13869
2019-05-24 09:08:32,452 [salt.minion      :1711][INFO    ][13869] Returning information for job: 20190524090832407427
2019-05-24 09:08:33,204 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command service.status with jid 20190524090833191796
2019-05-24 09:08:33,234 [salt.minion      :1432][INFO    ][13874] Starting a new job with PID 13874
2019-05-24 09:08:34,105 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13874] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-05-24 09:08:34,149 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13874] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-05-24 09:08:34,171 [salt.minion      :1711][INFO    ][13874] Returning information for job: 20190524090833191796
2019-05-24 09:08:34,891 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command state.apply with jid 20190524090834879642
2019-05-24 09:08:34,920 [salt.minion      :1432][INFO    ][13885] Starting a new job with PID 13885
2019-05-24 09:08:40,821 [salt.state       :915 ][INFO    ][13885] Loading fresh modules for state activity
2019-05-24 09:08:41,452 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13885] Executing command 'salt-minion --version' in directory '/root'
2019-05-24 09:08:41,800 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13885] Executing command 'salt-minion --version' in directory '/root'
2019-05-24 09:08:42,887 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13885] Executing command 'salt-minion --version' in directory '/root'
2019-05-24 09:08:43,246 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13885] Executing command 'salt-minion --version' in directory '/root'
2019-05-24 09:08:45,555 [salt.state       :1780][INFO    ][13885] Running state [salt-minion] at time 09:08:45.555899
2019-05-24 09:08:45,556 [salt.state       :1813][INFO    ][13885] Executing state pkg.installed for [salt-minion]
2019-05-24 09:08:45,557 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13885] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-24 09:08:45,675 [salt.state       :300 ][INFO    ][13885] All specified packages are already installed
2019-05-24 09:08:45,675 [salt.state       :1951][INFO    ][13885] Completed state [salt-minion] at time 09:08:45.675518 duration_in_ms=119.62
2019-05-24 09:08:45,675 [salt.state       :1780][INFO    ][13885] Running state [salt_minion_dependency_packages] at time 09:08:45.675901
2019-05-24 09:08:45,676 [salt.state       :1813][INFO    ][13885] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-05-24 09:08:45,685 [salt.state       :300 ][INFO    ][13885] All specified packages are already installed
2019-05-24 09:08:45,686 [salt.state       :1951][INFO    ][13885] Completed state [salt_minion_dependency_packages] at time 09:08:45.686240 duration_in_ms=10.339
2019-05-24 09:08:45,689 [salt.state       :1780][INFO    ][13885] Running state [/etc/salt/minion.d/minion.conf] at time 09:08:45.689393
2019-05-24 09:08:45,689 [salt.state       :1813][INFO    ][13885] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-05-24 09:08:45,972 [salt.state       :300 ][INFO    ][13885] File /etc/salt/minion.d/minion.conf is in the correct state
2019-05-24 09:08:45,972 [salt.state       :1951][INFO    ][13885] Completed state [/etc/salt/minion.d/minion.conf] at time 09:08:45.972412 duration_in_ms=283.02
2019-05-24 09:08:45,972 [salt.state       :1780][INFO    ][13885] Running state [python-netaddr] at time 09:08:45.972726
2019-05-24 09:08:45,973 [salt.state       :1813][INFO    ][13885] Executing state pkg.installed for [python-netaddr]
2019-05-24 09:08:45,982 [salt.state       :300 ][INFO    ][13885] All specified packages are already installed
2019-05-24 09:08:45,982 [salt.state       :1951][INFO    ][13885] Completed state [python-netaddr] at time 09:08:45.982758 duration_in_ms=10.032
2019-05-24 09:08:45,986 [salt.state       :1780][INFO    ][13885] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 09:08:45.986209
2019-05-24 09:08:45,986 [salt.state       :1813][INFO    ][13885] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-05-24 09:08:45,997 [salt.state       :300 ][INFO    ][13885] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-05-24 09:08:45,997 [salt.state       :1951][INFO    ][13885] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 09:08:45.997250 duration_in_ms=11.041
2019-05-24 09:08:46,000 [salt.state       :1780][INFO    ][13885] Running state [salt-minion] at time 09:08:46.000136
2019-05-24 09:08:46,000 [salt.state       :1813][INFO    ][13885] Executing state service.running for [salt-minion]
2019-05-24 09:08:46,001 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13885] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-24 09:08:46,040 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13885] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-05-24 09:08:46,055 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13885] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-05-24 09:08:46,074 [salt.state       :300 ][INFO    ][13885] The service salt-minion is already running
2019-05-24 09:08:46,074 [salt.state       :1951][INFO    ][13885] Completed state [salt-minion] at time 09:08:46.074712 duration_in_ms=74.575
2019-05-24 09:08:46,077 [salt.state       :1780][INFO    ][13885] Running state [/etc/salt/grains.d] at time 09:08:46.077434
2019-05-24 09:08:46,078 [salt.state       :1813][INFO    ][13885] Executing state file.directory for [/etc/salt/grains.d]
2019-05-24 09:08:46,080 [salt.state       :300 ][INFO    ][13885] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-05-24 09:08:46,080 [salt.state       :1951][INFO    ][13885] Completed state [/etc/salt/grains.d] at time 09:08:46.080510 duration_in_ms=3.076
2019-05-24 09:08:46,083 [salt.state       :1780][INFO    ][13885] Running state [/etc/salt/grains] at time 09:08:46.083406
2019-05-24 09:08:46,083 [salt.state       :1813][INFO    ][13885] Executing state file.managed for [/etc/salt/grains]
2019-05-24 09:08:46,084 [salt.state       :300 ][INFO    ][13885] File /etc/salt/grains exists with proper permissions. No changes made.
2019-05-24 09:08:46,084 [salt.state       :1951][INFO    ][13885] Completed state [/etc/salt/grains] at time 09:08:46.084599 duration_in_ms=1.193
2019-05-24 09:08:46,085 [salt.state       :1780][INFO    ][13885] Running state [/etc/salt/grains.d/placeholder] at time 09:08:46.085143
2019-05-24 09:08:46,085 [salt.state       :1813][INFO    ][13885] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-05-24 09:08:46,086 [salt.state       :300 ][INFO    ][13885] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-05-24 09:08:46,086 [salt.state       :1951][INFO    ][13885] Completed state [/etc/salt/grains.d/placeholder] at time 09:08:46.086616 duration_in_ms=1.472
2019-05-24 09:08:46,087 [salt.state       :1780][INFO    ][13885] Running state [/etc/salt/grains.d/sphinx] at time 09:08:46.087173
2019-05-24 09:08:46,087 [salt.state       :1813][INFO    ][13885] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-05-24 09:08:46,088 [salt.state       :300 ][INFO    ][13885] File /etc/salt/grains.d/sphinx is in the correct state
2019-05-24 09:08:46,089 [salt.state       :1951][INFO    ][13885] Completed state [/etc/salt/grains.d/sphinx] at time 09:08:46.089021 duration_in_ms=1.848
2019-05-24 09:08:46,091 [salt.state       :1780][INFO    ][13885] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 09:08:46.091923
2019-05-24 09:08:46,092 [salt.state       :1813][INFO    ][13885] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-05-24 09:08:46,092 [salt.state       :300 ][INFO    ][13885] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-05-24 09:08:46,092 [salt.state       :1951][INFO    ][13885] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 09:08:46.092773 duration_in_ms=0.85
2019-05-24 09:08:46,093 [salt.state       :1780][INFO    ][13885] Running state [/etc/salt/grains.d/dns_records] at time 09:08:46.093281
2019-05-24 09:08:46,093 [salt.state       :1813][INFO    ][13885] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-05-24 09:08:46,098 [salt.state       :300 ][INFO    ][13885] File /etc/salt/grains.d/dns_records is in the correct state
2019-05-24 09:08:46,098 [salt.state       :1951][INFO    ][13885] Completed state [/etc/salt/grains.d/dns_records] at time 09:08:46.098741 duration_in_ms=5.459
2019-05-24 09:08:46,099 [salt.state       :1780][INFO    ][13885] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 09:08:46.099709
2019-05-24 09:08:46,100 [salt.state       :1813][INFO    ][13885] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-05-24 09:08:46,100 [salt.state       :300 ][INFO    ][13885] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-05-24 09:08:46,100 [salt.state       :1951][INFO    ][13885] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 09:08:46.100539 duration_in_ms=0.83
2019-05-24 09:08:46,101 [salt.state       :1780][INFO    ][13885] Running state [/etc/salt/grains.d/salt] at time 09:08:46.101058
2019-05-24 09:08:46,101 [salt.state       :1813][INFO    ][13885] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-05-24 09:08:46,102 [salt.state       :300 ][INFO    ][13885] File /etc/salt/grains.d/salt is in the correct state
2019-05-24 09:08:46,102 [salt.state       :1951][INFO    ][13885] Completed state [/etc/salt/grains.d/salt] at time 09:08:46.102637 duration_in_ms=1.58
2019-05-24 09:08:46,103 [salt.state       :1780][INFO    ][13885] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 09:08:46.103556
2019-05-24 09:08:46,103 [salt.state       :1813][INFO    ][13885] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-05-24 09:08:46,104 [salt.state       :300 ][INFO    ][13885] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-05-24 09:08:46,104 [salt.state       :1951][INFO    ][13885] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 09:08:46.104381 duration_in_ms=0.825
2019-05-24 09:08:46,110 [salt.state       :1780][INFO    ][13885] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 09:08:46.110202
2019-05-24 09:08:46,110 [salt.state       :1813][INFO    ][13885] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-05-24 09:08:46,110 [salt.state       :300 ][INFO    ][13885] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-05-24 09:08:46,111 [salt.state       :1951][INFO    ][13885] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 09:08:46.111041 duration_in_ms=0.839
2019-05-24 09:08:46,111 [salt.state       :1780][INFO    ][13885] Running state [mine.update] at time 09:08:46.111736
2019-05-24 09:08:46,112 [salt.state       :1813][INFO    ][13885] Executing state module.wait for [mine.update]
2019-05-24 09:08:46,112 [salt.state       :300 ][INFO    ][13885] No changes made for mine.update
2019-05-24 09:08:46,112 [salt.state       :1951][INFO    ][13885] Completed state [mine.update] at time 09:08:46.112502 duration_in_ms=0.766
2019-05-24 09:08:46,112 [salt.state       :1780][INFO    ][13885] Running state [ca-certificates] at time 09:08:46.112769
2019-05-24 09:08:46,113 [salt.state       :1813][INFO    ][13885] Executing state pkg.installed for [ca-certificates]
2019-05-24 09:08:46,134 [salt.state       :300 ][INFO    ][13885] All specified packages are already installed
2019-05-24 09:08:46,134 [salt.state       :1951][INFO    ][13885] Completed state [ca-certificates] at time 09:08:46.134914 duration_in_ms=22.143
2019-05-24 09:08:46,135 [salt.state       :1780][INFO    ][13885] Running state [update-ca-certificates] at time 09:08:46.135714
2019-05-24 09:08:46,136 [salt.state       :1813][INFO    ][13885] Executing state cmd.wait for [update-ca-certificates]
2019-05-24 09:08:46,136 [salt.state       :300 ][INFO    ][13885] No changes made for update-ca-certificates
2019-05-24 09:08:46,136 [salt.state       :1951][INFO    ][13885] Completed state [update-ca-certificates] at time 09:08:46.136581 duration_in_ms=0.867
2019-05-24 09:08:46,136 [salt.state       :1780][INFO    ][13885] Running state [iptables] at time 09:08:46.136902
2019-05-24 09:08:46,137 [salt.state       :1813][INFO    ][13885] Executing state pkg.installed for [iptables]
2019-05-24 09:08:46,152 [salt.state       :300 ][INFO    ][13885] All specified packages are already installed
2019-05-24 09:08:46,152 [salt.state       :1951][INFO    ][13885] Completed state [iptables] at time 09:08:46.152505 duration_in_ms=15.603
2019-05-24 09:08:46,152 [salt.state       :1780][INFO    ][13885] Running state [iptables-persistent] at time 09:08:46.152822
2019-05-24 09:08:46,153 [salt.state       :1813][INFO    ][13885] Executing state pkg.installed for [iptables-persistent]
2019-05-24 09:08:46,171 [salt.state       :300 ][INFO    ][13885] All specified packages are already installed
2019-05-24 09:08:46,171 [salt.state       :1951][INFO    ][13885] Completed state [iptables-persistent] at time 09:08:46.171680 duration_in_ms=18.858
2019-05-24 09:08:46,172 [salt.state       :1780][INFO    ][13885] Running state [iptables_modules_v4_load] at time 09:08:46.172812
2019-05-24 09:08:46,173 [salt.state       :1813][INFO    ][13885] Executing state kmod.present for [iptables_modules_v4_load]
2019-05-24 09:08:46,173 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13885] Executing command 'lsmod' in directory '/root'
2019-05-24 09:08:46,207 [salt.state       :300 ][INFO    ][13885] Kernel modules iptable_filter, ip_tables are already present
2019-05-24 09:08:46,208 [salt.state       :1951][INFO    ][13885] Completed state [iptables_modules_v4_load] at time 09:08:46.207927 duration_in_ms=35.115
2019-05-24 09:08:46,208 [salt.state       :1780][INFO    ][13885] Running state [/etc/iptables/rules.v4] at time 09:08:46.208907
2019-05-24 09:08:46,209 [salt.state       :1813][INFO    ][13885] Executing state file.managed for [/etc/iptables/rules.v4]
2019-05-24 09:08:46,332 [salt.state       :300 ][INFO    ][13885] File /etc/iptables/rules.v4 is in the correct state
2019-05-24 09:08:46,333 [salt.state       :1951][INFO    ][13885] Completed state [/etc/iptables/rules.v4] at time 09:08:46.333022 duration_in_ms=124.114
2019-05-24 09:08:46,335 [salt.state       :1780][INFO    ][13885] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 09:08:46.335311
2019-05-24 09:08:46,335 [salt.state       :1813][INFO    ][13885] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-05-24 09:08:46,336 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13885] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-05-24 09:08:46,356 [salt.state       :300 ][INFO    ][13885] onlyif execution failed
2019-05-24 09:08:46,357 [salt.state       :1951][INFO    ][13885] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 09:08:46.357056 duration_in_ms=21.744
2019-05-24 09:08:46,361 [salt.state       :1780][INFO    ][13885] Running state [netfilter-persistent] at time 09:08:46.361395
2019-05-24 09:08:46,362 [salt.state       :1813][INFO    ][13885] Executing state service.running for [netfilter-persistent]
2019-05-24 09:08:46,363 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13885] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-05-24 09:08:46,388 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13885] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-05-24 09:08:46,410 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13885] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-05-24 09:08:46,426 [salt.state       :300 ][INFO    ][13885] The service netfilter-persistent is already running
2019-05-24 09:08:46,427 [salt.state       :1951][INFO    ][13885] Completed state [netfilter-persistent] at time 09:08:46.427330 duration_in_ms=65.936
2019-05-24 09:08:46,428 [salt.state       :1780][INFO    ][13885] Running state [iptables_extra.remove_stale_tables] at time 09:08:46.428502
2019-05-24 09:08:46,428 [salt.state       :1813][INFO    ][13885] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-05-24 09:08:46,429 [salt.state       :300 ][INFO    ][13885] No changes made for iptables_extra.remove_stale_tables
2019-05-24 09:08:46,432 [salt.state       :1951][INFO    ][13885] Completed state [iptables_extra.remove_stale_tables] at time 09:08:46.429687 duration_in_ms=1.185
2019-05-24 09:08:46,432 [salt.state       :1780][INFO    ][13885] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 09:08:46.432649
2019-05-24 09:08:46,433 [salt.state       :1813][INFO    ][13885] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-05-24 09:08:46,433 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13885] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-05-24 09:08:46,447 [salt.state       :300 ][INFO    ][13885] onlyif execution failed
2019-05-24 09:08:46,448 [salt.state       :1951][INFO    ][13885] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 09:08:46.447985 duration_in_ms=15.336
2019-05-24 09:08:46,449 [salt.state       :1780][INFO    ][13885] Running state [/etc/iptables/rules.v6] at time 09:08:46.449235
2019-05-24 09:08:46,449 [salt.state       :1813][INFO    ][13885] Executing state file.absent for [/etc/iptables/rules.v6]
2019-05-24 09:08:46,450 [salt.state       :300 ][INFO    ][13885] File /etc/iptables/rules.v6 is not present
2019-05-24 09:08:46,450 [salt.state       :1951][INFO    ][13885] Completed state [/etc/iptables/rules.v6] at time 09:08:46.450683 duration_in_ms=1.448
2019-05-24 09:08:46,451 [salt.state       :1780][INFO    ][13885] Running state [iptables_extra.flush_all] at time 09:08:46.451612
2019-05-24 09:08:46,452 [salt.state       :1813][INFO    ][13885] Executing state module.wait for [iptables_extra.flush_all]
2019-05-24 09:08:46,452 [salt.state       :300 ][INFO    ][13885] No changes made for iptables_extra.flush_all
2019-05-24 09:08:46,452 [salt.state       :1951][INFO    ][13885] Completed state [iptables_extra.flush_all] at time 09:08:46.452659 duration_in_ms=1.047
2019-05-24 09:08:46,458 [salt.minion      :1711][INFO    ][13885] Returning information for job: 20190524090834879642
2019-05-24 09:08:47,219 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command state.apply with jid 20190524090847211247
2019-05-24 09:08:47,239 [salt.minion      :1432][INFO    ][13984] Starting a new job with PID 13984
2019-05-24 09:08:48,196 [salt.state       :915 ][INFO    ][13984] Loading fresh modules for state activity
2019-05-24 09:08:49,529 [salt.state       :1780][INFO    ][13984] Running state [maas-rack-controller] at time 09:08:49.529060
2019-05-24 09:08:49,529 [salt.state       :1813][INFO    ][13984] Executing state pkg.installed for [maas-rack-controller]
2019-05-24 09:08:49,530 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13984] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-24 09:08:49,647 [salt.state       :300 ][INFO    ][13984] All specified packages are already installed
2019-05-24 09:08:49,648 [salt.state       :1951][INFO    ][13984] Completed state [maas-rack-controller] at time 09:08:49.647961 duration_in_ms=118.901
2019-05-24 09:08:49,648 [salt.state       :1780][INFO    ][13984] Running state [ipmitool] at time 09:08:49.648319
2019-05-24 09:08:49,648 [salt.state       :1813][INFO    ][13984] Executing state pkg.installed for [ipmitool]
2019-05-24 09:08:49,659 [salt.state       :300 ][INFO    ][13984] All specified packages are already installed
2019-05-24 09:08:49,659 [salt.state       :1951][INFO    ][13984] Completed state [ipmitool] at time 09:08:49.659928 duration_in_ms=11.61
2019-05-24 09:08:49,663 [salt.state       :1780][INFO    ][13984] Running state [/etc/maas/rackd.conf] at time 09:08:49.663046
2019-05-24 09:08:49,663 [salt.state       :1813][INFO    ][13984] Executing state file.line for [/etc/maas/rackd.conf]
2019-05-24 09:08:49,664 [salt.state       :300 ][INFO    ][13984] No changes needed to be made
2019-05-24 09:08:49,664 [salt.state       :1951][INFO    ][13984] Completed state [/etc/maas/rackd.conf] at time 09:08:49.664531 duration_in_ms=1.485
2019-05-24 09:08:49,664 [salt.state       :1780][INFO    ][13984] Running state [/etc/maas/rackd.conf] at time 09:08:49.664818
2019-05-24 09:08:49,665 [salt.state       :1813][INFO    ][13984] Executing state file.managed for [/etc/maas/rackd.conf]
2019-05-24 09:08:49,665 [salt.loaded.int.states.file:2298][WARNING ][13984] 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-05-24 09:08:49,666 [salt.state       :300 ][INFO    ][13984] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-05-24 09:08:49,666 [salt.state       :1951][INFO    ][13984] Completed state [/etc/maas/rackd.conf] at time 09:08:49.666850 duration_in_ms=2.031
2019-05-24 09:08:49,667 [salt.state       :1780][INFO    ][13984] Running state [maas-rackd] at time 09:08:49.667892
2019-05-24 09:08:49,668 [salt.state       :1813][INFO    ][13984] Executing state service.running for [maas-rackd]
2019-05-24 09:08:49,668 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13984] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-05-24 09:08:49,704 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13984] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-05-24 09:08:49,723 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13984] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-05-24 09:08:49,740 [salt.state       :300 ][INFO    ][13984] The service maas-rackd is already running
2019-05-24 09:08:49,741 [salt.state       :1951][INFO    ][13984] Completed state [maas-rackd] at time 09:08:49.740981 duration_in_ms=73.089
2019-05-24 09:08:49,745 [salt.minion      :1711][INFO    ][13984] Returning information for job: 20190524090847211247
2019-05-24 09:08:50,457 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command state.apply with jid 20190524090850446833
2019-05-24 09:08:50,489 [salt.minion      :1432][INFO    ][14015] Starting a new job with PID 14015
2019-05-24 09:08:51,484 [salt.state       :915 ][INFO    ][14015] Loading fresh modules for state activity
2019-05-24 09:08:52,951 [salt.state       :1780][INFO    ][14015] Running state [maas-region-controller] at time 09:08:52.951088
2019-05-24 09:08:52,951 [salt.state       :1813][INFO    ][14015] Executing state pkg.installed for [maas-region-controller]
2019-05-24 09:08:52,952 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14015] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-24 09:08:53,085 [salt.state       :300 ][INFO    ][14015] All specified packages are already installed
2019-05-24 09:08:53,086 [salt.state       :1951][INFO    ][14015] Completed state [maas-region-controller] at time 09:08:53.085927 duration_in_ms=134.84
2019-05-24 09:08:53,086 [salt.state       :1780][INFO    ][14015] Running state [python-oauth] at time 09:08:53.086301
2019-05-24 09:08:53,086 [salt.state       :1813][INFO    ][14015] Executing state pkg.installed for [python-oauth]
2019-05-24 09:08:53,100 [salt.state       :300 ][INFO    ][14015] All specified packages are already installed
2019-05-24 09:08:53,101 [salt.state       :1951][INFO    ][14015] Completed state [python-oauth] at time 09:08:53.100881 duration_in_ms=14.577
2019-05-24 09:08:53,106 [salt.state       :1780][INFO    ][14015] Running state [/etc/maas/regiond.conf] at time 09:08:53.106713
2019-05-24 09:08:53,107 [salt.state       :1813][INFO    ][14015] Executing state file.replace for [/etc/maas/regiond.conf]
2019-05-24 09:08:53,112 [salt.state       :300 ][INFO    ][14015] No changes needed to be made
2019-05-24 09:08:53,113 [salt.state       :1951][INFO    ][14015] Completed state [/etc/maas/regiond.conf] at time 09:08:53.113117 duration_in_ms=6.404
2019-05-24 09:08:53,114 [salt.state       :1780][INFO    ][14015] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 09:08:53.114705
2019-05-24 09:08:53,115 [salt.state       :1813][INFO    ][14015] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-05-24 09:08:53,179 [salt.state       :300 ][INFO    ][14015] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-05-24 09:08:53,179 [salt.state       :1951][INFO    ][14015] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 09:08:53.179348 duration_in_ms=64.643
2019-05-24 09:08:53,179 [salt.state       :1780][INFO    ][14015] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 09:08:53.179900
2019-05-24 09:08:53,180 [salt.state       :1813][INFO    ][14015] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-24 09:08:53,185 [salt.state       :300 ][INFO    ][14015] No changes needed to be made
2019-05-24 09:08:53,186 [salt.state       :1951][INFO    ][14015] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 09:08:53.186369 duration_in_ms=6.469
2019-05-24 09:08:53,186 [salt.state       :1780][INFO    ][14015] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 09:08:53.186899
2019-05-24 09:08:53,187 [salt.state       :1813][INFO    ][14015] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-24 09:08:53,192 [salt.state       :300 ][INFO    ][14015] No changes needed to be made
2019-05-24 09:08:53,192 [salt.state       :1951][INFO    ][14015] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 09:08:53.192248 duration_in_ms=5.349
2019-05-24 09:08:53,192 [salt.state       :1780][INFO    ][14015] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 09:08:53.192781
2019-05-24 09:08:53,193 [salt.state       :1813][INFO    ][14015] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-05-24 09:08:53,228 [salt.state       :300 ][INFO    ][14015] No changes needed to be made
2019-05-24 09:08:53,229 [salt.state       :1951][INFO    ][14015] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 09:08:53.229205 duration_in_ms=36.424
2019-05-24 09:08:53,230 [salt.state       :1780][INFO    ][14015] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 09:08:53.230120
2019-05-24 09:08:53,230 [salt.state       :1813][INFO    ][14015] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-05-24 09:08:53,249 [salt.state       :300 ][INFO    ][14015] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-05-24 09:08:53,250 [salt.state       :1951][INFO    ][14015] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 09:08:53.250005 duration_in_ms=19.885
2019-05-24 09:08:53,253 [salt.state       :1780][INFO    ][14015] Running state [a2enmod headers] at time 09:08:53.252990
2019-05-24 09:08:53,253 [salt.state       :1813][INFO    ][14015] Executing state cmd.run for [a2enmod headers]
2019-05-24 09:08:53,255 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14015] Executing command 'a2enmod headers' in directory '/root'
2019-05-24 09:08:53,333 [salt.state       :300 ][INFO    ][14015] {'pid': 14034, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-05-24 09:08:53,334 [salt.state       :1951][INFO    ][14015] Completed state [a2enmod headers] at time 09:08:53.334008 duration_in_ms=81.019
2019-05-24 09:08:53,334 [salt.state       :1780][INFO    ][14015] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 09:08:53.334679
2019-05-24 09:08:53,335 [salt.state       :1813][INFO    ][14015] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-05-24 09:08:53,362 [salt.state       :300 ][INFO    ][14015] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-05-24 09:08:53,362 [salt.state       :1951][INFO    ][14015] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 09:08:53.362751 duration_in_ms=28.073
2019-05-24 09:08:53,363 [salt.state       :1780][INFO    ][14015] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 09:08:53.363884
2019-05-24 09:08:53,364 [salt.state       :1813][INFO    ][14015] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-05-24 09:08:53,438 [salt.state       :300 ][INFO    ][14015] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-05-24 09:08:53,439 [salt.state       :1951][INFO    ][14015] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 09:08:53.439407 duration_in_ms=75.522
2019-05-24 09:08:53,440 [salt.state       :1780][INFO    ][14015] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 09:08:53.440529
2019-05-24 09:08:53,441 [salt.state       :1813][INFO    ][14015] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-05-24 09:08:53,503 [salt.state       :300 ][INFO    ][14015] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-05-24 09:08:53,503 [salt.state       :1951][INFO    ][14015] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 09:08:53.503324 duration_in_ms=62.795
2019-05-24 09:08:53,503 [salt.state       :1780][INFO    ][14015] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 09:08:53.503868
2019-05-24 09:08:53,504 [salt.state       :1813][INFO    ][14015] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-05-24 09:08:53,579 [salt.state       :300 ][INFO    ][14015] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-05-24 09:08:53,579 [salt.state       :1951][INFO    ][14015] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 09:08:53.579586 duration_in_ms=75.718
2019-05-24 09:08:53,579 [salt.state       :1780][INFO    ][14015] Running state [/root/.pgpass] at time 09:08:53.579889
2019-05-24 09:08:53,580 [salt.state       :1813][INFO    ][14015] Executing state file.managed for [/root/.pgpass]
2019-05-24 09:08:53,634 [salt.state       :300 ][INFO    ][14015] File /root/.pgpass is in the correct state
2019-05-24 09:08:53,634 [salt.state       :1951][INFO    ][14015] Completed state [/root/.pgpass] at time 09:08:53.634769 duration_in_ms=54.88
2019-05-24 09:08:53,639 [salt.state       :1780][INFO    ][14015] Running state [maas-region syncdb --noinput] at time 09:08:53.639899
2019-05-24 09:08:53,640 [salt.state       :1813][INFO    ][14015] Executing state cmd.run for [maas-region syncdb --noinput]
2019-05-24 09:08:53,640 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14015] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-05-24 09:08:56,483 [salt.state       :300 ][INFO    ][14015] {'pid': 14047, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: contenttypes, sessions, metadataserver, sites, auth, maasserver, piston3\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-05-24 09:08:56,484 [salt.state       :1951][INFO    ][14015] Completed state [maas-region syncdb --noinput] at time 09:08:56.484133 duration_in_ms=2844.232
2019-05-24 09:08:56,484 [salt.state       :2022][WARNING ][14015] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-24 09:08:56,488 [salt.state       :1780][INFO    ][14015] Running state [maas-regiond] at time 09:08:56.488344
2019-05-24 09:08:56,488 [salt.state       :1813][INFO    ][14015] Executing state service.running for [maas-regiond]
2019-05-24 09:08:56,489 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14015] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-05-24 09:08:56,539 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14015] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-05-24 09:08:56,562 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14015] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-05-24 09:08:56,585 [salt.state       :300 ][INFO    ][14015] The service maas-regiond is already running
2019-05-24 09:08:56,586 [salt.state       :1951][INFO    ][14015] Completed state [maas-regiond] at time 09:08:56.586554 duration_in_ms=98.208
2019-05-24 09:08:56,590 [salt.state       :1780][INFO    ][14015] Running state [bind9] at time 09:08:56.590696
2019-05-24 09:08:56,591 [salt.state       :1813][INFO    ][14015] Executing state service.running for [bind9]
2019-05-24 09:08:56,593 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14015] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-05-24 09:08:56,619 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14015] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-05-24 09:08:56,639 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14015] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-05-24 09:08:56,665 [salt.state       :300 ][INFO    ][14015] The service bind9 is already running
2019-05-24 09:08:56,667 [salt.state       :1951][INFO    ][14015] Completed state [bind9] at time 09:08:56.667678 duration_in_ms=76.981
2019-05-24 09:08:56,670 [salt.state       :1780][INFO    ][14015] Running state [apache2] at time 09:08:56.670763
2019-05-24 09:08:56,671 [salt.state       :1813][INFO    ][14015] Executing state service.running for [apache2]
2019-05-24 09:08:56,672 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14015] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-05-24 09:08:56,697 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14015] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-05-24 09:08:56,721 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14015] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-05-24 09:08:56,752 [salt.state       :300 ][INFO    ][14015] The service apache2 is already running
2019-05-24 09:08:56,752 [salt.state       :1951][INFO    ][14015] Completed state [apache2] at time 09:08:56.752704 duration_in_ms=81.941
2019-05-24 09:08:56,758 [salt.state       :1780][INFO    ][14015] Running state [maasng.wait_for_http_code] at time 09:08:56.757906
2019-05-24 09:08:56,758 [salt.state       :1813][INFO    ][14015] Executing state module.run for [maasng.wait_for_http_code]
2019-05-24 09:08:56,759 [salt.utils.decorators:613 ][WARNING ][14015] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-24 09:08:56,773 [salt.state       :300 ][INFO    ][14015] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-05-24 09:08:56,773 [salt.state       :1951][INFO    ][14015] Completed state [maasng.wait_for_http_code] at time 09:08:56.773482 duration_in_ms=15.575
2019-05-24 09:08:56,777 [salt.state       :1780][INFO    ][14015] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 09:08:56.777525
2019-05-24 09:08:56,778 [salt.state       :1813][INFO    ][14015] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-05-24 09:08:56,779 [salt.state       :300 ][INFO    ][14015] /var/lib/maas/.setup_admin exists
2019-05-24 09:08:56,779 [salt.state       :1951][INFO    ][14015] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 09:08:56.779605 duration_in_ms=2.081
2019-05-24 09:08:56,780 [salt.state       :1780][INFO    ][14015] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:08:56.780467
2019-05-24 09:08:56,780 [salt.state       :1813][INFO    ][14015] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-24 09:08:56,781 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14015] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-24 09:08:58,698 [salt.state       :300 ][INFO    ][14015] {'pid': 14066, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-24 09:08:58,699 [salt.state       :1951][INFO    ][14015] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:08:58.698997 duration_in_ms=1918.53
2019-05-24 09:08:58,710 [salt.state       :1780][INFO    ][14015] Running state [maas_region_boot_source_resources_mirror] at time 09:08:58.710215
2019-05-24 09:08:58,710 [salt.state       :1813][INFO    ][14015] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-05-24 09:08:58,804 [salt.state       :300 ][INFO    ][14015] {'changes': {}}
2019-05-24 09:08:58,804 [salt.state       :1951][INFO    ][14015] Completed state [maas_region_boot_source_resources_mirror] at time 09:08:58.804592 duration_in_ms=94.377
2019-05-24 09:08:58,806 [salt.state       :1780][INFO    ][14015] Running state [maasng.boot_resources_import] at time 09:08:58.805642
2019-05-24 09:08:58,807 [salt.state       :1813][INFO    ][14015] Executing state module.run for [maasng.boot_resources_import]
2019-05-24 09:08:58,807 [salt.utils.decorators:613 ][WARNING ][14015] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-24 09:08:58,900 [salt.loaded.ext.module.maasng:1600][INFO    ][14015] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-05-24 09:09:03,954 [salt.loaded.ext.module.maasng:1600][INFO    ][14015] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-05-24 09:09:05,553 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524090905537228
2019-05-24 09:09:05,584 [salt.minion      :1432][INFO    ][14097] Starting a new job with PID 14097
2019-05-24 09:09:05,615 [salt.minion      :1711][INFO    ][14097] Returning information for job: 20190524090905537228
2019-05-24 09:09:09,072 [salt.state       :300 ][INFO    ][14015] {'ret': True}
2019-05-24 09:09:09,073 [salt.state       :1951][INFO    ][14015] Completed state [maasng.boot_resources_import] at time 09:09:09.073012 duration_in_ms=10267.369
2019-05-24 09:09:09,075 [salt.state       :1780][INFO    ][14015] Running state [maas_region_boot_sources_selection_xenial] at time 09:09:09.075348
2019-05-24 09:09:09,075 [salt.state       :1813][INFO    ][14015] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-05-24 09:09:09,247 [salt.state       :300 ][INFO    ][14015] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-05-24 09:09:09,248 [salt.state       :1951][INFO    ][14015] Completed state [maas_region_boot_sources_selection_xenial] at time 09:09:09.247999 duration_in_ms=172.65
2019-05-24 09:09:09,249 [salt.state       :1780][INFO    ][14015] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 09:09:09.249565
2019-05-24 09:09:09,251 [salt.state       :1813][INFO    ][14015] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-05-24 09:09:09,251 [salt.utils.decorators:613 ][WARNING ][14015] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-24 09:09:09,252 [salt.loaded.ext.module.maasng:1771][INFO    ][14015] boot-sources sync initiated for ALL Rack's
2019-05-24 09:09:10,450 [salt.state       :300 ][INFO    ][14015] {'ret': True}
2019-05-24 09:09:10,451 [salt.state       :1951][INFO    ][14015] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 09:09:10.450987 duration_in_ms=1201.422
2019-05-24 09:09:10,453 [salt.state       :1780][INFO    ][14015] Running state [maas.process_maas_config] at time 09:09:10.453030
2019-05-24 09:09:10,453 [salt.state       :1813][INFO    ][14015] Executing state module.run for [maas.process_maas_config]
2019-05-24 09:09:10,455 [salt.utils.decorators:613 ][WARNING ][14015] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-24 09:09:10,456 [salt.loaded.ext.module.maas:92  ][INFO    ][14015] maasconfig name=enable_http_proxy value=True
2019-05-24 09:09:10,506 [salt.loaded.ext.module.maas:92  ][INFO    ][14015] maasconfig name=upstream_dns value=8.8.8.8
2019-05-24 09:09:10,559 [salt.loaded.ext.module.maas:92  ][INFO    ][14015] maasconfig name=commissioning_distro_series value=xenial
2019-05-24 09:09:10,618 [salt.loaded.ext.module.maas:92  ][INFO    ][14015] maasconfig name=default_osystem value=ubuntu
2019-05-24 09:09:10,682 [salt.loaded.ext.module.maas:92  ][INFO    ][14015] maasconfig name=active_discovery_interval value=600
2019-05-24 09:09:10,733 [salt.loaded.ext.module.maas:92  ][INFO    ][14015] maasconfig name=dnssec_validation value=no
2019-05-24 09:09:10,789 [salt.loaded.ext.module.maas:92  ][INFO    ][14015] maasconfig name=maas_name value=mas01
2019-05-24 09:09:10,851 [salt.loaded.ext.module.maas:92  ][INFO    ][14015] maasconfig name=network_discovery value=enabled
2019-05-24 09:09:12,213 [salt.loaded.ext.module.maas:92  ][INFO    ][14015] maasconfig name=enable_third_party_drivers value=True
2019-05-24 09:09:12,268 [salt.loaded.ext.module.maas:92  ][INFO    ][14015] maasconfig name=default_storage_layout value=lvm
2019-05-24 09:09:12,329 [salt.loaded.ext.module.maas:92  ][INFO    ][14015] maasconfig name=ntp_external_only value=True
2019-05-24 09:09:12,377 [salt.loaded.ext.module.maas:92  ][INFO    ][14015] maasconfig name=disk_erase_with_secure_erase value=False
2019-05-24 09:09:12,436 [salt.loaded.ext.module.maas:92  ][INFO    ][14015] maasconfig name=default_distro_series value=xenial
2019-05-24 09:09:12,487 [salt.loaded.ext.module.maas:92  ][INFO    ][14015] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-05-24 09:09:12,595 [salt.state       :300 ][INFO    ][14015] {'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-05-24 09:09:12,595 [salt.state       :1951][INFO    ][14015] Completed state [maas.process_maas_config] at time 09:09:12.595573 duration_in_ms=2142.544
2019-05-24 09:09:12,596 [salt.state       :1780][INFO    ][14015] Running state [pxe_admin] at time 09:09:12.596423
2019-05-24 09:09:12,596 [salt.state       :1813][INFO    ][14015] Executing state maasng.fabric_present for [pxe_admin]
2019-05-24 09:09:12,645 [salt.loaded.ext.module.maasng:945 ][INFO    ][14015] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'76dn3y', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-24 09:09:12,704 [salt.loaded.ext.module.maasng:1008][WARNING ][14015] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-05-24 09:09:12,704 [salt.loaded.ext.module.maasng:1011][WARNING ][14015] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-05-24 09:09:12,761 [salt.state       :300 ][INFO    ][14015] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-05-24 09:09:12,762 [salt.state       :1951][INFO    ][14015] Completed state [pxe_admin] at time 09:09:12.761681 duration_in_ms=165.257
2019-05-24 09:09:12,762 [salt.state       :1780][INFO    ][14015] Running state [vlan 0] at time 09:09:12.762450
2019-05-24 09:09:12,762 [salt.state       :1813][INFO    ][14015] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-24 09:09:12,831 [salt.loaded.ext.module.maasng:945 ][INFO    ][14015] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'76dn3y', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-24 09:09:12,936 [salt.loaded.ext.module.maasng:945 ][INFO    ][14015] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'76dn3y', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-24 09:09:13,196 [salt.loaded.ext.module.maasng:945 ][INFO    ][14015] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'76dn3y', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-24 09:09:13,281 [salt.state       :300 ][INFO    ][14015] {'new': 'Vlan untagged was updated'}
2019-05-24 09:09:13,282 [salt.state       :1951][INFO    ][14015] Completed state [vlan 0] at time 09:09:13.281617 duration_in_ms=519.167
2019-05-24 09:09:13,283 [salt.state       :1780][INFO    ][14015] Running state [192.168.11.0/24] at time 09:09:13.283375
2019-05-24 09:09:13,283 [salt.state       :1813][INFO    ][14015] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-05-24 09:09:13,484 [salt.loaded.ext.module.maasng:945 ][INFO    ][14015] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'76dn3y', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-24 09:09:13,485 [salt.loaded.ext.module.maasng:1235][WARNING ][14015] Ignoring parameter vlan:0
2019-05-24 09:09:13,540 [salt.state       :300 ][INFO    ][14015] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-05-24 09:09:13,540 [salt.state       :1951][INFO    ][14015] Completed state [192.168.11.0/24] at time 09:09:13.540392 duration_in_ms=257.016
2019-05-24 09:09:13,541 [salt.state       :1780][INFO    ][14015] Running state [maas_create_iprange_1] at time 09:09:13.541265
2019-05-24 09:09:13,541 [salt.state       :1813][INFO    ][14015] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-05-24 09:09:13,584 [salt.state       :300 ][INFO    ][14015] Iprange maas_create_iprange_1 already exist.
2019-05-24 09:09:13,584 [salt.state       :1951][INFO    ][14015] Completed state [maas_create_iprange_1] at time 09:09:13.584747 duration_in_ms=43.482
2019-05-24 09:09:13,585 [salt.state       :1780][INFO    ][14015] Running state [vlan 0] at time 09:09:13.585057
2019-05-24 09:09:13,585 [salt.state       :1813][INFO    ][14015] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-24 09:09:13,627 [salt.loaded.ext.module.maasng:945 ][INFO    ][14015] [{u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None, u'name': u'fabric-2', u'id': 2}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'76dn3y', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u'', u'name': u'pxe_admin', u'id': 1}]
2019-05-24 09:09:13,746 [salt.loaded.ext.module.maasng:945 ][INFO    ][14015] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'76dn3y', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-24 09:09:13,997 [salt.loaded.ext.module.maasng:945 ][INFO    ][14015] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'76dn3y', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-24 09:09:14,101 [salt.state       :300 ][INFO    ][14015] {'new': 'Vlan untagged was updated'}
2019-05-24 09:09:14,106 [salt.state       :1951][INFO    ][14015] Completed state [vlan 0] at time 09:09:14.103709 duration_in_ms=518.649
2019-05-24 09:09:14,107 [salt.state       :1780][INFO    ][14015] Running state [opnfv] at time 09:09:14.106982
2019-05-24 09:09:14,107 [salt.state       :1813][INFO    ][14015] Executing state maasng.sshkey_present for [opnfv]
2019-05-24 09:09:14,170 [salt.loaded.ext.module.maasng:1903][INFO    ][14015] [{u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b', u'keysource': u''}]
2019-05-24 09:09:14,170 [salt.state       :300 ][INFO    ][14015] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-05-24 09:09:14,171 [salt.state       :1951][INFO    ][14015] Completed state [opnfv] at time 09:09:14.171242 duration_in_ms=64.26
2019-05-24 09:09:14,172 [salt.state       :1780][INFO    ][14015] Running state [maas.process_tags] at time 09:09:14.171988
2019-05-24 09:09:14,172 [salt.state       :1813][INFO    ][14015] Executing state module.run for [maas.process_tags]
2019-05-24 09:09:14,172 [salt.utils.decorators:613 ][WARNING ][14015] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-24 09:09:14,235 [salt.loaded.ext.module.maas:92  ][INFO    ][14015] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-05-24 09:09:14,285 [salt.state       :300 ][INFO    ][14015] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-05-24 09:09:14,286 [salt.state       :1951][INFO    ][14015] Completed state [maas.process_tags] at time 09:09:14.286616 duration_in_ms=114.628
2019-05-24 09:09:14,290 [salt.minion      :1711][INFO    ][14015] Returning information for job: 20190524090850446833
2019-05-24 09:09:15,084 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command state.apply with jid 20190524090915073699
2019-05-24 09:09:15,106 [salt.minion      :1432][INFO    ][14452] Starting a new job with PID 14452
2019-05-24 09:09:21,160 [salt.state       :915 ][INFO    ][14452] Loading fresh modules for state activity
2019-05-24 09:09:21,282 [salt.state       :1780][INFO    ][14452] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:09:21.282595
2019-05-24 09:09:21,282 [salt.state       :1813][INFO    ][14452] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-24 09:09:21,284 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14452] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-24 09:09:23,192 [salt.state       :300 ][INFO    ][14452] {'pid': 14480, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-24 09:09:23,193 [salt.state       :1951][INFO    ][14452] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:09:23.193489 duration_in_ms=1910.894
2019-05-24 09:09:23,196 [salt.state       :1780][INFO    ][14452] Running state [maas.process_machines] at time 09:09:23.196411
2019-05-24 09:09:23,196 [salt.state       :1813][INFO    ][14452] Executing state module.run for [maas.process_machines]
2019-05-24 09:09:23,199 [salt.utils.decorators:613 ][WARNING ][14452] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-24 09:09:23,783 [salt.loaded.ext.module.maas:412 ][WARNING ][14452] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-24 09:09:23,784 [salt.loaded.ext.module.maas:92  ][INFO    ][14452] machine hostname=cmp002 power_type=ipmi mac_addresses=['9c:b6:54:8a:10:18'] power_parameters_power_address=172.16.1.20 power_parameters_power_pass=Winter2017 system_id=8ekr6p architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-24 09:09:25,124 [salt.loaded.ext.module.maas:412 ][WARNING ][14452] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-24 09:09:25,126 [salt.loaded.ext.module.maas:92  ][INFO    ][14452] machine hostname=cmp001 power_type=ipmi mac_addresses=['9c:b6:54:8a:95:a0'] power_parameters_power_address=172.16.1.19 power_parameters_power_pass=Winter2017 system_id=74nw77 architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-24 09:09:26,361 [salt.loaded.ext.module.maas:412 ][WARNING ][14452] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-24 09:09:26,362 [salt.loaded.ext.module.maas:92  ][INFO    ][14452] machine hostname=kvm01 power_type=ipmi mac_addresses=['14:58:d0:54:e7:88'] power_parameters_power_address=172.16.1.16 power_parameters_power_pass=Winter2017 system_id=ehgd4g architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-24 09:09:27,632 [salt.loaded.ext.module.maas:412 ][WARNING ][14452] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-24 09:09:27,633 [salt.loaded.ext.module.maas:92  ][INFO    ][14452] machine hostname=kvm03 power_type=ipmi mac_addresses=['14:58:d0:54:7a:28'] power_parameters_power_address=172.16.1.18 power_parameters_power_pass=Winter2017 system_id=shwwp4 architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-24 09:09:28,871 [salt.loaded.ext.module.maas:412 ][WARNING ][14452] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-24 09:09:28,872 [salt.loaded.ext.module.maas:92  ][INFO    ][14452] machine hostname=kvm02 power_type=ipmi mac_addresses=['14:58:d0:54:6a:60'] power_parameters_power_address=172.16.1.17 power_parameters_power_pass=Winter2017 system_id=whexg8 architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-24 09:09:30,121 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524090930112665
2019-05-24 09:09:30,141 [salt.minion      :1432][INFO    ][14692] Starting a new job with PID 14692
2019-05-24 09:09:30,173 [salt.minion      :1711][INFO    ][14692] Returning information for job: 20190524090930112665
2019-05-24 09:09:30,232 [salt.state       :300 ][INFO    ][14452] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-05-24 09:09:30,233 [salt.state       :1951][INFO    ][14452] Completed state [maas.process_machines] at time 09:09:30.232891 duration_in_ms=7036.479
2019-05-24 09:09:30,237 [salt.minion      :1711][INFO    ][14452] Returning information for job: 20190524090915073699
2019-05-24 09:10:04,335 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command state.apply with jid 20190524091004326508
2019-05-24 09:10:04,361 [salt.minion      :1432][INFO    ][14756] Starting a new job with PID 14756
2019-05-24 09:10:10,314 [salt.state       :915 ][INFO    ][14756] Loading fresh modules for state activity
2019-05-24 09:10:10,439 [salt.state       :1780][INFO    ][14756] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:10:10.439141
2019-05-24 09:10:10,439 [salt.state       :1813][INFO    ][14756] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-24 09:10:10,441 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14756] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-24 09:10:12,332 [salt.state       :300 ][INFO    ][14756] {'pid': 14763, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-24 09:10:12,333 [salt.state       :1951][INFO    ][14756] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:10:12.333168 duration_in_ms=1894.027
2019-05-24 09:10:12,337 [salt.state       :1780][INFO    ][14756] Running state [maas.wait_for_machine_status] at time 09:10:12.336963
2019-05-24 09:10:12,338 [salt.state       :1813][INFO    ][14756] Executing state module.run for [maas.wait_for_machine_status]
2019-05-24 09:10:12,339 [salt.utils.decorators:613 ][WARNING ][14756] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-24 09:10:15,061 [salt.state       :300 ][INFO    ][14756] {'ret': True}
2019-05-24 09:10:15,063 [salt.state       :1951][INFO    ][14756] Completed state [maas.wait_for_machine_status] at time 09:10:15.063501 duration_in_ms=2726.537
2019-05-24 09:10:15,067 [salt.minion      :1711][INFO    ][14756] Returning information for job: 20190524091004326508
2019-05-24 09:10:15,809 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command state.apply with jid 20190524091015792021
2019-05-24 09:10:15,832 [salt.minion      :1432][INFO    ][14778] Starting a new job with PID 14778
2019-05-24 09:10:16,808 [salt.state       :915 ][INFO    ][14778] Loading fresh modules for state activity
2019-05-24 09:10:16,963 [salt.state       :1780][INFO    ][14778] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:10:16.963721
2019-05-24 09:10:16,964 [salt.state       :1813][INFO    ][14778] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-24 09:10:16,966 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14778] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-24 09:10:18,961 [salt.state       :300 ][INFO    ][14778] {'pid': 14790, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-24 09:10:18,963 [salt.state       :1951][INFO    ][14778] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:10:18.962916 duration_in_ms=1999.194
2019-05-24 09:10:18,967 [salt.state       :1780][INFO    ][14778] Running state [maas_machines_storage_cmp002_lvm] at time 09:10:18.967464
2019-05-24 09:10:18,968 [salt.state       :1813][INFO    ][14778] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-24 09:10:19,535 [salt.state       :300 ][INFO    ][14778] Machine cmp002 is not in Ready state.
2019-05-24 09:10:19,536 [salt.state       :1951][INFO    ][14778] Completed state [maas_machines_storage_cmp002_lvm] at time 09:10:19.536094 duration_in_ms=568.63
2019-05-24 09:10:19,537 [salt.state       :1780][INFO    ][14778] Running state [maas_machines_storage_cmp001_lvm] at time 09:10:19.536981
2019-05-24 09:10:19,539 [salt.state       :1813][INFO    ][14778] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-24 09:10:20,088 [salt.state       :300 ][INFO    ][14778] Machine cmp001 is not in Ready state.
2019-05-24 09:10:20,089 [salt.state       :1951][INFO    ][14778] Completed state [maas_machines_storage_cmp001_lvm] at time 09:10:20.089288 duration_in_ms=552.306
2019-05-24 09:10:20,094 [salt.minion      :1711][INFO    ][14778] Returning information for job: 20190524091015792021
2019-05-24 09:10:20,788 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command state.apply with jid 20190524091020770913
2019-05-24 09:10:20,813 [salt.minion      :1432][INFO    ][14803] Starting a new job with PID 14803
2019-05-24 09:10:21,768 [salt.state       :915 ][INFO    ][14803] Loading fresh modules for state activity
2019-05-24 09:10:21,882 [salt.state       :1780][INFO    ][14803] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:10:21.882620
2019-05-24 09:10:21,882 [salt.state       :1813][INFO    ][14803] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-24 09:10:21,884 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14803] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-24 09:10:23,794 [salt.state       :300 ][INFO    ][14803] {'pid': 14810, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-24 09:10:23,795 [salt.state       :1951][INFO    ][14803] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:10:23.795666 duration_in_ms=1913.046
2019-05-24 09:10:23,799 [salt.state       :1780][INFO    ][14803] Running state [maas.deploy_machines] at time 09:10:23.799179
2019-05-24 09:10:23,799 [salt.state       :1813][INFO    ][14803] Executing state module.run for [maas.deploy_machines]
2019-05-24 09:10:23,800 [salt.utils.decorators:613 ][WARNING ][14803] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-24 09:10:24,359 [salt.loaded.ext.module.maas:684 ][INFO    ][14803] deploymachines hwe_kernel=hwe-16.04 system_id=whexg8 distro_series=xenial
2019-05-24 09:10:26,873 [salt.state       :300 ][INFO    ][14803] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-05-24 09:10:26,874 [salt.state       :1951][INFO    ][14803] Completed state [maas.deploy_machines] at time 09:10:26.874099 duration_in_ms=3074.918
2019-05-24 09:10:26,877 [salt.minion      :1711][INFO    ][14803] Returning information for job: 20190524091020770913
2019-05-24 09:10:27,594 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command state.apply with jid 20190524091027577256
2019-05-24 09:10:27,627 [salt.minion      :1432][INFO    ][14870] Starting a new job with PID 14870
2019-05-24 09:10:33,655 [salt.state       :915 ][INFO    ][14870] Loading fresh modules for state activity
2019-05-24 09:10:33,778 [salt.state       :1780][INFO    ][14870] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:10:33.778565
2019-05-24 09:10:33,779 [salt.state       :1813][INFO    ][14870] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-24 09:10:33,781 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14870] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-24 09:10:35,664 [salt.state       :300 ][INFO    ][14870] {'pid': 14892, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-24 09:10:35,667 [salt.state       :1951][INFO    ][14870] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:10:35.667341 duration_in_ms=1888.778
2019-05-24 09:10:35,669 [salt.state       :1780][INFO    ][14870] Running state [maas.wait_for_machine_status] at time 09:10:35.669653
2019-05-24 09:10:35,670 [salt.state       :1813][INFO    ][14870] Executing state module.run for [maas.wait_for_machine_status]
2019-05-24 09:10:35,670 [salt.utils.decorators:613 ][WARNING ][14870] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-24 09:10:38,416 [salt.loaded.ext.module.maas:1023][INFO    ][14870] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.27098393s left)
2019-05-24 09:10:42,624 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524091042605058
2019-05-24 09:10:42,655 [salt.minion      :1432][INFO    ][14904] Starting a new job with PID 14904
2019-05-24 09:10:42,691 [salt.minion      :1711][INFO    ][14904] Returning information for job: 20190524091042605058
2019-05-24 09:11:11,171 [salt.loaded.ext.module.maas:1023][INFO    ][14870] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.51603699s left)
2019-05-24 09:11:12,738 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524091112677553
2019-05-24 09:11:12,759 [salt.minion      :1432][INFO    ][14952] Starting a new job with PID 14952
2019-05-24 09:11:12,804 [salt.minion      :1711][INFO    ][14952] Returning information for job: 20190524091112677553
2019-05-24 09:11:42,823 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524091142812159
2019-05-24 09:11:42,846 [salt.minion      :1432][INFO    ][14989] Starting a new job with PID 14989
2019-05-24 09:11:42,880 [salt.minion      :1711][INFO    ][14989] Returning information for job: 20190524091142812159
2019-05-24 09:11:44,132 [salt.loaded.ext.module.maas:1023][INFO    ][14870] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2181.55494094s left)
2019-05-24 09:12:12,912 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524091212901197
2019-05-24 09:12:12,937 [salt.minion      :1432][INFO    ][15043] Starting a new job with PID 15043
2019-05-24 09:12:12,969 [salt.minion      :1711][INFO    ][15043] Returning information for job: 20190524091212901197
2019-05-24 09:12:16,942 [salt.loaded.ext.module.maas:1023][INFO    ][14870] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2148.74433398s left)
2019-05-24 09:12:43,014 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524091242995324
2019-05-24 09:12:43,041 [salt.minion      :1432][INFO    ][15073] Starting a new job with PID 15073
2019-05-24 09:12:43,075 [salt.minion      :1711][INFO    ][15073] Returning information for job: 20190524091242995324
2019-05-24 09:12:49,987 [salt.loaded.ext.module.maas:1023][INFO    ][14870] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2115.69951797s left)
2019-05-24 09:13:13,108 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524091313094614
2019-05-24 09:13:13,132 [salt.minion      :1432][INFO    ][15127] Starting a new job with PID 15127
2019-05-24 09:13:13,162 [salt.minion      :1711][INFO    ][15127] Returning information for job: 20190524091313094614
2019-05-24 09:13:22,721 [salt.loaded.ext.module.maas:1023][INFO    ][14870] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2082.96523404s left)
2019-05-24 09:13:43,204 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524091343188878
2019-05-24 09:13:43,235 [salt.minion      :1432][INFO    ][15157] Starting a new job with PID 15157
2019-05-24 09:13:43,270 [salt.minion      :1711][INFO    ][15157] Returning information for job: 20190524091343188878
2019-05-24 09:13:55,720 [salt.loaded.ext.module.maas:1023][INFO    ][14870] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2049.9662149s left)
2019-05-24 09:14:13,319 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524091413305966
2019-05-24 09:14:13,338 [salt.minion      :1432][INFO    ][15202] Starting a new job with PID 15202
2019-05-24 09:14:13,366 [salt.minion      :1711][INFO    ][15202] Returning information for job: 20190524091413305966
2019-05-24 09:14:28,580 [salt.loaded.ext.module.maas:1023][INFO    ][14870] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2017.106704s left)
2019-05-24 09:14:43,384 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524091443372163
2019-05-24 09:14:43,402 [salt.minion      :1432][INFO    ][15262] Starting a new job with PID 15262
2019-05-24 09:14:43,432 [salt.minion      :1711][INFO    ][15262] Returning information for job: 20190524091443372163
2019-05-24 09:15:01,344 [salt.loaded.ext.module.maas:1023][INFO    ][14870] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1984.34206295s left)
2019-05-24 09:15:13,421 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524091513408041
2019-05-24 09:15:13,444 [salt.minion      :1432][INFO    ][15312] Starting a new job with PID 15312
2019-05-24 09:15:13,474 [salt.minion      :1711][INFO    ][15312] Returning information for job: 20190524091513408041
2019-05-24 09:15:34,114 [salt.loaded.ext.module.maas:1023][INFO    ][14870] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1951.57251906s left)
2019-05-24 09:15:43,517 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524091543504600
2019-05-24 09:15:43,541 [salt.minion      :1432][INFO    ][15369] Starting a new job with PID 15369
2019-05-24 09:15:43,572 [salt.minion      :1711][INFO    ][15369] Returning information for job: 20190524091543504600
2019-05-24 09:16:06,960 [salt.loaded.ext.module.maas:1023][INFO    ][14870] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1918.72678399s left)
2019-05-24 09:16:13,647 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524091613636849
2019-05-24 09:16:13,666 [salt.minion      :1432][INFO    ][15529] Starting a new job with PID 15529
2019-05-24 09:16:13,698 [salt.minion      :1711][INFO    ][15529] Returning information for job: 20190524091613636849
2019-05-24 09:16:39,636 [salt.loaded.ext.module.maas:1023][INFO    ][14870] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1886.05012894s left)
2019-05-24 09:16:43,665 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524091643658715
2019-05-24 09:16:43,681 [salt.minion      :1432][INFO    ][15561] Starting a new job with PID 15561
2019-05-24 09:16:43,712 [salt.minion      :1711][INFO    ][15561] Returning information for job: 20190524091643658715
2019-05-24 09:17:12,462 [salt.loaded.ext.module.maas:1023][INFO    ][14870] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1853.22429299s left)
2019-05-24 09:17:13,784 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524091713770703
2019-05-24 09:17:13,806 [salt.minion      :1432][INFO    ][15658] Starting a new job with PID 15658
2019-05-24 09:17:13,837 [salt.minion      :1711][INFO    ][15658] Returning information for job: 20190524091713770703
2019-05-24 09:17:43,855 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524091743842908
2019-05-24 09:17:43,878 [salt.minion      :1432][INFO    ][15687] Starting a new job with PID 15687
2019-05-24 09:17:43,912 [salt.minion      :1711][INFO    ][15687] Returning information for job: 20190524091743842908
2019-05-24 09:17:45,328 [salt.loaded.ext.module.maas:1023][INFO    ][14870] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1820.35893512s left)
2019-05-24 09:18:14,016 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524091814001492
2019-05-24 09:18:14,039 [salt.minion      :1432][INFO    ][15802] Starting a new job with PID 15802
2019-05-24 09:18:14,073 [salt.minion      :1711][INFO    ][15802] Returning information for job: 20190524091814001492
2019-05-24 09:18:18,257 [salt.loaded.ext.module.maas:1023][INFO    ][14870] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1787.43006206s left)
2019-05-24 09:18:44,088 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524091844074344
2019-05-24 09:18:44,109 [salt.minion      :1432][INFO    ][15839] Starting a new job with PID 15839
2019-05-24 09:18:44,141 [salt.minion      :1711][INFO    ][15839] Returning information for job: 20190524091844074344
2019-05-24 09:18:51,169 [salt.loaded.ext.module.maas:1023][INFO    ][14870] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1754.51736903s left)
2019-05-24 09:19:14,253 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524091914243046
2019-05-24 09:19:14,284 [salt.minion      :1432][INFO    ][15968] Starting a new job with PID 15968
2019-05-24 09:19:14,323 [salt.minion      :1711][INFO    ][15968] Returning information for job: 20190524091914243046
2019-05-24 09:19:24,035 [salt.loaded.ext.module.maas:1023][INFO    ][14870] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1721.65191507s left)
2019-05-24 09:19:44,431 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524091944419480
2019-05-24 09:19:44,458 [salt.minion      :1432][INFO    ][15996] Starting a new job with PID 15996
2019-05-24 09:19:44,495 [salt.minion      :1711][INFO    ][15996] Returning information for job: 20190524091944419480
2019-05-24 09:19:57,018 [salt.loaded.ext.module.maas:1023][INFO    ][14870] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1688.66817808s left)
2019-05-24 09:20:14,626 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524092014612939
2019-05-24 09:20:14,650 [salt.minion      :1432][INFO    ][16042] Starting a new job with PID 16042
2019-05-24 09:20:14,686 [salt.minion      :1711][INFO    ][16042] Returning information for job: 20190524092014612939
2019-05-24 09:20:30,032 [salt.loaded.ext.module.maas:1023][INFO    ][14870] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1655.6545949s left)
2019-05-24 09:20:44,819 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524092044802741
2019-05-24 09:20:44,849 [salt.minion      :1432][INFO    ][16070] Starting a new job with PID 16070
2019-05-24 09:20:44,879 [salt.minion      :1711][INFO    ][16070] Returning information for job: 20190524092044802741
2019-05-24 09:21:03,016 [salt.loaded.ext.module.maas:1023][INFO    ][14870] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1622.6704731s left)
2019-05-24 09:21:15,010 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524092114995500
2019-05-24 09:21:15,040 [salt.minion      :1432][INFO    ][16117] Starting a new job with PID 16117
2019-05-24 09:21:15,071 [salt.minion      :1711][INFO    ][16117] Returning information for job: 20190524092114995500
2019-05-24 09:21:35,969 [salt.loaded.ext.module.maas:1023][INFO    ][14870] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1589.7176671s left)
2019-05-24 09:21:45,199 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524092145187938
2019-05-24 09:21:45,228 [salt.minion      :1432][INFO    ][16159] Starting a new job with PID 16159
2019-05-24 09:21:45,258 [salt.minion      :1711][INFO    ][16159] Returning information for job: 20190524092145187938
2019-05-24 09:22:08,813 [salt.loaded.ext.module.maas:1023][INFO    ][14870] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1556.87350392s left)
2019-05-24 09:22:15,391 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524092215376627
2019-05-24 09:22:15,420 [salt.minion      :1432][INFO    ][16354] Starting a new job with PID 16354
2019-05-24 09:22:15,450 [salt.minion      :1711][INFO    ][16354] Returning information for job: 20190524092215376627
2019-05-24 09:22:41,872 [salt.loaded.ext.module.maas:1023][INFO    ][14870] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1523.8148241s left)
2019-05-24 09:22:45,595 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190524092245580790
2019-05-24 09:22:45,624 [salt.minion      :1432][INFO    ][16403] Starting a new job with PID 16403
2019-05-24 09:22:45,660 [salt.minion      :1711][INFO    ][16403] Returning information for job: 20190524092245580790
2019-05-24 09:22:57,899 [salt.utils.schedule:1377][INFO    ][6035] Running scheduled job: __mine_interval
2019-05-24 09:23:14,767 [salt.state       :300 ][INFO    ][14870] {'ret': True}
2019-05-24 09:23:14,767 [salt.state       :1951][INFO    ][14870] Completed state [maas.wait_for_machine_status] at time 09:23:14.767772 duration_in_ms=759098.112
2019-05-24 09:23:14,776 [salt.minion      :1711][INFO    ][14870] Returning information for job: 20190524091027577256
2019-05-24 10:22:57,899 [salt.utils.schedule:1377][INFO    ][6035] Running scheduled job: __mine_interval
2019-05-24 10:43:56,133 [salt.minion      :1308][INFO    ][6035] User sudo_ubuntu Executing command cp.push_dir with jid 20190524104356120067
2019-05-24 10:43:56,158 [salt.minion      :1432][INFO    ][22112] Starting a new job with PID 22112
