2019-07-05 02:04:59,344 [salt.utils.decorators:613 ][WARNING ][1977] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-05 02:05:00,431 [salt.utils.decorators:613 ][WARNING ][1977] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-05 02:05:04,249 [salt.loaded.int.states.file:2298][WARNING ][2591] 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-07-05 02:05:34,347 [salt.state       :2022][WARNING ][3016] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-07-05 02:05:37,606 [salt.utils.decorators:613 ][WARNING ][3016] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-05 02:05:51,439 [salt.utils.decorators:613 ][WARNING ][3016] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-05 02:06:13,266 [salt.utils.decorators:613 ][WARNING ][3016] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-05 02:06:14,121 [salt.utils.decorators:613 ][WARNING ][3016] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-05 02:06:16,308 [salt.loaded.ext.module.maasng:1008][WARNING ][3016] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-07-05 02:06:16,308 [salt.loaded.ext.module.maasng:1011][WARNING ][3016] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-07-05 02:06:16,937 [salt.loaded.ext.module.maasng:1235][WARNING ][3016] Ignoring parameter vlan:0
2019-07-05 02:06:30,113 [salt.utils.decorators:613 ][WARNING ][5408] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-05 02:06:30,172 [salt.loaded.ext.module.maas:412 ][WARNING ][5408] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-05 02:06:31,472 [salt.loaded.ext.module.maas:412 ][WARNING ][5408] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-05 02:06:32,569 [salt.loaded.ext.module.maas:412 ][WARNING ][5408] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-05 02:06:33,744 [salt.loaded.ext.module.maas:412 ][WARNING ][5408] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-05 02:06:35,003 [salt.loaded.ext.module.maas:412 ][WARNING ][5408] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-05 02:06:39,151 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5959] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-07-05 02:06:39,187 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5959] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-07-05 02:06:39,240 [salt.utils.parsers:1051][WARNING ][388] Minion received a SIGTERM. Exiting.
2019-07-05 02:06:40,422 [salt.cli.daemons :293 ][INFO    ][6042] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-07-05 02:06:40,595 [salt.cli.daemons :82  ][INFO    ][6042] Starting up the Salt Minion
2019-07-05 02:06:40,595 [salt.utils.event :1017][INFO    ][6042] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-07-05 02:06:41,877 [salt.minion      :976 ][INFO    ][6042] Creating minion process manager
2019-07-05 02:06:43,911 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][6042] Executing command ['date', '+%z'] in directory '/root'
2019-07-05 02:06:43,941 [salt.utils.schedule:568 ][INFO    ][6042] Updating job settings for scheduled job: __mine_interval
2019-07-05 02:06:43,943 [salt.minion      :1108][INFO    ][6042] Added mine.update to scheduler
2019-07-05 02:06:43,951 [salt.minion      :1975][INFO    ][6042] Minion is starting as user 'root'
2019-07-05 02:06:43,974 [salt.minion      :2336][INFO    ][6042] Minion is ready to receive requests!
2019-07-05 02:07:07,419 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command state.apply with jid 20190705020707405331
2019-07-05 02:07:07,437 [salt.minion      :1432][INFO    ][6159] Starting a new job with PID 6159
2019-07-05 02:07:15,392 [salt.state       :915 ][INFO    ][6159] Loading fresh modules for state activity
2019-07-05 02:07:15,453 [salt.fileclient  :1219][INFO    ][6159] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-07-05 02:07:15,504 [salt.state       :1780][INFO    ][6159] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:07:15.504673
2019-07-05 02:07:15,505 [salt.state       :1813][INFO    ][6159] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-05 02:07:15,507 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6159] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-05 02:07:17,347 [salt.state       :300 ][INFO    ][6159] {'pid': 6170, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-05 02:07:17,348 [salt.state       :1951][INFO    ][6159] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:07:17.348631 duration_in_ms=1843.957
2019-07-05 02:07:17,352 [salt.state       :1780][INFO    ][6159] Running state [maas.wait_for_machine_status] at time 02:07:17.351947
2019-07-05 02:07:17,352 [salt.state       :1813][INFO    ][6159] Executing state module.run for [maas.wait_for_machine_status]
2019-07-05 02:07:17,353 [salt.utils.decorators:613 ][WARNING ][6159] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-05 02:07:18,264 [salt.loaded.ext.module.maas:1023][INFO    ][6159] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.10051107s left)
2019-07-05 02:07:22,491 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705020722475835
2019-07-05 02:07:22,511 [salt.minion      :1432][INFO    ][6193] Starting a new job with PID 6193
2019-07-05 02:07:22,540 [salt.minion      :1711][INFO    ][6193] Returning information for job: 20190705020722475835
2019-07-05 02:07:49,042 [salt.loaded.ext.module.maas:1023][INFO    ][6159] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.32253599s left)
2019-07-05 02:07:52,564 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705020752549018
2019-07-05 02:07:52,585 [salt.minion      :1432][INFO    ][6242] Starting a new job with PID 6242
2019-07-05 02:07:52,615 [salt.minion      :1711][INFO    ][6242] Returning information for job: 20190705020752549018
2019-07-05 02:08:19,803 [salt.loaded.ext.module.maas:1023][INFO    ][6159] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.56176805s left)
2019-07-05 02:08:22,671 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705020822656733
2019-07-05 02:08:22,689 [salt.minion      :1432][INFO    ][6271] Starting a new job with PID 6271
2019-07-05 02:08:22,716 [salt.minion      :1711][INFO    ][6271] Returning information for job: 20190705020822656733
2019-07-05 02:08:50,653 [salt.loaded.ext.module.maas:1023][INFO    ][6159] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.71218109s left)
2019-07-05 02:08:52,738 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705020852727117
2019-07-05 02:08:52,764 [salt.minion      :1432][INFO    ][6321] Starting a new job with PID 6321
2019-07-05 02:08:52,795 [salt.minion      :1711][INFO    ][6321] Returning information for job: 20190705020852727117
2019-07-05 02:09:21,675 [salt.loaded.ext.module.maas:1023][INFO    ][6159] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.68983102s left)
2019-07-05 02:09:22,830 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705020922818758
2019-07-05 02:09:22,847 [salt.minion      :1432][INFO    ][6381] Starting a new job with PID 6381
2019-07-05 02:09:22,876 [salt.minion      :1711][INFO    ][6381] Returning information for job: 20190705020922818758
2019-07-05 02:09:52,750 [salt.loaded.ext.module.maas:1023][INFO    ][6159] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.61523509s left)
2019-07-05 02:09:52,914 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705020952896409
2019-07-05 02:09:52,932 [salt.minion      :1432][INFO    ][6469] Starting a new job with PID 6469
2019-07-05 02:09:52,963 [salt.minion      :1711][INFO    ][6469] Returning information for job: 20190705020952896409
2019-07-05 02:10:23,002 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705021022989687
2019-07-05 02:10:23,023 [salt.minion      :1432][INFO    ][6531] Starting a new job with PID 6531
2019-07-05 02:10:23,051 [salt.minion      :1711][INFO    ][6531] Returning information for job: 20190705021022989687
2019-07-05 02:10:23,859 [salt.loaded.ext.module.maas:1023][INFO    ][6159] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1313.50565314s left)
2019-07-05 02:10:53,122 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705021053111781
2019-07-05 02:10:53,137 [salt.minion      :1432][INFO    ][6838] Starting a new job with PID 6838
2019-07-05 02:10:53,167 [salt.minion      :1711][INFO    ][6838] Returning information for job: 20190705021053111781
2019-07-05 02:10:55,156 [salt.loaded.ext.module.maas:1023][INFO    ][6159] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1282.20933414s left)
2019-07-05 02:11:23,241 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705021123228197
2019-07-05 02:11:23,266 [salt.minion      :1432][INFO    ][6937] Starting a new job with PID 6937
2019-07-05 02:11:23,293 [salt.minion      :1711][INFO    ][6937] Returning information for job: 20190705021123228197
2019-07-05 02:11:26,474 [salt.loaded.ext.module.maas:1023][INFO    ][6159] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1250.89044905s left)
2019-07-05 02:11:53,408 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705021153392774
2019-07-05 02:11:53,433 [salt.minion      :1432][INFO    ][7251] Starting a new job with PID 7251
2019-07-05 02:11:53,463 [salt.minion      :1711][INFO    ][7251] Returning information for job: 20190705021153392774
2019-07-05 02:11:58,339 [salt.loaded.ext.module.maas:1023][INFO    ][6159] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1219.02627802s left)
2019-07-05 02:12:23,549 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705021223538805
2019-07-05 02:12:23,576 [salt.minion      :1432][INFO    ][7445] Starting a new job with PID 7445
2019-07-05 02:12:23,612 [salt.minion      :1711][INFO    ][7445] Returning information for job: 20190705021223538805
2019-07-05 02:12:30,654 [salt.loaded.ext.module.maas:1023][INFO    ][6159] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1186.71068311s left)
2019-07-05 02:12:53,749 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705021253738985
2019-07-05 02:12:53,770 [salt.minion      :1432][INFO    ][7734] Starting a new job with PID 7734
2019-07-05 02:12:53,804 [salt.minion      :1711][INFO    ][7734] Returning information for job: 20190705021253738985
2019-07-05 02:13:03,012 [salt.loaded.ext.module.maas:1023][INFO    ][6159] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1154.35270309s left)
2019-07-05 02:13:23,901 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705021323888981
2019-07-05 02:13:23,924 [salt.minion      :1432][INFO    ][7848] Starting a new job with PID 7848
2019-07-05 02:13:23,956 [salt.minion      :1711][INFO    ][7848] Returning information for job: 20190705021323888981
2019-07-05 02:13:35,715 [salt.loaded.ext.module.maas:1023][INFO    ][6159] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1121.65033913s left)
2019-07-05 02:13:54,052 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705021354035108
2019-07-05 02:13:54,078 [salt.minion      :1432][INFO    ][7955] Starting a new job with PID 7955
2019-07-05 02:13:54,105 [salt.minion      :1711][INFO    ][7955] Returning information for job: 20190705021354035108
2019-07-05 02:14:08,669 [salt.state       :300 ][INFO    ][6159] {'ret': True}
2019-07-05 02:14:08,670 [salt.state       :1951][INFO    ][6159] Completed state [maas.wait_for_machine_status] at time 02:14:08.669675 duration_in_ms=411317.728
2019-07-05 02:14:08,672 [salt.minion      :1711][INFO    ][6159] Returning information for job: 20190705020707405331
2019-07-05 02:14:09,377 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command state.apply with jid 20190705021409366711
2019-07-05 02:14:09,396 [salt.minion      :1432][INFO    ][7977] Starting a new job with PID 7977
2019-07-05 02:14:17,599 [salt.state       :915 ][INFO    ][7977] Loading fresh modules for state activity
2019-07-05 02:14:17,672 [salt.fileclient  :1219][INFO    ][7977] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-07-05 02:14:17,790 [salt.state       :1780][INFO    ][7977] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:14:17.790553
2019-07-05 02:14:17,790 [salt.state       :1813][INFO    ][7977] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-05 02:14:17,792 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7977] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-05 02:14:19,735 [salt.state       :300 ][INFO    ][7977] {'pid': 7984, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-05 02:14:19,736 [salt.state       :1951][INFO    ][7977] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:14:19.736324 duration_in_ms=1945.77
2019-07-05 02:14:19,741 [salt.state       :1780][INFO    ][7977] Running state [maas_machines_storage_cmp002_lvm] at time 02:14:19.740920
2019-07-05 02:14:19,741 [salt.state       :1813][INFO    ][7977] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-07-05 02:14:20,932 [salt.loaded.ext.module.maasng:610 ][INFO    ][7977] hkrms6
2019-07-05 02:14:20,932 [salt.loaded.ext.module.maasng:626 ][INFO    ][7977] sda
2019-07-05 02:14:21,488 [salt.loaded.ext.module.maasng:361 ][INFO    ][7977] hkrms6
2019-07-05 02:14:21,588 [salt.loaded.ext.module.maasng:367 ][INFO    ][7977] [{u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/hkrms6/blockdevices/1/', u'uuid': None, u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'e67ac238-0d42-4e03-b028-ef0848c5f2f4', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hkrms6', u'device_id': 1, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'aa408d77-fdb1-4b3b-9702-eebc8c00f13a', u'fstype': u'lvm-pv', u'label': None}, 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/hkrms6/blockdevices/1/partition/1'}], u'path': u'/dev/disk/by-dname/sda', u'used_for': u'MBR partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'system_id': u'hkrms6', u'partition_table_type': u'MBR', u'filesystem': None, u'id': 1, u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'type': u'physical', u'model': u'LOGICAL VOLUME', u'name': u'sda'}, {u'size': 800097042432, u'resource_uri': u'/MAAS/api/2.0/nodes/hkrms6/blockdevices/3/', u'uuid': u'e0cd4407-6f3e-4711-82f6-96eb5717387f', u'tags': [], u'used_size': 800097042432, u'partitions': [], u'path': u'/dev/disk/by-dname/lvroot', u'used_for': u'ext4 formatted filesystem mounted at /', u'id_path': None, u'system_id': u'hkrms6', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'b462bf95-6b20-41c0-a7ad-c602fb217ef0', u'fstype': u'ext4', u'label': u'root'}, u'id': 3, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'model': None, u'name': u'vgroot-lvroot'}]
2019-07-05 02:14:21,589 [salt.loaded.ext.module.maasng:632 ][INFO    ][7977] vgroot
2019-07-05 02:14:21,590 [salt.loaded.ext.module.maasng:635 ][INFO    ][7977] lvroot
2019-07-05 02:14:21,590 [salt.loaded.ext.module.maasng:639 ][INFO    ][7977] 107374182400
2019-07-05 02:14:22,212 [salt.loaded.ext.module.maasng:645 ][INFO    ][7977] {u'hwe_kernel': u'', u'swap_size': None, u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], 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'memory_test_status_name': u'Unknown', u'fqdn': u'cmp002.maas', u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], 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/hkrms6/', u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'hkrms6', 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'owner_data': {}, u'blockdevice_set': [{u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/hkrms6/blockdevices/1/', u'uuid': None, u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'dd125d9c-996a-4f37-a069-91cae4ba9496', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hkrms6', u'device_id': 1, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'12669976-c279-47d8-b041-4b25e2f40bb1', u'fstype': u'lvm-pv', u'label': None}, 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/hkrms6/blockdevices/1/partition/6'}], u'path': u'/dev/disk/by-dname/sda', u'used_for': u'MBR partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'system_id': u'hkrms6', u'partition_table_type': u'MBR', u'filesystem': None, u'id': 1, u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'type': u'physical', u'model': u'LOGICAL VOLUME', u'name': u'sda'}, {u'size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/hkrms6/blockdevices/11/', u'uuid': u'd7f45b46-a61a-4f28-b7fd-0d6a9ae27d1e', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'path': u'/dev/disk/by-dname/lvroot', u'used_for': u'ext4 formatted filesystem mounted at /', u'id_path': None, u'system_id': u'hkrms6', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'3b7fc935-f062-49ac-81ae-d1a9196da655', u'fstype': u'ext4', u'label': u'root'}, u'id': 11, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'model': None, u'name': u'vgroot-lvroot'}], u'status': 4, u'storage_test_status': 2, u'cpu_count': 40, u'power_state': u'off', u'physicalblockdevice_set': [{u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/hkrms6/blockdevices/1/', u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'id': 1, u'partitions': [{u'uuid': u'dd125d9c-996a-4f37-a069-91cae4ba9496', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hkrms6', u'device_id': 1, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'12669976-c279-47d8-b041-4b25e2f40bb1', u'fstype': u'lvm-pv', u'label': None}, 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/hkrms6/blockdevices/1/partition/6'}], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'hkrms6', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'used_size': 800106479616, u'model': u'LOGICAL VOLUME', u'uuid': None}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'hkrms6', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'commissioning_status_name': u'Passed', u'current_testing_result_id': 3, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'bcaches': [], u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'status_action': u'', u'disable_ipv4': False, u'virtualblockdevice_set': [{u'size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/hkrms6/blockdevices/11/', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'id': 11, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'hkrms6', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'3b7fc935-f062-49ac-81ae-d1a9196da655', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'model': None, u'uuid': u'd7f45b46-a61a-4f28-b7fd-0d6a9ae27d1e'}], u'commissioning_status': 2, u'min_hwe_kernel': u'ga-16.04', u'boot_interface': {u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'nrs3xy', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'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'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'nrs3xy', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'id': 17, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'nrs3xy', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'system_id': u'hkrms6', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/hkrms6/interfaces/4/'}, u'interface_set': [{u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'nrs3xy', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'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'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'nrs3xy', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'id': 17, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'nrs3xy', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'system_id': u'hkrms6', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/hkrms6/interfaces/4/'}, {u'discovered': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'params': u'', u'mac_address': u'38:ea:a7:8f:07:50', u'parents': [], u'system_id': u'hkrms6', u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/hkrms6/interfaces/15/'}, {u'discovered': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'params': u'', u'mac_address': u'38:ea:a7:8f:07:51', u'parents': [], u'system_id': u'hkrms6', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/hkrms6/interfaces/11/'}, {u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'nrs3xy', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.40'}], u'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'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'nrs3xy', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 18, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'nrs3xy', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'params': u'', u'mac_address': u'9c:b6:54:8a:10:1c', u'parents': [], u'system_id': u'hkrms6', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/hkrms6/interfaces/12/'}, {u'discovered': None, u'name': u'ens2f1', u'links': [{u'id': 19, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'params': u'', u'mac_address': u'38:ea:a7:8f:12:49', u'parents': [], u'system_id': u'hkrms6', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/hkrms6/interfaces/13/'}, {u'discovered': None, u'name': u'ens2f0', u'links': [{u'id': 20, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'params': u'', u'mac_address': u'38:ea:a7:8f:12:48', u'parents': [], u'system_id': u'hkrms6', u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/hkrms6/interfaces/14/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'boot_disk': {u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/hkrms6/blockdevices/1/', u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'id': 1, u'partitions': [{u'uuid': u'dd125d9c-996a-4f37-a069-91cae4ba9496', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hkrms6', u'device_id': 1, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'12669976-c279-47d8-b041-4b25e2f40bb1', u'fstype': u'lvm-pv', u'label': None}, 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/hkrms6/blockdevices/1/partition/6'}], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'hkrms6', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'used_size': 800106479616, u'model': u'LOGICAL VOLUME', u'uuid': None}}
2019-07-05 02:14:22,216 [salt.state       :300 ][INFO    ][7977] {'new': {'storage_layout': 'lvm'}}
2019-07-05 02:14:22,217 [salt.state       :1951][INFO    ][7977] Completed state [maas_machines_storage_cmp002_lvm] at time 02:14:22.216964 duration_in_ms=2476.042
2019-07-05 02:14:22,217 [salt.state       :1780][INFO    ][7977] Running state [maas_machines_storage_cmp001_lvm] at time 02:14:22.217686
2019-07-05 02:14:22,218 [salt.state       :1813][INFO    ][7977] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-07-05 02:14:23,547 [salt.loaded.ext.module.maasng:610 ][INFO    ][7977] sxxw4x
2019-07-05 02:14:23,547 [salt.loaded.ext.module.maasng:626 ][INFO    ][7977] sda
2019-07-05 02:14:24,124 [salt.loaded.ext.module.maasng:361 ][INFO    ][7977] sxxw4x
2019-07-05 02:14:24,218 [salt.loaded.ext.module.maasng:367 ][INFO    ][7977] [{u'size': 800109715456, u'block_size': 4096, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/sxxw4x/blockdevices/2/', u'used_size': 800106479616, u'id': 2, u'tags': [u'ssd'], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'sxxw4x', 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'3e29058a-204a-4d8a-a675-cb5f67b94078', u'resource_uri': u'/MAAS/api/2.0/nodes/sxxw4x/blockdevices/2/partition/2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'sxxw4x', u'filesystem': {u'uuid': u'bde133ab-9ca3-4a9b-9cc5-fa247fe8c186', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 2, u'size': 800101236736}], u'type': u'physical', u'model': u'LOGICAL VOLUME', u'uuid': None}, {u'size': 800097042432, u'block_size': 4096, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/sxxw4x/blockdevices/4/', u'used_size': 800097042432, u'id': 4, u'tags': [], u'filesystem': {u'uuid': u'7d37c699-d0af-42f5-8f71-f5177ce50ed9', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'sxxw4x', 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'model': None, u'uuid': u'486b9b0e-9e7a-4e91-b9e8-8cac88fc6382'}]
2019-07-05 02:14:24,219 [salt.loaded.ext.module.maasng:632 ][INFO    ][7977] vgroot
2019-07-05 02:14:24,219 [salt.loaded.ext.module.maasng:635 ][INFO    ][7977] lvroot
2019-07-05 02:14:24,220 [salt.loaded.ext.module.maasng:639 ][INFO    ][7977] 107374182400
2019-07-05 02:14:24,462 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705021424447629
2019-07-05 02:14:24,486 [salt.minion      :1432][INFO    ][8014] Starting a new job with PID 8014
2019-07-05 02:14:24,514 [salt.minion      :1711][INFO    ][8014] Returning information for job: 20190705021424447629
2019-07-05 02:14:24,833 [salt.loaded.ext.module.maasng:645 ][INFO    ][7977] {u'hwe_kernel': u'', u'status_action': u'', u'testing_status_name': u'Passed', u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.39', u'192.168.11.43'], u'cpu_count': 40, 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'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'relay_vlan': None, u'primary_rack': u'nrs3xy', 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.39', u'id': 24, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'nrs3xy', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'nrs3xy', 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.39'}], u'mac_address': u'9c:b6:54:8a:95:a0', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'sxxw4x', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/sxxw4x/interfaces/5/'}, u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'uuid': None, u'resource_uri': u'/MAAS/api/2.0/nodes/sxxw4x/blockdevices/2/', u'used_size': 800106479616, u'tags': [u'ssd'], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'sxxw4x', 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'88da607d-1053-4f73-baf7-0025352cbfe7', u'resource_uri': u'/MAAS/api/2.0/nodes/sxxw4x/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'sxxw4x', u'filesystem': {u'uuid': u'51d43a81-8eef-446f-8808-dc706346b1a9', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'size': 800101236736}], u'type': u'physical', u'id': 2, u'name': u'sda'}, u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default'}, u'resource_uri': u'/MAAS/api/2.0/machines/sxxw4x/', u'disable_ipv4': False, u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'sxxw4x', 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'block_size': 4096, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/sxxw4x/blockdevices/2/', u'used_size': 800106479616, u'id': 2, u'tags': [u'ssd'], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'sxxw4x', 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'88da607d-1053-4f73-baf7-0025352cbfe7', u'resource_uri': u'/MAAS/api/2.0/nodes/sxxw4x/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'sxxw4x', u'filesystem': {u'uuid': u'51d43a81-8eef-446f-8808-dc706346b1a9', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'size': 800101236736}], u'type': u'physical', u'model': u'LOGICAL VOLUME', u'uuid': None}, {u'size': 107374182400, u'block_size': 4096, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/sxxw4x/blockdevices/12/', u'used_size': 107374182400, u'id': 12, u'tags': [], u'filesystem': {u'uuid': u'7b2898e2-6bb7-49cf-a613-6ac57e70e88b', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'sxxw4x', 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'model': None, u'uuid': u'27e821ff-a31e-4863-b88e-5b6f27282e62'}], u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'raids': [], u'physicalblockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'uuid': None, u'resource_uri': u'/MAAS/api/2.0/nodes/sxxw4x/blockdevices/2/', u'used_size': 800106479616, u'tags': [u'ssd'], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'sxxw4x', 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'88da607d-1053-4f73-baf7-0025352cbfe7', u'resource_uri': u'/MAAS/api/2.0/nodes/sxxw4x/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'sxxw4x', u'filesystem': {u'uuid': u'51d43a81-8eef-446f-8808-dc706346b1a9', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'size': 800101236736}], u'type': u'physical', u'id': 2, u'name': u'sda'}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'sxxw4x', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'commissioning_status_name': u'Passed', u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'nrs3xy', 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.39', u'id': 24, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'nrs3xy', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'nrs3xy', 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.39'}], u'mac_address': u'9c:b6:54:8a:95:a0', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'sxxw4x', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/sxxw4x/interfaces/5/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d5', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'sxxw4x', u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/sxxw4x/interfaces/16/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d4', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'sxxw4x', u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/sxxw4x/interfaces/19/'}, {u'name': u'ens2f0', u'links': [{u'id': 25, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cc', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'sxxw4x', u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/sxxw4x/interfaces/17/'}, {u'name': u'ens2f1', u'links': [{u'id': 26, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cd', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'sxxw4x', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/sxxw4x/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'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'relay_vlan': None, u'primary_rack': u'nrs3xy', 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'id': 27, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'nrs3xy', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'nrs3xy', 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.43'}], u'mac_address': u'9c:b6:54:8a:95:a4', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'sxxw4x', u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/sxxw4x/interfaces/20/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'bcaches': [], u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'block_size': 4096, u'uuid': u'27e821ff-a31e-4863-b88e-5b6f27282e62', u'resource_uri': u'/MAAS/api/2.0/nodes/sxxw4x/blockdevices/12/', u'used_size': 107374182400, u'tags': [], u'filesystem': {u'uuid': u'7b2898e2-6bb7-49cf-a613-6ac57e70e88b', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'sxxw4x', 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'name': u'vgroot-lvroot'}], u'commissioning_status': 2, u'min_hwe_kernel': u'ga-16.04', u'current_commissioning_result_id': 4, u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'node_type_name': u'Machine'}
2019-07-05 02:14:24,836 [salt.state       :300 ][INFO    ][7977] {'new': {'storage_layout': 'lvm'}}
2019-07-05 02:14:24,836 [salt.state       :1951][INFO    ][7977] Completed state [maas_machines_storage_cmp001_lvm] at time 02:14:24.836435 duration_in_ms=2618.749
2019-07-05 02:14:24,839 [salt.minion      :1711][INFO    ][7977] Returning information for job: 20190705021409366711
2019-07-05 02:14:25,548 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command state.apply with jid 20190705021425536055
2019-07-05 02:14:25,572 [salt.minion      :1432][INFO    ][8021] Starting a new job with PID 8021
2019-07-05 02:14:26,763 [salt.state       :915 ][INFO    ][8021] Loading fresh modules for state activity
2019-07-05 02:14:26,829 [salt.fileclient  :1219][INFO    ][8021] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-07-05 02:14:26,889 [salt.state       :1780][INFO    ][8021] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:14:26.889048
2019-07-05 02:14:26,889 [salt.state       :1813][INFO    ][8021] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-05 02:14:26,891 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8021] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-05 02:14:28,792 [salt.state       :300 ][INFO    ][8021] {'pid': 8029, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-05 02:14:28,793 [salt.state       :1951][INFO    ][8021] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:14:28.793249 duration_in_ms=1904.201
2019-07-05 02:14:28,796 [salt.state       :1780][INFO    ][8021] Running state [maas.deploy_machines] at time 02:14:28.796397
2019-07-05 02:14:28,797 [salt.state       :1813][INFO    ][8021] Executing state module.run for [maas.deploy_machines]
2019-07-05 02:14:28,798 [salt.utils.decorators:613 ][WARNING ][8021] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-05 02:14:29,384 [salt.loaded.ext.module.maas:684 ][INFO    ][8021] deploymachines hwe_kernel=ga-16.04 system_id=hkrms6 distro_series=xenial
2019-07-05 02:14:31,825 [salt.loaded.ext.module.maas:684 ][INFO    ][8021] deploymachines hwe_kernel=ga-16.04 system_id=sxxw4x distro_series=xenial
2019-07-05 02:14:34,306 [salt.loaded.ext.module.maas:684 ][INFO    ][8021] deploymachines hwe_kernel=ga-16.04 system_id=tb3hhw distro_series=xenial
2019-07-05 02:14:36,860 [salt.loaded.ext.module.maas:684 ][INFO    ][8021] deploymachines hwe_kernel=ga-16.04 system_id=ywske8 distro_series=xenial
2019-07-05 02:14:39,550 [salt.loaded.ext.module.maas:684 ][INFO    ][8021] deploymachines hwe_kernel=ga-16.04 system_id=6schfe distro_series=xenial
2019-07-05 02:14:40,613 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705021440600609
2019-07-05 02:14:40,638 [salt.minion      :1432][INFO    ][8298] Starting a new job with PID 8298
2019-07-05 02:14:40,663 [salt.minion      :1711][INFO    ][8298] Returning information for job: 20190705021440600609
2019-07-05 02:14:42,208 [salt.state       :300 ][INFO    ][8021] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-07-05 02:14:42,209 [salt.state       :1951][INFO    ][8021] Completed state [maas.deploy_machines] at time 02:14:42.209464 duration_in_ms=13413.066
2019-07-05 02:14:42,213 [salt.minion      :1711][INFO    ][8021] Returning information for job: 20190705021425536055
2019-07-05 02:14:42,796 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command state.apply with jid 20190705021442775114
2019-07-05 02:14:42,819 [salt.minion      :1432][INFO    ][8331] Starting a new job with PID 8331
2019-07-05 02:14:51,111 [salt.state       :915 ][INFO    ][8331] Loading fresh modules for state activity
2019-07-05 02:14:51,176 [salt.fileclient  :1219][INFO    ][8331] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-07-05 02:14:51,227 [salt.state       :1780][INFO    ][8331] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:14:51.227296
2019-07-05 02:14:51,227 [salt.state       :1813][INFO    ][8331] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-05 02:14:51,229 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8331] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-05 02:14:52,959 [salt.state       :300 ][INFO    ][8331] {'pid': 8352, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-05 02:14:52,961 [salt.state       :1951][INFO    ][8331] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:14:52.960836 duration_in_ms=1733.539
2019-07-05 02:14:52,965 [salt.state       :1780][INFO    ][8331] Running state [maas.wait_for_machine_status] at time 02:14:52.965131
2019-07-05 02:14:52,965 [salt.state       :1813][INFO    ][8331] Executing state module.run for [maas.wait_for_machine_status]
2019-07-05 02:14:52,966 [salt.utils.decorators:613 ][WARNING ][8331] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-05 02:14:55,870 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.10921907s left)
2019-07-05 02:14:57,874 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705021457858659
2019-07-05 02:14:57,904 [salt.minion      :1432][INFO    ][8366] Starting a new job with PID 8366
2019-07-05 02:14:57,926 [salt.minion      :1711][INFO    ][8366] Returning information for job: 20190705021457858659
2019-07-05 02:15:27,932 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705021527922497
2019-07-05 02:15:27,955 [salt.minion      :1432][INFO    ][8408] Starting a new job with PID 8408
2019-07-05 02:15:27,978 [salt.minion      :1711][INFO    ][8408] Returning information for job: 20190705021527922497
2019-07-05 02:15:28,857 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.12208605s left)
2019-07-05 02:15:58,047 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705021558032284
2019-07-05 02:15:58,077 [salt.minion      :1432][INFO    ][8463] Starting a new job with PID 8463
2019-07-05 02:15:58,097 [salt.minion      :1711][INFO    ][8463] Returning information for job: 20190705021558032284
2019-07-05 02:16:01,726 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.25302005s left)
2019-07-05 02:16:28,120 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705021628102958
2019-07-05 02:16:28,144 [salt.minion      :1432][INFO    ][8493] Starting a new job with PID 8493
2019-07-05 02:16:28,166 [salt.minion      :1711][INFO    ][8493] Returning information for job: 20190705021628102958
2019-07-05 02:16:34,735 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2148.24426198s left)
2019-07-05 02:16:58,199 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705021658184169
2019-07-05 02:16:58,224 [salt.minion      :1432][INFO    ][8538] Starting a new job with PID 8538
2019-07-05 02:16:58,246 [salt.minion      :1711][INFO    ][8538] Returning information for job: 20190705021658184169
2019-07-05 02:17:07,662 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2115.31720209s left)
2019-07-05 02:17:28,261 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705021728253259
2019-07-05 02:17:28,277 [salt.minion      :1432][INFO    ][8634] Starting a new job with PID 8634
2019-07-05 02:17:28,297 [salt.minion      :1711][INFO    ][8634] Returning information for job: 20190705021728253259
2019-07-05 02:17:41,207 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2081.77219105s left)
2019-07-05 02:17:58,353 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705021758342540
2019-07-05 02:17:58,379 [salt.minion      :1432][INFO    ][8796] Starting a new job with PID 8796
2019-07-05 02:17:58,401 [salt.minion      :1711][INFO    ][8796] Returning information for job: 20190705021758342540
2019-07-05 02:18:14,260 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2048.71869898s left)
2019-07-05 02:18:28,457 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705021828438781
2019-07-05 02:18:28,488 [salt.minion      :1432][INFO    ][8832] Starting a new job with PID 8832
2019-07-05 02:18:28,509 [salt.minion      :1711][INFO    ][8832] Returning information for job: 20190705021828438781
2019-07-05 02:18:47,360 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2015.61880112s left)
2019-07-05 02:18:58,572 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705021858558544
2019-07-05 02:18:58,600 [salt.minion      :1432][INFO    ][9162] Starting a new job with PID 9162
2019-07-05 02:18:58,624 [salt.minion      :1711][INFO    ][9162] Returning information for job: 20190705021858558544
2019-07-05 02:19:20,321 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1982.65760803s left)
2019-07-05 02:19:28,690 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705021928671777
2019-07-05 02:19:28,717 [salt.minion      :1432][INFO    ][9274] Starting a new job with PID 9274
2019-07-05 02:19:28,755 [salt.minion      :1711][INFO    ][9274] Returning information for job: 20190705021928671777
2019-07-05 02:19:53,515 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1949.46396613s left)
2019-07-05 02:19:58,880 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705021958865576
2019-07-05 02:19:58,906 [salt.minion      :1432][INFO    ][9648] Starting a new job with PID 9648
2019-07-05 02:19:58,932 [salt.minion      :1711][INFO    ][9648] Returning information for job: 20190705021958865576
2019-07-05 02:20:26,587 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1916.39238214s left)
2019-07-05 02:20:29,010 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705022028995612
2019-07-05 02:20:29,039 [salt.minion      :1432][INFO    ][9680] Starting a new job with PID 9680
2019-07-05 02:20:29,062 [salt.minion      :1711][INFO    ][9680] Returning information for job: 20190705022028995612
2019-07-05 02:20:59,195 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705022059183513
2019-07-05 02:20:59,217 [salt.minion      :1432][INFO    ][10053] Starting a new job with PID 10053
2019-07-05 02:20:59,242 [salt.minion      :1711][INFO    ][10053] Returning information for job: 20190705022059183513
2019-07-05 02:20:59,549 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1883.42987108s left)
2019-07-05 02:21:29,299 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705022129289486
2019-07-05 02:21:29,322 [salt.minion      :1432][INFO    ][10099] Starting a new job with PID 10099
2019-07-05 02:21:29,345 [salt.minion      :1711][INFO    ][10099] Returning information for job: 20190705022129289486
2019-07-05 02:21:32,691 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1850.28758502s left)
2019-07-05 02:21:59,478 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705022159462793
2019-07-05 02:21:59,504 [salt.minion      :1432][INFO    ][10485] Starting a new job with PID 10485
2019-07-05 02:21:59,541 [salt.minion      :1711][INFO    ][10485] Returning information for job: 20190705022159462793
2019-07-05 02:22:05,664 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1817.31532598s left)
2019-07-05 02:22:29,645 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705022229629584
2019-07-05 02:22:29,675 [salt.minion      :1432][INFO    ][10531] Starting a new job with PID 10531
2019-07-05 02:22:29,699 [salt.minion      :1711][INFO    ][10531] Returning information for job: 20190705022229629584
2019-07-05 02:22:39,272 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1783.70723104s left)
2019-07-05 02:22:59,800 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705022259787628
2019-07-05 02:22:59,827 [salt.minion      :1432][INFO    ][10763] Starting a new job with PID 10763
2019-07-05 02:22:59,850 [salt.minion      :1711][INFO    ][10763] Returning information for job: 20190705022259787628
2019-07-05 02:23:12,276 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1750.70310497s left)
2019-07-05 02:23:29,965 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705022329950113
2019-07-05 02:23:29,993 [salt.minion      :1432][INFO    ][10795] Starting a new job with PID 10795
2019-07-05 02:23:30,017 [salt.minion      :1711][INFO    ][10795] Returning information for job: 20190705022329950113
2019-07-05 02:23:45,392 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1717.58690095s left)
2019-07-05 02:24:00,149 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705022400137374
2019-07-05 02:24:00,169 [salt.minion      :1432][INFO    ][10904] Starting a new job with PID 10904
2019-07-05 02:24:00,192 [salt.minion      :1711][INFO    ][10904] Returning information for job: 20190705022400137374
2019-07-05 02:24:18,476 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1684.50287604s left)
2019-07-05 02:24:30,372 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705022430356328
2019-07-05 02:24:30,402 [salt.minion      :1432][INFO    ][10973] Starting a new job with PID 10973
2019-07-05 02:24:30,426 [salt.minion      :1711][INFO    ][10973] Returning information for job: 20190705022430356328
2019-07-05 02:24:51,575 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1651.40416098s left)
2019-07-05 02:25:00,588 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705022500574485
2019-07-05 02:25:00,616 [salt.minion      :1432][INFO    ][11270] Starting a new job with PID 11270
2019-07-05 02:25:00,640 [salt.minion      :1711][INFO    ][11270] Returning information for job: 20190705022500574485
2019-07-05 02:25:24,532 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1618.44695807s left)
2019-07-05 02:25:30,764 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705022530747628
2019-07-05 02:25:30,798 [salt.minion      :1432][INFO    ][11431] Starting a new job with PID 11431
2019-07-05 02:25:30,821 [salt.minion      :1711][INFO    ][11431] Returning information for job: 20190705022530747628
2019-07-05 02:25:57,528 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1585.45056701s left)
2019-07-05 02:26:00,948 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705022600938878
2019-07-05 02:26:00,978 [salt.minion      :1432][INFO    ][11488] Starting a new job with PID 11488
2019-07-05 02:26:00,999 [salt.minion      :1711][INFO    ][11488] Returning information for job: 20190705022600938878
2019-07-05 02:26:30,412 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1552.56662703s left)
2019-07-05 02:26:31,120 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705022631107467
2019-07-05 02:26:31,149 [salt.minion      :1432][INFO    ][11518] Starting a new job with PID 11518
2019-07-05 02:26:31,170 [salt.minion      :1711][INFO    ][11518] Returning information for job: 20190705022631107467
2019-07-05 02:27:01,315 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705022701304235
2019-07-05 02:27:01,338 [salt.minion      :1432][INFO    ][11620] Starting a new job with PID 11620
2019-07-05 02:27:01,356 [salt.minion      :1711][INFO    ][11620] Returning information for job: 20190705022701304235
2019-07-05 02:27:03,248 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1519.73125505s left)
2019-07-05 02:27:31,524 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705022731507938
2019-07-05 02:27:31,552 [salt.minion      :1432][INFO    ][11686] Starting a new job with PID 11686
2019-07-05 02:27:31,576 [salt.minion      :1711][INFO    ][11686] Returning information for job: 20190705022731507938
2019-07-05 02:27:36,131 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1486.8481431s left)
2019-07-05 02:28:01,553 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705022801536508
2019-07-05 02:28:01,579 [salt.minion      :1432][INFO    ][11914] Starting a new job with PID 11914
2019-07-05 02:28:01,603 [salt.minion      :1711][INFO    ][11914] Returning information for job: 20190705022801536508
2019-07-05 02:28:09,284 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1453.695508s left)
2019-07-05 02:28:31,572 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705022831562637
2019-07-05 02:28:31,599 [salt.minion      :1432][INFO    ][11946] Starting a new job with PID 11946
2019-07-05 02:28:31,623 [salt.minion      :1711][INFO    ][11946] Returning information for job: 20190705022831562637
2019-07-05 02:28:42,455 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1420.52438712s left)
2019-07-05 02:29:01,601 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705022901585427
2019-07-05 02:29:01,629 [salt.minion      :1432][INFO    ][11990] Starting a new job with PID 11990
2019-07-05 02:29:01,651 [salt.minion      :1711][INFO    ][11990] Returning information for job: 20190705022901585427
2019-07-05 02:29:15,304 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1387.67477608s left)
2019-07-05 02:29:31,648 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705022931632878
2019-07-05 02:29:31,676 [salt.minion      :1432][INFO    ][12022] Starting a new job with PID 12022
2019-07-05 02:29:31,697 [salt.minion      :1711][INFO    ][12022] Returning information for job: 20190705022931632878
2019-07-05 02:29:48,133 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1354.84610295s left)
2019-07-05 02:30:01,689 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705023001672330
2019-07-05 02:30:01,718 [salt.minion      :1432][INFO    ][12073] Starting a new job with PID 12073
2019-07-05 02:30:01,741 [salt.minion      :1711][INFO    ][12073] Returning information for job: 20190705023001672330
2019-07-05 02:30:20,996 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1321.98327708s left)
2019-07-05 02:30:31,768 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705023031753933
2019-07-05 02:30:31,796 [salt.minion      :1432][INFO    ][12101] Starting a new job with PID 12101
2019-07-05 02:30:31,817 [salt.minion      :1711][INFO    ][12101] Returning information for job: 20190705023031753933
2019-07-05 02:30:54,032 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1288.94655299s left)
2019-07-05 02:31:01,837 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705023101823825
2019-07-05 02:31:01,865 [salt.minion      :1432][INFO    ][12151] Starting a new job with PID 12151
2019-07-05 02:31:01,885 [salt.minion      :1711][INFO    ][12151] Returning information for job: 20190705023101823825
2019-07-05 02:31:26,956 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1256.02330112s left)
2019-07-05 02:31:31,924 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705023131908846
2019-07-05 02:31:31,953 [salt.minion      :1432][INFO    ][12181] Starting a new job with PID 12181
2019-07-05 02:31:31,976 [salt.minion      :1711][INFO    ][12181] Returning information for job: 20190705023131908846
2019-07-05 02:31:59,814 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1223.16484308s left)
2019-07-05 02:32:02,036 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705023202019923
2019-07-05 02:32:02,065 [salt.minion      :1432][INFO    ][12232] Starting a new job with PID 12232
2019-07-05 02:32:02,089 [salt.minion      :1711][INFO    ][12232] Returning information for job: 20190705023202019923
2019-07-05 02:32:32,134 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705023232125085
2019-07-05 02:32:32,158 [salt.minion      :1432][INFO    ][12261] Starting a new job with PID 12261
2019-07-05 02:32:32,183 [salt.minion      :1711][INFO    ][12261] Returning information for job: 20190705023232125085
2019-07-05 02:32:32,579 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1190.40045214s left)
2019-07-05 02:33:02,264 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705023302249309
2019-07-05 02:33:02,291 [salt.minion      :1432][INFO    ][12308] Starting a new job with PID 12308
2019-07-05 02:33:02,314 [salt.minion      :1711][INFO    ][12308] Returning information for job: 20190705023302249309
2019-07-05 02:33:05,509 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1157.46991515s left)
2019-07-05 02:33:32,401 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705023332383361
2019-07-05 02:33:32,431 [salt.minion      :1432][INFO    ][12340] Starting a new job with PID 12340
2019-07-05 02:33:32,455 [salt.minion      :1711][INFO    ][12340] Returning information for job: 20190705023332383361
2019-07-05 02:33:38,324 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1124.65486193s left)
2019-07-05 02:34:02,516 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705023402506460
2019-07-05 02:34:02,542 [salt.minion      :1432][INFO    ][12384] Starting a new job with PID 12384
2019-07-05 02:34:02,564 [salt.minion      :1711][INFO    ][12384] Returning information for job: 20190705023402506460
2019-07-05 02:34:11,191 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1091.78830409s left)
2019-07-05 02:34:32,677 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705023432662185
2019-07-05 02:34:32,705 [salt.minion      :1432][INFO    ][12414] Starting a new job with PID 12414
2019-07-05 02:34:32,729 [salt.minion      :1711][INFO    ][12414] Returning information for job: 20190705023432662185
2019-07-05 02:34:44,190 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1058.78907013s left)
2019-07-05 02:35:02,843 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705023502835383
2019-07-05 02:35:02,864 [salt.minion      :1432][INFO    ][12459] Starting a new job with PID 12459
2019-07-05 02:35:02,886 [salt.minion      :1711][INFO    ][12459] Returning information for job: 20190705023502835383
2019-07-05 02:35:17,098 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1025.88151002s left)
2019-07-05 02:35:33,036 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705023533026921
2019-07-05 02:35:33,064 [salt.minion      :1432][INFO    ][12499] Starting a new job with PID 12499
2019-07-05 02:35:33,084 [salt.minion      :1711][INFO    ][12499] Returning information for job: 20190705023533026921
2019-07-05 02:35:49,884 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (993.095103025s left)
2019-07-05 02:36:03,249 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705023603235538
2019-07-05 02:36:03,280 [salt.minion      :1432][INFO    ][12555] Starting a new job with PID 12555
2019-07-05 02:36:03,303 [salt.minion      :1711][INFO    ][12555] Returning information for job: 20190705023603235538
2019-07-05 02:36:22,663 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (960.315999031s left)
2019-07-05 02:36:33,461 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705023633444390
2019-07-05 02:36:33,497 [salt.minion      :1432][INFO    ][12583] Starting a new job with PID 12583
2019-07-05 02:36:33,533 [salt.minion      :1711][INFO    ][12583] Returning information for job: 20190705023633444390
2019-07-05 02:36:55,416 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (927.562932014s left)
2019-07-05 02:37:03,500 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705023703484838
2019-07-05 02:37:03,528 [salt.minion      :1432][INFO    ][12635] Starting a new job with PID 12635
2019-07-05 02:37:03,553 [salt.minion      :1711][INFO    ][12635] Returning information for job: 20190705023703484838
2019-07-05 02:37:28,156 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (894.823601961s left)
2019-07-05 02:37:33,546 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705023733531012
2019-07-05 02:37:33,577 [salt.minion      :1432][INFO    ][12666] Starting a new job with PID 12666
2019-07-05 02:37:33,601 [salt.minion      :1711][INFO    ][12666] Returning information for job: 20190705023733531012
2019-07-05 02:38:01,136 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (861.842689991s left)
2019-07-05 02:38:03,609 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705023803598546
2019-07-05 02:38:03,644 [salt.minion      :1432][INFO    ][12715] Starting a new job with PID 12715
2019-07-05 02:38:03,665 [salt.minion      :1711][INFO    ][12715] Returning information for job: 20190705023803598546
2019-07-05 02:38:33,676 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705023833665178
2019-07-05 02:38:33,699 [salt.minion      :1432][INFO    ][12743] Starting a new job with PID 12743
2019-07-05 02:38:33,720 [salt.minion      :1711][INFO    ][12743] Returning information for job: 20190705023833665178
2019-07-05 02:38:33,892 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (829.087249041s left)
2019-07-05 02:39:03,788 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705023903772817
2019-07-05 02:39:03,822 [salt.minion      :1432][INFO    ][12783] Starting a new job with PID 12783
2019-07-05 02:39:03,846 [salt.minion      :1711][INFO    ][12783] Returning information for job: 20190705023903772817
2019-07-05 02:39:06,608 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (796.370682955s left)
2019-07-05 02:39:33,876 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705023933858602
2019-07-05 02:39:33,905 [salt.minion      :1432][INFO    ][12815] Starting a new job with PID 12815
2019-07-05 02:39:33,928 [salt.minion      :1711][INFO    ][12815] Returning information for job: 20190705023933858602
2019-07-05 02:39:39,642 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (763.336537123s left)
2019-07-05 02:40:04,008 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705024003994479
2019-07-05 02:40:04,035 [salt.minion      :1432][INFO    ][12861] Starting a new job with PID 12861
2019-07-05 02:40:04,059 [salt.minion      :1711][INFO    ][12861] Returning information for job: 20190705024003994479
2019-07-05 02:40:12,368 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (730.611495018s left)
2019-07-05 02:40:34,089 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705024034074573
2019-07-05 02:40:34,117 [salt.minion      :1432][INFO    ][12889] Starting a new job with PID 12889
2019-07-05 02:40:34,146 [salt.minion      :1711][INFO    ][12889] Returning information for job: 20190705024034074573
2019-07-05 02:40:45,229 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (697.750213146s left)
2019-07-05 02:41:04,280 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705024104262726
2019-07-05 02:41:04,307 [salt.minion      :1432][INFO    ][12938] Starting a new job with PID 12938
2019-07-05 02:41:04,330 [salt.minion      :1711][INFO    ][12938] Returning information for job: 20190705024104262726
2019-07-05 02:41:18,056 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (664.923156023s left)
2019-07-05 02:41:34,453 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705024134443055
2019-07-05 02:41:34,476 [salt.minion      :1432][INFO    ][12971] Starting a new job with PID 12971
2019-07-05 02:41:34,499 [salt.minion      :1711][INFO    ][12971] Returning information for job: 20190705024134443055
2019-07-05 02:41:50,863 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (632.115989923s left)
2019-07-05 02:42:04,641 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705024204626218
2019-07-05 02:42:04,668 [salt.minion      :1432][INFO    ][13018] Starting a new job with PID 13018
2019-07-05 02:42:04,691 [salt.minion      :1711][INFO    ][13018] Returning information for job: 20190705024204626218
2019-07-05 02:42:23,794 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (599.18524909s left)
2019-07-05 02:42:34,664 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705024234647342
2019-07-05 02:42:34,692 [salt.minion      :1432][INFO    ][13046] Starting a new job with PID 13046
2019-07-05 02:42:34,720 [salt.minion      :1711][INFO    ][13046] Returning information for job: 20190705024234647342
2019-07-05 02:42:56,562 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (566.416753054s left)
2019-07-05 02:43:04,896 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705024304880711
2019-07-05 02:43:04,923 [salt.minion      :1432][INFO    ][13095] Starting a new job with PID 13095
2019-07-05 02:43:04,947 [salt.minion      :1711][INFO    ][13095] Returning information for job: 20190705024304880711
2019-07-05 02:43:29,386 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (533.592957973s left)
2019-07-05 02:43:34,954 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705024334941108
2019-07-05 02:43:34,980 [salt.minion      :1432][INFO    ][13125] Starting a new job with PID 13125
2019-07-05 02:43:35,010 [salt.minion      :1711][INFO    ][13125] Returning information for job: 20190705024334941108
2019-07-05 02:44:02,264 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (500.714896917s left)
2019-07-05 02:44:05,178 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705024405160429
2019-07-05 02:44:05,206 [salt.minion      :1432][INFO    ][13171] Starting a new job with PID 13171
2019-07-05 02:44:05,228 [salt.minion      :1711][INFO    ][13171] Returning information for job: 20190705024405160429
2019-07-05 02:44:35,140 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (467.83931303s left)
2019-07-05 02:44:35,280 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705024435265045
2019-07-05 02:44:35,306 [salt.minion      :1432][INFO    ][13200] Starting a new job with PID 13200
2019-07-05 02:44:35,331 [salt.minion      :1711][INFO    ][13200] Returning information for job: 20190705024435265045
2019-07-05 02:45:05,357 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705024505343500
2019-07-05 02:45:05,382 [salt.minion      :1432][INFO    ][13246] Starting a new job with PID 13246
2019-07-05 02:45:05,405 [salt.minion      :1711][INFO    ][13246] Returning information for job: 20190705024505343500
2019-07-05 02:45:08,104 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (434.875486135s left)
2019-07-05 02:45:35,496 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705024535484298
2019-07-05 02:45:35,521 [salt.minion      :1432][INFO    ][13415] Starting a new job with PID 13415
2019-07-05 02:45:35,544 [salt.minion      :1711][INFO    ][13415] Returning information for job: 20190705024535484298
2019-07-05 02:45:41,082 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (401.896726131s left)
2019-07-05 02:46:05,656 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705024605639709
2019-07-05 02:46:05,684 [salt.minion      :1432][INFO    ][13468] Starting a new job with PID 13468
2019-07-05 02:46:05,708 [salt.minion      :1711][INFO    ][13468] Returning information for job: 20190705024605639709
2019-07-05 02:46:13,855 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (369.124125004s left)
2019-07-05 02:46:35,824 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705024635808092
2019-07-05 02:46:35,851 [salt.minion      :1432][INFO    ][13498] Starting a new job with PID 13498
2019-07-05 02:46:35,874 [salt.minion      :1711][INFO    ][13498] Returning information for job: 20190705024635808092
2019-07-05 02:46:46,723 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (336.256276131s left)
2019-07-05 02:47:05,988 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705024705975502
2019-07-05 02:47:06,009 [salt.minion      :1432][INFO    ][13543] Starting a new job with PID 13543
2019-07-05 02:47:06,034 [salt.minion      :1711][INFO    ][13543] Returning information for job: 20190705024705975502
2019-07-05 02:47:19,564 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (303.415333986s left)
2019-07-05 02:47:36,198 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705024736184591
2019-07-05 02:47:36,223 [salt.minion      :1432][INFO    ][13572] Starting a new job with PID 13572
2019-07-05 02:47:36,248 [salt.minion      :1711][INFO    ][13572] Returning information for job: 20190705024736184591
2019-07-05 02:47:52,330 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (270.649044991s left)
2019-07-05 02:48:06,263 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705024806247488
2019-07-05 02:48:06,295 [salt.minion      :1432][INFO    ][13626] Starting a new job with PID 13626
2019-07-05 02:48:06,321 [salt.minion      :1711][INFO    ][13626] Returning information for job: 20190705024806247488
2019-07-05 02:48:25,243 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (237.735918045s left)
2019-07-05 02:48:36,345 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705024836330582
2019-07-05 02:48:36,371 [salt.minion      :1432][INFO    ][13654] Starting a new job with PID 13654
2019-07-05 02:48:36,395 [salt.minion      :1711][INFO    ][13654] Returning information for job: 20190705024836330582
2019-07-05 02:48:57,982 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (204.996881008s left)
2019-07-05 02:49:06,379 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705024906364915
2019-07-05 02:49:06,407 [salt.minion      :1432][INFO    ][13700] Starting a new job with PID 13700
2019-07-05 02:49:06,430 [salt.minion      :1711][INFO    ][13700] Returning information for job: 20190705024906364915
2019-07-05 02:49:30,851 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (172.128253937s left)
2019-07-05 02:49:36,465 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705024936450655
2019-07-05 02:49:36,491 [salt.minion      :1432][INFO    ][13730] Starting a new job with PID 13730
2019-07-05 02:49:36,514 [salt.minion      :1711][INFO    ][13730] Returning information for job: 20190705024936450655
2019-07-05 02:50:03,777 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (139.202121019s left)
2019-07-05 02:50:06,572 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705025006559995
2019-07-05 02:50:06,599 [salt.minion      :1432][INFO    ][13776] Starting a new job with PID 13776
2019-07-05 02:50:06,621 [salt.minion      :1711][INFO    ][13776] Returning information for job: 20190705025006559995
2019-07-05 02:50:36,503 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (106.475534916s left)
2019-07-05 02:50:36,692 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705025036675068
2019-07-05 02:50:36,719 [salt.minion      :1432][INFO    ][13805] Starting a new job with PID 13805
2019-07-05 02:50:36,742 [salt.minion      :1711][INFO    ][13805] Returning information for job: 20190705025036675068
2019-07-05 02:51:06,838 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705025106828298
2019-07-05 02:51:06,863 [salt.minion      :1432][INFO    ][13855] Starting a new job with PID 13855
2019-07-05 02:51:06,888 [salt.minion      :1711][INFO    ][13855] Returning information for job: 20190705025106828298
2019-07-05 02:51:09,259 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (73.7203249931s left)
2019-07-05 02:51:36,997 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705025136983548
2019-07-05 02:51:37,024 [salt.minion      :1432][INFO    ][13883] Starting a new job with PID 13883
2019-07-05 02:51:37,052 [salt.minion      :1711][INFO    ][13883] Returning information for job: 20190705025136983548
2019-07-05 02:51:42,415 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (40.5641081333s left)
2019-07-05 02:52:07,212 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705025207194609
2019-07-05 02:52:07,241 [salt.minion      :1432][INFO    ][13933] Starting a new job with PID 13933
2019-07-05 02:52:07,265 [salt.minion      :1711][INFO    ][13933] Returning information for job: 20190705025207194609
2019-07-05 02:52:15,160 [salt.loaded.ext.module.maas:1023][INFO    ][8331] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (7.81890010834s left)
2019-07-05 02:52:37,327 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705025237315062
2019-07-05 02:52:37,355 [salt.minion      :1432][INFO    ][13962] Starting a new job with PID 13962
2019-07-05 02:52:37,379 [salt.minion      :1711][INFO    ][13962] Returning information for job: 20190705025237315062
2019-07-05 02:52:48,047 [salt.state       :302 ][ERROR   ][8331] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-07-05 02:52:48,048 [salt.state       :1951][INFO    ][8331] Completed state [maas.wait_for_machine_status] at time 02:52:48.048235 duration_in_ms=2275083.1
2019-07-05 02:52:48,058 [salt.minion      :1711][INFO    ][8331] Returning information for job: 20190705021442775114
2019-07-05 02:52:58,848 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command pillar.get with jid 20190705025258837055
2019-07-05 02:52:58,877 [salt.minion      :1432][INFO    ][14009] Starting a new job with PID 14009
2019-07-05 02:52:58,891 [salt.minion      :1711][INFO    ][14009] Returning information for job: 20190705025258837055
2019-07-05 02:52:59,457 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command service.status with jid 20190705025259444877
2019-07-05 02:52:59,484 [salt.minion      :1432][INFO    ][14014] Starting a new job with PID 14014
2019-07-05 02:53:00,166 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][14014] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-07-05 02:53:00,203 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][14014] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-07-05 02:53:00,223 [salt.minion      :1711][INFO    ][14014] Returning information for job: 20190705025259444877
2019-07-05 02:53:00,793 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command state.apply with jid 20190705025300782393
2019-07-05 02:53:00,826 [salt.minion      :1432][INFO    ][14026] Starting a new job with PID 14026
2019-07-05 02:53:09,006 [salt.state       :915 ][INFO    ][14026] Loading fresh modules for state activity
2019-07-05 02:53:09,612 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14026] Executing command 'salt-minion --version' in directory '/root'
2019-07-05 02:53:09,907 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14026] Executing command 'salt-minion --version' in directory '/root'
2019-07-05 02:53:10,934 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14026] Executing command 'salt-minion --version' in directory '/root'
2019-07-05 02:53:11,227 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14026] Executing command 'salt-minion --version' in directory '/root'
2019-07-05 02:53:13,077 [salt.state       :1780][INFO    ][14026] Running state [salt-minion] at time 02:53:13.077644
2019-07-05 02:53:13,078 [salt.state       :1813][INFO    ][14026] Executing state pkg.installed for [salt-minion]
2019-07-05 02:53:13,078 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14026] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-07-05 02:53:13,188 [salt.state       :300 ][INFO    ][14026] All specified packages are already installed
2019-07-05 02:53:13,189 [salt.state       :1951][INFO    ][14026] Completed state [salt-minion] at time 02:53:13.188963 duration_in_ms=111.32
2019-07-05 02:53:13,189 [salt.state       :1780][INFO    ][14026] Running state [salt_minion_dependency_packages] at time 02:53:13.189302
2019-07-05 02:53:13,189 [salt.state       :1813][INFO    ][14026] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-07-05 02:53:13,199 [salt.state       :300 ][INFO    ][14026] All specified packages are already installed
2019-07-05 02:53:13,200 [salt.state       :1951][INFO    ][14026] Completed state [salt_minion_dependency_packages] at time 02:53:13.199971 duration_in_ms=10.668
2019-07-05 02:53:13,204 [salt.state       :1780][INFO    ][14026] Running state [/etc/salt/minion.d/minion.conf] at time 02:53:13.204082
2019-07-05 02:53:13,204 [salt.state       :1813][INFO    ][14026] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-07-05 02:53:13,486 [salt.state       :300 ][INFO    ][14026] File /etc/salt/minion.d/minion.conf is in the correct state
2019-07-05 02:53:13,486 [salt.state       :1951][INFO    ][14026] Completed state [/etc/salt/minion.d/minion.conf] at time 02:53:13.486370 duration_in_ms=282.287
2019-07-05 02:53:13,486 [salt.state       :1780][INFO    ][14026] Running state [python-netaddr] at time 02:53:13.486619
2019-07-05 02:53:13,486 [salt.state       :1813][INFO    ][14026] Executing state pkg.installed for [python-netaddr]
2019-07-05 02:53:13,495 [salt.state       :300 ][INFO    ][14026] All specified packages are already installed
2019-07-05 02:53:13,496 [salt.state       :1951][INFO    ][14026] Completed state [python-netaddr] at time 02:53:13.496048 duration_in_ms=9.429
2019-07-05 02:53:13,499 [salt.state       :1780][INFO    ][14026] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:53:13.499038
2019-07-05 02:53:13,499 [salt.state       :1813][INFO    ][14026] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-07-05 02:53:13,516 [salt.state       :300 ][INFO    ][14026] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-07-05 02:53:13,516 [salt.state       :1951][INFO    ][14026] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:53:13.516791 duration_in_ms=17.752
2019-07-05 02:53:13,519 [salt.state       :1780][INFO    ][14026] Running state [salt-minion] at time 02:53:13.519421
2019-07-05 02:53:13,519 [salt.state       :1813][INFO    ][14026] Executing state service.running for [salt-minion]
2019-07-05 02:53:13,520 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14026] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-07-05 02:53:13,566 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14026] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-07-05 02:53:13,588 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14026] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-07-05 02:53:13,608 [salt.state       :300 ][INFO    ][14026] The service salt-minion is already running
2019-07-05 02:53:13,609 [salt.state       :1951][INFO    ][14026] Completed state [salt-minion] at time 02:53:13.609186 duration_in_ms=89.764
2019-07-05 02:53:13,613 [salt.state       :1780][INFO    ][14026] Running state [/etc/salt/grains.d] at time 02:53:13.612978
2019-07-05 02:53:13,613 [salt.state       :1813][INFO    ][14026] Executing state file.directory for [/etc/salt/grains.d]
2019-07-05 02:53:13,615 [salt.state       :300 ][INFO    ][14026] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-07-05 02:53:13,615 [salt.state       :1951][INFO    ][14026] Completed state [/etc/salt/grains.d] at time 02:53:13.615339 duration_in_ms=2.361
2019-07-05 02:53:13,616 [salt.state       :1780][INFO    ][14026] Running state [/etc/salt/grains] at time 02:53:13.616425
2019-07-05 02:53:13,616 [salt.state       :1813][INFO    ][14026] Executing state file.managed for [/etc/salt/grains]
2019-07-05 02:53:13,617 [salt.state       :300 ][INFO    ][14026] File /etc/salt/grains exists with proper permissions. No changes made.
2019-07-05 02:53:13,618 [salt.state       :1951][INFO    ][14026] Completed state [/etc/salt/grains] at time 02:53:13.618036 duration_in_ms=1.611
2019-07-05 02:53:13,618 [salt.state       :1780][INFO    ][14026] Running state [/etc/salt/grains.d/placeholder] at time 02:53:13.618795
2019-07-05 02:53:13,619 [salt.state       :1813][INFO    ][14026] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-07-05 02:53:13,620 [salt.state       :300 ][INFO    ][14026] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-07-05 02:53:13,620 [salt.state       :1951][INFO    ][14026] Completed state [/etc/salt/grains.d/placeholder] at time 02:53:13.620335 duration_in_ms=1.539
2019-07-05 02:53:13,621 [salt.state       :1780][INFO    ][14026] Running state [/etc/salt/grains.d/sphinx] at time 02:53:13.621101
2019-07-05 02:53:13,621 [salt.state       :1813][INFO    ][14026] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-07-05 02:53:13,624 [salt.state       :300 ][INFO    ][14026] File /etc/salt/grains.d/sphinx is in the correct state
2019-07-05 02:53:13,625 [salt.state       :1951][INFO    ][14026] Completed state [/etc/salt/grains.d/sphinx] at time 02:53:13.625184 duration_in_ms=4.083
2019-07-05 02:53:13,627 [salt.state       :1780][INFO    ][14026] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:53:13.627488
2019-07-05 02:53:13,627 [salt.state       :1813][INFO    ][14026] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-07-05 02:53:13,628 [salt.state       :300 ][INFO    ][14026] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-07-05 02:53:13,628 [salt.state       :1951][INFO    ][14026] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:53:13.628249 duration_in_ms=0.761
2019-07-05 02:53:13,628 [salt.state       :1780][INFO    ][14026] Running state [/etc/salt/grains.d/dns_records] at time 02:53:13.628725
2019-07-05 02:53:13,629 [salt.state       :1813][INFO    ][14026] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-07-05 02:53:13,630 [salt.state       :300 ][INFO    ][14026] File /etc/salt/grains.d/dns_records is in the correct state
2019-07-05 02:53:13,630 [salt.state       :1951][INFO    ][14026] Completed state [/etc/salt/grains.d/dns_records] at time 02:53:13.630381 duration_in_ms=1.655
2019-07-05 02:53:13,631 [salt.state       :1780][INFO    ][14026] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:53:13.631290
2019-07-05 02:53:13,631 [salt.state       :1813][INFO    ][14026] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-07-05 02:53:13,631 [salt.state       :300 ][INFO    ][14026] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-07-05 02:53:13,632 [salt.state       :1951][INFO    ][14026] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:53:13.632039 duration_in_ms=0.749
2019-07-05 02:53:13,632 [salt.state       :1780][INFO    ][14026] Running state [/etc/salt/grains.d/salt] at time 02:53:13.632512
2019-07-05 02:53:13,632 [salt.state       :1813][INFO    ][14026] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-07-05 02:53:13,633 [salt.state       :300 ][INFO    ][14026] File /etc/salt/grains.d/salt is in the correct state
2019-07-05 02:53:13,635 [salt.state       :1951][INFO    ][14026] Completed state [/etc/salt/grains.d/salt] at time 02:53:13.635273 duration_in_ms=2.761
2019-07-05 02:53:13,636 [salt.state       :1780][INFO    ][14026] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:53:13.636147
2019-07-05 02:53:13,636 [salt.state       :1813][INFO    ][14026] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-07-05 02:53:13,636 [salt.state       :300 ][INFO    ][14026] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-07-05 02:53:13,636 [salt.state       :1951][INFO    ][14026] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:53:13.636885 duration_in_ms=0.738
2019-07-05 02:53:13,638 [salt.state       :1780][INFO    ][14026] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:53:13.638783
2019-07-05 02:53:13,639 [salt.state       :1813][INFO    ][14026] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-07-05 02:53:13,639 [salt.state       :300 ][INFO    ][14026] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-07-05 02:53:13,639 [salt.state       :1951][INFO    ][14026] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:53:13.639551 duration_in_ms=0.768
2019-07-05 02:53:13,640 [salt.state       :1780][INFO    ][14026] Running state [mine.update] at time 02:53:13.640206
2019-07-05 02:53:13,640 [salt.state       :1813][INFO    ][14026] Executing state module.wait for [mine.update]
2019-07-05 02:53:13,640 [salt.state       :300 ][INFO    ][14026] No changes made for mine.update
2019-07-05 02:53:13,640 [salt.state       :1951][INFO    ][14026] Completed state [mine.update] at time 02:53:13.640919 duration_in_ms=0.713
2019-07-05 02:53:13,641 [salt.state       :1780][INFO    ][14026] Running state [ca-certificates] at time 02:53:13.641154
2019-07-05 02:53:13,641 [salt.state       :1813][INFO    ][14026] Executing state pkg.installed for [ca-certificates]
2019-07-05 02:53:13,651 [salt.state       :300 ][INFO    ][14026] All specified packages are already installed
2019-07-05 02:53:13,651 [salt.state       :1951][INFO    ][14026] Completed state [ca-certificates] at time 02:53:13.651256 duration_in_ms=10.101
2019-07-05 02:53:13,651 [salt.state       :1780][INFO    ][14026] Running state [update-ca-certificates] at time 02:53:13.651944
2019-07-05 02:53:13,652 [salt.state       :1813][INFO    ][14026] Executing state cmd.wait for [update-ca-certificates]
2019-07-05 02:53:13,652 [salt.state       :300 ][INFO    ][14026] No changes made for update-ca-certificates
2019-07-05 02:53:13,652 [salt.state       :1951][INFO    ][14026] Completed state [update-ca-certificates] at time 02:53:13.652662 duration_in_ms=0.717
2019-07-05 02:53:13,652 [salt.state       :1780][INFO    ][14026] Running state [iptables] at time 02:53:13.652899
2019-07-05 02:53:13,653 [salt.state       :1813][INFO    ][14026] Executing state pkg.installed for [iptables]
2019-07-05 02:53:13,662 [salt.state       :300 ][INFO    ][14026] All specified packages are already installed
2019-07-05 02:53:13,662 [salt.state       :1951][INFO    ][14026] Completed state [iptables] at time 02:53:13.662749 duration_in_ms=9.849
2019-07-05 02:53:13,663 [salt.state       :1780][INFO    ][14026] Running state [iptables-persistent] at time 02:53:13.662988
2019-07-05 02:53:13,663 [salt.state       :1813][INFO    ][14026] Executing state pkg.installed for [iptables-persistent]
2019-07-05 02:53:13,672 [salt.state       :300 ][INFO    ][14026] All specified packages are already installed
2019-07-05 02:53:13,672 [salt.state       :1951][INFO    ][14026] Completed state [iptables-persistent] at time 02:53:13.672886 duration_in_ms=9.899
2019-07-05 02:53:13,673 [salt.state       :1780][INFO    ][14026] Running state [iptables_modules_v4_load] at time 02:53:13.673854
2019-07-05 02:53:13,674 [salt.state       :1813][INFO    ][14026] Executing state kmod.present for [iptables_modules_v4_load]
2019-07-05 02:53:13,674 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14026] Executing command 'lsmod' in directory '/root'
2019-07-05 02:53:13,697 [salt.state       :300 ][INFO    ][14026] Kernel modules iptable_filter, ip_tables are already present
2019-07-05 02:53:13,698 [salt.state       :1951][INFO    ][14026] Completed state [iptables_modules_v4_load] at time 02:53:13.698364 duration_in_ms=24.51
2019-07-05 02:53:13,699 [salt.state       :1780][INFO    ][14026] Running state [/etc/iptables/rules.v4] at time 02:53:13.699596
2019-07-05 02:53:13,700 [salt.state       :1813][INFO    ][14026] Executing state file.managed for [/etc/iptables/rules.v4]
2019-07-05 02:53:13,810 [salt.state       :300 ][INFO    ][14026] File /etc/iptables/rules.v4 is in the correct state
2019-07-05 02:53:13,810 [salt.state       :1951][INFO    ][14026] Completed state [/etc/iptables/rules.v4] at time 02:53:13.810410 duration_in_ms=110.815
2019-07-05 02:53:13,811 [salt.state       :1780][INFO    ][14026] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:53:13.811493
2019-07-05 02:53:13,811 [salt.state       :1813][INFO    ][14026] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-07-05 02:53:13,812 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14026] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-07-05 02:53:13,831 [salt.state       :300 ][INFO    ][14026] onlyif execution failed
2019-07-05 02:53:13,832 [salt.state       :1951][INFO    ][14026] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:53:13.832336 duration_in_ms=20.841
2019-07-05 02:53:13,835 [salt.state       :1780][INFO    ][14026] Running state [netfilter-persistent] at time 02:53:13.835011
2019-07-05 02:53:13,835 [salt.state       :1813][INFO    ][14026] Executing state service.running for [netfilter-persistent]
2019-07-05 02:53:13,837 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14026] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-07-05 02:53:13,864 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14026] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-07-05 02:53:13,882 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14026] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-07-05 02:53:13,901 [salt.state       :300 ][INFO    ][14026] The service netfilter-persistent is already running
2019-07-05 02:53:13,903 [salt.state       :1951][INFO    ][14026] Completed state [netfilter-persistent] at time 02:53:13.903097 duration_in_ms=68.087
2019-07-05 02:53:13,904 [salt.state       :1780][INFO    ][14026] Running state [iptables_extra.remove_stale_tables] at time 02:53:13.904602
2019-07-05 02:53:13,905 [salt.state       :1813][INFO    ][14026] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-07-05 02:53:13,905 [salt.state       :300 ][INFO    ][14026] No changes made for iptables_extra.remove_stale_tables
2019-07-05 02:53:13,906 [salt.state       :1951][INFO    ][14026] Completed state [iptables_extra.remove_stale_tables] at time 02:53:13.906104 duration_in_ms=1.503
2019-07-05 02:53:13,906 [salt.state       :1780][INFO    ][14026] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:53:13.906557
2019-07-05 02:53:13,907 [salt.state       :1813][INFO    ][14026] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-07-05 02:53:13,908 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14026] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-07-05 02:53:13,926 [salt.state       :300 ][INFO    ][14026] onlyif execution failed
2019-07-05 02:53:13,927 [salt.state       :1951][INFO    ][14026] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:53:13.927467 duration_in_ms=20.91
2019-07-05 02:53:13,929 [salt.state       :1780][INFO    ][14026] Running state [/etc/iptables/rules.v6] at time 02:53:13.929100
2019-07-05 02:53:13,929 [salt.state       :1813][INFO    ][14026] Executing state file.absent for [/etc/iptables/rules.v6]
2019-07-05 02:53:13,930 [salt.state       :300 ][INFO    ][14026] File /etc/iptables/rules.v6 is not present
2019-07-05 02:53:13,930 [salt.state       :1951][INFO    ][14026] Completed state [/etc/iptables/rules.v6] at time 02:53:13.930784 duration_in_ms=1.684
2019-07-05 02:53:13,932 [salt.state       :1780][INFO    ][14026] Running state [iptables_extra.flush_all] at time 02:53:13.931994
2019-07-05 02:53:13,932 [salt.state       :1813][INFO    ][14026] Executing state module.wait for [iptables_extra.flush_all]
2019-07-05 02:53:13,933 [salt.state       :300 ][INFO    ][14026] No changes made for iptables_extra.flush_all
2019-07-05 02:53:13,933 [salt.state       :1951][INFO    ][14026] Completed state [iptables_extra.flush_all] at time 02:53:13.933324 duration_in_ms=1.33
2019-07-05 02:53:13,938 [salt.minion      :1711][INFO    ][14026] Returning information for job: 20190705025300782393
2019-07-05 02:53:14,517 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command state.apply with jid 20190705025314502091
2019-07-05 02:53:14,543 [salt.minion      :1432][INFO    ][14108] Starting a new job with PID 14108
2019-07-05 02:53:15,708 [salt.state       :915 ][INFO    ][14108] Loading fresh modules for state activity
2019-07-05 02:53:16,653 [salt.state       :1780][INFO    ][14108] Running state [maas-rack-controller] at time 02:53:16.653491
2019-07-05 02:53:16,654 [salt.state       :1813][INFO    ][14108] Executing state pkg.installed for [maas-rack-controller]
2019-07-05 02:53:16,654 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14108] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-07-05 02:53:16,771 [salt.state       :300 ][INFO    ][14108] All specified packages are already installed
2019-07-05 02:53:16,772 [salt.state       :1951][INFO    ][14108] Completed state [maas-rack-controller] at time 02:53:16.771942 duration_in_ms=118.452
2019-07-05 02:53:16,772 [salt.state       :1780][INFO    ][14108] Running state [ipmitool] at time 02:53:16.772285
2019-07-05 02:53:16,772 [salt.state       :1813][INFO    ][14108] Executing state pkg.installed for [ipmitool]
2019-07-05 02:53:16,782 [salt.state       :300 ][INFO    ][14108] All specified packages are already installed
2019-07-05 02:53:16,783 [salt.state       :1951][INFO    ][14108] Completed state [ipmitool] at time 02:53:16.783145 duration_in_ms=10.86
2019-07-05 02:53:16,786 [salt.state       :1780][INFO    ][14108] Running state [/etc/maas/rackd.conf] at time 02:53:16.786488
2019-07-05 02:53:16,786 [salt.state       :1813][INFO    ][14108] Executing state file.line for [/etc/maas/rackd.conf]
2019-07-05 02:53:16,787 [salt.state       :300 ][INFO    ][14108] No changes needed to be made
2019-07-05 02:53:16,787 [salt.state       :1951][INFO    ][14108] Completed state [/etc/maas/rackd.conf] at time 02:53:16.787867 duration_in_ms=1.379
2019-07-05 02:53:16,788 [salt.state       :1780][INFO    ][14108] Running state [/etc/maas/rackd.conf] at time 02:53:16.788114
2019-07-05 02:53:16,788 [salt.state       :1813][INFO    ][14108] Executing state file.managed for [/etc/maas/rackd.conf]
2019-07-05 02:53:16,788 [salt.loaded.int.states.file:2298][WARNING ][14108] 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-07-05 02:53:16,790 [salt.state       :300 ][INFO    ][14108] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-07-05 02:53:16,791 [salt.state       :1951][INFO    ][14108] Completed state [/etc/maas/rackd.conf] at time 02:53:16.791332 duration_in_ms=3.217
2019-07-05 02:53:16,793 [salt.state       :1780][INFO    ][14108] Running state [maas-rackd] at time 02:53:16.793560
2019-07-05 02:53:16,795 [salt.state       :1813][INFO    ][14108] Executing state service.running for [maas-rackd]
2019-07-05 02:53:16,796 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14108] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-07-05 02:53:16,844 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14108] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-07-05 02:53:16,867 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14108] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-07-05 02:53:16,890 [salt.state       :300 ][INFO    ][14108] The service maas-rackd is already running
2019-07-05 02:53:16,891 [salt.state       :1951][INFO    ][14108] Completed state [maas-rackd] at time 02:53:16.891274 duration_in_ms=97.714
2019-07-05 02:53:16,894 [salt.minion      :1711][INFO    ][14108] Returning information for job: 20190705025314502091
2019-07-05 02:53:17,469 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command state.apply with jid 20190705025317454815
2019-07-05 02:53:17,496 [salt.minion      :1432][INFO    ][14131] Starting a new job with PID 14131
2019-07-05 02:53:18,635 [salt.state       :915 ][INFO    ][14131] Loading fresh modules for state activity
2019-07-05 02:53:19,696 [salt.state       :1780][INFO    ][14131] Running state [maas-region-controller] at time 02:53:19.696137
2019-07-05 02:53:19,696 [salt.state       :1813][INFO    ][14131] Executing state pkg.installed for [maas-region-controller]
2019-07-05 02:53:19,697 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14131] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-07-05 02:53:19,816 [salt.state       :300 ][INFO    ][14131] All specified packages are already installed
2019-07-05 02:53:19,816 [salt.state       :1951][INFO    ][14131] Completed state [maas-region-controller] at time 02:53:19.816684 duration_in_ms=120.547
2019-07-05 02:53:19,817 [salt.state       :1780][INFO    ][14131] Running state [python-oauth] at time 02:53:19.817016
2019-07-05 02:53:19,817 [salt.state       :1813][INFO    ][14131] Executing state pkg.installed for [python-oauth]
2019-07-05 02:53:19,827 [salt.state       :300 ][INFO    ][14131] All specified packages are already installed
2019-07-05 02:53:19,827 [salt.state       :1951][INFO    ][14131] Completed state [python-oauth] at time 02:53:19.827183 duration_in_ms=10.167
2019-07-05 02:53:19,830 [salt.state       :1780][INFO    ][14131] Running state [/etc/maas/regiond.conf] at time 02:53:19.830414
2019-07-05 02:53:19,830 [salt.state       :1813][INFO    ][14131] Executing state file.replace for [/etc/maas/regiond.conf]
2019-07-05 02:53:19,835 [salt.state       :300 ][INFO    ][14131] No changes needed to be made
2019-07-05 02:53:19,835 [salt.state       :1951][INFO    ][14131] Completed state [/etc/maas/regiond.conf] at time 02:53:19.835329 duration_in_ms=4.914
2019-07-05 02:53:19,835 [salt.state       :1780][INFO    ][14131] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:53:19.835809
2019-07-05 02:53:19,836 [salt.state       :1813][INFO    ][14131] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-07-05 02:53:19,902 [salt.state       :300 ][INFO    ][14131] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-07-05 02:53:19,902 [salt.state       :1951][INFO    ][14131] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:53:19.902927 duration_in_ms=67.118
2019-07-05 02:53:19,903 [salt.state       :1780][INFO    ][14131] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:53:19.903446
2019-07-05 02:53:19,903 [salt.state       :1813][INFO    ][14131] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-07-05 02:53:19,907 [salt.state       :300 ][INFO    ][14131] No changes needed to be made
2019-07-05 02:53:19,907 [salt.state       :1951][INFO    ][14131] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:53:19.907531 duration_in_ms=4.084
2019-07-05 02:53:19,908 [salt.state       :1780][INFO    ][14131] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:53:19.908003
2019-07-05 02:53:19,908 [salt.state       :1813][INFO    ][14131] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-07-05 02:53:19,912 [salt.state       :300 ][INFO    ][14131] No changes needed to be made
2019-07-05 02:53:19,912 [salt.state       :1951][INFO    ][14131] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:53:19.912794 duration_in_ms=4.79
2019-07-05 02:53:19,913 [salt.state       :1780][INFO    ][14131] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:53:19.913264
2019-07-05 02:53:19,913 [salt.state       :1813][INFO    ][14131] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-07-05 02:53:19,942 [salt.state       :300 ][INFO    ][14131] No changes needed to be made
2019-07-05 02:53:19,943 [salt.state       :1951][INFO    ][14131] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:53:19.943026 duration_in_ms=29.762
2019-07-05 02:53:19,943 [salt.state       :1780][INFO    ][14131] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:53:19.943543
2019-07-05 02:53:19,943 [salt.state       :1813][INFO    ][14131] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-07-05 02:53:19,962 [salt.state       :300 ][INFO    ][14131] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-07-05 02:53:19,962 [salt.state       :1951][INFO    ][14131] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:53:19.962363 duration_in_ms=18.819
2019-07-05 02:53:19,963 [salt.state       :1780][INFO    ][14131] Running state [a2enmod headers] at time 02:53:19.963798
2019-07-05 02:53:19,964 [salt.state       :1813][INFO    ][14131] Executing state cmd.run for [a2enmod headers]
2019-07-05 02:53:19,964 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14131] Executing command 'a2enmod headers' in directory '/root'
2019-07-05 02:53:20,029 [salt.state       :300 ][INFO    ][14131] {'pid': 14150, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-07-05 02:53:20,032 [salt.state       :1951][INFO    ][14131] Completed state [a2enmod headers] at time 02:53:20.029679 duration_in_ms=65.88
2019-07-05 02:53:20,032 [salt.state       :1780][INFO    ][14131] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:53:20.032628
2019-07-05 02:53:20,033 [salt.state       :1813][INFO    ][14131] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-07-05 02:53:20,060 [salt.state       :300 ][INFO    ][14131] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-07-05 02:53:20,061 [salt.state       :1951][INFO    ][14131] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:53:20.061176 duration_in_ms=28.549
2019-07-05 02:53:20,062 [salt.state       :1780][INFO    ][14131] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:53:20.062341
2019-07-05 02:53:20,062 [salt.state       :1813][INFO    ][14131] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-07-05 02:53:20,125 [salt.state       :300 ][INFO    ][14131] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-07-05 02:53:20,125 [salt.state       :1951][INFO    ][14131] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:53:20.125565 duration_in_ms=63.224
2019-07-05 02:53:20,127 [salt.state       :1780][INFO    ][14131] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:53:20.127007
2019-07-05 02:53:20,127 [salt.state       :1813][INFO    ][14131] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-07-05 02:53:20,181 [salt.state       :300 ][INFO    ][14131] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-07-05 02:53:20,181 [salt.state       :1951][INFO    ][14131] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:53:20.181466 duration_in_ms=54.459
2019-07-05 02:53:20,182 [salt.state       :1780][INFO    ][14131] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:53:20.182082
2019-07-05 02:53:20,182 [salt.state       :1813][INFO    ][14131] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-07-05 02:53:20,253 [salt.state       :300 ][INFO    ][14131] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-07-05 02:53:20,253 [salt.state       :1951][INFO    ][14131] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:53:20.253262 duration_in_ms=71.18
2019-07-05 02:53:20,253 [salt.state       :1780][INFO    ][14131] Running state [/root/.pgpass] at time 02:53:20.253544
2019-07-05 02:53:20,254 [salt.state       :1813][INFO    ][14131] Executing state file.managed for [/root/.pgpass]
2019-07-05 02:53:20,298 [salt.state       :300 ][INFO    ][14131] File /root/.pgpass is in the correct state
2019-07-05 02:53:20,298 [salt.state       :1951][INFO    ][14131] Completed state [/root/.pgpass] at time 02:53:20.298736 duration_in_ms=45.192
2019-07-05 02:53:20,303 [salt.state       :1780][INFO    ][14131] Running state [maas-region syncdb --noinput] at time 02:53:20.303937
2019-07-05 02:53:20,304 [salt.state       :1813][INFO    ][14131] Executing state cmd.run for [maas-region syncdb --noinput]
2019-07-05 02:53:20,304 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14131] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-07-05 02:53:22,826 [salt.state       :300 ][INFO    ][14131] {'pid': 14163, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: maasserver, sessions, piston3, contenttypes, sites, auth, metadataserver\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-07-05 02:53:22,827 [salt.state       :1951][INFO    ][14131] Completed state [maas-region syncdb --noinput] at time 02:53:22.827245 duration_in_ms=2523.305
2019-07-05 02:53:22,828 [salt.state       :2022][WARNING ][14131] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-07-05 02:53:22,832 [salt.state       :1780][INFO    ][14131] Running state [maas-regiond] at time 02:53:22.832052
2019-07-05 02:53:22,832 [salt.state       :1813][INFO    ][14131] Executing state service.running for [maas-regiond]
2019-07-05 02:53:22,834 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14131] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-07-05 02:53:22,880 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14131] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-07-05 02:53:22,901 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14131] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-07-05 02:53:22,926 [salt.state       :300 ][INFO    ][14131] The service maas-regiond is already running
2019-07-05 02:53:22,927 [salt.state       :1951][INFO    ][14131] Completed state [maas-regiond] at time 02:53:22.927177 duration_in_ms=95.126
2019-07-05 02:53:22,930 [salt.state       :1780][INFO    ][14131] Running state [bind9] at time 02:53:22.930312
2019-07-05 02:53:22,930 [salt.state       :1813][INFO    ][14131] Executing state service.running for [bind9]
2019-07-05 02:53:22,932 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14131] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-07-05 02:53:22,956 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14131] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-07-05 02:53:22,980 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14131] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-07-05 02:53:23,008 [salt.state       :300 ][INFO    ][14131] The service bind9 is already running
2019-07-05 02:53:23,009 [salt.state       :1951][INFO    ][14131] Completed state [bind9] at time 02:53:23.009059 duration_in_ms=78.747
2019-07-05 02:53:23,012 [salt.state       :1780][INFO    ][14131] Running state [apache2] at time 02:53:23.012439
2019-07-05 02:53:23,013 [salt.state       :1813][INFO    ][14131] Executing state service.running for [apache2]
2019-07-05 02:53:23,014 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14131] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-07-05 02:53:23,040 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14131] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-07-05 02:53:23,064 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14131] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-07-05 02:53:23,092 [salt.state       :300 ][INFO    ][14131] The service apache2 is already running
2019-07-05 02:53:23,093 [salt.state       :1951][INFO    ][14131] Completed state [apache2] at time 02:53:23.093313 duration_in_ms=80.873
2019-07-05 02:53:23,095 [salt.state       :1780][INFO    ][14131] Running state [maasng.wait_for_http_code] at time 02:53:23.095895
2019-07-05 02:53:23,096 [salt.state       :1813][INFO    ][14131] Executing state module.run for [maasng.wait_for_http_code]
2019-07-05 02:53:23,097 [salt.utils.decorators:613 ][WARNING ][14131] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-05 02:53:23,108 [salt.state       :300 ][INFO    ][14131] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-07-05 02:53:23,108 [salt.state       :1951][INFO    ][14131] Completed state [maasng.wait_for_http_code] at time 02:53:23.108356 duration_in_ms=12.462
2019-07-05 02:53:23,109 [salt.state       :1780][INFO    ][14131] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:53:23.109099
2019-07-05 02:53:23,109 [salt.state       :1813][INFO    ][14131] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-07-05 02:53:23,109 [salt.state       :300 ][INFO    ][14131] /var/lib/maas/.setup_admin exists
2019-07-05 02:53:23,110 [salt.state       :1951][INFO    ][14131] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:53:23.109987 duration_in_ms=0.888
2019-07-05 02:53:23,110 [salt.state       :1780][INFO    ][14131] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:23.110664
2019-07-05 02:53:23,110 [salt.state       :1813][INFO    ][14131] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-05 02:53:23,111 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14131] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-05 02:53:24,851 [salt.state       :300 ][INFO    ][14131] {'pid': 14195, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-05 02:53:24,852 [salt.state       :1951][INFO    ][14131] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:24.852254 duration_in_ms=1741.587
2019-07-05 02:53:24,863 [salt.state       :1780][INFO    ][14131] Running state [maas_region_boot_source_resources_mirror] at time 02:53:24.863330
2019-07-05 02:53:24,864 [salt.state       :1813][INFO    ][14131] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-07-05 02:53:24,964 [salt.state       :300 ][INFO    ][14131] {'changes': {}}
2019-07-05 02:53:24,964 [salt.state       :1951][INFO    ][14131] Completed state [maas_region_boot_source_resources_mirror] at time 02:53:24.964637 duration_in_ms=101.308
2019-07-05 02:53:24,965 [salt.state       :1780][INFO    ][14131] Running state [maasng.boot_resources_import] at time 02:53:24.965588
2019-07-05 02:53:24,966 [salt.state       :1813][INFO    ][14131] Executing state module.run for [maasng.boot_resources_import]
2019-07-05 02:53:24,966 [salt.utils.decorators:613 ][WARNING ][14131] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-05 02:53:25,061 [salt.loaded.ext.module.maasng:1600][INFO    ][14131] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-07-05 02:53:30,120 [salt.loaded.ext.module.maasng:1600][INFO    ][14131] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-07-05 02:53:32,587 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705025332574122
2019-07-05 02:53:32,610 [salt.minion      :1432][INFO    ][14233] Starting a new job with PID 14233
2019-07-05 02:53:32,636 [salt.minion      :1711][INFO    ][14233] Returning information for job: 20190705025332574122
2019-07-05 02:53:35,224 [salt.state       :300 ][INFO    ][14131] {'ret': True}
2019-07-05 02:53:35,224 [salt.state       :1951][INFO    ][14131] Completed state [maasng.boot_resources_import] at time 02:53:35.224549 duration_in_ms=10258.96
2019-07-05 02:53:35,226 [salt.state       :1780][INFO    ][14131] Running state [maas_region_boot_sources_selection_xenial] at time 02:53:35.225699
2019-07-05 02:53:35,226 [salt.state       :1813][INFO    ][14131] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-07-05 02:53:35,412 [salt.state       :300 ][INFO    ][14131] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-07-05 02:53:35,413 [salt.state       :1951][INFO    ][14131] Completed state [maas_region_boot_sources_selection_xenial] at time 02:53:35.413065 duration_in_ms=187.366
2019-07-05 02:53:35,415 [salt.state       :1780][INFO    ][14131] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 02:53:35.415236
2019-07-05 02:53:35,415 [salt.state       :1813][INFO    ][14131] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-07-05 02:53:35,416 [salt.utils.decorators:613 ][WARNING ][14131] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-05 02:53:35,416 [salt.loaded.ext.module.maasng:1771][INFO    ][14131] boot-sources sync initiated for ALL Rack's
2019-07-05 02:53:36,485 [salt.state       :300 ][INFO    ][14131] {'ret': True}
2019-07-05 02:53:36,486 [salt.state       :1951][INFO    ][14131] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 02:53:36.486513 duration_in_ms=1071.277
2019-07-05 02:53:36,488 [salt.state       :1780][INFO    ][14131] Running state [maas.process_maas_config] at time 02:53:36.488175
2019-07-05 02:53:36,488 [salt.state       :1813][INFO    ][14131] Executing state module.run for [maas.process_maas_config]
2019-07-05 02:53:36,489 [salt.utils.decorators:613 ][WARNING ][14131] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-05 02:53:36,489 [salt.loaded.ext.module.maas:92  ][INFO    ][14131] maasconfig name=enable_http_proxy value=True
2019-07-05 02:53:36,538 [salt.loaded.ext.module.maas:92  ][INFO    ][14131] maasconfig name=upstream_dns value=8.8.8.8
2019-07-05 02:53:36,596 [salt.loaded.ext.module.maas:92  ][INFO    ][14131] maasconfig name=commissioning_distro_series value=xenial
2019-07-05 02:53:36,651 [salt.loaded.ext.module.maas:92  ][INFO    ][14131] maasconfig name=default_osystem value=ubuntu
2019-07-05 02:53:36,696 [salt.loaded.ext.module.maas:92  ][INFO    ][14131] maasconfig name=active_discovery_interval value=600
2019-07-05 02:53:37,940 [salt.loaded.ext.module.maas:92  ][INFO    ][14131] maasconfig name=dnssec_validation value=no
2019-07-05 02:53:37,984 [salt.loaded.ext.module.maas:92  ][INFO    ][14131] maasconfig name=maas_name value=mas01
2019-07-05 02:53:38,043 [salt.loaded.ext.module.maas:92  ][INFO    ][14131] maasconfig name=network_discovery value=enabled
2019-07-05 02:53:38,135 [salt.loaded.ext.module.maas:92  ][INFO    ][14131] maasconfig name=enable_third_party_drivers value=True
2019-07-05 02:53:38,172 [salt.loaded.ext.module.maas:92  ][INFO    ][14131] maasconfig name=default_storage_layout value=lvm
2019-07-05 02:53:38,242 [salt.loaded.ext.module.maas:92  ][INFO    ][14131] maasconfig name=ntp_external_only value=True
2019-07-05 02:53:38,290 [salt.loaded.ext.module.maas:92  ][INFO    ][14131] maasconfig name=disk_erase_with_secure_erase value=False
2019-07-05 02:53:38,348 [salt.loaded.ext.module.maas:92  ][INFO    ][14131] maasconfig name=default_distro_series value=xenial
2019-07-05 02:53:38,407 [salt.loaded.ext.module.maas:92  ][INFO    ][14131] maasconfig name=default_min_hwe_kernel value=ga-16.04
2019-07-05 02:53:38,517 [salt.state       :300 ][INFO    ][14131] {'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-07-05 02:53:38,518 [salt.state       :1951][INFO    ][14131] Completed state [maas.process_maas_config] at time 02:53:38.518486 duration_in_ms=2030.311
2019-07-05 02:53:38,519 [salt.state       :1780][INFO    ][14131] Running state [pxe_admin] at time 02:53:38.519166
2019-07-05 02:53:38,519 [salt.state       :1813][INFO    ][14131] Executing state maasng.fabric_present for [pxe_admin]
2019-07-05 02:53:38,576 [salt.loaded.ext.module.maasng:945 ][INFO    ][14131] [{u'name': u'fabric-0', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'name': u'fabric-2', u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': 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'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}, {u'name': u'pxe_admin', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'nrs3xy', 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'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}]
2019-07-05 02:53:38,643 [salt.loaded.ext.module.maasng:1008][WARNING ][14131] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-07-05 02:53:38,644 [salt.loaded.ext.module.maasng:1011][WARNING ][14131] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-07-05 02:53:38,701 [salt.state       :300 ][INFO    ][14131] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-07-05 02:53:38,701 [salt.state       :1951][INFO    ][14131] Completed state [pxe_admin] at time 02:53:38.701243 duration_in_ms=182.075
2019-07-05 02:53:38,701 [salt.state       :1780][INFO    ][14131] Running state [vlan 0] at time 02:53:38.701582
2019-07-05 02:53:38,702 [salt.state       :1813][INFO    ][14131] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-07-05 02:53:38,752 [salt.loaded.ext.module.maasng:945 ][INFO    ][14131] [{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'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'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'relay_vlan': None, u'primary_rack': 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'nrs3xy', 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-07-05 02:53:38,850 [salt.loaded.ext.module.maasng:945 ][INFO    ][14131] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': 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'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'nrs3xy', 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'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-07-05 02:53:39,101 [salt.loaded.ext.module.maasng:945 ][INFO    ][14131] [{u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None, u'name': u'fabric-2', u'id': 2}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'nrs3xy', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u'', u'name': u'pxe_admin', u'id': 1}]
2019-07-05 02:53:39,186 [salt.state       :300 ][INFO    ][14131] {'new': 'Vlan untagged was updated'}
2019-07-05 02:53:39,186 [salt.state       :1951][INFO    ][14131] Completed state [vlan 0] at time 02:53:39.186730 duration_in_ms=485.146
2019-07-05 02:53:39,188 [salt.state       :1780][INFO    ][14131] Running state [192.168.11.0/24] at time 02:53:39.187957
2019-07-05 02:53:39,188 [salt.state       :1813][INFO    ][14131] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-07-05 02:53:39,385 [salt.loaded.ext.module.maasng:945 ][INFO    ][14131] [{u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None, u'name': u'fabric-2', u'id': 2}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'nrs3xy', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u'', u'name': u'pxe_admin', u'id': 1}]
2019-07-05 02:53:39,388 [salt.loaded.ext.module.maasng:1235][WARNING ][14131] Ignoring parameter vlan:0
2019-07-05 02:53:39,448 [salt.state       :300 ][INFO    ][14131] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-07-05 02:53:39,448 [salt.state       :1951][INFO    ][14131] Completed state [192.168.11.0/24] at time 02:53:39.448671 duration_in_ms=260.713
2019-07-05 02:53:39,449 [salt.state       :1780][INFO    ][14131] Running state [maas_create_iprange_1] at time 02:53:39.449423
2019-07-05 02:53:39,450 [salt.state       :1813][INFO    ][14131] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-07-05 02:53:39,501 [salt.state       :300 ][INFO    ][14131] Iprange maas_create_iprange_1 already exist.
2019-07-05 02:53:39,501 [salt.state       :1951][INFO    ][14131] Completed state [maas_create_iprange_1] at time 02:53:39.501534 duration_in_ms=52.111
2019-07-05 02:53:39,501 [salt.state       :1780][INFO    ][14131] Running state [vlan 0] at time 02:53:39.501802
2019-07-05 02:53:39,502 [salt.state       :1813][INFO    ][14131] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-07-05 02:53:39,551 [salt.loaded.ext.module.maasng:945 ][INFO    ][14131] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': 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'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'nrs3xy', 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'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-07-05 02:53:39,659 [salt.loaded.ext.module.maasng:945 ][INFO    ][14131] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': 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'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'nrs3xy', 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'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-07-05 02:53:39,941 [salt.loaded.ext.module.maasng:945 ][INFO    ][14131] [{u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None, u'name': u'fabric-2', u'id': 2}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'nrs3xy', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u'', u'name': u'pxe_admin', u'id': 1}]
2019-07-05 02:53:40,041 [salt.state       :300 ][INFO    ][14131] {'new': 'Vlan untagged was updated'}
2019-07-05 02:53:40,042 [salt.state       :1951][INFO    ][14131] Completed state [vlan 0] at time 02:53:40.042602 duration_in_ms=540.8
2019-07-05 02:53:40,043 [salt.state       :1780][INFO    ][14131] Running state [opnfv] at time 02:53:40.043326
2019-07-05 02:53:40,043 [salt.state       :1813][INFO    ][14131] Executing state maasng.sshkey_present for [opnfv]
2019-07-05 02:53:40,081 [salt.loaded.ext.module.maasng:1903][INFO    ][14131] [{u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b', u'keysource': u''}]
2019-07-05 02:53:40,081 [salt.state       :300 ][INFO    ][14131] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-07-05 02:53:40,081 [salt.state       :1951][INFO    ][14131] Completed state [opnfv] at time 02:53:40.081640 duration_in_ms=38.313
2019-07-05 02:53:40,086 [salt.minion      :1711][INFO    ][14131] Returning information for job: 20190705025317454815
2019-07-05 02:53:40,705 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command state.apply with jid 20190705025340697163
2019-07-05 02:53:40,728 [salt.minion      :1432][INFO    ][14603] Starting a new job with PID 14603
2019-07-05 02:53:49,183 [salt.state       :915 ][INFO    ][14603] Loading fresh modules for state activity
2019-07-05 02:53:49,317 [salt.state       :1780][INFO    ][14603] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:49.317758
2019-07-05 02:53:49,318 [salt.state       :1813][INFO    ][14603] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-05 02:53:49,320 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14603] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-05 02:53:51,060 [salt.state       :300 ][INFO    ][14603] {'pid': 14629, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-05 02:53:51,062 [salt.state       :1951][INFO    ][14603] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:51.062682 duration_in_ms=1744.933
2019-07-05 02:53:51,064 [salt.state       :1780][INFO    ][14603] Running state [maas.process_machines] at time 02:53:51.064415
2019-07-05 02:53:51,064 [salt.state       :1813][INFO    ][14603] Executing state module.run for [maas.process_machines]
2019-07-05 02:53:51,065 [salt.utils.decorators:613 ][WARNING ][14603] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-05 02:53:51,653 [salt.loaded.ext.module.maas:412 ][WARNING ][14603] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-05 02:53:51,654 [salt.loaded.ext.module.maas:92  ][INFO    ][14603] 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=hkrms6 architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-05 02:53:52,845 [salt.loaded.ext.module.maas:412 ][WARNING ][14603] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-05 02:53:52,847 [salt.loaded.ext.module.maas:92  ][INFO    ][14603] 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=sxxw4x architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-05 02:53:54,067 [salt.loaded.ext.module.maas:412 ][WARNING ][14603] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-05 02:53:54,067 [salt.loaded.ext.module.maas:92  ][INFO    ][14603] 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=tb3hhw architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-05 02:53:55,317 [salt.loaded.ext.module.maas:412 ][WARNING ][14603] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-05 02:53:55,319 [salt.loaded.ext.module.maas:92  ][INFO    ][14603] 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=ywske8 architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-05 02:53:55,831 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705025355818669
2019-07-05 02:53:55,853 [salt.minion      :1432][INFO    ][14794] Starting a new job with PID 14794
2019-07-05 02:53:55,878 [salt.minion      :1711][INFO    ][14794] Returning information for job: 20190705025355818669
2019-07-05 02:53:56,548 [salt.loaded.ext.module.maas:412 ][WARNING ][14603] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-05 02:53:56,549 [salt.loaded.ext.module.maas:92  ][INFO    ][14603] 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=6schfe architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-05 02:53:57,720 [salt.state       :300 ][INFO    ][14603] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-07-05 02:53:57,721 [salt.state       :1951][INFO    ][14603] Completed state [maas.process_machines] at time 02:53:57.721112 duration_in_ms=6656.694
2019-07-05 02:53:57,727 [salt.minion      :1711][INFO    ][14603] Returning information for job: 20190705025340697163
2019-07-05 02:54:30,777 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command state.apply with jid 20190705025430763532
2019-07-05 02:54:30,806 [salt.minion      :1432][INFO    ][14872] Starting a new job with PID 14872
2019-07-05 02:54:39,050 [salt.state       :915 ][INFO    ][14872] Loading fresh modules for state activity
2019-07-05 02:54:39,153 [salt.state       :1780][INFO    ][14872] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:39.153505
2019-07-05 02:54:39,154 [salt.state       :1813][INFO    ][14872] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-05 02:54:39,155 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14872] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-05 02:54:40,950 [salt.state       :300 ][INFO    ][14872] {'pid': 14902, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-05 02:54:40,951 [salt.state       :1951][INFO    ][14872] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:40.951313 duration_in_ms=1797.808
2019-07-05 02:54:40,954 [salt.state       :1780][INFO    ][14872] Running state [maas.wait_for_machine_status] at time 02:54:40.954386
2019-07-05 02:54:40,955 [salt.state       :1813][INFO    ][14872] Executing state module.run for [maas.wait_for_machine_status]
2019-07-05 02:54:40,956 [salt.utils.decorators:613 ][WARNING ][14872] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-05 02:54:43,772 [salt.state       :300 ][INFO    ][14872] {'ret': True}
2019-07-05 02:54:43,773 [salt.state       :1951][INFO    ][14872] Completed state [maas.wait_for_machine_status] at time 02:54:43.773175 duration_in_ms=2818.788
2019-07-05 02:54:43,776 [salt.minion      :1711][INFO    ][14872] Returning information for job: 20190705025430763532
2019-07-05 02:54:44,324 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command state.apply with jid 20190705025444307890
2019-07-05 02:54:44,345 [salt.minion      :1432][INFO    ][14924] Starting a new job with PID 14924
2019-07-05 02:54:45,480 [salt.state       :915 ][INFO    ][14924] Loading fresh modules for state activity
2019-07-05 02:54:45,631 [salt.state       :1780][INFO    ][14924] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:45.631587
2019-07-05 02:54:45,631 [salt.state       :1813][INFO    ][14924] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-05 02:54:45,633 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14924] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-05 02:54:47,328 [salt.state       :300 ][INFO    ][14924] {'pid': 14931, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-05 02:54:47,331 [salt.state       :1951][INFO    ][14924] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:47.329600 duration_in_ms=1698.011
2019-07-05 02:54:47,334 [salt.state       :1780][INFO    ][14924] Running state [maas_machines_storage_cmp002_lvm] at time 02:54:47.334379
2019-07-05 02:54:47,334 [salt.state       :1813][INFO    ][14924] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-07-05 02:54:48,081 [salt.state       :300 ][INFO    ][14924] Machine cmp002 is not in Ready state.
2019-07-05 02:54:48,081 [salt.state       :1951][INFO    ][14924] Completed state [maas_machines_storage_cmp002_lvm] at time 02:54:48.081670 duration_in_ms=747.291
2019-07-05 02:54:48,082 [salt.state       :1780][INFO    ][14924] Running state [maas_machines_storage_cmp001_lvm] at time 02:54:48.082100
2019-07-05 02:54:48,082 [salt.state       :1813][INFO    ][14924] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-07-05 02:54:48,586 [salt.state       :300 ][INFO    ][14924] Machine cmp001 is not in Ready state.
2019-07-05 02:54:48,587 [salt.state       :1951][INFO    ][14924] Completed state [maas_machines_storage_cmp001_lvm] at time 02:54:48.587196 duration_in_ms=505.095
2019-07-05 02:54:48,593 [salt.minion      :1711][INFO    ][14924] Returning information for job: 20190705025444307890
2019-07-05 02:54:49,192 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command state.apply with jid 20190705025449131108
2019-07-05 02:54:49,218 [salt.minion      :1432][INFO    ][14943] Starting a new job with PID 14943
2019-07-05 02:54:50,365 [salt.state       :915 ][INFO    ][14943] Loading fresh modules for state activity
2019-07-05 02:54:50,461 [salt.state       :1780][INFO    ][14943] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:50.461288
2019-07-05 02:54:50,461 [salt.state       :1813][INFO    ][14943] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-05 02:54:50,463 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14943] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-05 02:54:52,165 [salt.state       :300 ][INFO    ][14943] {'pid': 14950, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-05 02:54:52,166 [salt.state       :1951][INFO    ][14943] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:52.166363 duration_in_ms=1705.076
2019-07-05 02:54:52,168 [salt.state       :1780][INFO    ][14943] Running state [maas.deploy_machines] at time 02:54:52.168758
2019-07-05 02:54:52,170 [salt.state       :1813][INFO    ][14943] Executing state module.run for [maas.deploy_machines]
2019-07-05 02:54:52,171 [salt.utils.decorators:613 ][WARNING ][14943] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-05 02:54:52,729 [salt.loaded.ext.module.maas:684 ][INFO    ][14943] deploymachines hwe_kernel=ga-16.04 system_id=6schfe distro_series=xenial
2019-07-05 02:54:55,241 [salt.state       :300 ][INFO    ][14943] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-07-05 02:54:55,242 [salt.state       :1951][INFO    ][14943] Completed state [maas.deploy_machines] at time 02:54:55.242022 duration_in_ms=3073.263
2019-07-05 02:54:55,245 [salt.minion      :1711][INFO    ][14943] Returning information for job: 20190705025449131108
2019-07-05 02:54:55,835 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command state.apply with jid 20190705025455820504
2019-07-05 02:54:55,859 [salt.minion      :1432][INFO    ][15016] Starting a new job with PID 15016
2019-07-05 02:55:04,016 [salt.state       :915 ][INFO    ][15016] Loading fresh modules for state activity
2019-07-05 02:55:04,122 [salt.state       :1780][INFO    ][15016] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:55:04.122808
2019-07-05 02:55:04,123 [salt.state       :1813][INFO    ][15016] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-05 02:55:04,124 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15016] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-05 02:55:05,882 [salt.state       :300 ][INFO    ][15016] {'pid': 15028, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-05 02:55:05,883 [salt.state       :1951][INFO    ][15016] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:55:05.883601 duration_in_ms=1760.792
2019-07-05 02:55:05,886 [salt.state       :1780][INFO    ][15016] Running state [maas.wait_for_machine_status] at time 02:55:05.886675
2019-07-05 02:55:05,887 [salt.state       :1813][INFO    ][15016] Executing state module.run for [maas.wait_for_machine_status]
2019-07-05 02:55:05,888 [salt.utils.decorators:613 ][WARNING ][15016] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-05 02:55:08,621 [salt.loaded.ext.module.maas:1023][INFO    ][15016] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.29102111s left)
2019-07-05 02:55:10,880 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705025510870220
2019-07-05 02:55:10,908 [salt.minion      :1432][INFO    ][15048] Starting a new job with PID 15048
2019-07-05 02:55:10,930 [salt.minion      :1711][INFO    ][15048] Returning information for job: 20190705025510870220
2019-07-05 02:55:40,952 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705025540940961
2019-07-05 02:55:40,974 [salt.minion      :1432][INFO    ][15119] Starting a new job with PID 15119
2019-07-05 02:55:40,998 [salt.minion      :1711][INFO    ][15119] Returning information for job: 20190705025540940961
2019-07-05 02:55:41,636 [salt.loaded.ext.module.maas:1023][INFO    ][15016] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.27552891s left)
2019-07-05 02:56:11,034 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705025611020359
2019-07-05 02:56:11,061 [salt.minion      :1432][INFO    ][15140] Starting a new job with PID 15140
2019-07-05 02:56:11,084 [salt.minion      :1711][INFO    ][15140] Returning information for job: 20190705025611020359
2019-07-05 02:56:14,369 [salt.loaded.ext.module.maas:1023][INFO    ][15016] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2181.54323196s left)
2019-07-05 02:56:41,116 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705025641099631
2019-07-05 02:56:41,145 [salt.minion      :1432][INFO    ][15199] Starting a new job with PID 15199
2019-07-05 02:56:41,170 [salt.minion      :1711][INFO    ][15199] Returning information for job: 20190705025641099631
2019-07-05 02:56:47,298 [salt.loaded.ext.module.maas:1023][INFO    ][15016] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2148.61346602s left)
2019-07-05 02:57:11,192 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705025711176090
2019-07-05 02:57:11,220 [salt.minion      :1432][INFO    ][15220] Starting a new job with PID 15220
2019-07-05 02:57:11,244 [salt.minion      :1711][INFO    ][15220] Returning information for job: 20190705025711176090
2019-07-05 02:57:20,188 [salt.loaded.ext.module.maas:1023][INFO    ][15016] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2115.72381997s left)
2019-07-05 02:57:41,285 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705025741269709
2019-07-05 02:57:41,309 [salt.minion      :1432][INFO    ][15276] Starting a new job with PID 15276
2019-07-05 02:57:41,336 [salt.minion      :1711][INFO    ][15276] Returning information for job: 20190705025741269709
2019-07-05 02:57:53,119 [salt.loaded.ext.module.maas:1023][INFO    ][15016] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2082.79317188s left)
2019-07-05 02:58:11,383 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705025811364340
2019-07-05 02:58:11,412 [salt.minion      :1432][INFO    ][15299] Starting a new job with PID 15299
2019-07-05 02:58:11,445 [salt.minion      :1711][INFO    ][15299] Returning information for job: 20190705025811364340
2019-07-05 02:58:25,996 [salt.loaded.ext.module.maas:1023][INFO    ][15016] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2049.91862988s left)
2019-07-05 02:58:41,488 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705025841471663
2019-07-05 02:58:41,517 [salt.minion      :1432][INFO    ][15360] Starting a new job with PID 15360
2019-07-05 02:58:41,540 [salt.minion      :1711][INFO    ][15360] Returning information for job: 20190705025841471663
2019-07-05 02:58:58,864 [salt.loaded.ext.module.maas:1023][INFO    ][15016] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2017.04768991s left)
2019-07-05 02:59:11,564 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705025911555591
2019-07-05 02:59:11,579 [salt.minion      :1432][INFO    ][15408] Starting a new job with PID 15408
2019-07-05 02:59:11,602 [salt.minion      :1711][INFO    ][15408] Returning information for job: 20190705025911555591
2019-07-05 02:59:31,846 [salt.loaded.ext.module.maas:1023][INFO    ][15016] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1984.06543493s left)
2019-07-05 02:59:41,677 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705025941664191
2019-07-05 02:59:41,707 [salt.minion      :1432][INFO    ][15566] Starting a new job with PID 15566
2019-07-05 02:59:41,733 [salt.minion      :1711][INFO    ][15566] Returning information for job: 20190705025941664191
2019-07-05 03:00:04,696 [salt.loaded.ext.module.maas:1023][INFO    ][15016] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1951.21862292s left)
2019-07-05 03:00:11,796 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705030011779549
2019-07-05 03:00:11,827 [salt.minion      :1432][INFO    ][15591] Starting a new job with PID 15591
2019-07-05 03:00:11,851 [salt.minion      :1711][INFO    ][15591] Returning information for job: 20190705030011779549
2019-07-05 03:00:37,705 [salt.loaded.ext.module.maas:1023][INFO    ][15016] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1918.20706201s left)
2019-07-05 03:00:41,935 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705030041919076
2019-07-05 03:00:41,965 [salt.minion      :1432][INFO    ][15737] Starting a new job with PID 15737
2019-07-05 03:00:41,999 [salt.minion      :1711][INFO    ][15737] Returning information for job: 20190705030041919076
2019-07-05 03:01:10,788 [salt.loaded.ext.module.maas:1023][INFO    ][15016] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1885.12343097s left)
2019-07-05 03:01:12,070 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705030112060271
2019-07-05 03:01:12,101 [salt.minion      :1432][INFO    ][15758] Starting a new job with PID 15758
2019-07-05 03:01:12,126 [salt.minion      :1711][INFO    ][15758] Returning information for job: 20190705030112060271
2019-07-05 03:01:42,200 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705030142189507
2019-07-05 03:01:42,225 [salt.minion      :1432][INFO    ][15862] Starting a new job with PID 15862
2019-07-05 03:01:42,248 [salt.minion      :1711][INFO    ][15862] Returning information for job: 20190705030142189507
2019-07-05 03:01:43,684 [salt.loaded.ext.module.maas:1023][INFO    ][15016] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1852.22809005s left)
2019-07-05 03:02:12,315 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705030212306370
2019-07-05 03:02:12,345 [salt.minion      :1432][INFO    ][15881] Starting a new job with PID 15881
2019-07-05 03:02:12,372 [salt.minion      :1711][INFO    ][15881] Returning information for job: 20190705030212306370
2019-07-05 03:02:16,573 [salt.loaded.ext.module.maas:1023][INFO    ][15016] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1819.33924508s left)
2019-07-05 03:02:42,472 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705030242456325
2019-07-05 03:02:42,502 [salt.minion      :1432][INFO    ][16005] Starting a new job with PID 16005
2019-07-05 03:02:42,532 [salt.minion      :1711][INFO    ][16005] Returning information for job: 20190705030242456325
2019-07-05 03:02:49,792 [salt.loaded.ext.module.maas:1023][INFO    ][15016] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1786.11981106s left)
2019-07-05 03:03:12,628 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705030312615063
2019-07-05 03:03:12,657 [salt.minion      :1432][INFO    ][16034] Starting a new job with PID 16034
2019-07-05 03:03:12,680 [salt.minion      :1711][INFO    ][16034] Returning information for job: 20190705030312615063
2019-07-05 03:03:23,026 [salt.loaded.ext.module.maas:1023][INFO    ][15016] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1752.88616204s left)
2019-07-05 03:03:42,804 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705030342787309
2019-07-05 03:03:42,835 [salt.minion      :1432][INFO    ][16157] Starting a new job with PID 16157
2019-07-05 03:03:42,859 [salt.minion      :1711][INFO    ][16157] Returning information for job: 20190705030342787309
2019-07-05 03:03:55,916 [salt.loaded.ext.module.maas:1023][INFO    ][15016] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1719.99583602s left)
2019-07-05 03:04:12,973 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705030412957814
2019-07-05 03:04:13,001 [salt.minion      :1432][INFO    ][16180] Starting a new job with PID 16180
2019-07-05 03:04:13,023 [salt.minion      :1711][INFO    ][16180] Returning information for job: 20190705030412957814
2019-07-05 03:04:28,819 [salt.loaded.ext.module.maas:1023][INFO    ][15016] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1687.09268904s left)
2019-07-05 03:04:43,136 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705030443119453
2019-07-05 03:04:43,170 [salt.minion      :1432][INFO    ][16236] Starting a new job with PID 16236
2019-07-05 03:04:43,195 [salt.minion      :1711][INFO    ][16236] Returning information for job: 20190705030443119453
2019-07-05 03:05:01,627 [salt.loaded.ext.module.maas:1023][INFO    ][15016] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1654.28452706s left)
2019-07-05 03:05:13,320 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705030513303365
2019-07-05 03:05:13,350 [salt.minion      :1432][INFO    ][16257] Starting a new job with PID 16257
2019-07-05 03:05:13,374 [salt.minion      :1711][INFO    ][16257] Returning information for job: 20190705030513303365
2019-07-05 03:05:34,607 [salt.loaded.ext.module.maas:1023][INFO    ][15016] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1621.304739s left)
2019-07-05 03:05:43,483 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705030543473119
2019-07-05 03:05:43,506 [salt.minion      :1432][INFO    ][16352] Starting a new job with PID 16352
2019-07-05 03:05:43,531 [salt.minion      :1711][INFO    ][16352] Returning information for job: 20190705030543473119
2019-07-05 03:06:07,517 [salt.loaded.ext.module.maas:1023][INFO    ][15016] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1588.3946979s left)
2019-07-05 03:06:13,662 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705030613648502
2019-07-05 03:06:13,690 [salt.minion      :1432][INFO    ][16505] Starting a new job with PID 16505
2019-07-05 03:06:13,715 [salt.minion      :1711][INFO    ][16505] Returning information for job: 20190705030613648502
2019-07-05 03:06:40,792 [salt.loaded.ext.module.maas:1023][INFO    ][15016] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1555.11985207s left)
2019-07-05 03:06:43,860 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705030643844504
2019-07-05 03:06:43,889 [salt.minion      :1432][INFO    ][16560] Starting a new job with PID 16560
2019-07-05 03:06:43,909 [salt.minion      :1711][INFO    ][16560] Returning information for job: 20190705030643844504
2019-07-05 03:06:44,978 [salt.utils.schedule:1377][INFO    ][6042] Running scheduled job: __mine_interval
2019-07-05 03:07:13,933 [salt.loaded.ext.module.maas:1023][INFO    ][15016] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1521.97893095s left)
2019-07-05 03:07:14,046 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705030714038955
2019-07-05 03:07:14,067 [salt.minion      :1432][INFO    ][16615] Starting a new job with PID 16615
2019-07-05 03:07:14,087 [salt.minion      :1711][INFO    ][16615] Returning information for job: 20190705030714038955
2019-07-05 03:07:44,164 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command saltutil.find_job with jid 20190705030744147921
2019-07-05 03:07:44,184 [salt.minion      :1432][INFO    ][16815] Starting a new job with PID 16815
2019-07-05 03:07:44,212 [salt.minion      :1711][INFO    ][16815] Returning information for job: 20190705030744147921
2019-07-05 03:07:46,816 [salt.state       :300 ][INFO    ][15016] {'ret': True}
2019-07-05 03:07:46,816 [salt.state       :1951][INFO    ][15016] Completed state [maas.wait_for_machine_status] at time 03:07:46.816580 duration_in_ms=760929.898
2019-07-05 03:07:46,823 [salt.minion      :1711][INFO    ][15016] Returning information for job: 20190705025455820504
2019-07-05 04:06:44,978 [salt.utils.schedule:1377][INFO    ][6042] Running scheduled job: __mine_interval
2019-07-05 04:42:02,061 [salt.minion      :1308][INFO    ][6042] User sudo_ubuntu Executing command cp.push_dir with jid 20190705044202050790
2019-07-05 04:42:02,084 [salt.minion      :1432][INFO    ][23150] Starting a new job with PID 23150
