2019-04-11 08:33:15,640 [salt.utils.decorators:613 ][WARNING ][2280] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-11 08:33:16,676 [salt.utils.decorators:613 ][WARNING ][2280] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-11 08:33:20,776 [salt.loaded.int.states.file:2298][WARNING ][2516] 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-04-11 08:33:41,901 [salt.state       :2022][WARNING ][2764] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-04-11 08:33:44,952 [salt.utils.decorators:613 ][WARNING ][2764] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-11 08:33:58,064 [salt.utils.decorators:613 ][WARNING ][2764] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-11 08:34:20,485 [salt.utils.decorators:613 ][WARNING ][2764] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-11 08:34:21,393 [salt.utils.decorators:613 ][WARNING ][2764] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-11 08:34:23,430 [salt.loaded.ext.module.maasng:1008][WARNING ][2764] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-04-11 08:34:23,431 [salt.loaded.ext.module.maasng:1011][WARNING ][2764] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-04-11 08:34:24,042 [salt.loaded.ext.module.maasng:1235][WARNING ][2764] Ignoring parameter vlan:0
2019-04-11 08:34:24,944 [salt.utils.decorators:613 ][WARNING ][2764] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-11 08:34:36,797 [salt.utils.decorators:613 ][WARNING ][4930] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-11 08:34:36,850 [salt.loaded.ext.module.maas:412 ][WARNING ][4930] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-11 08:34:38,269 [salt.loaded.ext.module.maas:412 ][WARNING ][4930] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-11 08:34:39,525 [salt.loaded.ext.module.maas:412 ][WARNING ][4930] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-11 08:34:40,614 [salt.loaded.ext.module.maas:412 ][WARNING ][4930] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-11 08:34:41,734 [salt.loaded.ext.module.maas:412 ][WARNING ][4930] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-11 08:34:47,144 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5541] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-04-11 08:34:47,178 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5541] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-04-11 08:34:47,221 [salt.utils.parsers:1051][WARNING ][387] Minion received a SIGTERM. Exiting.
2019-04-11 08:34:48,385 [salt.cli.daemons :293 ][INFO    ][5625] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-04-11 08:34:48,554 [salt.cli.daemons :82  ][INFO    ][5625] Starting up the Salt Minion
2019-04-11 08:34:48,554 [salt.utils.event :1017][INFO    ][5625] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-04-11 08:34:49,831 [salt.minion      :976 ][INFO    ][5625] Creating minion process manager
2019-04-11 08:34:51,950 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][5625] Executing command ['date', '+%z'] in directory '/root'
2019-04-11 08:34:51,979 [salt.utils.schedule:568 ][INFO    ][5625] Updating job settings for scheduled job: __mine_interval
2019-04-11 08:34:51,981 [salt.minion      :1108][INFO    ][5625] Added mine.update to scheduler
2019-04-11 08:34:51,985 [salt.minion      :1975][INFO    ][5625] Minion is starting as user 'root'
2019-04-11 08:34:52,003 [salt.minion      :2336][INFO    ][5625] Minion is ready to receive requests!
2019-04-11 08:35:14,248 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command state.apply with jid 20190411083514236429
2019-04-11 08:35:14,268 [salt.minion      :1432][INFO    ][5714] Starting a new job with PID 5714
2019-04-11 08:35:22,265 [salt.state       :915 ][INFO    ][5714] Loading fresh modules for state activity
2019-04-11 08:35:22,328 [salt.fileclient  :1219][INFO    ][5714] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-04-11 08:35:22,379 [salt.state       :1780][INFO    ][5714] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:35:22.379808
2019-04-11 08:35:22,380 [salt.state       :1813][INFO    ][5714] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-11 08:35:22,382 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5714] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-11 08:35:24,320 [salt.state       :300 ][INFO    ][5714] {'pid': 5722, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-11 08:35:24,321 [salt.state       :1951][INFO    ][5714] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:35:24.321468 duration_in_ms=1941.659
2019-04-11 08:35:24,326 [salt.state       :1780][INFO    ][5714] Running state [maas.wait_for_machine_status] at time 08:35:24.326102
2019-04-11 08:35:24,328 [salt.state       :1813][INFO    ][5714] Executing state module.run for [maas.wait_for_machine_status]
2019-04-11 08:35:24,329 [salt.utils.decorators:613 ][WARNING ][5714] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-11 08:35:25,132 [salt.loaded.ext.module.maas:1023][INFO    ][5714] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.20932293s left)
2019-04-11 08:35:29,350 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411083529338798
2019-04-11 08:35:29,373 [salt.minion      :1432][INFO    ][5760] Starting a new job with PID 5760
2019-04-11 08:35:29,402 [salt.minion      :1711][INFO    ][5760] Returning information for job: 20190411083529338798
2019-04-11 08:35:55,961 [salt.loaded.ext.module.maas:1023][INFO    ][5714] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.37950993s left)
2019-04-11 08:35:59,448 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411083559431415
2019-04-11 08:35:59,468 [salt.minion      :1432][INFO    ][5820] Starting a new job with PID 5820
2019-04-11 08:35:59,495 [salt.minion      :1711][INFO    ][5820] Returning information for job: 20190411083559431415
2019-04-11 08:36:26,771 [salt.loaded.ext.module.maas:1023][INFO    ][5714] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.57044005s left)
2019-04-11 08:36:29,526 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411083629509154
2019-04-11 08:36:29,546 [salt.minion      :1432][INFO    ][5859] Starting a new job with PID 5859
2019-04-11 08:36:29,576 [salt.minion      :1711][INFO    ][5859] Returning information for job: 20190411083629509154
2019-04-11 08:36:57,598 [salt.loaded.ext.module.maas:1023][INFO    ][5714] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.74302793s left)
2019-04-11 08:36:59,725 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411083659713834
2019-04-11 08:36:59,745 [salt.minion      :1432][INFO    ][5921] Starting a new job with PID 5921
2019-04-11 08:36:59,778 [salt.minion      :1711][INFO    ][5921] Returning information for job: 20190411083659713834
2019-04-11 08:37:28,724 [salt.loaded.ext.module.maas:1023][INFO    ][5714] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.61633611s left)
2019-04-11 08:37:29,950 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411083729935190
2019-04-11 08:37:29,971 [salt.minion      :1432][INFO    ][5988] Starting a new job with PID 5988
2019-04-11 08:37:29,997 [salt.minion      :1711][INFO    ][5988] Returning information for job: 20190411083729935190
2019-04-11 08:37:59,810 [salt.loaded.ext.module.maas:1023][INFO    ][5714] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.53056502s left)
2019-04-11 08:37:59,985 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411083759971835
2019-04-11 08:38:00,002 [salt.minion      :1432][INFO    ][6159] Starting a new job with PID 6159
2019-04-11 08:38:00,033 [salt.minion      :1711][INFO    ][6159] Returning information for job: 20190411083759971835
2019-04-11 08:38:30,205 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411083830194515
2019-04-11 08:38:30,224 [salt.minion      :1432][INFO    ][6246] Starting a new job with PID 6246
2019-04-11 08:38:30,251 [salt.minion      :1711][INFO    ][6246] Returning information for job: 20190411083830194515
2019-04-11 08:38:30,953 [salt.loaded.ext.module.maas:1023][INFO    ][5714] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1313.38829494s left)
2019-04-11 08:39:00,242 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411083900235071
2019-04-11 08:39:00,254 [salt.minion      :1432][INFO    ][6581] Starting a new job with PID 6581
2019-04-11 08:39:00,283 [salt.minion      :1711][INFO    ][6581] Returning information for job: 20190411083900235071
2019-04-11 08:39:02,177 [salt.loaded.ext.module.maas:1023][INFO    ][5714] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1282.16382098s left)
2019-04-11 08:39:30,284 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411083930271316
2019-04-11 08:39:30,302 [salt.minion      :1432][INFO    ][6699] Starting a new job with PID 6699
2019-04-11 08:39:30,331 [salt.minion      :1711][INFO    ][6699] Returning information for job: 20190411083930271316
2019-04-11 08:39:34,044 [salt.loaded.ext.module.maas:1023][INFO    ][5714] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1250.2969141s left)
2019-04-11 08:40:00,402 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411084000390376
2019-04-11 08:40:00,426 [salt.minion      :1432][INFO    ][7056] Starting a new job with PID 7056
2019-04-11 08:40:00,456 [salt.minion      :1711][INFO    ][7056] Returning information for job: 20190411084000390376
2019-04-11 08:40:05,892 [salt.loaded.ext.module.maas:1023][INFO    ][5714] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1218.44908309s left)
2019-04-11 08:40:30,624 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411084030612485
2019-04-11 08:40:30,646 [salt.minion      :1432][INFO    ][7258] Starting a new job with PID 7258
2019-04-11 08:40:30,674 [salt.minion      :1711][INFO    ][7258] Returning information for job: 20190411084030612485
2019-04-11 08:40:37,883 [salt.loaded.ext.module.maas:1023][INFO    ][5714] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1186.45781302s left)
2019-04-11 08:41:00,752 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411084100741495
2019-04-11 08:41:00,768 [salt.minion      :1432][INFO    ][7469] Starting a new job with PID 7469
2019-04-11 08:41:00,798 [salt.minion      :1711][INFO    ][7469] Returning information for job: 20190411084100741495
2019-04-11 08:41:09,811 [salt.loaded.ext.module.maas:1023][INFO    ][5714] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1154.52962804s left)
2019-04-11 08:41:30,834 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411084130825549
2019-04-11 08:41:30,858 [salt.minion      :1432][INFO    ][7635] Starting a new job with PID 7635
2019-04-11 08:41:30,886 [salt.minion      :1711][INFO    ][7635] Returning information for job: 20190411084130825549
2019-04-11 08:41:42,943 [salt.state       :300 ][INFO    ][5714] {'ret': True}
2019-04-11 08:41:42,943 [salt.state       :1951][INFO    ][5714] Completed state [maas.wait_for_machine_status] at time 08:41:42.943558 duration_in_ms=378617.456
2019-04-11 08:41:42,947 [salt.minion      :1711][INFO    ][5714] Returning information for job: 20190411083514236429
2019-04-11 08:41:43,641 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command state.apply with jid 20190411084143631519
2019-04-11 08:41:43,658 [salt.minion      :1432][INFO    ][7677] Starting a new job with PID 7677
2019-04-11 08:41:51,907 [salt.state       :915 ][INFO    ][7677] Loading fresh modules for state activity
2019-04-11 08:41:51,970 [salt.fileclient  :1219][INFO    ][7677] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-04-11 08:41:52,073 [salt.state       :1780][INFO    ][7677] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:41:52.073733
2019-04-11 08:41:52,074 [salt.state       :1813][INFO    ][7677] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-11 08:41:52,076 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7677] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-11 08:41:54,023 [salt.state       :300 ][INFO    ][7677] {'pid': 7766, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-11 08:41:54,024 [salt.state       :1951][INFO    ][7677] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:41:54.024362 duration_in_ms=1950.628
2019-04-11 08:41:54,026 [salt.state       :1780][INFO    ][7677] Running state [maas_machines_storage_cmp002_lvm] at time 08:41:54.026312
2019-04-11 08:41:54,026 [salt.state       :1813][INFO    ][7677] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-04-11 08:41:55,142 [salt.loaded.ext.module.maasng:610 ][INFO    ][7677] m6tfqs
2019-04-11 08:41:55,142 [salt.loaded.ext.module.maasng:626 ][INFO    ][7677] sda
2019-04-11 08:41:55,726 [salt.loaded.ext.module.maasng:361 ][INFO    ][7677] m6tfqs
2019-04-11 08:41:55,825 [salt.loaded.ext.module.maasng:367 ][INFO    ][7677] [{u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/m6tfqs/blockdevices/1/', u'uuid': None, u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'3a811174-576a-4b0b-aa41-7c477d77cbab', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'm6tfqs', u'device_id': 1, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'9e788930-e259-4040-b9c8-fb308ce9cc60', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/m6tfqs/blockdevices/1/partition/1'}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'm6tfqs', 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'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'name': u'sda'}, {u'size': 800097042432, u'resource_uri': u'/MAAS/api/2.0/nodes/m6tfqs/blockdevices/3/', u'uuid': u'56d7d768-64b1-43a5-881a-e66257d3df17', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'87244191-2ccc-44ea-a066-266e2302efc6', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'm6tfqs', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'used_size': 800097042432, u'id': 3, u'serial': None, u'name': u'vgroot-lvroot'}]
2019-04-11 08:41:55,825 [salt.loaded.ext.module.maasng:632 ][INFO    ][7677] vgroot
2019-04-11 08:41:55,826 [salt.loaded.ext.module.maasng:635 ][INFO    ][7677] lvroot
2019-04-11 08:41:55,826 [salt.loaded.ext.module.maasng:639 ][INFO    ][7677] 107374182400
2019-04-11 08:41:56,410 [salt.loaded.ext.module.maasng:645 ][INFO    ][7677] {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'min_hwe_kernel': u'hwe-16.04', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'description': u''}, u'resource_uri': u'/MAAS/api/2.0/machines/m6tfqs/', 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'm6tfqs', 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'owner_data': {}, u'blockdevice_set': [{u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/m6tfqs/blockdevices/1/', u'uuid': None, u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'435df5a2-6445-4f1f-9d39-89d87c1cc50b', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'm6tfqs', u'device_id': 1, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'dfc0e78b-8393-4756-b045-6616e8f10961', 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'resource_uri': u'/MAAS/api/2.0/nodes/m6tfqs/blockdevices/1/partition/6'}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'm6tfqs', 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'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'name': u'sda'}, {u'size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/m6tfqs/blockdevices/11/', u'uuid': u'5d981394-1f12-4bd1-9726-7d24e68806e5', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'908d8343-b446-4375-93c0-05a130d7def4', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'm6tfqs', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 11, u'serial': None, u'name': u'vgroot-lvroot'}], u'status': 4, u'bcaches': [], u'cpu_count': 40, u'raids': [], u'physicalblockdevice_set': [{u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/m6tfqs/blockdevices/1/', u'uuid': None, u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'435df5a2-6445-4f1f-9d39-89d87c1cc50b', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'm6tfqs', u'device_id': 1, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'dfc0e78b-8393-4756-b045-6616e8f10961', 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'resource_uri': u'/MAAS/api/2.0/nodes/m6tfqs/blockdevices/1/partition/6'}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'm6tfqs', 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'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'name': u'sda'}], u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'm6tfqs', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/m6tfqs/blockdevices/1/', u'uuid': None, u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'435df5a2-6445-4f1f-9d39-89d87c1cc50b', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'm6tfqs', u'device_id': 1, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'dfc0e78b-8393-4756-b045-6616e8f10961', 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'resource_uri': u'/MAAS/api/2.0/nodes/m6tfqs/blockdevices/1/partition/6'}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'm6tfqs', 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'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'name': u'sda'}, u'current_testing_result_id': 3, u'cpu_test_status': -1, u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'node_type': 0, u'virtualblockdevice_set': [{u'size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/m6tfqs/blockdevices/11/', u'uuid': u'5d981394-1f12-4bd1-9726-7d24e68806e5', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'908d8343-b446-4375-93c0-05a130d7def4', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'm6tfqs', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 11, u'serial': None, u'name': u'vgroot-lvroot'}], u'commissioning_status': 2, u'architecture': u'amd64/generic', 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'primary_rack': u'n3f7kf', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 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': 19}], u'tags': [u'sriov'], u'enabled': True, 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'primary_rack': u'n3f7kf', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, 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'primary_rack': u'n3f7kf', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'system_id': u'm6tfqs', u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/m6tfqs/interfaces/4/'}, 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'primary_rack': u'n3f7kf', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 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': 19}], u'tags': [u'sriov'], u'enabled': True, 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'primary_rack': u'n3f7kf', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, 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'primary_rack': u'n3f7kf', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'system_id': u'm6tfqs', u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/m6tfqs/interfaces/4/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'vlan': None, u'effective_mtu': 1500, u'id': 13, u'discovered': None, u'system_id': u'm6tfqs', u'params': u'', u'mac_address': u'38:ea:a7:8f:07:50', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/m6tfqs/interfaces/13/'}, {u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 20}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'effective_mtu': 1500, u'id': 14, u'discovered': None, u'system_id': u'm6tfqs', u'params': u'', u'mac_address': u'38:ea:a7:8f:12:48', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/m6tfqs/interfaces/14/'}, {u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 21}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'effective_mtu': 1500, u'id': 15, u'discovered': None, u'system_id': u'm6tfqs', u'params': u'', u'mac_address': u'38:ea:a7:8f:12:49', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/m6tfqs/interfaces/15/'}, {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'primary_rack': u'n3f7kf', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'mode': u'link_up', u'id': 22}], u'tags': [u'sriov'], u'enabled': True, 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'primary_rack': u'n3f7kf', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'effective_mtu': 1500, u'id': 12, 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'primary_rack': u'n3f7kf', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.40'}], u'system_id': u'm6tfqs', u'params': u'', u'mac_address': u'9c:b6:54:8a:10:1c', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/m6tfqs/interfaces/12/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'vlan': None, u'effective_mtu': 1500, u'id': 11, u'discovered': None, u'system_id': u'm6tfqs', u'params': u'', u'mac_address': u'38:ea:a7:8f:07:51', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/m6tfqs/interfaces/11/'}], u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2019-04-11 08:41:56,412 [salt.state       :300 ][INFO    ][7677] {'new': {'storage_layout': 'lvm'}}
2019-04-11 08:41:56,412 [salt.state       :1951][INFO    ][7677] Completed state [maas_machines_storage_cmp002_lvm] at time 08:41:56.412548 duration_in_ms=2386.235
2019-04-11 08:41:56,412 [salt.state       :1780][INFO    ][7677] Running state [maas_machines_storage_cmp001_lvm] at time 08:41:56.412929
2019-04-11 08:41:56,413 [salt.state       :1813][INFO    ][7677] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-04-11 08:41:57,585 [salt.loaded.ext.module.maasng:610 ][INFO    ][7677] e6rxkg
2019-04-11 08:41:57,585 [salt.loaded.ext.module.maasng:626 ][INFO    ][7677] sda
2019-04-11 08:41:58,101 [salt.loaded.ext.module.maasng:361 ][INFO    ][7677] e6rxkg
2019-04-11 08:41:58,184 [salt.loaded.ext.module.maasng:367 ][INFO    ][7677] [{u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/e6rxkg/blockdevices/2/', u'uuid': None, u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'1f9ea3f0-2d3c-4223-90bd-3ce9f9001c53', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'e6rxkg', u'device_id': 2, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'5bbcf560-ee46-4975-9bdf-ba11d461dde1', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/e6rxkg/blockdevices/2/partition/2'}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'e6rxkg', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', 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'resource_uri': u'/MAAS/api/2.0/nodes/e6rxkg/blockdevices/4/', u'uuid': u'9efe36b5-a454-4185-9069-f780836cde68', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'65d84cc8-4080-447b-b34c-f77e347d9de4', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'e6rxkg', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'used_size': 800097042432, u'id': 4, u'serial': None, u'name': u'vgroot-lvroot'}]
2019-04-11 08:41:58,184 [salt.loaded.ext.module.maasng:632 ][INFO    ][7677] vgroot
2019-04-11 08:41:58,184 [salt.loaded.ext.module.maasng:635 ][INFO    ][7677] lvroot
2019-04-11 08:41:58,184 [salt.loaded.ext.module.maasng:639 ][INFO    ][7677] 107374182400
2019-04-11 08:41:58,669 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411084158660765
2019-04-11 08:41:58,689 [salt.minion      :1432][INFO    ][7790] Starting a new job with PID 7790
2019-04-11 08:41:58,717 [salt.minion      :1711][INFO    ][7790] Returning information for job: 20190411084158660765
2019-04-11 08:41:58,829 [salt.loaded.ext.module.maasng:645 ][INFO    ][7677] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'boot_interface': {u'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'primary_rack': u'n3f7kf', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'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'id': 25, u'mode': u'dhcp'}], 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'primary_rack': u'n3f7kf', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'mac_address': u'9c:b6:54:8a:95:a0', u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'n3f7kf', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'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'parents': [], u'system_id': u'e6rxkg', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/e6rxkg/interfaces/5/'}, 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'block_size': 4096, u'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/e6rxkg/blockdevices/2/', u'used_size': 800106479616, u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'e6rxkg', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'8ea9212e-fb79-4c5c-b08d-98f0893aa41e', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'e6rxkg', u'device_id': 2, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'6d050851-950a-4b03-a255-09078a92fb15', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/e6rxkg/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}], u'type': u'physical', u'id': 2, u'tags': [u'ssd'], u'size': 800109715456}, 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/e6rxkg/', u'current_commissioning_result_id': 4, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'e6rxkg', u'raids': [], 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.39', u'192.168.11.44'], u'blockdevice_set': [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/e6rxkg/blockdevices/2/', u'system_id': u'e6rxkg', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'8ea9212e-fb79-4c5c-b08d-98f0893aa41e', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'e6rxkg', u'device_id': 2, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'6d050851-950a-4b03-a255-09078a92fb15', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/e6rxkg/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}], u'type': u'physical', u'id': 2, u'size': 800109715456}, {u'model': None, u'block_size': 4096, u'uuid': u'618db97c-ab4e-40bb-a1ec-9dedb6aacef1', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'594730f8-9146-4979-befc-a6a568235716', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'resource_uri': u'/MAAS/api/2.0/nodes/e6rxkg/blockdevices/12/', u'system_id': u'e6rxkg', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'partitions': [], u'type': u'virtual', u'id': 12, u'size': 107374182400}], u'status': 4, u'storage_test_status': 2, u'cpu_count': 40, u'power_state': u'off', u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'e6rxkg', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'memory_test_status_name': u'Unknown', u'current_testing_result_id': 5, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'bcaches': [], u'status_name': u'Ready', u'physicalblockdevice_set': [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/e6rxkg/blockdevices/2/', u'used_size': 800106479616, u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'e6rxkg', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'8ea9212e-fb79-4c5c-b08d-98f0893aa41e', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'e6rxkg', u'device_id': 2, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'6d050851-950a-4b03-a255-09078a92fb15', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/e6rxkg/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}], u'type': u'physical', u'id': 2, u'tags': [u'ssd'], u'size': 800109715456}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'node_type': 0, u'virtualblockdevice_set': [{u'model': None, u'block_size': 4096, u'uuid': u'618db97c-ab4e-40bb-a1ec-9dedb6aacef1', u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/e6rxkg/blockdevices/12/', u'used_size': 107374182400, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'594730f8-9146-4979-befc-a6a568235716', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'e6rxkg', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'partitions': [], u'type': u'virtual', u'id': 12, u'tags': [], u'size': 107374182400}], u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'commissioning_status_name': u'Passed', u'interface_set': [{u'name': u'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'primary_rack': u'n3f7kf', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'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'id': 25, u'mode': u'dhcp'}], 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'primary_rack': u'n3f7kf', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'mac_address': u'9c:b6:54:8a:95:a0', u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'n3f7kf', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'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'parents': [], u'system_id': u'e6rxkg', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/e6rxkg/interfaces/5/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'mac_address': u'38:ea:a7:8f:1f:d5', u'children': [], u'discovered': None, u'parents': [], u'system_id': u'e6rxkg', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/e6rxkg/interfaces/16/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'mac_address': u'38:ea:a7:8f:1f:d4', u'children': [], u'discovered': None, u'parents': [], u'system_id': u'e6rxkg', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/e6rxkg/interfaces/18/'}, {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'primary_rack': u'n3f7kf', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'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'id': 26, u'mode': u'link_up'}], 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'primary_rack': u'n3f7kf', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'mac_address': u'9c:b6:54:8a:95:a4', u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'n3f7kf', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'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'parents': [], u'system_id': u'e6rxkg', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/e6rxkg/interfaces/17/'}, {u'name': u'ens2f0', u'links': [{u'id': 27, u'mode': u'link_up'}], 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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'mac_address': u'38:ea:a7:8f:52:cc', u'children': [], u'discovered': None, u'parents': [], u'system_id': u'e6rxkg', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/e6rxkg/interfaces/19/'}, {u'name': u'ens2f1', u'links': [{u'id': 28, u'mode': u'link_up'}], 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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'mac_address': u'38:ea:a7:8f:52:cd', u'children': [], u'discovered': None, u'parents': [], u'system_id': u'e6rxkg', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/e6rxkg/interfaces/20/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-04-11 08:41:58,831 [salt.state       :300 ][INFO    ][7677] {'new': {'storage_layout': 'lvm'}}
2019-04-11 08:41:58,831 [salt.state       :1951][INFO    ][7677] Completed state [maas_machines_storage_cmp001_lvm] at time 08:41:58.831616 duration_in_ms=2418.686
2019-04-11 08:41:58,834 [salt.minion      :1711][INFO    ][7677] Returning information for job: 20190411084143631519
2019-04-11 08:41:59,529 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command state.apply with jid 20190411084159517267
2019-04-11 08:41:59,551 [salt.minion      :1432][INFO    ][7797] Starting a new job with PID 7797
2019-04-11 08:42:00,844 [salt.state       :915 ][INFO    ][7797] Loading fresh modules for state activity
2019-04-11 08:42:00,901 [salt.fileclient  :1219][INFO    ][7797] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-04-11 08:42:00,953 [salt.state       :1780][INFO    ][7797] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:42:00.953114
2019-04-11 08:42:00,953 [salt.state       :1813][INFO    ][7797] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-11 08:42:00,955 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7797] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-11 08:42:02,885 [salt.state       :300 ][INFO    ][7797] {'pid': 7804, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-11 08:42:02,886 [salt.state       :1951][INFO    ][7797] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:42:02.886626 duration_in_ms=1933.511
2019-04-11 08:42:02,890 [salt.state       :1780][INFO    ][7797] Running state [maas.deploy_machines] at time 08:42:02.889906
2019-04-11 08:42:02,890 [salt.state       :1813][INFO    ][7797] Executing state module.run for [maas.deploy_machines]
2019-04-11 08:42:02,892 [salt.utils.decorators:613 ][WARNING ][7797] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-11 08:42:03,464 [salt.loaded.ext.module.maas:684 ][INFO    ][7797] deploymachines hwe_kernel=hwe-16.04 system_id=m6tfqs distro_series=xenial
2019-04-11 08:42:05,832 [salt.loaded.ext.module.maas:684 ][INFO    ][7797] deploymachines hwe_kernel=hwe-16.04 system_id=e6rxkg distro_series=xenial
2019-04-11 08:42:08,137 [salt.loaded.ext.module.maas:684 ][INFO    ][7797] deploymachines hwe_kernel=hwe-16.04 system_id=ahatch distro_series=xenial
2019-04-11 08:42:10,483 [salt.loaded.ext.module.maas:684 ][INFO    ][7797] deploymachines hwe_kernel=hwe-16.04 system_id=wkkhc6 distro_series=xenial
2019-04-11 08:42:12,806 [salt.loaded.ext.module.maas:684 ][INFO    ][7797] deploymachines hwe_kernel=hwe-16.04 system_id=8nwcma distro_series=xenial
2019-04-11 08:42:14,564 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411084214554621
2019-04-11 08:42:14,579 [salt.minion      :1432][INFO    ][8061] Starting a new job with PID 8061
2019-04-11 08:42:14,610 [salt.minion      :1711][INFO    ][8061] Returning information for job: 20190411084214554621
2019-04-11 08:42:14,997 [salt.state       :300 ][INFO    ][7797] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-04-11 08:42:14,997 [salt.state       :1951][INFO    ][7797] Completed state [maas.deploy_machines] at time 08:42:14.997660 duration_in_ms=12107.755
2019-04-11 08:42:15,000 [salt.minion      :1711][INFO    ][7797] Returning information for job: 20190411084159517267
2019-04-11 08:42:15,672 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command state.apply with jid 20190411084215665227
2019-04-11 08:42:15,686 [salt.minion      :1432][INFO    ][8080] Starting a new job with PID 8080
2019-04-11 08:42:23,857 [salt.state       :915 ][INFO    ][8080] Loading fresh modules for state activity
2019-04-11 08:42:23,920 [salt.fileclient  :1219][INFO    ][8080] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-04-11 08:42:23,969 [salt.state       :1780][INFO    ][8080] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:42:23.969211
2019-04-11 08:42:23,969 [salt.state       :1813][INFO    ][8080] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-11 08:42:23,971 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8080] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-11 08:42:25,944 [salt.state       :300 ][INFO    ][8080] {'pid': 8091, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-11 08:42:25,945 [salt.state       :1951][INFO    ][8080] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:42:25.945391 duration_in_ms=1976.18
2019-04-11 08:42:25,953 [salt.state       :1780][INFO    ][8080] Running state [maas.wait_for_machine_status] at time 08:42:25.953208
2019-04-11 08:42:25,953 [salt.state       :1813][INFO    ][8080] Executing state module.run for [maas.wait_for_machine_status]
2019-04-11 08:42:25,954 [salt.utils.decorators:613 ][WARNING ][8080] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-11 08:42:28,885 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.08559299s left)
2019-04-11 08:42:30,709 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411084230702190
2019-04-11 08:42:30,728 [salt.minion      :1432][INFO    ][8128] Starting a new job with PID 8128
2019-04-11 08:42:30,755 [salt.minion      :1711][INFO    ][8128] Returning information for job: 20190411084230702190
2019-04-11 08:43:00,910 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411084300901929
2019-04-11 08:43:00,925 [salt.minion      :1432][INFO    ][8188] Starting a new job with PID 8188
2019-04-11 08:43:00,956 [salt.minion      :1711][INFO    ][8188] Returning information for job: 20190411084300901929
2019-04-11 08:43:01,778 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.19272208s left)
2019-04-11 08:43:30,990 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411084330975772
2019-04-11 08:43:31,010 [salt.minion      :1432][INFO    ][8231] Starting a new job with PID 8231
2019-04-11 08:43:31,037 [salt.minion      :1711][INFO    ][8231] Returning information for job: 20190411084330975772
2019-04-11 08:43:34,554 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.41642094s left)
2019-04-11 08:44:01,052 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411084401038994
2019-04-11 08:44:01,074 [salt.minion      :1432][INFO    ][8303] Starting a new job with PID 8303
2019-04-11 08:44:01,100 [salt.minion      :1711][INFO    ][8303] Returning information for job: 20190411084401038994
2019-04-11 08:44:07,556 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2148.41424108s left)
2019-04-11 08:44:31,261 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411084431245619
2019-04-11 08:44:31,284 [salt.minion      :1432][INFO    ][8343] Starting a new job with PID 8343
2019-04-11 08:44:31,316 [salt.minion      :1711][INFO    ][8343] Returning information for job: 20190411084431245619
2019-04-11 08:44:40,112 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2115.85832095s left)
2019-04-11 08:45:01,333 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411084501324302
2019-04-11 08:45:01,355 [salt.minion      :1432][INFO    ][8427] Starting a new job with PID 8427
2019-04-11 08:45:01,381 [salt.minion      :1711][INFO    ][8427] Returning information for job: 20190411084501324302
2019-04-11 08:45:12,885 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2083.08502197s left)
2019-04-11 08:45:31,397 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411084531388106
2019-04-11 08:45:31,411 [salt.minion      :1432][INFO    ][8517] Starting a new job with PID 8517
2019-04-11 08:45:31,437 [salt.minion      :1711][INFO    ][8517] Returning information for job: 20190411084531388106
2019-04-11 08:45:46,370 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2049.6002059s left)
2019-04-11 08:46:01,518 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411084601501265
2019-04-11 08:46:01,537 [salt.minion      :1432][INFO    ][8806] Starting a new job with PID 8806
2019-04-11 08:46:01,565 [salt.minion      :1711][INFO    ][8806] Returning information for job: 20190411084601501265
2019-04-11 08:46:19,150 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2016.82030702s left)
2019-04-11 08:46:31,580 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411084631570515
2019-04-11 08:46:31,594 [salt.minion      :1432][INFO    ][8889] Starting a new job with PID 8889
2019-04-11 08:46:31,620 [salt.minion      :1711][INFO    ][8889] Returning information for job: 20190411084631570515
2019-04-11 08:46:51,854 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1984.11663389s left)
2019-04-11 08:47:01,678 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411084701662946
2019-04-11 08:47:01,699 [salt.minion      :1432][INFO    ][9128] Starting a new job with PID 9128
2019-04-11 08:47:01,727 [salt.minion      :1711][INFO    ][9128] Returning information for job: 20190411084701662946
2019-04-11 08:47:24,899 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1951.07144213s left)
2019-04-11 08:47:31,795 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411084731778360
2019-04-11 08:47:31,814 [salt.minion      :1432][INFO    ][9180] Starting a new job with PID 9180
2019-04-11 08:47:31,842 [salt.minion      :1711][INFO    ][9180] Returning information for job: 20190411084731778360
2019-04-11 08:47:58,061 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1917.90897703s left)
2019-04-11 08:48:01,943 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411084801924990
2019-04-11 08:48:01,966 [salt.minion      :1432][INFO    ][9576] Starting a new job with PID 9576
2019-04-11 08:48:01,998 [salt.minion      :1711][INFO    ][9576] Returning information for job: 20190411084801924990
2019-04-11 08:48:30,875 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1885.09542298s left)
2019-04-11 08:48:32,061 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411084832049188
2019-04-11 08:48:32,083 [salt.minion      :1432][INFO    ][9619] Starting a new job with PID 9619
2019-04-11 08:48:32,109 [salt.minion      :1711][INFO    ][9619] Returning information for job: 20190411084832049188
2019-04-11 08:49:02,177 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411084902166623
2019-04-11 08:49:02,194 [salt.minion      :1432][INFO    ][10001] Starting a new job with PID 10001
2019-04-11 08:49:02,226 [salt.minion      :1711][INFO    ][10001] Returning information for job: 20190411084902166623
2019-04-11 08:49:03,780 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1852.19081712s left)
2019-04-11 08:49:32,193 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411084932181936
2019-04-11 08:49:32,214 [salt.minion      :1432][INFO    ][10064] Starting a new job with PID 10064
2019-04-11 08:49:32,243 [salt.minion      :1711][INFO    ][10064] Returning information for job: 20190411084932181936
2019-04-11 08:49:36,722 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1819.24838495s left)
2019-04-11 08:50:02,370 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411085002351183
2019-04-11 08:50:02,388 [salt.minion      :1432][INFO    ][10476] Starting a new job with PID 10476
2019-04-11 08:50:02,419 [salt.minion      :1711][INFO    ][10476] Returning information for job: 20190411085002351183
2019-04-11 08:50:09,521 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1786.44927311s left)
2019-04-11 08:50:32,513 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411085032499713
2019-04-11 08:50:32,529 [salt.minion      :1432][INFO    ][10546] Starting a new job with PID 10546
2019-04-11 08:50:32,562 [salt.minion      :1711][INFO    ][10546] Returning information for job: 20190411085032499713
2019-04-11 08:50:42,275 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1753.695158s left)
2019-04-11 08:51:02,664 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411085102656361
2019-04-11 08:51:02,677 [salt.minion      :1432][INFO    ][10791] Starting a new job with PID 10791
2019-04-11 08:51:02,704 [salt.minion      :1711][INFO    ][10791] Returning information for job: 20190411085102656361
2019-04-11 08:51:15,083 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1720.8875761s left)
2019-04-11 08:51:32,754 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411085132739951
2019-04-11 08:51:32,769 [salt.minion      :1432][INFO    ][10871] Starting a new job with PID 10871
2019-04-11 08:51:32,797 [salt.minion      :1711][INFO    ][10871] Returning information for job: 20190411085132739951
2019-04-11 08:51:48,802 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1687.16866112s left)
2019-04-11 08:52:02,930 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411085202918050
2019-04-11 08:52:02,951 [salt.minion      :1432][INFO    ][11073] Starting a new job with PID 11073
2019-04-11 08:52:02,983 [salt.minion      :1711][INFO    ][11073] Returning information for job: 20190411085202918050
2019-04-11 08:52:21,788 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1654.18202209s left)
2019-04-11 08:52:32,984 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411085232968816
2019-04-11 08:52:33,006 [salt.minion      :1432][INFO    ][11138] Starting a new job with PID 11138
2019-04-11 08:52:33,033 [salt.minion      :1711][INFO    ][11138] Returning information for job: 20190411085232968816
2019-04-11 08:52:54,602 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1621.36877394s left)
2019-04-11 08:53:03,172 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411085303156152
2019-04-11 08:53:03,192 [salt.minion      :1432][INFO    ][11297] Starting a new job with PID 11297
2019-04-11 08:53:03,218 [salt.minion      :1711][INFO    ][11297] Returning information for job: 20190411085303156152
2019-04-11 08:53:27,534 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1588.43636894s left)
2019-04-11 08:53:33,317 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411085333300813
2019-04-11 08:53:33,336 [salt.minion      :1432][INFO    ][11472] Starting a new job with PID 11472
2019-04-11 08:53:33,367 [salt.minion      :1711][INFO    ][11472] Returning information for job: 20190411085333300813
2019-04-11 08:54:00,422 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1555.54877496s left)
2019-04-11 08:54:03,493 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411085403484423
2019-04-11 08:54:03,512 [salt.minion      :1432][INFO    ][11548] Starting a new job with PID 11548
2019-04-11 08:54:03,542 [salt.minion      :1711][INFO    ][11548] Returning information for job: 20190411085403484423
2019-04-11 08:54:33,269 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1522.70178795s left)
2019-04-11 08:54:33,657 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411085433646292
2019-04-11 08:54:33,679 [salt.minion      :1432][INFO    ][11589] Starting a new job with PID 11589
2019-04-11 08:54:33,707 [salt.minion      :1711][INFO    ][11589] Returning information for job: 20190411085433646292
2019-04-11 08:55:03,847 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411085503837954
2019-04-11 08:55:03,862 [salt.minion      :1432][INFO    ][11690] Starting a new job with PID 11690
2019-04-11 08:55:03,896 [salt.minion      :1711][INFO    ][11690] Returning information for job: 20190411085503837954
2019-04-11 08:55:06,175 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1489.79551792s left)
2019-04-11 08:55:33,965 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411085533956017
2019-04-11 08:55:33,984 [salt.minion      :1432][INFO    ][11790] Starting a new job with PID 11790
2019-04-11 08:55:34,015 [salt.minion      :1711][INFO    ][11790] Returning information for job: 20190411085533956017
2019-04-11 08:55:38,897 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1457.073843s left)
2019-04-11 08:56:04,002 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411085603986814
2019-04-11 08:56:04,028 [salt.minion      :1432][INFO    ][12053] Starting a new job with PID 12053
2019-04-11 08:56:04,057 [salt.minion      :1711][INFO    ][12053] Returning information for job: 20190411085603986814
2019-04-11 08:56:11,681 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1424.28989506s left)
2019-04-11 08:56:34,218 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411085634205444
2019-04-11 08:56:34,242 [salt.minion      :1432][INFO    ][12098] Starting a new job with PID 12098
2019-04-11 08:56:34,270 [salt.minion      :1711][INFO    ][12098] Returning information for job: 20190411085634205444
2019-04-11 08:56:44,399 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1391.5714221s left)
2019-04-11 08:57:04,243 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411085704235226
2019-04-11 08:57:04,259 [salt.minion      :1432][INFO    ][12158] Starting a new job with PID 12158
2019-04-11 08:57:04,289 [salt.minion      :1711][INFO    ][12158] Returning information for job: 20190411085704235226
2019-04-11 08:57:17,306 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1358.66476011s left)
2019-04-11 08:57:34,269 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411085734261988
2019-04-11 08:57:34,283 [salt.minion      :1432][INFO    ][12198] Starting a new job with PID 12198
2019-04-11 08:57:34,310 [salt.minion      :1711][INFO    ][12198] Returning information for job: 20190411085734261988
2019-04-11 08:57:50,279 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1325.6914711s left)
2019-04-11 08:58:04,289 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411085804280920
2019-04-11 08:58:04,309 [salt.minion      :1432][INFO    ][12259] Starting a new job with PID 12259
2019-04-11 08:58:04,340 [salt.minion      :1711][INFO    ][12259] Returning information for job: 20190411085804280920
2019-04-11 08:58:23,052 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1292.91799808s left)
2019-04-11 08:58:34,357 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411085834343836
2019-04-11 08:58:34,378 [salt.minion      :1432][INFO    ][12300] Starting a new job with PID 12300
2019-04-11 08:58:34,408 [salt.minion      :1711][INFO    ][12300] Returning information for job: 20190411085834343836
2019-04-11 08:58:55,799 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1260.17153597s left)
2019-04-11 08:59:04,400 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411085904386106
2019-04-11 08:59:04,423 [salt.minion      :1432][INFO    ][12375] Starting a new job with PID 12375
2019-04-11 08:59:04,452 [salt.minion      :1711][INFO    ][12375] Returning information for job: 20190411085904386106
2019-04-11 08:59:28,650 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1227.32052493s left)
2019-04-11 08:59:34,474 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411085934464987
2019-04-11 08:59:34,497 [salt.minion      :1432][INFO    ][12418] Starting a new job with PID 12418
2019-04-11 08:59:34,529 [salt.minion      :1711][INFO    ][12418] Returning information for job: 20190411085934464987
2019-04-11 09:00:01,289 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1194.68158007s left)
2019-04-11 09:00:04,554 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411090004541934
2019-04-11 09:00:04,577 [salt.minion      :1432][INFO    ][12480] Starting a new job with PID 12480
2019-04-11 09:00:04,604 [salt.minion      :1711][INFO    ][12480] Returning information for job: 20190411090004541934
2019-04-11 09:00:34,271 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1161.70002794s left)
2019-04-11 09:00:34,656 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411090034645423
2019-04-11 09:00:34,679 [salt.minion      :1432][INFO    ][12525] Starting a new job with PID 12525
2019-04-11 09:00:34,712 [salt.minion      :1711][INFO    ][12525] Returning information for job: 20190411090034645423
2019-04-11 09:01:04,745 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411090104735474
2019-04-11 09:01:04,758 [salt.minion      :1432][INFO    ][12584] Starting a new job with PID 12584
2019-04-11 09:01:04,791 [salt.minion      :1711][INFO    ][12584] Returning information for job: 20190411090104735474
2019-04-11 09:01:06,956 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1129.01487207s left)
2019-04-11 09:01:34,872 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411090134857851
2019-04-11 09:01:34,893 [salt.minion      :1432][INFO    ][12626] Starting a new job with PID 12626
2019-04-11 09:01:34,922 [salt.minion      :1711][INFO    ][12626] Returning information for job: 20190411090134857851
2019-04-11 09:01:39,565 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1096.4049499s left)
2019-04-11 09:02:04,989 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411090204973018
2019-04-11 09:02:05,012 [salt.minion      :1432][INFO    ][12686] Starting a new job with PID 12686
2019-04-11 09:02:05,048 [salt.minion      :1711][INFO    ][12686] Returning information for job: 20190411090204973018
2019-04-11 09:02:12,197 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1063.77359605s left)
2019-04-11 09:02:35,171 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411090235160485
2019-04-11 09:02:35,191 [salt.minion      :1432][INFO    ][12728] Starting a new job with PID 12728
2019-04-11 09:02:35,220 [salt.minion      :1711][INFO    ][12728] Returning information for job: 20190411090235160485
2019-04-11 09:02:44,994 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1030.97631407s left)
2019-04-11 09:03:05,265 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411090305258665
2019-04-11 09:03:05,279 [salt.minion      :1432][INFO    ][12787] Starting a new job with PID 12787
2019-04-11 09:03:05,311 [salt.minion      :1711][INFO    ][12787] Returning information for job: 20190411090305258665
2019-04-11 09:03:17,712 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (998.258639097s left)
2019-04-11 09:03:35,362 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411090335352145
2019-04-11 09:03:35,388 [salt.minion      :1432][INFO    ][12837] Starting a new job with PID 12837
2019-04-11 09:03:35,425 [salt.minion      :1711][INFO    ][12837] Returning information for job: 20190411090335352145
2019-04-11 09:03:50,875 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (965.095402956s left)
2019-04-11 09:04:05,481 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411090405472036
2019-04-11 09:04:05,498 [salt.minion      :1432][INFO    ][12916] Starting a new job with PID 12916
2019-04-11 09:04:05,532 [salt.minion      :1711][INFO    ][12916] Returning information for job: 20190411090405472036
2019-04-11 09:04:23,578 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (932.392458916s left)
2019-04-11 09:04:35,519 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411090435507927
2019-04-11 09:04:35,533 [salt.minion      :1432][INFO    ][12959] Starting a new job with PID 12959
2019-04-11 09:04:35,564 [salt.minion      :1711][INFO    ][12959] Returning information for job: 20190411090435507927
2019-04-11 09:04:56,214 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (899.756020069s left)
2019-04-11 09:05:05,700 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411090505690681
2019-04-11 09:05:05,721 [salt.minion      :1432][INFO    ][13020] Starting a new job with PID 13020
2019-04-11 09:05:05,752 [salt.minion      :1711][INFO    ][13020] Returning information for job: 20190411090505690681
2019-04-11 09:05:28,700 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (867.270486116s left)
2019-04-11 09:05:35,896 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411090535887291
2019-04-11 09:05:35,912 [salt.minion      :1432][INFO    ][13064] Starting a new job with PID 13064
2019-04-11 09:05:35,946 [salt.minion      :1711][INFO    ][13064] Returning information for job: 20190411090535887291
2019-04-11 09:06:01,077 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (834.893153906s left)
2019-04-11 09:06:06,060 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411090606042698
2019-04-11 09:06:06,081 [salt.minion      :1432][INFO    ][13124] Starting a new job with PID 13124
2019-04-11 09:06:06,112 [salt.minion      :1711][INFO    ][13124] Returning information for job: 20190411090606042698
2019-04-11 09:06:33,756 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (802.214596033s left)
2019-04-11 09:06:36,198 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411090636181491
2019-04-11 09:06:36,221 [salt.minion      :1432][INFO    ][13167] Starting a new job with PID 13167
2019-04-11 09:06:36,250 [salt.minion      :1711][INFO    ][13167] Returning information for job: 20190411090636181491
2019-04-11 09:07:06,305 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411090706295590
2019-04-11 09:07:06,320 [salt.minion      :1432][INFO    ][13228] Starting a new job with PID 13228
2019-04-11 09:07:06,349 [salt.minion      :1711][INFO    ][13228] Returning information for job: 20190411090706295590
2019-04-11 09:07:06,410 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (769.560687065s left)
2019-04-11 09:07:36,369 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411090736359288
2019-04-11 09:07:36,390 [salt.minion      :1432][INFO    ][13265] Starting a new job with PID 13265
2019-04-11 09:07:36,418 [salt.minion      :1711][INFO    ][13265] Returning information for job: 20190411090736359288
2019-04-11 09:07:39,345 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (736.625149012s left)
2019-04-11 09:08:06,506 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411090806493544
2019-04-11 09:08:06,526 [salt.minion      :1432][INFO    ][13326] Starting a new job with PID 13326
2019-04-11 09:08:06,556 [salt.minion      :1711][INFO    ][13326] Returning information for job: 20190411090806493544
2019-04-11 09:08:12,125 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (703.845284939s left)
2019-04-11 09:08:36,585 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411090836572630
2019-04-11 09:08:36,606 [salt.minion      :1432][INFO    ][13371] Starting a new job with PID 13371
2019-04-11 09:08:36,634 [salt.minion      :1711][INFO    ][13371] Returning information for job: 20190411090836572630
2019-04-11 09:08:44,806 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (671.164510965s left)
2019-04-11 09:09:06,746 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411090906729363
2019-04-11 09:09:06,768 [salt.minion      :1432][INFO    ][13431] Starting a new job with PID 13431
2019-04-11 09:09:06,801 [salt.minion      :1711][INFO    ][13431] Returning information for job: 20190411090906729363
2019-04-11 09:09:17,502 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (638.468105078s left)
2019-04-11 09:09:36,914 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411090936907634
2019-04-11 09:09:36,929 [salt.minion      :1432][INFO    ][13479] Starting a new job with PID 13479
2019-04-11 09:09:36,961 [salt.minion      :1711][INFO    ][13479] Returning information for job: 20190411090936907634
2019-04-11 09:09:50,123 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (605.847018957s left)
2019-04-11 09:10:06,956 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411091006945335
2019-04-11 09:10:06,977 [salt.minion      :1432][INFO    ][13541] Starting a new job with PID 13541
2019-04-11 09:10:07,005 [salt.minion      :1711][INFO    ][13541] Returning information for job: 20190411091006945335
2019-04-11 09:10:22,897 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (573.073326111s left)
2019-04-11 09:10:36,973 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411091036964642
2019-04-11 09:10:36,995 [salt.minion      :1432][INFO    ][13583] Starting a new job with PID 13583
2019-04-11 09:10:37,029 [salt.minion      :1711][INFO    ][13583] Returning information for job: 20190411091036964642
2019-04-11 09:10:55,676 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (540.294698954s left)
2019-04-11 09:11:07,128 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411091107121239
2019-04-11 09:11:07,149 [salt.minion      :1432][INFO    ][13642] Starting a new job with PID 13642
2019-04-11 09:11:07,177 [salt.minion      :1711][INFO    ][13642] Returning information for job: 20190411091107121239
2019-04-11 09:11:28,441 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (507.529473066s left)
2019-04-11 09:11:37,178 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411091137170777
2019-04-11 09:11:37,197 [salt.minion      :1432][INFO    ][13688] Starting a new job with PID 13688
2019-04-11 09:11:37,229 [salt.minion      :1711][INFO    ][13688] Returning information for job: 20190411091137170777
2019-04-11 09:12:01,093 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (474.877113104s left)
2019-04-11 09:12:07,402 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411091207387444
2019-04-11 09:12:07,421 [salt.minion      :1432][INFO    ][13746] Starting a new job with PID 13746
2019-04-11 09:12:07,452 [salt.minion      :1711][INFO    ][13746] Returning information for job: 20190411091207387444
2019-04-11 09:12:33,946 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (442.024631977s left)
2019-04-11 09:12:37,477 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411091237469820
2019-04-11 09:12:37,494 [salt.minion      :1432][INFO    ][13796] Starting a new job with PID 13796
2019-04-11 09:12:37,524 [salt.minion      :1711][INFO    ][13796] Returning information for job: 20190411091237469820
2019-04-11 09:13:06,584 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (409.386514902s left)
2019-04-11 09:13:07,510 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411091307501099
2019-04-11 09:13:07,532 [salt.minion      :1432][INFO    ][13853] Starting a new job with PID 13853
2019-04-11 09:13:07,565 [salt.minion      :1711][INFO    ][13853] Returning information for job: 20190411091307501099
2019-04-11 09:13:37,653 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411091337643745
2019-04-11 09:13:37,668 [salt.minion      :1432][INFO    ][14034] Starting a new job with PID 14034
2019-04-11 09:13:37,703 [salt.minion      :1711][INFO    ][14034] Returning information for job: 20190411091337643745
2019-04-11 09:13:39,251 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (376.719736099s left)
2019-04-11 09:14:07,768 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411091407752646
2019-04-11 09:14:07,787 [salt.minion      :1432][INFO    ][14099] Starting a new job with PID 14099
2019-04-11 09:14:07,816 [salt.minion      :1711][INFO    ][14099] Returning information for job: 20190411091407752646
2019-04-11 09:14:11,931 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (344.040084124s left)
2019-04-11 09:14:37,913 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411091437904959
2019-04-11 09:14:37,929 [salt.minion      :1432][INFO    ][14147] Starting a new job with PID 14147
2019-04-11 09:14:37,963 [salt.minion      :1711][INFO    ][14147] Returning information for job: 20190411091437904959
2019-04-11 09:14:44,530 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (311.44084692s left)
2019-04-11 09:15:08,034 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411091508021631
2019-04-11 09:15:08,054 [salt.minion      :1432][INFO    ][14201] Starting a new job with PID 14201
2019-04-11 09:15:08,085 [salt.minion      :1711][INFO    ][14201] Returning information for job: 20190411091508021631
2019-04-11 09:15:17,424 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (278.546166897s left)
2019-04-11 09:15:38,238 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411091538229784
2019-04-11 09:15:38,253 [salt.minion      :1432][INFO    ][14255] Starting a new job with PID 14255
2019-04-11 09:15:38,281 [salt.minion      :1711][INFO    ][14255] Returning information for job: 20190411091538229784
2019-04-11 09:15:50,215 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (245.755705118s left)
2019-04-11 09:16:08,426 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411091608418527
2019-04-11 09:16:08,442 [salt.minion      :1432][INFO    ][14305] Starting a new job with PID 14305
2019-04-11 09:16:08,470 [salt.minion      :1711][INFO    ][14305] Returning information for job: 20190411091608418527
2019-04-11 09:16:22,868 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (213.102813959s left)
2019-04-11 09:16:38,601 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411091638590080
2019-04-11 09:16:38,622 [salt.minion      :1432][INFO    ][14362] Starting a new job with PID 14362
2019-04-11 09:16:38,652 [salt.minion      :1711][INFO    ][14362] Returning information for job: 20190411091638590080
2019-04-11 09:16:55,508 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (180.462186098s left)
2019-04-11 09:17:08,672 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411091708660985
2019-04-11 09:17:08,694 [salt.minion      :1432][INFO    ][14417] Starting a new job with PID 14417
2019-04-11 09:17:08,723 [salt.minion      :1711][INFO    ][14417] Returning information for job: 20190411091708660985
2019-04-11 09:17:28,259 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (147.712053061s left)
2019-04-11 09:17:38,856 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411091738846782
2019-04-11 09:17:38,876 [salt.minion      :1432][INFO    ][14472] Starting a new job with PID 14472
2019-04-11 09:17:38,910 [salt.minion      :1711][INFO    ][14472] Returning information for job: 20190411091738846782
2019-04-11 09:18:01,004 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (114.966217995s left)
2019-04-11 09:18:08,933 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411091808919698
2019-04-11 09:18:08,951 [salt.minion      :1432][INFO    ][14519] Starting a new job with PID 14519
2019-04-11 09:18:08,982 [salt.minion      :1711][INFO    ][14519] Returning information for job: 20190411091808919698
2019-04-11 09:18:33,646 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (82.3246660233s left)
2019-04-11 09:18:39,161 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411091839147824
2019-04-11 09:18:39,186 [salt.minion      :1432][INFO    ][14575] Starting a new job with PID 14575
2019-04-11 09:18:39,214 [salt.minion      :1711][INFO    ][14575] Returning information for job: 20190411091839147824
2019-04-11 09:19:06,409 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (49.5614190102s left)
2019-04-11 09:19:09,330 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411091909315747
2019-04-11 09:19:09,348 [salt.minion      :1432][INFO    ][14625] Starting a new job with PID 14625
2019-04-11 09:19:09,379 [salt.minion      :1711][INFO    ][14625] Returning information for job: 20190411091909315747
2019-04-11 09:19:39,001 [salt.loaded.ext.module.maas:1023][INFO    ][8080] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (16.9698119164s left)
2019-04-11 09:19:39,414 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411091939405054
2019-04-11 09:19:39,432 [salt.minion      :1432][INFO    ][14680] Starting a new job with PID 14680
2019-04-11 09:19:39,463 [salt.minion      :1711][INFO    ][14680] Returning information for job: 20190411091939405054
2019-04-11 09:20:09,529 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411092009521602
2019-04-11 09:20:09,542 [salt.minion      :1432][INFO    ][14723] Starting a new job with PID 14723
2019-04-11 09:20:09,574 [salt.minion      :1711][INFO    ][14723] Returning information for job: 20190411092009521602
2019-04-11 09:20:11,869 [salt.state       :302 ][ERROR   ][8080] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-04-11 09:20:11,869 [salt.state       :1951][INFO    ][8080] Completed state [maas.wait_for_machine_status] at time 09:20:11.869634 duration_in_ms=2265916.419
2019-04-11 09:20:11,875 [salt.minion      :1711][INFO    ][8080] Returning information for job: 20190411084215665227
2019-04-11 09:20:22,765 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command pillar.get with jid 20190411092022755567
2019-04-11 09:20:22,785 [salt.minion      :1432][INFO    ][14739] Starting a new job with PID 14739
2019-04-11 09:20:22,796 [salt.minion      :1711][INFO    ][14739] Returning information for job: 20190411092022755567
2019-04-11 09:20:23,477 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command service.status with jid 20190411092023465531
2019-04-11 09:20:23,495 [salt.minion      :1432][INFO    ][14744] Starting a new job with PID 14744
2019-04-11 09:20:24,265 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][14744] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-04-11 09:20:24,300 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][14744] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-04-11 09:20:24,317 [salt.minion      :1711][INFO    ][14744] Returning information for job: 20190411092023465531
2019-04-11 09:20:25,002 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command state.apply with jid 20190411092024990052
2019-04-11 09:20:25,022 [salt.minion      :1432][INFO    ][14755] Starting a new job with PID 14755
2019-04-11 09:20:33,209 [salt.state       :915 ][INFO    ][14755] Loading fresh modules for state activity
2019-04-11 09:20:33,751 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14755] Executing command 'salt-minion --version' in directory '/root'
2019-04-11 09:20:34,061 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14755] Executing command 'salt-minion --version' in directory '/root'
2019-04-11 09:20:35,000 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14755] Executing command 'salt-minion --version' in directory '/root'
2019-04-11 09:20:35,336 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14755] Executing command 'salt-minion --version' in directory '/root'
2019-04-11 09:20:37,505 [salt.state       :1780][INFO    ][14755] Running state [salt-minion] at time 09:20:37.505742
2019-04-11 09:20:37,506 [salt.state       :1813][INFO    ][14755] Executing state pkg.installed for [salt-minion]
2019-04-11 09:20:37,507 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14755] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-11 09:20:37,608 [salt.state       :300 ][INFO    ][14755] All specified packages are already installed
2019-04-11 09:20:37,608 [salt.state       :1951][INFO    ][14755] Completed state [salt-minion] at time 09:20:37.608430 duration_in_ms=102.688
2019-04-11 09:20:37,608 [salt.state       :1780][INFO    ][14755] Running state [salt_minion_dependency_packages] at time 09:20:37.608846
2019-04-11 09:20:37,609 [salt.state       :1813][INFO    ][14755] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-04-11 09:20:37,620 [salt.state       :300 ][INFO    ][14755] All specified packages are already installed
2019-04-11 09:20:37,620 [salt.state       :1951][INFO    ][14755] Completed state [salt_minion_dependency_packages] at time 09:20:37.620644 duration_in_ms=11.798
2019-04-11 09:20:37,624 [salt.state       :1780][INFO    ][14755] Running state [/etc/salt/minion.d/minion.conf] at time 09:20:37.624905
2019-04-11 09:20:37,625 [salt.state       :1813][INFO    ][14755] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-04-11 09:20:37,896 [salt.state       :300 ][INFO    ][14755] File /etc/salt/minion.d/minion.conf is in the correct state
2019-04-11 09:20:37,897 [salt.state       :1951][INFO    ][14755] Completed state [/etc/salt/minion.d/minion.conf] at time 09:20:37.897091 duration_in_ms=272.185
2019-04-11 09:20:37,897 [salt.state       :1780][INFO    ][14755] Running state [python-netaddr] at time 09:20:37.897409
2019-04-11 09:20:37,897 [salt.state       :1813][INFO    ][14755] Executing state pkg.installed for [python-netaddr]
2019-04-11 09:20:37,909 [salt.state       :300 ][INFO    ][14755] All specified packages are already installed
2019-04-11 09:20:37,909 [salt.state       :1951][INFO    ][14755] Completed state [python-netaddr] at time 09:20:37.909570 duration_in_ms=12.161
2019-04-11 09:20:37,915 [salt.state       :1780][INFO    ][14755] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 09:20:37.915824
2019-04-11 09:20:37,916 [salt.state       :1813][INFO    ][14755] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-04-11 09:20:37,930 [salt.state       :300 ][INFO    ][14755] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-04-11 09:20:37,930 [salt.state       :1951][INFO    ][14755] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 09:20:37.930449 duration_in_ms=14.625
2019-04-11 09:20:37,931 [salt.state       :1780][INFO    ][14755] Running state [salt-minion] at time 09:20:37.931670
2019-04-11 09:20:37,932 [salt.state       :1813][INFO    ][14755] Executing state service.running for [salt-minion]
2019-04-11 09:20:37,932 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14755] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-04-11 09:20:37,969 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14755] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-04-11 09:20:37,987 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14755] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-04-11 09:20:38,002 [salt.state       :300 ][INFO    ][14755] The service salt-minion is already running
2019-04-11 09:20:38,002 [salt.state       :1951][INFO    ][14755] Completed state [salt-minion] at time 09:20:38.002488 duration_in_ms=70.818
2019-04-11 09:20:38,004 [salt.state       :1780][INFO    ][14755] Running state [/etc/salt/grains.d] at time 09:20:38.004198
2019-04-11 09:20:38,004 [salt.state       :1813][INFO    ][14755] Executing state file.directory for [/etc/salt/grains.d]
2019-04-11 09:20:38,005 [salt.state       :300 ][INFO    ][14755] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-04-11 09:20:38,006 [salt.state       :1951][INFO    ][14755] Completed state [/etc/salt/grains.d] at time 09:20:38.006107 duration_in_ms=1.908
2019-04-11 09:20:38,009 [salt.state       :1780][INFO    ][14755] Running state [/etc/salt/grains] at time 09:20:38.009051
2019-04-11 09:20:38,009 [salt.state       :1813][INFO    ][14755] Executing state file.managed for [/etc/salt/grains]
2019-04-11 09:20:38,010 [salt.state       :300 ][INFO    ][14755] File /etc/salt/grains exists with proper permissions. No changes made.
2019-04-11 09:20:38,010 [salt.state       :1951][INFO    ][14755] Completed state [/etc/salt/grains] at time 09:20:38.010248 duration_in_ms=1.197
2019-04-11 09:20:38,011 [salt.state       :1780][INFO    ][14755] Running state [/etc/salt/grains.d/placeholder] at time 09:20:38.010792
2019-04-11 09:20:38,011 [salt.state       :1813][INFO    ][14755] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-04-11 09:20:38,012 [salt.state       :300 ][INFO    ][14755] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-04-11 09:20:38,012 [salt.state       :1951][INFO    ][14755] Completed state [/etc/salt/grains.d/placeholder] at time 09:20:38.012473 duration_in_ms=1.681
2019-04-11 09:20:38,013 [salt.state       :1780][INFO    ][14755] Running state [/etc/salt/grains.d/sphinx] at time 09:20:38.013051
2019-04-11 09:20:38,013 [salt.state       :1813][INFO    ][14755] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-04-11 09:20:38,015 [salt.state       :300 ][INFO    ][14755] File /etc/salt/grains.d/sphinx is in the correct state
2019-04-11 09:20:38,015 [salt.state       :1951][INFO    ][14755] Completed state [/etc/salt/grains.d/sphinx] at time 09:20:38.015277 duration_in_ms=2.227
2019-04-11 09:20:38,017 [salt.state       :1780][INFO    ][14755] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 09:20:38.017570
2019-04-11 09:20:38,017 [salt.state       :1813][INFO    ][14755] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-04-11 09:20:38,018 [salt.state       :300 ][INFO    ][14755] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-04-11 09:20:38,018 [salt.state       :1951][INFO    ][14755] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 09:20:38.018643 duration_in_ms=1.074
2019-04-11 09:20:38,022 [salt.state       :1780][INFO    ][14755] Running state [/etc/salt/grains.d/dns_records] at time 09:20:38.022186
2019-04-11 09:20:38,022 [salt.state       :1813][INFO    ][14755] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-04-11 09:20:38,023 [salt.state       :300 ][INFO    ][14755] File /etc/salt/grains.d/dns_records is in the correct state
2019-04-11 09:20:38,024 [salt.state       :1951][INFO    ][14755] Completed state [/etc/salt/grains.d/dns_records] at time 09:20:38.024114 duration_in_ms=1.928
2019-04-11 09:20:38,025 [salt.state       :1780][INFO    ][14755] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 09:20:38.025134
2019-04-11 09:20:38,025 [salt.state       :1813][INFO    ][14755] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-04-11 09:20:38,025 [salt.state       :300 ][INFO    ][14755] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-04-11 09:20:38,026 [salt.state       :1951][INFO    ][14755] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 09:20:38.025974 duration_in_ms=0.84
2019-04-11 09:20:38,026 [salt.state       :1780][INFO    ][14755] Running state [/etc/salt/grains.d/salt] at time 09:20:38.026550
2019-04-11 09:20:38,026 [salt.state       :1813][INFO    ][14755] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-04-11 09:20:38,028 [salt.state       :300 ][INFO    ][14755] File /etc/salt/grains.d/salt is in the correct state
2019-04-11 09:20:38,028 [salt.state       :1951][INFO    ][14755] Completed state [/etc/salt/grains.d/salt] at time 09:20:38.028207 duration_in_ms=1.657
2019-04-11 09:20:38,029 [salt.state       :1780][INFO    ][14755] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 09:20:38.029215
2019-04-11 09:20:38,029 [salt.state       :1813][INFO    ][14755] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-04-11 09:20:38,030 [salt.state       :300 ][INFO    ][14755] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-04-11 09:20:38,030 [salt.state       :1951][INFO    ][14755] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 09:20:38.030249 duration_in_ms=1.033
2019-04-11 09:20:38,034 [salt.state       :1780][INFO    ][14755] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 09:20:38.034704
2019-04-11 09:20:38,035 [salt.state       :1813][INFO    ][14755] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-04-11 09:20:38,036 [salt.state       :300 ][INFO    ][14755] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-04-11 09:20:38,036 [salt.state       :1951][INFO    ][14755] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 09:20:38.036344 duration_in_ms=1.639
2019-04-11 09:20:38,037 [salt.state       :1780][INFO    ][14755] Running state [mine.update] at time 09:20:38.037668
2019-04-11 09:20:38,038 [salt.state       :1813][INFO    ][14755] Executing state module.wait for [mine.update]
2019-04-11 09:20:38,038 [salt.state       :300 ][INFO    ][14755] No changes made for mine.update
2019-04-11 09:20:38,039 [salt.state       :1951][INFO    ][14755] Completed state [mine.update] at time 09:20:38.039222 duration_in_ms=1.555
2019-04-11 09:20:38,039 [salt.state       :1780][INFO    ][14755] Running state [ca-certificates] at time 09:20:38.039631
2019-04-11 09:20:38,040 [salt.state       :1813][INFO    ][14755] Executing state pkg.installed for [ca-certificates]
2019-04-11 09:20:38,061 [salt.state       :300 ][INFO    ][14755] All specified packages are already installed
2019-04-11 09:20:38,061 [salt.state       :1951][INFO    ][14755] Completed state [ca-certificates] at time 09:20:38.061420 duration_in_ms=21.788
2019-04-11 09:20:38,062 [salt.state       :1780][INFO    ][14755] Running state [update-ca-certificates] at time 09:20:38.062403
2019-04-11 09:20:38,062 [salt.state       :1813][INFO    ][14755] Executing state cmd.wait for [update-ca-certificates]
2019-04-11 09:20:38,063 [salt.state       :300 ][INFO    ][14755] No changes made for update-ca-certificates
2019-04-11 09:20:38,063 [salt.state       :1951][INFO    ][14755] Completed state [update-ca-certificates] at time 09:20:38.063604 duration_in_ms=1.201
2019-04-11 09:20:38,063 [salt.state       :1780][INFO    ][14755] Running state [iptables] at time 09:20:38.063918
2019-04-11 09:20:38,064 [salt.state       :1813][INFO    ][14755] Executing state pkg.installed for [iptables]
2019-04-11 09:20:38,084 [salt.state       :300 ][INFO    ][14755] All specified packages are already installed
2019-04-11 09:20:38,085 [salt.state       :1951][INFO    ][14755] Completed state [iptables] at time 09:20:38.085298 duration_in_ms=21.379
2019-04-11 09:20:38,085 [salt.state       :1780][INFO    ][14755] Running state [iptables-persistent] at time 09:20:38.085730
2019-04-11 09:20:38,086 [salt.state       :1813][INFO    ][14755] Executing state pkg.installed for [iptables-persistent]
2019-04-11 09:20:38,108 [salt.state       :300 ][INFO    ][14755] All specified packages are already installed
2019-04-11 09:20:38,108 [salt.state       :1951][INFO    ][14755] Completed state [iptables-persistent] at time 09:20:38.108826 duration_in_ms=23.095
2019-04-11 09:20:38,110 [salt.state       :1780][INFO    ][14755] Running state [iptables_modules_v4_load] at time 09:20:38.110621
2019-04-11 09:20:38,111 [salt.state       :1813][INFO    ][14755] Executing state kmod.present for [iptables_modules_v4_load]
2019-04-11 09:20:38,111 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14755] Executing command 'lsmod' in directory '/root'
2019-04-11 09:20:38,136 [salt.state       :300 ][INFO    ][14755] Kernel modules iptable_filter, ip_tables are already present
2019-04-11 09:20:38,136 [salt.state       :1951][INFO    ][14755] Completed state [iptables_modules_v4_load] at time 09:20:38.136516 duration_in_ms=25.895
2019-04-11 09:20:38,137 [salt.state       :1780][INFO    ][14755] Running state [/etc/iptables/rules.v4] at time 09:20:38.137293
2019-04-11 09:20:38,137 [salt.state       :1813][INFO    ][14755] Executing state file.managed for [/etc/iptables/rules.v4]
2019-04-11 09:20:38,242 [salt.state       :300 ][INFO    ][14755] File /etc/iptables/rules.v4 is in the correct state
2019-04-11 09:20:38,243 [salt.state       :1951][INFO    ][14755] Completed state [/etc/iptables/rules.v4] at time 09:20:38.243053 duration_in_ms=105.76
2019-04-11 09:20:38,244 [salt.state       :1780][INFO    ][14755] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 09:20:38.244276
2019-04-11 09:20:38,244 [salt.state       :1813][INFO    ][14755] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-04-11 09:20:38,245 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14755] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-04-11 09:20:38,258 [salt.state       :300 ][INFO    ][14755] onlyif execution failed
2019-04-11 09:20:38,261 [salt.state       :1951][INFO    ][14755] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 09:20:38.258790 duration_in_ms=14.514
2019-04-11 09:20:38,262 [salt.state       :1780][INFO    ][14755] Running state [netfilter-persistent] at time 09:20:38.262638
2019-04-11 09:20:38,263 [salt.state       :1813][INFO    ][14755] Executing state service.running for [netfilter-persistent]
2019-04-11 09:20:38,263 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14755] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-04-11 09:20:38,286 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14755] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-04-11 09:20:38,302 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14755] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-04-11 09:20:38,322 [salt.state       :300 ][INFO    ][14755] The service netfilter-persistent is already running
2019-04-11 09:20:38,322 [salt.state       :1951][INFO    ][14755] Completed state [netfilter-persistent] at time 09:20:38.322467 duration_in_ms=59.829
2019-04-11 09:20:38,324 [salt.state       :1780][INFO    ][14755] Running state [iptables_extra.remove_stale_tables] at time 09:20:38.323991
2019-04-11 09:20:38,324 [salt.state       :1813][INFO    ][14755] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-04-11 09:20:38,325 [salt.state       :300 ][INFO    ][14755] No changes made for iptables_extra.remove_stale_tables
2019-04-11 09:20:38,325 [salt.state       :1951][INFO    ][14755] Completed state [iptables_extra.remove_stale_tables] at time 09:20:38.325473 duration_in_ms=1.482
2019-04-11 09:20:38,325 [salt.state       :1780][INFO    ][14755] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 09:20:38.325899
2019-04-11 09:20:38,326 [salt.state       :1813][INFO    ][14755] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-04-11 09:20:38,327 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14755] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-04-11 09:20:38,340 [salt.state       :300 ][INFO    ][14755] onlyif execution failed
2019-04-11 09:20:38,340 [salt.state       :1951][INFO    ][14755] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 09:20:38.340807 duration_in_ms=14.909
2019-04-11 09:20:38,341 [salt.state       :1780][INFO    ][14755] Running state [/etc/iptables/rules.v6] at time 09:20:38.341840
2019-04-11 09:20:38,342 [salt.state       :1813][INFO    ][14755] Executing state file.absent for [/etc/iptables/rules.v6]
2019-04-11 09:20:38,342 [salt.state       :300 ][INFO    ][14755] File /etc/iptables/rules.v6 is not present
2019-04-11 09:20:38,345 [salt.state       :1951][INFO    ][14755] Completed state [/etc/iptables/rules.v6] at time 09:20:38.345798 duration_in_ms=3.957
2019-04-11 09:20:38,347 [salt.state       :1780][INFO    ][14755] Running state [iptables_extra.flush_all] at time 09:20:38.347119
2019-04-11 09:20:38,347 [salt.state       :1813][INFO    ][14755] Executing state module.wait for [iptables_extra.flush_all]
2019-04-11 09:20:38,348 [salt.state       :300 ][INFO    ][14755] No changes made for iptables_extra.flush_all
2019-04-11 09:20:38,348 [salt.state       :1951][INFO    ][14755] Completed state [iptables_extra.flush_all] at time 09:20:38.348339 duration_in_ms=1.22
2019-04-11 09:20:38,352 [salt.minion      :1711][INFO    ][14755] Returning information for job: 20190411092024990052
2019-04-11 09:20:39,041 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command state.apply with jid 20190411092039029024
2019-04-11 09:20:39,060 [salt.minion      :1432][INFO    ][14871] Starting a new job with PID 14871
2019-04-11 09:20:40,160 [salt.state       :915 ][INFO    ][14871] Loading fresh modules for state activity
2019-04-11 09:20:41,382 [salt.state       :1780][INFO    ][14871] Running state [maas-rack-controller] at time 09:20:41.382104
2019-04-11 09:20:41,382 [salt.state       :1813][INFO    ][14871] Executing state pkg.installed for [maas-rack-controller]
2019-04-11 09:20:41,384 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14871] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-11 09:20:41,492 [salt.state       :300 ][INFO    ][14871] All specified packages are already installed
2019-04-11 09:20:41,492 [salt.state       :1951][INFO    ][14871] Completed state [maas-rack-controller] at time 09:20:41.492535 duration_in_ms=110.431
2019-04-11 09:20:41,492 [salt.state       :1780][INFO    ][14871] Running state [ipmitool] at time 09:20:41.492887
2019-04-11 09:20:41,493 [salt.state       :1813][INFO    ][14871] Executing state pkg.installed for [ipmitool]
2019-04-11 09:20:41,504 [salt.state       :300 ][INFO    ][14871] All specified packages are already installed
2019-04-11 09:20:41,504 [salt.state       :1951][INFO    ][14871] Completed state [ipmitool] at time 09:20:41.504253 duration_in_ms=11.366
2019-04-11 09:20:41,507 [salt.state       :1780][INFO    ][14871] Running state [/etc/maas/rackd.conf] at time 09:20:41.507096
2019-04-11 09:20:41,507 [salt.state       :1813][INFO    ][14871] Executing state file.line for [/etc/maas/rackd.conf]
2019-04-11 09:20:41,508 [salt.state       :300 ][INFO    ][14871] No changes needed to be made
2019-04-11 09:20:41,508 [salt.state       :1951][INFO    ][14871] Completed state [/etc/maas/rackd.conf] at time 09:20:41.508545 duration_in_ms=1.449
2019-04-11 09:20:41,508 [salt.state       :1780][INFO    ][14871] Running state [/etc/maas/rackd.conf] at time 09:20:41.508826
2019-04-11 09:20:41,509 [salt.state       :1813][INFO    ][14871] Executing state file.managed for [/etc/maas/rackd.conf]
2019-04-11 09:20:41,509 [salt.loaded.int.states.file:2298][WARNING ][14871] 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-04-11 09:20:41,510 [salt.state       :300 ][INFO    ][14871] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-04-11 09:20:41,510 [salt.state       :1951][INFO    ][14871] Completed state [/etc/maas/rackd.conf] at time 09:20:41.510460 duration_in_ms=1.634
2019-04-11 09:20:41,512 [salt.state       :1780][INFO    ][14871] Running state [maas-rackd] at time 09:20:41.512472
2019-04-11 09:20:41,512 [salt.state       :1813][INFO    ][14871] Executing state service.running for [maas-rackd]
2019-04-11 09:20:41,513 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14871] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-04-11 09:20:41,545 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14871] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-04-11 09:20:41,565 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14871] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-04-11 09:20:41,584 [salt.state       :300 ][INFO    ][14871] The service maas-rackd is already running
2019-04-11 09:20:41,585 [salt.state       :1951][INFO    ][14871] Completed state [maas-rackd] at time 09:20:41.585331 duration_in_ms=72.859
2019-04-11 09:20:41,587 [salt.minion      :1711][INFO    ][14871] Returning information for job: 20190411092039029024
2019-04-11 09:20:42,260 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command state.apply with jid 20190411092042250796
2019-04-11 09:20:42,286 [salt.minion      :1432][INFO    ][14894] Starting a new job with PID 14894
2019-04-11 09:20:43,406 [salt.state       :915 ][INFO    ][14894] Loading fresh modules for state activity
2019-04-11 09:20:44,761 [salt.state       :1780][INFO    ][14894] Running state [maas-region-controller] at time 09:20:44.761469
2019-04-11 09:20:44,761 [salt.state       :1813][INFO    ][14894] Executing state pkg.installed for [maas-region-controller]
2019-04-11 09:20:44,762 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14894] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-11 09:20:44,878 [salt.state       :300 ][INFO    ][14894] All specified packages are already installed
2019-04-11 09:20:44,879 [salt.state       :1951][INFO    ][14894] Completed state [maas-region-controller] at time 09:20:44.879859 duration_in_ms=118.391
2019-04-11 09:20:44,880 [salt.state       :1780][INFO    ][14894] Running state [python-oauth] at time 09:20:44.880218
2019-04-11 09:20:44,880 [salt.state       :1813][INFO    ][14894] Executing state pkg.installed for [python-oauth]
2019-04-11 09:20:44,890 [salt.state       :300 ][INFO    ][14894] All specified packages are already installed
2019-04-11 09:20:44,891 [salt.state       :1951][INFO    ][14894] Completed state [python-oauth] at time 09:20:44.891086 duration_in_ms=10.867
2019-04-11 09:20:44,894 [salt.state       :1780][INFO    ][14894] Running state [/etc/maas/regiond.conf] at time 09:20:44.894258
2019-04-11 09:20:44,894 [salt.state       :1813][INFO    ][14894] Executing state file.replace for [/etc/maas/regiond.conf]
2019-04-11 09:20:44,900 [salt.state       :300 ][INFO    ][14894] No changes needed to be made
2019-04-11 09:20:44,900 [salt.state       :1951][INFO    ][14894] Completed state [/etc/maas/regiond.conf] at time 09:20:44.900816 duration_in_ms=6.558
2019-04-11 09:20:44,901 [salt.state       :1780][INFO    ][14894] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 09:20:44.901348
2019-04-11 09:20:44,901 [salt.state       :1813][INFO    ][14894] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-04-11 09:20:44,962 [salt.state       :300 ][INFO    ][14894] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-04-11 09:20:44,962 [salt.state       :1951][INFO    ][14894] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 09:20:44.962278 duration_in_ms=60.93
2019-04-11 09:20:44,963 [salt.state       :1780][INFO    ][14894] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 09:20:44.963180
2019-04-11 09:20:44,963 [salt.state       :1813][INFO    ][14894] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-04-11 09:20:44,968 [salt.state       :300 ][INFO    ][14894] No changes needed to be made
2019-04-11 09:20:44,969 [salt.state       :1951][INFO    ][14894] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 09:20:44.969085 duration_in_ms=5.905
2019-04-11 09:20:44,969 [salt.state       :1780][INFO    ][14894] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 09:20:44.969610
2019-04-11 09:20:44,969 [salt.state       :1813][INFO    ][14894] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-04-11 09:20:44,974 [salt.state       :300 ][INFO    ][14894] No changes needed to be made
2019-04-11 09:20:44,974 [salt.state       :1951][INFO    ][14894] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 09:20:44.974585 duration_in_ms=4.975
2019-04-11 09:20:44,975 [salt.state       :1780][INFO    ][14894] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 09:20:44.975407
2019-04-11 09:20:44,975 [salt.state       :1813][INFO    ][14894] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-04-11 09:20:45,000 [salt.state       :300 ][INFO    ][14894] No changes needed to be made
2019-04-11 09:20:45,001 [salt.state       :1951][INFO    ][14894] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 09:20:45.001216 duration_in_ms=25.81
2019-04-11 09:20:45,001 [salt.state       :1780][INFO    ][14894] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 09:20:45.001744
2019-04-11 09:20:45,002 [salt.state       :1813][INFO    ][14894] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-04-11 09:20:45,014 [salt.state       :300 ][INFO    ][14894] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-04-11 09:20:45,014 [salt.state       :1951][INFO    ][14894] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 09:20:45.014305 duration_in_ms=12.561
2019-04-11 09:20:45,017 [salt.state       :1780][INFO    ][14894] Running state [a2enmod headers] at time 09:20:45.017136
2019-04-11 09:20:45,017 [salt.state       :1813][INFO    ][14894] Executing state cmd.run for [a2enmod headers]
2019-04-11 09:20:45,018 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14894] Executing command 'a2enmod headers' in directory '/root'
2019-04-11 09:20:45,083 [salt.state       :300 ][INFO    ][14894] {'pid': 14914, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-04-11 09:20:45,084 [salt.state       :1951][INFO    ][14894] Completed state [a2enmod headers] at time 09:20:45.083915 duration_in_ms=66.778
2019-04-11 09:20:45,084 [salt.state       :1780][INFO    ][14894] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 09:20:45.084561
2019-04-11 09:20:45,085 [salt.state       :1813][INFO    ][14894] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-04-11 09:20:45,104 [salt.state       :300 ][INFO    ][14894] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-04-11 09:20:45,105 [salt.state       :1951][INFO    ][14894] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 09:20:45.105119 duration_in_ms=20.559
2019-04-11 09:20:45,105 [salt.state       :1780][INFO    ][14894] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 09:20:45.105794
2019-04-11 09:20:45,106 [salt.state       :1813][INFO    ][14894] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-04-11 09:20:45,161 [salt.state       :300 ][INFO    ][14894] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-04-11 09:20:45,162 [salt.state       :1951][INFO    ][14894] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 09:20:45.161991 duration_in_ms=56.197
2019-04-11 09:20:45,163 [salt.state       :1780][INFO    ][14894] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 09:20:45.163276
2019-04-11 09:20:45,163 [salt.state       :1813][INFO    ][14894] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-04-11 09:20:45,216 [salt.state       :300 ][INFO    ][14894] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-04-11 09:20:45,216 [salt.state       :1951][INFO    ][14894] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 09:20:45.216501 duration_in_ms=53.225
2019-04-11 09:20:45,217 [salt.state       :1780][INFO    ][14894] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 09:20:45.217342
2019-04-11 09:20:45,217 [salt.state       :1813][INFO    ][14894] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-04-11 09:20:45,297 [salt.state       :300 ][INFO    ][14894] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-04-11 09:20:45,297 [salt.state       :1951][INFO    ][14894] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 09:20:45.297722 duration_in_ms=80.379
2019-04-11 09:20:45,298 [salt.state       :1780][INFO    ][14894] Running state [/root/.pgpass] at time 09:20:45.298023
2019-04-11 09:20:45,298 [salt.state       :1813][INFO    ][14894] Executing state file.managed for [/root/.pgpass]
2019-04-11 09:20:45,346 [salt.state       :300 ][INFO    ][14894] File /root/.pgpass is in the correct state
2019-04-11 09:20:45,346 [salt.state       :1951][INFO    ][14894] Completed state [/root/.pgpass] at time 09:20:45.346340 duration_in_ms=48.316
2019-04-11 09:20:45,353 [salt.state       :1780][INFO    ][14894] Running state [maas-region syncdb --noinput] at time 09:20:45.353724
2019-04-11 09:20:45,354 [salt.state       :1813][INFO    ][14894] Executing state cmd.run for [maas-region syncdb --noinput]
2019-04-11 09:20:45,354 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14894] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-04-11 09:20:48,191 [salt.state       :300 ][INFO    ][14894] {'pid': 14934, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: contenttypes, metadataserver, sessions, maasserver, auth, piston3, sites\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-04-11 09:20:48,192 [salt.state       :1951][INFO    ][14894] Completed state [maas-region syncdb --noinput] at time 09:20:48.192680 duration_in_ms=2838.955
2019-04-11 09:20:48,193 [salt.state       :2022][WARNING ][14894] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-04-11 09:20:48,198 [salt.state       :1780][INFO    ][14894] Running state [maas-regiond] at time 09:20:48.198210
2019-04-11 09:20:48,198 [salt.state       :1813][INFO    ][14894] Executing state service.running for [maas-regiond]
2019-04-11 09:20:48,199 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14894] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-04-11 09:20:48,232 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14894] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-04-11 09:20:48,244 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14894] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-04-11 09:20:48,257 [salt.state       :300 ][INFO    ][14894] The service maas-regiond is already running
2019-04-11 09:20:48,257 [salt.state       :1951][INFO    ][14894] Completed state [maas-regiond] at time 09:20:48.257702 duration_in_ms=59.493
2019-04-11 09:20:48,260 [salt.state       :1780][INFO    ][14894] Running state [bind9] at time 09:20:48.260098
2019-04-11 09:20:48,260 [salt.state       :1813][INFO    ][14894] Executing state service.running for [bind9]
2019-04-11 09:20:48,261 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14894] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-04-11 09:20:48,277 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14894] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-04-11 09:20:48,296 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14894] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-04-11 09:20:48,313 [salt.state       :300 ][INFO    ][14894] The service bind9 is already running
2019-04-11 09:20:48,314 [salt.state       :1951][INFO    ][14894] Completed state [bind9] at time 09:20:48.314229 duration_in_ms=54.131
2019-04-11 09:20:48,318 [salt.state       :1780][INFO    ][14894] Running state [apache2] at time 09:20:48.318720
2019-04-11 09:20:48,319 [salt.state       :1813][INFO    ][14894] Executing state service.running for [apache2]
2019-04-11 09:20:48,320 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14894] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-04-11 09:20:48,342 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14894] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-04-11 09:20:48,359 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14894] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-04-11 09:20:48,384 [salt.state       :300 ][INFO    ][14894] The service apache2 is already running
2019-04-11 09:20:48,385 [salt.state       :1951][INFO    ][14894] Completed state [apache2] at time 09:20:48.384998 duration_in_ms=66.278
2019-04-11 09:20:48,386 [salt.state       :1780][INFO    ][14894] Running state [maasng.wait_for_http_code] at time 09:20:48.386723
2019-04-11 09:20:48,390 [salt.state       :1813][INFO    ][14894] Executing state module.run for [maasng.wait_for_http_code]
2019-04-11 09:20:48,391 [salt.utils.decorators:613 ][WARNING ][14894] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-11 09:20:48,533 [salt.state       :300 ][INFO    ][14894] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-04-11 09:20:48,534 [salt.state       :1951][INFO    ][14894] Completed state [maasng.wait_for_http_code] at time 09:20:48.534325 duration_in_ms=147.602
2019-04-11 09:20:48,536 [salt.state       :1780][INFO    ][14894] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 09:20:48.536163
2019-04-11 09:20:48,536 [salt.state       :1813][INFO    ][14894] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-04-11 09:20:48,537 [salt.state       :300 ][INFO    ][14894] /var/lib/maas/.setup_admin exists
2019-04-11 09:20:48,537 [salt.state       :1951][INFO    ][14894] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 09:20:48.537249 duration_in_ms=1.085
2019-04-11 09:20:48,538 [salt.state       :1780][INFO    ][14894] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:20:48.537973
2019-04-11 09:20:48,538 [salt.state       :1813][INFO    ][14894] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-11 09:20:48,539 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14894] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-11 09:20:50,380 [salt.state       :300 ][INFO    ][14894] {'pid': 14976, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-11 09:20:50,380 [salt.state       :1951][INFO    ][14894] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:20:50.380835 duration_in_ms=1842.861
2019-04-11 09:20:50,386 [salt.state       :1780][INFO    ][14894] Running state [maas_region_boot_source_resources_mirror] at time 09:20:50.386192
2019-04-11 09:20:50,386 [salt.state       :1813][INFO    ][14894] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-04-11 09:20:50,471 [salt.state       :300 ][INFO    ][14894] {'changes': {}}
2019-04-11 09:20:50,471 [salt.state       :1951][INFO    ][14894] Completed state [maas_region_boot_source_resources_mirror] at time 09:20:50.471899 duration_in_ms=85.707
2019-04-11 09:20:50,472 [salt.state       :1780][INFO    ][14894] Running state [maasng.boot_resources_import] at time 09:20:50.472770
2019-04-11 09:20:50,473 [salt.state       :1813][INFO    ][14894] Executing state module.run for [maasng.boot_resources_import]
2019-04-11 09:20:50,473 [salt.utils.decorators:613 ][WARNING ][14894] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-11 09:20:50,562 [salt.loaded.ext.module.maasng:1600][INFO    ][14894] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-04-11 09:20:55,598 [salt.loaded.ext.module.maasng:1600][INFO    ][14894] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-04-11 09:20:57,321 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411092057311890
2019-04-11 09:20:57,344 [salt.minion      :1432][INFO    ][15002] Starting a new job with PID 15002
2019-04-11 09:20:57,373 [salt.minion      :1711][INFO    ][15002] Returning information for job: 20190411092057311890
2019-04-11 09:21:00,647 [salt.loaded.ext.module.maasng:1600][INFO    ][14894] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-04-11 09:21:05,739 [salt.state       :300 ][INFO    ][14894] {'ret': True}
2019-04-11 09:21:05,739 [salt.state       :1951][INFO    ][14894] Completed state [maasng.boot_resources_import] at time 09:21:05.739715 duration_in_ms=15266.944
2019-04-11 09:21:05,740 [salt.state       :1780][INFO    ][14894] Running state [maas_region_boot_sources_selection_xenial] at time 09:21:05.740713
2019-04-11 09:21:05,741 [salt.state       :1813][INFO    ][14894] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-04-11 09:21:05,903 [salt.state       :300 ][INFO    ][14894] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-04-11 09:21:05,903 [salt.state       :1951][INFO    ][14894] Completed state [maas_region_boot_sources_selection_xenial] at time 09:21:05.903868 duration_in_ms=163.154
2019-04-11 09:21:05,905 [salt.state       :1780][INFO    ][14894] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 09:21:05.905327
2019-04-11 09:21:05,905 [salt.state       :1813][INFO    ][14894] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-04-11 09:21:05,906 [salt.utils.decorators:613 ][WARNING ][14894] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-11 09:21:05,908 [salt.loaded.ext.module.maasng:1771][INFO    ][14894] boot-sources sync initiated for ALL Rack's
2019-04-11 09:21:06,851 [salt.state       :300 ][INFO    ][14894] {'ret': True}
2019-04-11 09:21:06,851 [salt.state       :1951][INFO    ][14894] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 09:21:06.851446 duration_in_ms=946.118
2019-04-11 09:21:06,852 [salt.state       :1780][INFO    ][14894] Running state [maas.process_maas_config] at time 09:21:06.852752
2019-04-11 09:21:06,853 [salt.state       :1813][INFO    ][14894] Executing state module.run for [maas.process_maas_config]
2019-04-11 09:21:06,853 [salt.utils.decorators:613 ][WARNING ][14894] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-11 09:21:06,853 [salt.loaded.ext.module.maas:92  ][INFO    ][14894] maasconfig name=enable_http_proxy value=True
2019-04-11 09:21:06,896 [salt.loaded.ext.module.maas:92  ][INFO    ][14894] maasconfig name=upstream_dns value=8.8.8.8
2019-04-11 09:21:06,946 [salt.loaded.ext.module.maas:92  ][INFO    ][14894] maasconfig name=commissioning_distro_series value=xenial
2019-04-11 09:21:07,002 [salt.loaded.ext.module.maas:92  ][INFO    ][14894] maasconfig name=default_osystem value=ubuntu
2019-04-11 09:21:07,058 [salt.loaded.ext.module.maas:92  ][INFO    ][14894] maasconfig name=active_discovery_interval value=600
2019-04-11 09:21:07,102 [salt.loaded.ext.module.maas:92  ][INFO    ][14894] maasconfig name=dnssec_validation value=no
2019-04-11 09:21:07,152 [salt.loaded.ext.module.maas:92  ][INFO    ][14894] maasconfig name=maas_name value=mas01
2019-04-11 09:21:07,197 [salt.loaded.ext.module.maas:92  ][INFO    ][14894] maasconfig name=network_discovery value=enabled
2019-04-11 09:21:07,300 [salt.loaded.ext.module.maas:92  ][INFO    ][14894] maasconfig name=enable_third_party_drivers value=True
2019-04-11 09:21:07,346 [salt.loaded.ext.module.maas:92  ][INFO    ][14894] maasconfig name=default_storage_layout value=lvm
2019-04-11 09:21:08,241 [salt.loaded.ext.module.maas:92  ][INFO    ][14894] maasconfig name=ntp_external_only value=True
2019-04-11 09:21:08,290 [salt.loaded.ext.module.maas:92  ][INFO    ][14894] maasconfig name=disk_erase_with_secure_erase value=False
2019-04-11 09:21:08,348 [salt.loaded.ext.module.maas:92  ][INFO    ][14894] maasconfig name=default_distro_series value=xenial
2019-04-11 09:21:08,408 [salt.loaded.ext.module.maas:92  ][INFO    ][14894] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-04-11 09:21:08,529 [salt.state       :300 ][INFO    ][14894] {'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-04-11 09:21:08,529 [salt.state       :1951][INFO    ][14894] Completed state [maas.process_maas_config] at time 09:21:08.529469 duration_in_ms=1676.716
2019-04-11 09:21:08,530 [salt.state       :1780][INFO    ][14894] Running state [pxe_admin] at time 09:21:08.530150
2019-04-11 09:21:08,530 [salt.state       :1813][INFO    ][14894] Executing state maasng.fabric_present for [pxe_admin]
2019-04-11 09:21:08,576 [salt.loaded.ext.module.maasng:945 ][INFO    ][14894] [{u'id': 0, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, 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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, 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'primary_rack': u'n3f7kf', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-11 09:21:08,632 [salt.loaded.ext.module.maasng:1008][WARNING ][14894] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-04-11 09:21:08,632 [salt.loaded.ext.module.maasng:1011][WARNING ][14894] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-04-11 09:21:08,682 [salt.state       :300 ][INFO    ][14894] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-04-11 09:21:08,682 [salt.state       :1951][INFO    ][14894] Completed state [pxe_admin] at time 09:21:08.682354 duration_in_ms=152.204
2019-04-11 09:21:08,682 [salt.state       :1780][INFO    ][14894] Running state [vlan 0] at time 09:21:08.682687
2019-04-11 09:21:08,685 [salt.state       :1813][INFO    ][14894] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-04-11 09:21:08,731 [salt.loaded.ext.module.maasng:945 ][INFO    ][14894] [{u'id': 0, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, 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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, 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'primary_rack': u'n3f7kf', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-11 09:21:08,834 [salt.loaded.ext.module.maasng:945 ][INFO    ][14894] [{u'id': 0, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, 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'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, 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'n3f7kf', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-11 09:21:09,055 [salt.loaded.ext.module.maasng:945 ][INFO    ][14894] [{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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-0', u'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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': 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'primary_rack': u'n3f7kf', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-11 09:21:09,141 [salt.state       :300 ][INFO    ][14894] {'new': 'Vlan untagged was updated'}
2019-04-11 09:21:09,141 [salt.state       :1951][INFO    ][14894] Completed state [vlan 0] at time 09:21:09.141444 duration_in_ms=458.754
2019-04-11 09:21:09,143 [salt.state       :1780][INFO    ][14894] Running state [192.168.11.0/24] at time 09:21:09.143552
2019-04-11 09:21:09,143 [salt.state       :1813][INFO    ][14894] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-04-11 09:21:09,334 [salt.loaded.ext.module.maasng:945 ][INFO    ][14894] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'n3f7kf', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-04-11 09:21:09,335 [salt.loaded.ext.module.maasng:1235][WARNING ][14894] Ignoring parameter vlan:0
2019-04-11 09:21:09,404 [salt.state       :300 ][INFO    ][14894] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-04-11 09:21:09,404 [salt.state       :1951][INFO    ][14894] Completed state [192.168.11.0/24] at time 09:21:09.404334 duration_in_ms=260.781
2019-04-11 09:21:09,405 [salt.state       :1780][INFO    ][14894] Running state [maas_create_iprange_1] at time 09:21:09.405168
2019-04-11 09:21:09,405 [salt.state       :1813][INFO    ][14894] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-04-11 09:21:09,460 [salt.state       :300 ][INFO    ][14894] Iprange maas_create_iprange_1 already exist.
2019-04-11 09:21:09,460 [salt.state       :1951][INFO    ][14894] Completed state [maas_create_iprange_1] at time 09:21:09.460314 duration_in_ms=55.146
2019-04-11 09:21:09,460 [salt.state       :1780][INFO    ][14894] Running state [vlan 0] at time 09:21:09.460626
2019-04-11 09:21:09,460 [salt.state       :1813][INFO    ][14894] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-04-11 09:21:09,501 [salt.loaded.ext.module.maasng:945 ][INFO    ][14894] [{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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-0', u'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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': 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'primary_rack': u'n3f7kf', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-11 09:21:09,578 [salt.loaded.ext.module.maasng:945 ][INFO    ][14894] [{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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-0', u'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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': 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'primary_rack': u'n3f7kf', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-11 09:21:09,780 [salt.loaded.ext.module.maasng:945 ][INFO    ][14894] [{u'id': 0, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, 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'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'class_type': u'', 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'n3f7kf', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-11 09:21:09,862 [salt.state       :300 ][INFO    ][14894] {'new': 'Vlan untagged was updated'}
2019-04-11 09:21:09,862 [salt.state       :1951][INFO    ][14894] Completed state [vlan 0] at time 09:21:09.862370 duration_in_ms=401.742
2019-04-11 09:21:09,863 [salt.state       :1780][INFO    ][14894] Running state [opnfv] at time 09:21:09.863260
2019-04-11 09:21:09,863 [salt.state       :1813][INFO    ][14894] Executing state maasng.sshkey_present for [opnfv]
2019-04-11 09:21:09,908 [salt.loaded.ext.module.maasng:1903][INFO    ][14894] [{u'keysource': u'', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b', u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/'}]
2019-04-11 09:21:09,908 [salt.state       :300 ][INFO    ][14894] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-04-11 09:21:09,908 [salt.state       :1951][INFO    ][14894] Completed state [opnfv] at time 09:21:09.908701 duration_in_ms=45.441
2019-04-11 09:21:09,909 [salt.state       :1780][INFO    ][14894] Running state [maas.process_tags] at time 09:21:09.909346
2019-04-11 09:21:09,909 [salt.state       :1813][INFO    ][14894] Executing state module.run for [maas.process_tags]
2019-04-11 09:21:09,910 [salt.utils.decorators:613 ][WARNING ][14894] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-11 09:21:09,956 [salt.loaded.ext.module.maas:92  ][INFO    ][14894] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-04-11 09:21:10,016 [salt.state       :300 ][INFO    ][14894] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-04-11 09:21:10,017 [salt.state       :1951][INFO    ][14894] Completed state [maas.process_tags] at time 09:21:10.017077 duration_in_ms=107.73
2019-04-11 09:21:10,020 [salt.minion      :1711][INFO    ][14894] Returning information for job: 20190411092042250796
2019-04-11 09:21:10,788 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command state.apply with jid 20190411092110780971
2019-04-11 09:21:10,801 [salt.minion      :1432][INFO    ][15369] Starting a new job with PID 15369
2019-04-11 09:21:18,837 [salt.state       :915 ][INFO    ][15369] Loading fresh modules for state activity
2019-04-11 09:21:18,949 [salt.state       :1780][INFO    ][15369] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:21:18.949725
2019-04-11 09:21:18,950 [salt.state       :1813][INFO    ][15369] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-11 09:21:18,952 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15369] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-11 09:21:20,780 [salt.state       :300 ][INFO    ][15369] {'pid': 15395, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-11 09:21:20,781 [salt.state       :1951][INFO    ][15369] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:21:20.781066 duration_in_ms=1831.342
2019-04-11 09:21:20,782 [salt.state       :1780][INFO    ][15369] Running state [maas.process_machines] at time 09:21:20.782624
2019-04-11 09:21:20,783 [salt.state       :1813][INFO    ][15369] Executing state module.run for [maas.process_machines]
2019-04-11 09:21:20,784 [salt.utils.decorators:613 ][WARNING ][15369] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-11 09:21:21,323 [salt.loaded.ext.module.maas:412 ][WARNING ][15369] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-11 09:21:21,324 [salt.loaded.ext.module.maas:92  ][INFO    ][15369] 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=m6tfqs architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-11 09:21:22,422 [salt.loaded.ext.module.maas:412 ][WARNING ][15369] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-11 09:21:22,425 [salt.loaded.ext.module.maas:92  ][INFO    ][15369] 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=e6rxkg architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-11 09:21:23,571 [salt.loaded.ext.module.maas:412 ][WARNING ][15369] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-11 09:21:23,572 [salt.loaded.ext.module.maas:92  ][INFO    ][15369] 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=ahatch architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-11 09:21:24,683 [salt.loaded.ext.module.maas:412 ][WARNING ][15369] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-11 09:21:24,683 [salt.loaded.ext.module.maas:92  ][INFO    ][15369] 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=wkkhc6 architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-11 09:21:25,788 [salt.loaded.ext.module.maas:412 ][WARNING ][15369] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-11 09:21:25,789 [salt.loaded.ext.module.maas:92  ][INFO    ][15369] 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=8nwcma architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-11 09:21:25,905 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411092125894639
2019-04-11 09:21:25,928 [salt.minion      :1432][INFO    ][15572] Starting a new job with PID 15572
2019-04-11 09:21:25,959 [salt.minion      :1711][INFO    ][15572] Returning information for job: 20190411092125894639
2019-04-11 09:21:26,889 [salt.state       :300 ][INFO    ][15369] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-04-11 09:21:26,890 [salt.state       :1951][INFO    ][15369] Completed state [maas.process_machines] at time 09:21:26.890235 duration_in_ms=6107.609
2019-04-11 09:21:26,894 [salt.minion      :1711][INFO    ][15369] Returning information for job: 20190411092110780971
2019-04-11 09:21:59,874 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command state.apply with jid 20190411092159862606
2019-04-11 09:21:59,891 [salt.minion      :1432][INFO    ][15697] Starting a new job with PID 15697
2019-04-11 09:22:07,771 [salt.state       :915 ][INFO    ][15697] Loading fresh modules for state activity
2019-04-11 09:22:07,859 [salt.state       :1780][INFO    ][15697] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:22:07.859361
2019-04-11 09:22:07,859 [salt.state       :1813][INFO    ][15697] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-11 09:22:07,861 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15697] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-11 09:22:09,539 [salt.state       :300 ][INFO    ][15697] {'pid': 15704, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-11 09:22:09,540 [salt.state       :1951][INFO    ][15697] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:22:09.540508 duration_in_ms=1681.148
2019-04-11 09:22:09,544 [salt.state       :1780][INFO    ][15697] Running state [maas.wait_for_machine_status] at time 09:22:09.544646
2019-04-11 09:22:09,544 [salt.state       :1813][INFO    ][15697] Executing state module.run for [maas.wait_for_machine_status]
2019-04-11 09:22:09,545 [salt.utils.decorators:613 ][WARNING ][15697] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-11 09:22:12,211 [salt.state       :300 ][INFO    ][15697] {'ret': True}
2019-04-11 09:22:12,212 [salt.state       :1951][INFO    ][15697] Completed state [maas.wait_for_machine_status] at time 09:22:12.212296 duration_in_ms=2667.649
2019-04-11 09:22:12,215 [salt.minion      :1711][INFO    ][15697] Returning information for job: 20190411092159862606
2019-04-11 09:22:12,751 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command state.apply with jid 20190411092212737211
2019-04-11 09:22:12,773 [salt.minion      :1432][INFO    ][15717] Starting a new job with PID 15717
2019-04-11 09:22:13,903 [salt.state       :915 ][INFO    ][15717] Loading fresh modules for state activity
2019-04-11 09:22:14,045 [salt.state       :1780][INFO    ][15717] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:22:14.045612
2019-04-11 09:22:14,045 [salt.state       :1813][INFO    ][15717] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-11 09:22:14,048 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15717] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-11 09:22:15,719 [salt.state       :300 ][INFO    ][15717] {'pid': 15725, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-11 09:22:15,720 [salt.state       :1951][INFO    ][15717] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:22:15.720113 duration_in_ms=1674.5
2019-04-11 09:22:15,724 [salt.state       :1780][INFO    ][15717] Running state [maas_machines_storage_cmp002_lvm] at time 09:22:15.724346
2019-04-11 09:22:15,724 [salt.state       :1813][INFO    ][15717] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-04-11 09:22:16,271 [salt.state       :300 ][INFO    ][15717] Machine cmp002 is not in Ready state.
2019-04-11 09:22:16,272 [salt.state       :1951][INFO    ][15717] Completed state [maas_machines_storage_cmp002_lvm] at time 09:22:16.272160 duration_in_ms=547.812
2019-04-11 09:22:16,272 [salt.state       :1780][INFO    ][15717] Running state [maas_machines_storage_cmp001_lvm] at time 09:22:16.272886
2019-04-11 09:22:16,273 [salt.state       :1813][INFO    ][15717] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-04-11 09:22:16,810 [salt.state       :300 ][INFO    ][15717] Machine cmp001 is not in Ready state.
2019-04-11 09:22:16,811 [salt.state       :1951][INFO    ][15717] Completed state [maas_machines_storage_cmp001_lvm] at time 09:22:16.811216 duration_in_ms=538.33
2019-04-11 09:22:16,816 [salt.minion      :1711][INFO    ][15717] Returning information for job: 20190411092212737211
2019-04-11 09:22:17,349 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command state.apply with jid 20190411092217337821
2019-04-11 09:22:17,370 [salt.minion      :1432][INFO    ][15737] Starting a new job with PID 15737
2019-04-11 09:22:18,492 [salt.state       :915 ][INFO    ][15737] Loading fresh modules for state activity
2019-04-11 09:22:18,577 [salt.state       :1780][INFO    ][15737] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:22:18.577078
2019-04-11 09:22:18,577 [salt.state       :1813][INFO    ][15737] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-11 09:22:18,580 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15737] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-11 09:22:20,261 [salt.state       :300 ][INFO    ][15737] {'pid': 15744, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-11 09:22:20,261 [salt.state       :1951][INFO    ][15737] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:22:20.261631 duration_in_ms=1684.554
2019-04-11 09:22:20,263 [salt.state       :1780][INFO    ][15737] Running state [maas.deploy_machines] at time 09:22:20.263722
2019-04-11 09:22:20,264 [salt.state       :1813][INFO    ][15737] Executing state module.run for [maas.deploy_machines]
2019-04-11 09:22:20,264 [salt.utils.decorators:613 ][WARNING ][15737] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-11 09:22:20,884 [salt.loaded.ext.module.maas:684 ][INFO    ][15737] deploymachines hwe_kernel=hwe-16.04 system_id=8nwcma distro_series=xenial
2019-04-11 09:22:23,117 [salt.state       :300 ][INFO    ][15737] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-04-11 09:22:23,118 [salt.state       :1951][INFO    ][15737] Completed state [maas.deploy_machines] at time 09:22:23.118194 duration_in_ms=2854.471
2019-04-11 09:22:23,121 [salt.minion      :1711][INFO    ][15737] Returning information for job: 20190411092217337821
2019-04-11 09:22:23,698 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command state.apply with jid 20190411092223687428
2019-04-11 09:22:23,713 [salt.minion      :1432][INFO    ][15804] Starting a new job with PID 15804
2019-04-11 09:22:31,922 [salt.state       :915 ][INFO    ][15804] Loading fresh modules for state activity
2019-04-11 09:22:32,024 [salt.state       :1780][INFO    ][15804] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:22:32.024787
2019-04-11 09:22:32,025 [salt.state       :1813][INFO    ][15804] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-11 09:22:32,029 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15804] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-11 09:22:33,689 [salt.state       :300 ][INFO    ][15804] {'pid': 15844, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-11 09:22:33,690 [salt.state       :1951][INFO    ][15804] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:22:33.690539 duration_in_ms=1665.753
2019-04-11 09:22:33,693 [salt.state       :1780][INFO    ][15804] Running state [maas.wait_for_machine_status] at time 09:22:33.693660
2019-04-11 09:22:33,694 [salt.state       :1813][INFO    ][15804] Executing state module.run for [maas.wait_for_machine_status]
2019-04-11 09:22:33,695 [salt.utils.decorators:613 ][WARNING ][15804] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-11 09:22:36,044 [salt.loaded.ext.module.maas:1023][INFO    ][15804] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.6637218s left)
2019-04-11 09:22:38,774 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411092238765500
2019-04-11 09:22:38,791 [salt.minion      :1432][INFO    ][15868] Starting a new job with PID 15868
2019-04-11 09:22:38,813 [salt.minion      :1711][INFO    ][15868] Returning information for job: 20190411092238765500
2019-04-11 09:23:08,717 [salt.loaded.ext.module.maas:1023][INFO    ][15804] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.99130988s left)
2019-04-11 09:23:08,924 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411092308880325
2019-04-11 09:23:08,940 [salt.minion      :1432][INFO    ][15919] Starting a new job with PID 15919
2019-04-11 09:23:08,964 [salt.minion      :1711][INFO    ][15919] Returning information for job: 20190411092308880325
2019-04-11 09:23:38,939 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411092338932918
2019-04-11 09:23:38,954 [salt.minion      :1432][INFO    ][15979] Starting a new job with PID 15979
2019-04-11 09:23:38,977 [salt.minion      :1711][INFO    ][15979] Returning information for job: 20190411092338932918
2019-04-11 09:23:41,375 [salt.loaded.ext.module.maas:1023][INFO    ][15804] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2182.33286595s left)
2019-04-11 09:24:08,988 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411092408970099
2019-04-11 09:24:09,011 [salt.minion      :1432][INFO    ][16036] Starting a new job with PID 16036
2019-04-11 09:24:09,038 [salt.minion      :1711][INFO    ][16036] Returning information for job: 20190411092408970099
2019-04-11 09:24:13,932 [salt.loaded.ext.module.maas:1023][INFO    ][15804] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2149.77560091s left)
2019-04-11 09:24:39,209 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411092439194682
2019-04-11 09:24:39,229 [salt.minion      :1432][INFO    ][16092] Starting a new job with PID 16092
2019-04-11 09:24:39,256 [salt.minion      :1711][INFO    ][16092] Returning information for job: 20190411092439194682
2019-04-11 09:24:46,685 [salt.loaded.ext.module.maas:1023][INFO    ][15804] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2117.02315998s left)
2019-04-11 09:25:09,264 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411092509253294
2019-04-11 09:25:09,289 [salt.minion      :1432][INFO    ][16140] Starting a new job with PID 16140
2019-04-11 09:25:09,313 [salt.minion      :1711][INFO    ][16140] Returning information for job: 20190411092509253294
2019-04-11 09:25:19,486 [salt.loaded.ext.module.maas:1023][INFO    ][15804] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2084.22199178s left)
2019-04-11 09:25:39,313 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411092539305651
2019-04-11 09:25:39,334 [salt.minion      :1432][INFO    ][16194] Starting a new job with PID 16194
2019-04-11 09:25:39,356 [salt.minion      :1711][INFO    ][16194] Returning information for job: 20190411092539305651
2019-04-11 09:25:52,247 [salt.loaded.ext.module.maas:1023][INFO    ][15804] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2051.46111989s left)
2019-04-11 09:26:09,511 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411092609503239
2019-04-11 09:26:09,529 [salt.minion      :1432][INFO    ][16243] Starting a new job with PID 16243
2019-04-11 09:26:09,554 [salt.minion      :1711][INFO    ][16243] Returning information for job: 20190411092609503239
2019-04-11 09:26:24,980 [salt.loaded.ext.module.maas:1023][INFO    ][15804] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2018.72749877s left)
2019-04-11 09:26:39,715 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411092639706662
2019-04-11 09:26:39,730 [salt.minion      :1432][INFO    ][16322] Starting a new job with PID 16322
2019-04-11 09:26:39,752 [salt.minion      :1711][INFO    ][16322] Returning information for job: 20190411092639706662
2019-04-11 09:26:57,693 [salt.loaded.ext.module.maas:1023][INFO    ][15804] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1986.01509881s left)
2019-04-11 09:27:09,762 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411092709748553
2019-04-11 09:27:09,786 [salt.minion      :1432][INFO    ][16375] Starting a new job with PID 16375
2019-04-11 09:27:09,808 [salt.minion      :1711][INFO    ][16375] Returning information for job: 20190411092709748553
2019-04-11 09:27:30,464 [salt.loaded.ext.module.maas:1023][INFO    ][15804] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1953.24445987s left)
2019-04-11 09:27:39,870 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411092739856386
2019-04-11 09:27:39,894 [salt.minion      :1432][INFO    ][16456] Starting a new job with PID 16456
2019-04-11 09:27:39,919 [salt.minion      :1711][INFO    ][16456] Returning information for job: 20190411092739856386
2019-04-11 09:28:03,394 [salt.loaded.ext.module.maas:1023][INFO    ][15804] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1920.31415296s left)
2019-04-11 09:28:09,884 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411092809876368
2019-04-11 09:28:09,907 [salt.minion      :1432][INFO    ][16626] Starting a new job with PID 16626
2019-04-11 09:28:09,933 [salt.minion      :1711][INFO    ][16626] Returning information for job: 20190411092809876368
2019-04-11 09:28:36,204 [salt.loaded.ext.module.maas:1023][INFO    ][15804] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1887.50432086s left)
2019-04-11 09:28:40,092 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411092840084248
2019-04-11 09:28:40,117 [salt.minion      :1432][INFO    ][16680] Starting a new job with PID 16680
2019-04-11 09:28:40,146 [salt.minion      :1711][INFO    ][16680] Returning information for job: 20190411092840084248
2019-04-11 09:29:08,909 [salt.loaded.ext.module.maas:1023][INFO    ][15804] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1854.798702s left)
2019-04-11 09:29:10,110 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411092910098485
2019-04-11 09:29:10,127 [salt.minion      :1432][INFO    ][16778] Starting a new job with PID 16778
2019-04-11 09:29:10,157 [salt.minion      :1711][INFO    ][16778] Returning information for job: 20190411092910098485
2019-04-11 09:29:40,322 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411092940314362
2019-04-11 09:29:40,342 [salt.minion      :1432][INFO    ][16831] Starting a new job with PID 16831
2019-04-11 09:29:40,366 [salt.minion      :1711][INFO    ][16831] Returning information for job: 20190411092940314362
2019-04-11 09:29:41,758 [salt.loaded.ext.module.maas:1023][INFO    ][15804] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1821.95007896s left)
2019-04-11 09:30:10,341 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411093010330872
2019-04-11 09:30:10,358 [salt.minion      :1432][INFO    ][16982] Starting a new job with PID 16982
2019-04-11 09:30:10,385 [salt.minion      :1711][INFO    ][16982] Returning information for job: 20190411093010330872
2019-04-11 09:30:14,434 [salt.loaded.ext.module.maas:1023][INFO    ][15804] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1789.27420688s left)
2019-04-11 09:30:40,357 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411093040348549
2019-04-11 09:30:40,375 [salt.minion      :1432][INFO    ][17044] Starting a new job with PID 17044
2019-04-11 09:30:40,400 [salt.minion      :1711][INFO    ][17044] Returning information for job: 20190411093040348549
2019-04-11 09:30:47,682 [salt.loaded.ext.module.maas:1023][INFO    ][15804] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1756.02544999s left)
2019-04-11 09:31:10,397 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411093110388772
2019-04-11 09:31:10,416 [salt.minion      :1432][INFO    ][17170] Starting a new job with PID 17170
2019-04-11 09:31:10,438 [salt.minion      :1711][INFO    ][17170] Returning information for job: 20190411093110388772
2019-04-11 09:31:20,122 [salt.loaded.ext.module.maas:1023][INFO    ][15804] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1723.58636689s left)
2019-04-11 09:31:40,417 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411093140409428
2019-04-11 09:31:40,434 [salt.minion      :1432][INFO    ][17227] Starting a new job with PID 17227
2019-04-11 09:31:40,458 [salt.minion      :1711][INFO    ][17227] Returning information for job: 20190411093140409428
2019-04-11 09:31:52,760 [salt.loaded.ext.module.maas:1023][INFO    ][15804] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1690.94819188s left)
2019-04-11 09:32:10,433 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411093210422578
2019-04-11 09:32:10,447 [salt.minion      :1432][INFO    ][17275] Starting a new job with PID 17275
2019-04-11 09:32:10,471 [salt.minion      :1711][INFO    ][17275] Returning information for job: 20190411093210422578
2019-04-11 09:32:25,486 [salt.loaded.ext.module.maas:1023][INFO    ][15804] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1658.22180295s left)
2019-04-11 09:32:40,510 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411093240502649
2019-04-11 09:32:40,525 [salt.minion      :1432][INFO    ][17329] Starting a new job with PID 17329
2019-04-11 09:32:40,548 [salt.minion      :1711][INFO    ][17329] Returning information for job: 20190411093240502649
2019-04-11 09:32:57,860 [salt.loaded.ext.module.maas:1023][INFO    ][15804] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1625.84755397s left)
2019-04-11 09:33:10,561 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411093310554496
2019-04-11 09:33:10,574 [salt.minion      :1432][INFO    ][17379] Starting a new job with PID 17379
2019-04-11 09:33:10,598 [salt.minion      :1711][INFO    ][17379] Returning information for job: 20190411093310554496
2019-04-11 09:33:30,560 [salt.loaded.ext.module.maas:1023][INFO    ][15804] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1593.1482408s left)
2019-04-11 09:33:40,608 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411093340599733
2019-04-11 09:33:40,627 [salt.minion      :1432][INFO    ][17444] Starting a new job with PID 17444
2019-04-11 09:33:40,654 [salt.minion      :1711][INFO    ][17444] Returning information for job: 20190411093340599733
2019-04-11 09:34:03,299 [salt.loaded.ext.module.maas:1023][INFO    ][15804] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1560.40885282s left)
2019-04-11 09:34:10,676 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411093410664254
2019-04-11 09:34:10,700 [salt.minion      :1432][INFO    ][17649] Starting a new job with PID 17649
2019-04-11 09:34:10,727 [salt.minion      :1711][INFO    ][17649] Returning information for job: 20190411093410664254
2019-04-11 09:34:35,963 [salt.loaded.ext.module.maas:1023][INFO    ][15804] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1527.74500585s left)
2019-04-11 09:34:40,748 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.find_job with jid 20190411093440740459
2019-04-11 09:34:40,763 [salt.minion      :1432][INFO    ][17739] Starting a new job with PID 17739
2019-04-11 09:34:40,786 [salt.minion      :1711][INFO    ][17739] Returning information for job: 20190411093440740459
2019-04-11 09:34:53,006 [salt.utils.schedule:1377][INFO    ][5625] Running scheduled job: __mine_interval
2019-04-11 09:35:08,637 [salt.state       :300 ][INFO    ][15804] {'ret': True}
2019-04-11 09:35:08,637 [salt.state       :1951][INFO    ][15804] Completed state [maas.wait_for_machine_status] at time 09:35:08.637787 duration_in_ms=754944.123
2019-04-11 09:35:08,642 [salt.minion      :1711][INFO    ][15804] Returning information for job: 20190411092223687428
2019-04-11 10:34:53,007 [salt.utils.schedule:1377][INFO    ][5625] Running scheduled job: __mine_interval
2019-04-11 11:34:53,007 [salt.utils.schedule:1377][INFO    ][5625] Running scheduled job: __mine_interval
2019-04-11 11:47:54,226 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command saltutil.refresh_pillar with jid 20190411114754217908
2019-04-11 11:47:54,249 [salt.minion      :1432][INFO    ][31154] Starting a new job with PID 31154
2019-04-11 11:47:54,362 [salt.minion      :1711][INFO    ][31154] Returning information for job: 20190411114754217908
2019-04-11 12:30:22,715 [salt.minion      :1308][INFO    ][5625] User sudo_ubuntu Executing command cp.push_dir with jid 20190411123022703989
2019-04-11 12:30:22,736 [salt.minion      :1432][INFO    ][34997] Starting a new job with PID 34997
