2019-05-11 02:04:20,950 [salt.utils.decorators:613 ][WARNING ][2081] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-11 02:04:22,031 [salt.utils.decorators:613 ][WARNING ][2081] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-11 02:04:25,613 [salt.loaded.int.states.file:2298][WARNING ][2443] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-05-11 02:04:53,217 [salt.state       :2022][WARNING ][2859] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-11 02:04:56,309 [salt.utils.decorators:613 ][WARNING ][2859] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-11 02:05:06,943 [salt.utils.decorators:613 ][WARNING ][2859] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-11 02:05:33,860 [salt.utils.decorators:613 ][WARNING ][2859] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-11 02:05:34,708 [salt.utils.decorators:613 ][WARNING ][2859] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-11 02:05:36,943 [salt.loaded.ext.module.maasng:1008][WARNING ][2859] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-05-11 02:05:36,943 [salt.loaded.ext.module.maasng:1011][WARNING ][2859] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-05-11 02:05:37,676 [salt.loaded.ext.module.maasng:1235][WARNING ][2859] Ignoring parameter vlan:0
2019-05-11 02:05:49,523 [salt.utils.decorators:613 ][WARNING ][4964] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-11 02:05:49,582 [salt.loaded.ext.module.maas:412 ][WARNING ][4964] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-11 02:05:50,988 [salt.loaded.ext.module.maas:412 ][WARNING ][4964] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-11 02:05:52,193 [salt.loaded.ext.module.maas:412 ][WARNING ][4964] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-11 02:05:53,373 [salt.loaded.ext.module.maas:412 ][WARNING ][4964] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-11 02:05:54,692 [salt.loaded.ext.module.maas:412 ][WARNING ][4964] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-11 02:06:01,322 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5615] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-11 02:06:01,350 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5615] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-05-11 02:06:01,397 [salt.utils.parsers:1051][WARNING ][388] Minion received a SIGTERM. Exiting.
2019-05-11 02:06:02,597 [salt.cli.daemons :293 ][INFO    ][5668] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-05-11 02:06:02,756 [salt.cli.daemons :82  ][INFO    ][5668] Starting up the Salt Minion
2019-05-11 02:06:02,757 [salt.utils.event :1017][INFO    ][5668] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-05-11 02:06:03,960 [salt.minion      :976 ][INFO    ][5668] Creating minion process manager
2019-05-11 02:06:05,997 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][5668] Executing command ['date', '+%z'] in directory '/root'
2019-05-11 02:06:06,027 [salt.utils.schedule:568 ][INFO    ][5668] Updating job settings for scheduled job: __mine_interval
2019-05-11 02:06:06,029 [salt.minion      :1108][INFO    ][5668] Added mine.update to scheduler
2019-05-11 02:06:06,034 [salt.minion      :1975][INFO    ][5668] Minion is starting as user 'root'
2019-05-11 02:06:06,052 [salt.minion      :2336][INFO    ][5668] Minion is ready to receive requests!
2019-05-11 02:06:27,175 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command state.apply with jid 20190511020627161016
2019-05-11 02:06:27,200 [salt.minion      :1432][INFO    ][5756] Starting a new job with PID 5756
2019-05-11 02:06:34,935 [salt.state       :915 ][INFO    ][5756] Loading fresh modules for state activity
2019-05-11 02:06:34,993 [salt.fileclient  :1219][INFO    ][5756] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-05-11 02:06:35,041 [salt.state       :1780][INFO    ][5756] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:06:35.041483
2019-05-11 02:06:35,042 [salt.state       :1813][INFO    ][5756] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-11 02:06:35,044 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5756] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-11 02:06:37,014 [salt.state       :300 ][INFO    ][5756] {'pid': 5763, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-11 02:06:37,015 [salt.state       :1951][INFO    ][5756] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:06:37.014968 duration_in_ms=1973.486
2019-05-11 02:06:37,016 [salt.state       :1780][INFO    ][5756] Running state [maas.wait_for_machine_status] at time 02:06:37.016499
2019-05-11 02:06:37,016 [salt.state       :1813][INFO    ][5756] Executing state module.run for [maas.wait_for_machine_status]
2019-05-11 02:06:37,017 [salt.utils.decorators:613 ][WARNING ][5756] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-11 02:06:37,871 [salt.loaded.ext.module.maas:1023][INFO    ][5756] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.15815401s left)
2019-05-11 02:06:42,238 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511020642230735
2019-05-11 02:06:42,265 [salt.minion      :1432][INFO    ][5786] Starting a new job with PID 5786
2019-05-11 02:06:42,295 [salt.minion      :1711][INFO    ][5786] Returning information for job: 20190511020642230735
2019-05-11 02:07:08,740 [salt.loaded.ext.module.maas:1023][INFO    ][5756] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.2888329s left)
2019-05-11 02:07:12,340 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511020712324847
2019-05-11 02:07:12,366 [salt.minion      :1432][INFO    ][5834] Starting a new job with PID 5834
2019-05-11 02:07:12,395 [salt.minion      :1711][INFO    ][5834] Returning information for job: 20190511020712324847
2019-05-11 02:07:39,592 [salt.loaded.ext.module.maas:1023][INFO    ][5756] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.436378s left)
2019-05-11 02:07:42,448 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511020742432836
2019-05-11 02:07:42,476 [salt.minion      :1432][INFO    ][5862] Starting a new job with PID 5862
2019-05-11 02:07:42,504 [salt.minion      :1711][INFO    ][5862] Returning information for job: 20190511020742432836
2019-05-11 02:08:10,421 [salt.loaded.ext.module.maas:1023][INFO    ][5756] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.60813689s left)
2019-05-11 02:08:12,540 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511020812529599
2019-05-11 02:08:12,569 [salt.minion      :1432][INFO    ][5913] Starting a new job with PID 5913
2019-05-11 02:08:12,600 [salt.minion      :1711][INFO    ][5913] Returning information for job: 20190511020812529599
2019-05-11 02:08:41,514 [salt.loaded.ext.module.maas:1023][INFO    ][5756] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.51471806s left)
2019-05-11 02:08:42,648 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511020842634234
2019-05-11 02:08:42,671 [salt.minion      :1432][INFO    ][5971] Starting a new job with PID 5971
2019-05-11 02:08:42,703 [salt.minion      :1711][INFO    ][5971] Returning information for job: 20190511020842634234
2019-05-11 02:09:12,568 [salt.loaded.ext.module.maas:1023][INFO    ][5756] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.4608469s left)
2019-05-11 02:09:12,763 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511020912750875
2019-05-11 02:09:12,787 [salt.minion      :1432][INFO    ][6149] Starting a new job with PID 6149
2019-05-11 02:09:12,815 [salt.minion      :1711][INFO    ][6149] Returning information for job: 20190511020912750875
2019-05-11 02:09:42,876 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511020942861432
2019-05-11 02:09:42,897 [salt.minion      :1432][INFO    ][6222] Starting a new job with PID 6222
2019-05-11 02:09:42,925 [salt.minion      :1711][INFO    ][6222] Returning information for job: 20190511020942861432
2019-05-11 02:09:43,737 [salt.loaded.ext.module.maas:1023][INFO    ][5756] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1313.29176188s left)
2019-05-11 02:10:13,012 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511021012997225
2019-05-11 02:10:13,031 [salt.minion      :1432][INFO    ][6531] Starting a new job with PID 6531
2019-05-11 02:10:13,060 [salt.minion      :1711][INFO    ][6531] Returning information for job: 20190511021012997225
2019-05-11 02:10:15,129 [salt.loaded.ext.module.maas:1023][INFO    ][5756] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1281.90030098s left)
2019-05-11 02:10:43,139 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511021043127313
2019-05-11 02:10:43,169 [salt.minion      :1432][INFO    ][6647] Starting a new job with PID 6647
2019-05-11 02:10:43,199 [salt.minion      :1711][INFO    ][6647] Returning information for job: 20190511021043127313
2019-05-11 02:10:47,010 [salt.loaded.ext.module.maas:1023][INFO    ][5756] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1250.01934791s left)
2019-05-11 02:11:13,326 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511021113310878
2019-05-11 02:11:13,355 [salt.minion      :1432][INFO    ][7044] Starting a new job with PID 7044
2019-05-11 02:11:13,386 [salt.minion      :1711][INFO    ][7044] Returning information for job: 20190511021113310878
2019-05-11 02:11:19,379 [salt.loaded.ext.module.maas:1023][INFO    ][5756] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1217.64992595s left)
2019-05-11 02:11:43,463 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511021143452878
2019-05-11 02:11:43,492 [salt.minion      :1432][INFO    ][7165] Starting a new job with PID 7165
2019-05-11 02:11:43,521 [salt.minion      :1711][INFO    ][7165] Returning information for job: 20190511021143452878
2019-05-11 02:11:51,595 [salt.loaded.ext.module.maas:1023][INFO    ][5756] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1185.43407893s left)
2019-05-11 02:12:13,615 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511021213604025
2019-05-11 02:12:13,642 [salt.minion      :1432][INFO    ][7343] Starting a new job with PID 7343
2019-05-11 02:12:13,668 [salt.minion      :1711][INFO    ][7343] Returning information for job: 20190511021213604025
2019-05-11 02:12:24,078 [salt.loaded.ext.module.maas:1023][INFO    ][5756] Waiting status:Ready|Deployed for machines:['kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1152.95042396s left)
2019-05-11 02:12:43,760 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511021243744477
2019-05-11 02:12:43,788 [salt.minion      :1432][INFO    ][7467] Starting a new job with PID 7467
2019-05-11 02:12:43,816 [salt.minion      :1711][INFO    ][7467] Returning information for job: 20190511021243744477
2019-05-11 02:12:57,237 [salt.state       :300 ][INFO    ][5756] {'ret': True}
2019-05-11 02:12:57,237 [salt.state       :1951][INFO    ][5756] Completed state [maas.wait_for_machine_status] at time 02:12:57.237340 duration_in_ms=380220.84
2019-05-11 02:12:57,241 [salt.minion      :1711][INFO    ][5756] Returning information for job: 20190511020627161016
2019-05-11 02:12:57,955 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command state.apply with jid 20190511021257942461
2019-05-11 02:12:57,982 [salt.minion      :1432][INFO    ][7525] Starting a new job with PID 7525
2019-05-11 02:13:05,242 [salt.state       :915 ][INFO    ][7525] Loading fresh modules for state activity
2019-05-11 02:13:05,303 [salt.fileclient  :1219][INFO    ][7525] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-05-11 02:13:05,410 [salt.state       :1780][INFO    ][7525] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:13:05.410692
2019-05-11 02:13:05,411 [salt.state       :1813][INFO    ][7525] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-11 02:13:05,412 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7525] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-11 02:13:07,393 [salt.state       :300 ][INFO    ][7525] {'pid': 7539, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-11 02:13:07,395 [salt.state       :1951][INFO    ][7525] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:13:07.394931 duration_in_ms=1984.239
2019-05-11 02:13:07,399 [salt.state       :1780][INFO    ][7525] Running state [maas_machines_storage_cmp002_lvm] at time 02:13:07.399292
2019-05-11 02:13:07,400 [salt.state       :1813][INFO    ][7525] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-11 02:13:08,703 [salt.loaded.ext.module.maasng:610 ][INFO    ][7525] qahfrb
2019-05-11 02:13:08,704 [salt.loaded.ext.module.maasng:626 ][INFO    ][7525] sda
2019-05-11 02:13:09,420 [salt.loaded.ext.module.maasng:361 ][INFO    ][7525] qahfrb
2019-05-11 02:13:09,520 [salt.loaded.ext.module.maasng:367 ][INFO    ][7525] [{u'size': 800109715456, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/qahfrb/blockdevices/1/', u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'tags': [u'ssd'], u'filesystem': None, u'uuid': None, u'id': 1, u'system_id': u'qahfrb', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'5109ec8d-11c7-41fe-820e-f1eaea670479', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qahfrb', u'device_id': 1, u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'953eb852-941a-4db9-9226-2eed314c2d1f', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/qahfrb/blockdevices/1/partition/1', u'type': u'partition', u'id': 1, u'size': 800101236736}]}, {u'size': 800097042432, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/qahfrb/blockdevices/3/', u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'tags': [], u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'ade7a1b2-6874-44b0-ae94-0ac4987191aa', u'label': u'root'}, u'uuid': u'9ef7304c-da95-4503-956a-b103bd03e739', u'id': 3, u'system_id': u'qahfrb', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 800097042432, u'serial': None, u'partitions': []}]
2019-05-11 02:13:09,521 [salt.loaded.ext.module.maasng:632 ][INFO    ][7525] vgroot
2019-05-11 02:13:09,523 [salt.loaded.ext.module.maasng:635 ][INFO    ][7525] lvroot
2019-05-11 02:13:09,523 [salt.loaded.ext.module.maasng:639 ][INFO    ][7525] 107374182400
2019-05-11 02:13:10,155 [salt.loaded.ext.module.maasng:645 ][INFO    ][7525] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'testing_status_name': u'Passed', u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'cpu_count': 40, u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'min_hwe_kernel': u'ga-16.04', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'status_action': u'', 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/qahfrb/', u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'qahfrb', 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'model': u'LOGICAL VOLUME', u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'system_id': u'qahfrb', u'type': u'physical', u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'resource_uri': u'/MAAS/api/2.0/nodes/qahfrb/blockdevices/1/', 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'partitions': [{u'uuid': u'fc68ce17-b065-416d-aa62-352b5e70bd55', u'resource_uri': u'/MAAS/api/2.0/nodes/qahfrb/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qahfrb', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'81101507-6857-488d-ae22-b774c84a3bab', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'device_id': 1}], u'used_size': 800106479616, u'id': 1, u'size': 800109715456}, {u'model': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'system_id': u'qahfrb', u'type': u'virtual', u'uuid': u'79414e52-6473-40d4-9feb-376ad075a042', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/qahfrb/blockdevices/11/', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'1c5b917c-6226-4516-83c3-ba3c9e58b83c', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'partitions': [], u'used_size': 107374182400, u'id': 11, u'size': 107374182400}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'power_state': u'off', u'physicalblockdevice_set': [{u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/qahfrb/blockdevices/1/', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 1, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'qahfrb', 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'type': u'physical', u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'fc68ce17-b065-416d-aa62-352b5e70bd55', u'resource_uri': u'/MAAS/api/2.0/nodes/qahfrb/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qahfrb', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'81101507-6857-488d-ae22-b774c84a3bab', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'device_id': 1}]}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'qahfrb', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'boot_disk': {u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/qahfrb/blockdevices/1/', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 1, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'qahfrb', 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'type': u'physical', u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'fc68ce17-b065-416d-aa62-352b5e70bd55', u'resource_uri': u'/MAAS/api/2.0/nodes/qahfrb/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qahfrb', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'81101507-6857-488d-ae22-b774c84a3bab', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'device_id': 1}]}, u'current_testing_result_id': 3, u'cpu_test_status': -1, u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'disable_ipv4': False, u'virtualblockdevice_set': [{u'size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/qahfrb/blockdevices/11/', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'id': 11, u'uuid': u'79414e52-6473-40d4-9feb-376ad075a042', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'qahfrb', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'1c5b917c-6226-4516-83c3-ba3c9e58b83c', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'model': None, u'partitions': []}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'boot_interface': {u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'4677cy', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'4677cy', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 18}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 4, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'4677cy', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'system_id': u'qahfrb', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qahfrb/interfaces/4/'}, u'interface_set': [{u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'4677cy', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'4677cy', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 18}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 4, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'4677cy', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'system_id': u'qahfrb', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qahfrb/interfaces/4/'}, {u'vlan': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 14, u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:07:51', u'parents': [], u'system_id': u'qahfrb', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qahfrb/interfaces/14/'}, {u'vlan': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 11, u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:07:50', u'parents': [], u'system_id': u'qahfrb', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qahfrb/interfaces/11/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 19}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 15, u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:12:48', u'parents': [], u'system_id': u'qahfrb', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qahfrb/interfaces/15/'}, {u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'4677cy', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'4677cy', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'mode': u'link_up', u'id': 20}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 12, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'4677cy', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40'}], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:1c', u'parents': [], u'system_id': u'qahfrb', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qahfrb/interfaces/12/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 21}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 13, u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:12:49', u'parents': [], u'system_id': u'qahfrb', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qahfrb/interfaces/13/'}], u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2019-05-11 02:13:10,158 [salt.state       :300 ][INFO    ][7525] {'new': {'storage_layout': 'lvm'}}
2019-05-11 02:13:10,159 [salt.state       :1951][INFO    ][7525] Completed state [maas_machines_storage_cmp002_lvm] at time 02:13:10.159213 duration_in_ms=2759.921
2019-05-11 02:13:10,160 [salt.state       :1780][INFO    ][7525] Running state [maas_machines_storage_cmp001_lvm] at time 02:13:10.159876
2019-05-11 02:13:10,160 [salt.state       :1813][INFO    ][7525] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-11 02:13:11,327 [salt.loaded.ext.module.maasng:610 ][INFO    ][7525] cexsc8
2019-05-11 02:13:11,327 [salt.loaded.ext.module.maasng:626 ][INFO    ][7525] sda
2019-05-11 02:13:11,887 [salt.loaded.ext.module.maasng:361 ][INFO    ][7525] cexsc8
2019-05-11 02:13:11,980 [salt.loaded.ext.module.maasng:367 ][INFO    ][7525] [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'system_id': u'cexsc8', u'type': u'physical', u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'resource_uri': u'/MAAS/api/2.0/nodes/cexsc8/blockdevices/2/', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'070c9987-d3a3-4d41-86c8-9fc24abd4c9f', u'resource_uri': u'/MAAS/api/2.0/nodes/cexsc8/blockdevices/2/partition/2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'cexsc8', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'b6d992ce-d617-4edb-8cff-8420a07ed111', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 2, u'device_id': 2}], u'used_size': 800106479616, u'id': 2, u'size': 800109715456}, {u'model': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'system_id': u'cexsc8', u'type': u'virtual', u'uuid': u'3871daf8-c74a-4a06-8902-82a631195584', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/cexsc8/blockdevices/4/', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'31d7e2f1-24c7-4cea-aff4-73fbd91df6f5', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'partitions': [], u'used_size': 800097042432, u'id': 4, u'size': 800097042432}]
2019-05-11 02:13:11,981 [salt.loaded.ext.module.maasng:632 ][INFO    ][7525] vgroot
2019-05-11 02:13:11,982 [salt.loaded.ext.module.maasng:635 ][INFO    ][7525] lvroot
2019-05-11 02:13:11,982 [salt.loaded.ext.module.maasng:639 ][INFO    ][7525] 107374182400
2019-05-11 02:13:12,591 [salt.loaded.ext.module.maasng:645 ][INFO    ][7525] {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'status_action': u'', u'swap_size': None, u'ip_addresses': [u'192.168.11.39', u'192.168.11.44'], u'cpu_count': 40, u'owner_data': {}, u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', 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'boot_disk': {u'partition_table_type': u'MBR', u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'd07eef9a-a606-47ae-a827-a017a24b8e53', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'cexsc8', u'device_id': 2, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'cc9ff774-b91b-4981-b7fb-10a1650d9cf4', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/cexsc8/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'cexsc8', u'resource_uri': u'/MAAS/api/2.0/nodes/cexsc8/blockdevices/2/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, u'blockdevice_set': [{u'partition_table_type': u'MBR', u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'd07eef9a-a606-47ae-a827-a017a24b8e53', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'cexsc8', u'device_id': 2, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'cc9ff774-b91b-4981-b7fb-10a1650d9cf4', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/cexsc8/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'cexsc8', u'resource_uri': u'/MAAS/api/2.0/nodes/cexsc8/blockdevices/2/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, {u'partition_table_type': None, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'uuid': u'2c673243-7830-4630-ab70-49650a15149b', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'cexsc8', u'resource_uri': u'/MAAS/api/2.0/nodes/cexsc8/blockdevices/12/', u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'd23188a2-f867-478b-989a-455394672d2f', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 12, u'serial': None, u'size': 107374182400}], u'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'id': 1}, u'resource_uri': u'/MAAS/api/2.0/machines/cexsc8/', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'cexsc8', 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'virtualblockdevice_set': [{u'partition_table_type': None, u'available_size': 0, u'uuid': u'2c673243-7830-4630-ab70-49650a15149b', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'cexsc8', u'resource_uri': u'/MAAS/api/2.0/nodes/cexsc8/blockdevices/12/', u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'd23188a2-f867-478b-989a-455394672d2f', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 12, u'serial': None, u'size': 107374182400}], u'min_hwe_kernel': u'ga-16.04', u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'power_type': u'ipmi', u'physicalblockdevice_set': [{u'partition_table_type': u'MBR', u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'd07eef9a-a606-47ae-a827-a017a24b8e53', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'cexsc8', u'device_id': 2, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'cc9ff774-b91b-4981-b7fb-10a1650d9cf4', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/cexsc8/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'cexsc8', u'resource_uri': u'/MAAS/api/2.0/nodes/cexsc8/blockdevices/2/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'cexsc8', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'commissioning_status_name': u'Passed', u'current_testing_result_id': 5, u'cpu_test_status': -1, u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'disable_ipv4': False, u'commissioning_status': 2, u'architecture': u'amd64/generic', u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'4677cy', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'id': 24, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'4677cy', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'4677cy', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'cexsc8', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/cexsc8/interfaces/5/'}, u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'4677cy', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'id': 24, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'4677cy', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'4677cy', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'cexsc8', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/cexsc8/interfaces/5/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'id': 16, u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d4', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'cexsc8', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/cexsc8/interfaces/16/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'id': 19, u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d5', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'cexsc8', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/cexsc8/interfaces/19/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'4677cy', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'id': 25, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'4677cy', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'id': 17, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'4677cy', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.44'}], u'mac_address': u'9c:b6:54:8a:95:a4', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'cexsc8', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/cexsc8/interfaces/17/'}, {u'name': u'ens2f1', u'links': [{u'id': 26, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'id': 18, u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cd', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'cexsc8', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/cexsc8/interfaces/18/'}, {u'name': u'ens2f0', u'links': [{u'id': 27, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'id': 20, u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cc', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'cexsc8', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/cexsc8/interfaces/20/'}], u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'node_type_name': u'Machine'}
2019-05-11 02:13:12,595 [salt.state       :300 ][INFO    ][7525] {'new': {'storage_layout': 'lvm'}}
2019-05-11 02:13:12,595 [salt.state       :1951][INFO    ][7525] Completed state [maas_machines_storage_cmp001_lvm] at time 02:13:12.595596 duration_in_ms=2435.719
2019-05-11 02:13:12,600 [salt.minion      :1711][INFO    ][7525] Returning information for job: 20190511021257942461
2019-05-11 02:13:13,305 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command state.apply with jid 20190511021313290894
2019-05-11 02:13:13,337 [salt.minion      :1432][INFO    ][7562] Starting a new job with PID 7562
2019-05-11 02:13:14,467 [salt.state       :915 ][INFO    ][7562] Loading fresh modules for state activity
2019-05-11 02:13:14,528 [salt.fileclient  :1219][INFO    ][7562] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-05-11 02:13:14,579 [salt.state       :1780][INFO    ][7562] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:13:14.579693
2019-05-11 02:13:14,580 [salt.state       :1813][INFO    ][7562] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-11 02:13:14,582 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7562] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-11 02:13:16,396 [salt.state       :300 ][INFO    ][7562] {'pid': 7569, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-11 02:13:16,397 [salt.state       :1951][INFO    ][7562] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:13:16.397308 duration_in_ms=1817.614
2019-05-11 02:13:16,400 [salt.state       :1780][INFO    ][7562] Running state [maas.deploy_machines] at time 02:13:16.400281
2019-05-11 02:13:16,401 [salt.state       :1813][INFO    ][7562] Executing state module.run for [maas.deploy_machines]
2019-05-11 02:13:16,402 [salt.utils.decorators:613 ][WARNING ][7562] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-11 02:13:16,990 [salt.loaded.ext.module.maas:684 ][INFO    ][7562] deploymachines hwe_kernel=ga-16.04 system_id=qahfrb distro_series=xenial
2019-05-11 02:13:19,523 [salt.loaded.ext.module.maas:684 ][INFO    ][7562] deploymachines hwe_kernel=ga-16.04 system_id=cexsc8 distro_series=xenial
2019-05-11 02:13:22,243 [salt.loaded.ext.module.maas:684 ][INFO    ][7562] deploymachines hwe_kernel=ga-16.04 system_id=sxkcb7 distro_series=xenial
2019-05-11 02:13:24,752 [salt.loaded.ext.module.maas:684 ][INFO    ][7562] deploymachines hwe_kernel=ga-16.04 system_id=4heerr distro_series=xenial
2019-05-11 02:13:27,413 [salt.loaded.ext.module.maas:684 ][INFO    ][7562] deploymachines hwe_kernel=ga-16.04 system_id=ekacmg distro_series=xenial
2019-05-11 02:13:28,437 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511021328422010
2019-05-11 02:13:28,465 [salt.minion      :1432][INFO    ][7816] Starting a new job with PID 7816
2019-05-11 02:13:28,507 [salt.minion      :1711][INFO    ][7816] Returning information for job: 20190511021328422010
2019-05-11 02:13:30,095 [salt.state       :300 ][INFO    ][7562] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-05-11 02:13:30,096 [salt.state       :1951][INFO    ][7562] Completed state [maas.deploy_machines] at time 02:13:30.096255 duration_in_ms=13695.974
2019-05-11 02:13:30,104 [salt.minion      :1711][INFO    ][7562] Returning information for job: 20190511021313290894
2019-05-11 02:13:30,802 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command state.apply with jid 20190511021330781763
2019-05-11 02:13:30,834 [salt.minion      :1432][INFO    ][7847] Starting a new job with PID 7847
2019-05-11 02:13:38,421 [salt.state       :915 ][INFO    ][7847] Loading fresh modules for state activity
2019-05-11 02:13:38,477 [salt.fileclient  :1219][INFO    ][7847] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-05-11 02:13:38,526 [salt.state       :1780][INFO    ][7847] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:13:38.526473
2019-05-11 02:13:38,526 [salt.state       :1813][INFO    ][7847] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-11 02:13:38,528 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7847] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-11 02:13:40,373 [salt.state       :300 ][INFO    ][7847] {'pid': 7866, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-11 02:13:40,375 [salt.state       :1951][INFO    ][7847] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:13:40.375105 duration_in_ms=1848.631
2019-05-11 02:13:40,379 [salt.state       :1780][INFO    ][7847] Running state [maas.wait_for_machine_status] at time 02:13:40.379755
2019-05-11 02:13:40,380 [salt.state       :1813][INFO    ][7847] Executing state module.run for [maas.wait_for_machine_status]
2019-05-11 02:13:40,381 [salt.utils.decorators:613 ][WARNING ][7847] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-11 02:13:43,390 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.00461793s left)
2019-05-11 02:13:45,855 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511021345840669
2019-05-11 02:13:45,884 [salt.minion      :1432][INFO    ][7889] Starting a new job with PID 7889
2019-05-11 02:13:45,909 [salt.minion      :1711][INFO    ][7889] Returning information for job: 20190511021345840669
2019-05-11 02:14:15,952 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511021415943332
2019-05-11 02:14:15,979 [salt.minion      :1432][INFO    ][7939] Starting a new job with PID 7939
2019-05-11 02:14:16,006 [salt.minion      :1711][INFO    ][7939] Returning information for job: 20190511021415943332
2019-05-11 02:14:16,301 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.09384799s left)
2019-05-11 02:14:46,080 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511021446068526
2019-05-11 02:14:46,111 [salt.minion      :1432][INFO    ][7972] Starting a new job with PID 7972
2019-05-11 02:14:46,140 [salt.minion      :1711][INFO    ][7972] Returning information for job: 20190511021446068526
2019-05-11 02:14:49,230 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.16492891s left)
2019-05-11 02:15:16,180 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511021516162537
2019-05-11 02:15:16,205 [salt.minion      :1432][INFO    ][8037] Starting a new job with PID 8037
2019-05-11 02:15:16,233 [salt.minion      :1711][INFO    ][8037] Returning information for job: 20190511021516162537
2019-05-11 02:15:22,407 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2147.98731804s left)
2019-05-11 02:15:46,268 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511021546254876
2019-05-11 02:15:46,291 [salt.minion      :1432][INFO    ][8068] Starting a new job with PID 8068
2019-05-11 02:15:46,317 [salt.minion      :1711][INFO    ][8068] Returning information for job: 20190511021546254876
2019-05-11 02:15:55,315 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2115.07975292s left)
2019-05-11 02:16:16,353 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511021616332012
2019-05-11 02:16:16,387 [salt.minion      :1432][INFO    ][8164] Starting a new job with PID 8164
2019-05-11 02:16:16,416 [salt.minion      :1711][INFO    ][8164] Returning information for job: 20190511021616332012
2019-05-11 02:16:28,567 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2081.82761502s left)
2019-05-11 02:16:46,485 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511021646468360
2019-05-11 02:16:46,518 [salt.minion      :1432][INFO    ][8251] Starting a new job with PID 8251
2019-05-11 02:16:46,547 [salt.minion      :1711][INFO    ][8251] Returning information for job: 20190511021646468360
2019-05-11 02:17:01,996 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2048.39876199s left)
2019-05-11 02:17:16,633 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511021716616130
2019-05-11 02:17:16,664 [salt.minion      :1432][INFO    ][8531] Starting a new job with PID 8531
2019-05-11 02:17:16,691 [salt.minion      :1711][INFO    ][8531] Returning information for job: 20190511021716616130
2019-05-11 02:17:35,035 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2015.35967994s left)
2019-05-11 02:17:46,711 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511021746692269
2019-05-11 02:17:46,735 [salt.minion      :1432][INFO    ][8600] Starting a new job with PID 8600
2019-05-11 02:17:46,765 [salt.minion      :1711][INFO    ][8600] Returning information for job: 20190511021746692269
2019-05-11 02:18:08,111 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1982.28358984s left)
2019-05-11 02:18:16,853 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511021816835067
2019-05-11 02:18:16,884 [salt.minion      :1432][INFO    ][8814] Starting a new job with PID 8814
2019-05-11 02:18:16,915 [salt.minion      :1711][INFO    ][8814] Returning information for job: 20190511021816835067
2019-05-11 02:18:41,232 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1949.16295385s left)
2019-05-11 02:18:47,020 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511021846990719
2019-05-11 02:18:47,047 [salt.minion      :1432][INFO    ][8854] Starting a new job with PID 8854
2019-05-11 02:18:47,073 [salt.minion      :1711][INFO    ][8854] Returning information for job: 20190511021846990719
2019-05-11 02:19:14,417 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1915.97809196s left)
2019-05-11 02:19:17,204 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511021917188801
2019-05-11 02:19:17,233 [salt.minion      :1432][INFO    ][9297] Starting a new job with PID 9297
2019-05-11 02:19:17,266 [salt.minion      :1711][INFO    ][9297] Returning information for job: 20190511021917188801
2019-05-11 02:19:47,347 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511021947336578
2019-05-11 02:19:47,371 [salt.minion      :1432][INFO    ][9344] Starting a new job with PID 9344
2019-05-11 02:19:47,400 [salt.minion      :1711][INFO    ][9344] Returning information for job: 20190511021947336578
2019-05-11 02:19:47,575 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1882.81959391s left)
2019-05-11 02:20:17,538 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511022017521543
2019-05-11 02:20:17,565 [salt.minion      :1432][INFO    ][9683] Starting a new job with PID 9683
2019-05-11 02:20:17,595 [salt.minion      :1711][INFO    ][9683] Returning information for job: 20190511022017521543
2019-05-11 02:20:20,709 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1849.68610883s left)
2019-05-11 02:20:47,721 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511022047702794
2019-05-11 02:20:47,748 [salt.minion      :1432][INFO    ][9721] Starting a new job with PID 9721
2019-05-11 02:20:47,778 [salt.minion      :1711][INFO    ][9721] Returning information for job: 20190511022047702794
2019-05-11 02:20:53,547 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1816.84726286s left)
2019-05-11 02:21:17,909 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511022117900102
2019-05-11 02:21:17,926 [salt.minion      :1432][INFO    ][9937] Starting a new job with PID 9937
2019-05-11 02:21:17,953 [salt.minion      :1711][INFO    ][9937] Returning information for job: 20190511022117900102
2019-05-11 02:21:26,473 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1783.92221594s left)
2019-05-11 02:21:48,044 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511022148029519
2019-05-11 02:21:48,075 [salt.minion      :1432][INFO    ][9988] Starting a new job with PID 9988
2019-05-11 02:21:48,101 [salt.minion      :1711][INFO    ][9988] Returning information for job: 20190511022148029519
2019-05-11 02:22:00,141 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1750.25392294s left)
2019-05-11 02:22:18,257 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511022218238821
2019-05-11 02:22:18,287 [salt.minion      :1432][INFO    ][10187] Starting a new job with PID 10187
2019-05-11 02:22:18,329 [salt.minion      :1711][INFO    ][10187] Returning information for job: 20190511022218238821
2019-05-11 02:22:33,160 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1717.23489594s left)
2019-05-11 02:22:48,418 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511022248405660
2019-05-11 02:22:48,449 [salt.minion      :1432][INFO    ][10244] Starting a new job with PID 10244
2019-05-11 02:22:48,483 [salt.minion      :1711][INFO    ][10244] Returning information for job: 20190511022248405660
2019-05-11 02:23:06,224 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1684.17079306s left)
2019-05-11 02:23:18,476 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511022318461404
2019-05-11 02:23:18,503 [salt.minion      :1432][INFO    ][10428] Starting a new job with PID 10428
2019-05-11 02:23:18,531 [salt.minion      :1711][INFO    ][10428] Returning information for job: 20190511022318461404
2019-05-11 02:23:39,048 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1651.34645486s left)
2019-05-11 02:23:48,635 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511022348622565
2019-05-11 02:23:48,659 [salt.minion      :1432][INFO    ][10468] Starting a new job with PID 10468
2019-05-11 02:23:48,691 [salt.minion      :1711][INFO    ][10468] Returning information for job: 20190511022348622565
2019-05-11 02:24:12,244 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1618.15111399s left)
2019-05-11 02:24:18,848 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511022418835491
2019-05-11 02:24:18,875 [salt.minion      :1432][INFO    ][10632] Starting a new job with PID 10632
2019-05-11 02:24:18,910 [salt.minion      :1711][INFO    ][10632] Returning information for job: 20190511022418835491
2019-05-11 02:24:45,048 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1585.34723186s left)
2019-05-11 02:24:49,043 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511022449027581
2019-05-11 02:24:49,069 [salt.minion      :1432][INFO    ][10794] Starting a new job with PID 10794
2019-05-11 02:24:49,102 [salt.minion      :1711][INFO    ][10794] Returning information for job: 20190511022449027581
2019-05-11 02:25:17,859 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1552.53611493s left)
2019-05-11 02:25:19,257 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511022519242822
2019-05-11 02:25:19,287 [salt.minion      :1432][INFO    ][10874] Starting a new job with PID 10874
2019-05-11 02:25:19,316 [salt.minion      :1711][INFO    ][10874] Returning information for job: 20190511022519242822
2019-05-11 02:25:49,472 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511022549458587
2019-05-11 02:25:49,493 [salt.minion      :1432][INFO    ][10952] Starting a new job with PID 10952
2019-05-11 02:25:49,528 [salt.minion      :1711][INFO    ][10952] Returning information for job: 20190511022549458587
2019-05-11 02:25:51,005 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1519.38957405s left)
2019-05-11 02:26:19,649 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511022619632999
2019-05-11 02:26:19,679 [salt.minion      :1432][INFO    ][11130] Starting a new job with PID 11130
2019-05-11 02:26:19,709 [salt.minion      :1711][INFO    ][11130] Returning information for job: 20190511022619632999
2019-05-11 02:26:23,835 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1486.55949688s left)
2019-05-11 02:26:49,670 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511022649662593
2019-05-11 02:26:49,690 [salt.minion      :1432][INFO    ][11162] Starting a new job with PID 11162
2019-05-11 02:26:49,719 [salt.minion      :1711][INFO    ][11162] Returning information for job: 20190511022649662593
2019-05-11 02:26:56,826 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1453.56905198s left)
2019-05-11 02:27:19,689 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511022719682227
2019-05-11 02:27:19,711 [salt.minion      :1432][INFO    ][11254] Starting a new job with PID 11254
2019-05-11 02:27:19,747 [salt.minion      :1711][INFO    ][11254] Returning information for job: 20190511022719682227
2019-05-11 02:27:29,772 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1420.62288284s left)
2019-05-11 02:27:49,737 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511022749721440
2019-05-11 02:27:49,769 [salt.minion      :1432][INFO    ][11282] Starting a new job with PID 11282
2019-05-11 02:27:49,800 [salt.minion      :1711][INFO    ][11282] Returning information for job: 20190511022749721440
2019-05-11 02:28:02,689 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1387.70594883s left)
2019-05-11 02:28:19,772 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511022819756720
2019-05-11 02:28:19,799 [salt.minion      :1432][INFO    ][11326] Starting a new job with PID 11326
2019-05-11 02:28:19,827 [salt.minion      :1711][INFO    ][11326] Returning information for job: 20190511022819756720
2019-05-11 02:28:35,501 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1354.89408398s left)
2019-05-11 02:28:49,854 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511022849835833
2019-05-11 02:28:49,876 [salt.minion      :1432][INFO    ][11357] Starting a new job with PID 11357
2019-05-11 02:28:49,907 [salt.minion      :1711][INFO    ][11357] Returning information for job: 20190511022849835833
2019-05-11 02:29:08,622 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1321.77242804s left)
2019-05-11 02:29:19,918 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511022919904024
2019-05-11 02:29:19,948 [salt.minion      :1432][INFO    ][11402] Starting a new job with PID 11402
2019-05-11 02:29:19,987 [salt.minion      :1711][INFO    ][11402] Returning information for job: 20190511022919904024
2019-05-11 02:29:41,424 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1288.97103786s left)
2019-05-11 02:29:50,044 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511022950029555
2019-05-11 02:29:50,074 [salt.minion      :1432][INFO    ][11432] Starting a new job with PID 11432
2019-05-11 02:29:50,114 [salt.minion      :1711][INFO    ][11432] Returning information for job: 20190511022950029555
2019-05-11 02:30:14,175 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1256.21933389s left)
2019-05-11 02:30:20,147 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511023020138498
2019-05-11 02:30:20,170 [salt.minion      :1432][INFO    ][11484] Starting a new job with PID 11484
2019-05-11 02:30:20,197 [salt.minion      :1711][INFO    ][11484] Returning information for job: 20190511023020138498
2019-05-11 02:30:47,075 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1223.31965804s left)
2019-05-11 02:30:50,269 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511023050253773
2019-05-11 02:30:50,302 [salt.minion      :1432][INFO    ][11515] Starting a new job with PID 11515
2019-05-11 02:30:50,329 [salt.minion      :1711][INFO    ][11515] Returning information for job: 20190511023050253773
2019-05-11 02:31:20,041 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1190.35384583s left)
2019-05-11 02:31:20,414 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511023120399352
2019-05-11 02:31:20,442 [salt.minion      :1432][INFO    ][11561] Starting a new job with PID 11561
2019-05-11 02:31:20,471 [salt.minion      :1711][INFO    ][11561] Returning information for job: 20190511023120399352
2019-05-11 02:31:50,563 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511023150555555
2019-05-11 02:31:50,588 [salt.minion      :1432][INFO    ][11588] Starting a new job with PID 11588
2019-05-11 02:31:50,624 [salt.minion      :1711][INFO    ][11588] Returning information for job: 20190511023150555555
2019-05-11 02:31:52,941 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1157.45417786s left)
2019-05-11 02:32:20,722 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511023220708377
2019-05-11 02:32:20,752 [salt.minion      :1432][INFO    ][11640] Starting a new job with PID 11640
2019-05-11 02:32:20,782 [salt.minion      :1711][INFO    ][11640] Returning information for job: 20190511023220708377
2019-05-11 02:32:25,919 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1124.47579193s left)
2019-05-11 02:32:50,911 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511023250895333
2019-05-11 02:32:50,935 [salt.minion      :1432][INFO    ][11672] Starting a new job with PID 11672
2019-05-11 02:32:50,964 [salt.minion      :1711][INFO    ][11672] Returning information for job: 20190511023250895333
2019-05-11 02:32:58,882 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1091.5128119s left)
2019-05-11 02:33:21,099 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511023321090479
2019-05-11 02:33:21,119 [salt.minion      :1432][INFO    ][11719] Starting a new job with PID 11719
2019-05-11 02:33:21,148 [salt.minion      :1711][INFO    ][11719] Returning information for job: 20190511023321090479
2019-05-11 02:33:31,658 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1058.73669791s left)
2019-05-11 02:33:51,281 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511023351267766
2019-05-11 02:33:51,305 [salt.minion      :1432][INFO    ][11751] Starting a new job with PID 11751
2019-05-11 02:33:51,334 [salt.minion      :1711][INFO    ][11751] Returning information for job: 20190511023351267766
2019-05-11 02:34:04,396 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1025.99846983s left)
2019-05-11 02:34:21,508 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511023421491452
2019-05-11 02:34:21,536 [salt.minion      :1432][INFO    ][11797] Starting a new job with PID 11797
2019-05-11 02:34:21,565 [salt.minion      :1711][INFO    ][11797] Returning information for job: 20190511023421491452
2019-05-11 02:34:37,471 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (992.923538923s left)
2019-05-11 02:34:51,714 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511023451701193
2019-05-11 02:34:51,740 [salt.minion      :1432][INFO    ][11841] Starting a new job with PID 11841
2019-05-11 02:34:51,770 [salt.minion      :1711][INFO    ][11841] Returning information for job: 20190511023451701193
2019-05-11 02:35:10,444 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (959.950279951s left)
2019-05-11 02:35:21,757 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511023521741603
2019-05-11 02:35:21,783 [salt.minion      :1432][INFO    ][11902] Starting a new job with PID 11902
2019-05-11 02:35:21,815 [salt.minion      :1711][INFO    ][11902] Returning information for job: 20190511023521741603
2019-05-11 02:35:43,258 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (927.136695862s left)
2019-05-11 02:35:51,816 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511023551799053
2019-05-11 02:35:51,844 [salt.minion      :1432][INFO    ][11936] Starting a new job with PID 11936
2019-05-11 02:35:51,876 [salt.minion      :1711][INFO    ][11936] Returning information for job: 20190511023551799053
2019-05-11 02:36:16,194 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (894.200944901s left)
2019-05-11 02:36:21,909 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511023621892309
2019-05-11 02:36:21,939 [salt.minion      :1432][INFO    ][11982] Starting a new job with PID 11982
2019-05-11 02:36:21,968 [salt.minion      :1711][INFO    ][11982] Returning information for job: 20190511023621892309
2019-05-11 02:36:49,104 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (861.290335894s left)
2019-05-11 02:36:51,969 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511023651953473
2019-05-11 02:36:52,000 [salt.minion      :1432][INFO    ][12013] Starting a new job with PID 12013
2019-05-11 02:36:52,032 [salt.minion      :1711][INFO    ][12013] Returning information for job: 20190511023651953473
2019-05-11 02:37:21,965 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (828.429913044s left)
2019-05-11 02:37:22,083 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511023722073324
2019-05-11 02:37:22,110 [salt.minion      :1432][INFO    ][12061] Starting a new job with PID 12061
2019-05-11 02:37:22,138 [salt.minion      :1711][INFO    ][12061] Returning information for job: 20190511023722073324
2019-05-11 02:37:52,188 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511023752180439
2019-05-11 02:37:52,211 [salt.minion      :1432][INFO    ][12087] Starting a new job with PID 12087
2019-05-11 02:37:52,239 [salt.minion      :1711][INFO    ][12087] Returning information for job: 20190511023752180439
2019-05-11 02:37:54,714 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (795.680949926s left)
2019-05-11 02:38:22,342 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511023822327267
2019-05-11 02:38:22,373 [salt.minion      :1432][INFO    ][12129] Starting a new job with PID 12129
2019-05-11 02:38:22,403 [salt.minion      :1711][INFO    ][12129] Returning information for job: 20190511023822327267
2019-05-11 02:38:27,524 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (762.870831966s left)
2019-05-11 02:38:52,484 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511023852473328
2019-05-11 02:38:52,508 [salt.minion      :1432][INFO    ][12160] Starting a new job with PID 12160
2019-05-11 02:38:52,537 [salt.minion      :1711][INFO    ][12160] Returning information for job: 20190511023852473328
2019-05-11 02:39:00,475 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (729.919740915s left)
2019-05-11 02:39:22,652 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511023922639065
2019-05-11 02:39:22,681 [salt.minion      :1432][INFO    ][12210] Starting a new job with PID 12210
2019-05-11 02:39:22,713 [salt.minion      :1711][INFO    ][12210] Returning information for job: 20190511023922639065
2019-05-11 02:39:33,371 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (697.023447037s left)
2019-05-11 02:39:52,683 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511023952666245
2019-05-11 02:39:52,712 [salt.minion      :1432][INFO    ][12239] Starting a new job with PID 12239
2019-05-11 02:39:52,740 [salt.minion      :1711][INFO    ][12239] Returning information for job: 20190511023952666245
2019-05-11 02:40:06,272 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (664.123102903s left)
2019-05-11 02:40:22,868 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511024022853515
2019-05-11 02:40:22,894 [salt.minion      :1432][INFO    ][12288] Starting a new job with PID 12288
2019-05-11 02:40:22,925 [salt.minion      :1711][INFO    ][12288] Returning information for job: 20190511024022853515
2019-05-11 02:40:39,118 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (631.276371002s left)
2019-05-11 02:40:52,911 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511024052895717
2019-05-11 02:40:52,938 [salt.minion      :1432][INFO    ][12317] Starting a new job with PID 12317
2019-05-11 02:40:52,969 [salt.minion      :1711][INFO    ][12317] Returning information for job: 20190511024052895717
2019-05-11 02:41:11,956 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (598.439156055s left)
2019-05-11 02:41:23,129 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511024123114604
2019-05-11 02:41:23,163 [salt.minion      :1432][INFO    ][12365] Starting a new job with PID 12365
2019-05-11 02:41:23,191 [salt.minion      :1711][INFO    ][12365] Returning information for job: 20190511024123114604
2019-05-11 02:41:44,691 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (565.703310966s left)
2019-05-11 02:41:53,216 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511024153200139
2019-05-11 02:41:53,244 [salt.minion      :1432][INFO    ][12397] Starting a new job with PID 12397
2019-05-11 02:41:53,272 [salt.minion      :1711][INFO    ][12397] Returning information for job: 20190511024153200139
2019-05-11 02:42:17,586 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (532.808660984s left)
2019-05-11 02:42:23,302 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511024223294471
2019-05-11 02:42:23,331 [salt.minion      :1432][INFO    ][12446] Starting a new job with PID 12446
2019-05-11 02:42:23,358 [salt.minion      :1711][INFO    ][12446] Returning information for job: 20190511024223294471
2019-05-11 02:42:50,347 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (500.048006058s left)
2019-05-11 02:42:53,441 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511024253428602
2019-05-11 02:42:53,470 [salt.minion      :1432][INFO    ][12478] Starting a new job with PID 12478
2019-05-11 02:42:53,495 [salt.minion      :1711][INFO    ][12478] Returning information for job: 20190511024253428602
2019-05-11 02:43:23,417 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (466.978093863s left)
2019-05-11 02:43:23,563 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511024323550753
2019-05-11 02:43:23,590 [salt.minion      :1432][INFO    ][12524] Starting a new job with PID 12524
2019-05-11 02:43:23,625 [salt.minion      :1711][INFO    ][12524] Returning information for job: 20190511024323550753
2019-05-11 02:43:53,752 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511024353744144
2019-05-11 02:43:53,772 [salt.minion      :1432][INFO    ][12552] Starting a new job with PID 12552
2019-05-11 02:43:53,796 [salt.minion      :1711][INFO    ][12552] Returning information for job: 20190511024353744144
2019-05-11 02:43:56,225 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (434.169545889s left)
2019-05-11 02:44:23,932 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511024423918257
2019-05-11 02:44:23,958 [salt.minion      :1432][INFO    ][12603] Starting a new job with PID 12603
2019-05-11 02:44:23,982 [salt.minion      :1711][INFO    ][12603] Returning information for job: 20190511024423918257
2019-05-11 02:44:29,236 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (401.158833027s left)
2019-05-11 02:44:54,149 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511024454136760
2019-05-11 02:44:54,177 [salt.minion      :1432][INFO    ][12772] Starting a new job with PID 12772
2019-05-11 02:44:54,205 [salt.minion      :1711][INFO    ][12772] Returning information for job: 20190511024454136760
2019-05-11 02:45:02,180 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (368.215126991s left)
2019-05-11 02:45:24,372 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511024524360188
2019-05-11 02:45:24,395 [salt.minion      :1432][INFO    ][12829] Starting a new job with PID 12829
2019-05-11 02:45:24,423 [salt.minion      :1711][INFO    ][12829] Returning information for job: 20190511024524360188
2019-05-11 02:45:34,969 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (335.425737858s left)
2019-05-11 02:45:54,595 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511024554579296
2019-05-11 02:45:54,625 [salt.minion      :1432][INFO    ][12862] Starting a new job with PID 12862
2019-05-11 02:45:54,656 [salt.minion      :1711][INFO    ][12862] Returning information for job: 20190511024554579296
2019-05-11 02:46:07,765 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (302.629675865s left)
2019-05-11 02:46:24,668 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511024624659111
2019-05-11 02:46:24,695 [salt.minion      :1432][INFO    ][12910] Starting a new job with PID 12910
2019-05-11 02:46:24,721 [salt.minion      :1711][INFO    ][12910] Returning information for job: 20190511024624659111
2019-05-11 02:46:40,786 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (269.608695984s left)
2019-05-11 02:46:54,708 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511024654692337
2019-05-11 02:46:54,739 [salt.minion      :1432][INFO    ][12939] Starting a new job with PID 12939
2019-05-11 02:46:54,764 [salt.minion      :1711][INFO    ][12939] Returning information for job: 20190511024654692337
2019-05-11 02:47:13,636 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (236.758553982s left)
2019-05-11 02:47:24,843 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511024724829152
2019-05-11 02:47:24,869 [salt.minion      :1432][INFO    ][12988] Starting a new job with PID 12988
2019-05-11 02:47:24,891 [salt.minion      :1711][INFO    ][12988] Returning information for job: 20190511024724829152
2019-05-11 02:47:46,396 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (203.998644829s left)
2019-05-11 02:47:54,904 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511024754890693
2019-05-11 02:47:54,932 [salt.minion      :1432][INFO    ][13017] Starting a new job with PID 13017
2019-05-11 02:47:54,956 [salt.minion      :1711][INFO    ][13017] Returning information for job: 20190511024754890693
2019-05-11 02:48:19,112 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (171.28231883s left)
2019-05-11 02:48:25,050 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511024825034853
2019-05-11 02:48:25,079 [salt.minion      :1432][INFO    ][13064] Starting a new job with PID 13064
2019-05-11 02:48:25,102 [salt.minion      :1711][INFO    ][13064] Returning information for job: 20190511024825034853
2019-05-11 02:48:51,943 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (138.451699018s left)
2019-05-11 02:48:55,165 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511024855152765
2019-05-11 02:48:55,192 [salt.minion      :1432][INFO    ][13096] Starting a new job with PID 13096
2019-05-11 02:48:55,211 [salt.minion      :1711][INFO    ][13096] Returning information for job: 20190511024855152765
2019-05-11 02:49:24,827 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (105.567292929s left)
2019-05-11 02:49:25,314 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511024925298604
2019-05-11 02:49:25,336 [salt.minion      :1432][INFO    ][13141] Starting a new job with PID 13141
2019-05-11 02:49:25,360 [salt.minion      :1711][INFO    ][13141] Returning information for job: 20190511024925298604
2019-05-11 02:49:55,519 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511024955505534
2019-05-11 02:49:55,545 [salt.minion      :1432][INFO    ][13166] Starting a new job with PID 13166
2019-05-11 02:49:55,567 [salt.minion      :1711][INFO    ][13166] Returning information for job: 20190511024955505534
2019-05-11 02:49:58,161 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (72.2337510586s left)
2019-05-11 02:50:25,705 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511025025694296
2019-05-11 02:50:25,736 [salt.minion      :1432][INFO    ][13214] Starting a new job with PID 13214
2019-05-11 02:50:25,758 [salt.minion      :1711][INFO    ][13214] Returning information for job: 20190511025025694296
2019-05-11 02:50:30,912 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (39.4823079109s left)
2019-05-11 02:50:55,877 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511025055863645
2019-05-11 02:50:55,896 [salt.minion      :1432][INFO    ][13245] Starting a new job with PID 13245
2019-05-11 02:50:55,922 [salt.minion      :1711][INFO    ][13245] Returning information for job: 20190511025055863645
2019-05-11 02:51:03,778 [salt.loaded.ext.module.maas:1023][INFO    ][7847] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (6.61664104462s left)
2019-05-11 02:51:26,081 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511025126067668
2019-05-11 02:51:26,104 [salt.minion      :1432][INFO    ][13291] Starting a new job with PID 13291
2019-05-11 02:51:26,131 [salt.minion      :1711][INFO    ][13291] Returning information for job: 20190511025126067668
2019-05-11 02:51:36,671 [salt.state       :302 ][ERROR   ][7847] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-05-11 02:51:36,672 [salt.state       :1951][INFO    ][7847] Completed state [maas.wait_for_machine_status] at time 02:51:36.672156 duration_in_ms=2276292.396
2019-05-11 02:51:36,680 [salt.minion      :1711][INFO    ][7847] Returning information for job: 20190511021330781763
2019-05-11 02:51:47,539 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command pillar.get with jid 20190511025147526170
2019-05-11 02:51:47,567 [salt.minion      :1432][INFO    ][13321] Starting a new job with PID 13321
2019-05-11 02:51:47,575 [salt.minion      :1711][INFO    ][13321] Returning information for job: 20190511025147526170
2019-05-11 02:51:48,148 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command service.status with jid 20190511025148131393
2019-05-11 02:51:48,180 [salt.minion      :1432][INFO    ][13326] Starting a new job with PID 13326
2019-05-11 02:51:48,726 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13326] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-05-11 02:51:48,767 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13326] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-05-11 02:51:48,789 [salt.minion      :1711][INFO    ][13326] Returning information for job: 20190511025148131393
2019-05-11 02:51:49,365 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command state.apply with jid 20190511025149349588
2019-05-11 02:51:49,393 [salt.minion      :1432][INFO    ][13337] Starting a new job with PID 13337
2019-05-11 02:51:56,609 [salt.state       :915 ][INFO    ][13337] Loading fresh modules for state activity
2019-05-11 02:51:57,261 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13337] Executing command 'salt-minion --version' in directory '/root'
2019-05-11 02:51:57,574 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13337] Executing command 'salt-minion --version' in directory '/root'
2019-05-11 02:51:58,534 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13337] Executing command 'salt-minion --version' in directory '/root'
2019-05-11 02:51:58,819 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13337] Executing command 'salt-minion --version' in directory '/root'
2019-05-11 02:52:00,555 [salt.state       :1780][INFO    ][13337] Running state [salt-minion] at time 02:52:00.555495
2019-05-11 02:52:00,555 [salt.state       :1813][INFO    ][13337] Executing state pkg.installed for [salt-minion]
2019-05-11 02:52:00,556 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13337] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-11 02:52:00,661 [salt.state       :300 ][INFO    ][13337] All specified packages are already installed
2019-05-11 02:52:00,663 [salt.state       :1951][INFO    ][13337] Completed state [salt-minion] at time 02:52:00.663020 duration_in_ms=107.526
2019-05-11 02:52:00,663 [salt.state       :1780][INFO    ][13337] Running state [salt_minion_dependency_packages] at time 02:52:00.663352
2019-05-11 02:52:00,663 [salt.state       :1813][INFO    ][13337] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-05-11 02:52:00,673 [salt.state       :300 ][INFO    ][13337] All specified packages are already installed
2019-05-11 02:52:00,673 [salt.state       :1951][INFO    ][13337] Completed state [salt_minion_dependency_packages] at time 02:52:00.673189 duration_in_ms=9.837
2019-05-11 02:52:00,678 [salt.state       :1780][INFO    ][13337] Running state [/etc/salt/minion.d/minion.conf] at time 02:52:00.678058
2019-05-11 02:52:00,678 [salt.state       :1813][INFO    ][13337] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-05-11 02:52:00,954 [salt.state       :300 ][INFO    ][13337] File /etc/salt/minion.d/minion.conf is in the correct state
2019-05-11 02:52:00,954 [salt.state       :1951][INFO    ][13337] Completed state [/etc/salt/minion.d/minion.conf] at time 02:52:00.954398 duration_in_ms=276.34
2019-05-11 02:52:00,954 [salt.state       :1780][INFO    ][13337] Running state [python-netaddr] at time 02:52:00.954651
2019-05-11 02:52:00,954 [salt.state       :1813][INFO    ][13337] Executing state pkg.installed for [python-netaddr]
2019-05-11 02:52:00,963 [salt.state       :300 ][INFO    ][13337] All specified packages are already installed
2019-05-11 02:52:00,963 [salt.state       :1951][INFO    ][13337] Completed state [python-netaddr] at time 02:52:00.963926 duration_in_ms=9.275
2019-05-11 02:52:00,967 [salt.state       :1780][INFO    ][13337] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:52:00.967219
2019-05-11 02:52:00,967 [salt.state       :1813][INFO    ][13337] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-05-11 02:52:00,979 [salt.state       :300 ][INFO    ][13337] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-05-11 02:52:00,980 [salt.state       :1951][INFO    ][13337] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:52:00.980020 duration_in_ms=12.801
2019-05-11 02:52:00,981 [salt.state       :1780][INFO    ][13337] Running state [salt-minion] at time 02:52:00.981169
2019-05-11 02:52:00,981 [salt.state       :1813][INFO    ][13337] Executing state service.running for [salt-minion]
2019-05-11 02:52:00,982 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13337] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-11 02:52:01,028 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13337] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-05-11 02:52:01,048 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13337] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-05-11 02:52:01,072 [salt.state       :300 ][INFO    ][13337] The service salt-minion is already running
2019-05-11 02:52:01,073 [salt.state       :1951][INFO    ][13337] Completed state [salt-minion] at time 02:52:01.073330 duration_in_ms=92.16
2019-05-11 02:52:01,075 [salt.state       :1780][INFO    ][13337] Running state [/etc/salt/grains.d] at time 02:52:01.075760
2019-05-11 02:52:01,076 [salt.state       :1813][INFO    ][13337] Executing state file.directory for [/etc/salt/grains.d]
2019-05-11 02:52:01,077 [salt.state       :300 ][INFO    ][13337] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-05-11 02:52:01,078 [salt.state       :1951][INFO    ][13337] Completed state [/etc/salt/grains.d] at time 02:52:01.077973 duration_in_ms=2.212
2019-05-11 02:52:01,079 [salt.state       :1780][INFO    ][13337] Running state [/etc/salt/grains] at time 02:52:01.078995
2019-05-11 02:52:01,079 [salt.state       :1813][INFO    ][13337] Executing state file.managed for [/etc/salt/grains]
2019-05-11 02:52:01,080 [salt.state       :300 ][INFO    ][13337] File /etc/salt/grains exists with proper permissions. No changes made.
2019-05-11 02:52:01,080 [salt.state       :1951][INFO    ][13337] Completed state [/etc/salt/grains] at time 02:52:01.080487 duration_in_ms=1.492
2019-05-11 02:52:01,081 [salt.state       :1780][INFO    ][13337] Running state [/etc/salt/grains.d/placeholder] at time 02:52:01.081212
2019-05-11 02:52:01,081 [salt.state       :1813][INFO    ][13337] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-05-11 02:52:01,084 [salt.state       :300 ][INFO    ][13337] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-05-11 02:52:01,084 [salt.state       :1951][INFO    ][13337] Completed state [/etc/salt/grains.d/placeholder] at time 02:52:01.084825 duration_in_ms=3.613
2019-05-11 02:52:01,085 [salt.state       :1780][INFO    ][13337] Running state [/etc/salt/grains.d/sphinx] at time 02:52:01.085293
2019-05-11 02:52:01,085 [salt.state       :1813][INFO    ][13337] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-05-11 02:52:01,086 [salt.state       :300 ][INFO    ][13337] File /etc/salt/grains.d/sphinx is in the correct state
2019-05-11 02:52:01,086 [salt.state       :1951][INFO    ][13337] Completed state [/etc/salt/grains.d/sphinx] at time 02:52:01.086850 duration_in_ms=1.556
2019-05-11 02:52:01,088 [salt.state       :1780][INFO    ][13337] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:52:01.088916
2019-05-11 02:52:01,089 [salt.state       :1813][INFO    ][13337] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-05-11 02:52:01,089 [salt.state       :300 ][INFO    ][13337] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-05-11 02:52:01,089 [salt.state       :1951][INFO    ][13337] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:52:01.089685 duration_in_ms=0.77
2019-05-11 02:52:01,090 [salt.state       :1780][INFO    ][13337] Running state [/etc/salt/grains.d/dns_records] at time 02:52:01.090170
2019-05-11 02:52:01,090 [salt.state       :1813][INFO    ][13337] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-05-11 02:52:01,091 [salt.state       :300 ][INFO    ][13337] File /etc/salt/grains.d/dns_records is in the correct state
2019-05-11 02:52:01,091 [salt.state       :1951][INFO    ][13337] Completed state [/etc/salt/grains.d/dns_records] at time 02:52:01.091534 duration_in_ms=1.364
2019-05-11 02:52:01,092 [salt.state       :1780][INFO    ][13337] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:52:01.092456
2019-05-11 02:52:01,092 [salt.state       :1813][INFO    ][13337] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-05-11 02:52:01,093 [salt.state       :300 ][INFO    ][13337] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-05-11 02:52:01,093 [salt.state       :1951][INFO    ][13337] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:52:01.093209 duration_in_ms=0.753
2019-05-11 02:52:01,095 [salt.state       :1780][INFO    ][13337] Running state [/etc/salt/grains.d/salt] at time 02:52:01.093679
2019-05-11 02:52:01,096 [salt.state       :1813][INFO    ][13337] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-05-11 02:52:01,097 [salt.state       :300 ][INFO    ][13337] File /etc/salt/grains.d/salt is in the correct state
2019-05-11 02:52:01,097 [salt.state       :1951][INFO    ][13337] Completed state [/etc/salt/grains.d/salt] at time 02:52:01.097224 duration_in_ms=3.545
2019-05-11 02:52:01,098 [salt.state       :1780][INFO    ][13337] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:52:01.098110
2019-05-11 02:52:01,098 [salt.state       :1813][INFO    ][13337] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-05-11 02:52:01,098 [salt.state       :300 ][INFO    ][13337] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-05-11 02:52:01,098 [salt.state       :1951][INFO    ][13337] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:52:01.098869 duration_in_ms=0.76
2019-05-11 02:52:01,100 [salt.state       :1780][INFO    ][13337] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:52:01.100771
2019-05-11 02:52:01,101 [salt.state       :1813][INFO    ][13337] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-05-11 02:52:01,101 [salt.state       :300 ][INFO    ][13337] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-05-11 02:52:01,101 [salt.state       :1951][INFO    ][13337] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:52:01.101526 duration_in_ms=0.754
2019-05-11 02:52:01,102 [salt.state       :1780][INFO    ][13337] Running state [mine.update] at time 02:52:01.102197
2019-05-11 02:52:01,102 [salt.state       :1813][INFO    ][13337] Executing state module.wait for [mine.update]
2019-05-11 02:52:01,102 [salt.state       :300 ][INFO    ][13337] No changes made for mine.update
2019-05-11 02:52:01,102 [salt.state       :1951][INFO    ][13337] Completed state [mine.update] at time 02:52:01.102887 duration_in_ms=0.69
2019-05-11 02:52:01,103 [salt.state       :1780][INFO    ][13337] Running state [ca-certificates] at time 02:52:01.103118
2019-05-11 02:52:01,103 [salt.state       :1813][INFO    ][13337] Executing state pkg.installed for [ca-certificates]
2019-05-11 02:52:01,114 [salt.state       :300 ][INFO    ][13337] All specified packages are already installed
2019-05-11 02:52:01,114 [salt.state       :1951][INFO    ][13337] Completed state [ca-certificates] at time 02:52:01.114491 duration_in_ms=11.373
2019-05-11 02:52:01,115 [salt.state       :1780][INFO    ][13337] Running state [update-ca-certificates] at time 02:52:01.115167
2019-05-11 02:52:01,115 [salt.state       :1813][INFO    ][13337] Executing state cmd.wait for [update-ca-certificates]
2019-05-11 02:52:01,115 [salt.state       :300 ][INFO    ][13337] No changes made for update-ca-certificates
2019-05-11 02:52:01,115 [salt.state       :1951][INFO    ][13337] Completed state [update-ca-certificates] at time 02:52:01.115875 duration_in_ms=0.708
2019-05-11 02:52:01,116 [salt.state       :1780][INFO    ][13337] Running state [iptables] at time 02:52:01.116106
2019-05-11 02:52:01,116 [salt.state       :1813][INFO    ][13337] Executing state pkg.installed for [iptables]
2019-05-11 02:52:01,126 [salt.state       :300 ][INFO    ][13337] All specified packages are already installed
2019-05-11 02:52:01,126 [salt.state       :1951][INFO    ][13337] Completed state [iptables] at time 02:52:01.126841 duration_in_ms=10.734
2019-05-11 02:52:01,127 [salt.state       :1780][INFO    ][13337] Running state [iptables-persistent] at time 02:52:01.127077
2019-05-11 02:52:01,127 [salt.state       :1813][INFO    ][13337] Executing state pkg.installed for [iptables-persistent]
2019-05-11 02:52:01,137 [salt.state       :300 ][INFO    ][13337] All specified packages are already installed
2019-05-11 02:52:01,137 [salt.state       :1951][INFO    ][13337] Completed state [iptables-persistent] at time 02:52:01.137560 duration_in_ms=10.482
2019-05-11 02:52:01,138 [salt.state       :1780][INFO    ][13337] Running state [iptables_modules_v4_load] at time 02:52:01.138731
2019-05-11 02:52:01,139 [salt.state       :1813][INFO    ][13337] Executing state kmod.present for [iptables_modules_v4_load]
2019-05-11 02:52:01,139 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13337] Executing command 'lsmod' in directory '/root'
2019-05-11 02:52:01,160 [salt.state       :300 ][INFO    ][13337] Kernel modules iptable_filter, ip_tables are already present
2019-05-11 02:52:01,161 [salt.state       :1951][INFO    ][13337] Completed state [iptables_modules_v4_load] at time 02:52:01.161364 duration_in_ms=22.632
2019-05-11 02:52:01,162 [salt.state       :1780][INFO    ][13337] Running state [/etc/iptables/rules.v4] at time 02:52:01.162560
2019-05-11 02:52:01,163 [salt.state       :1813][INFO    ][13337] Executing state file.managed for [/etc/iptables/rules.v4]
2019-05-11 02:52:01,259 [salt.state       :300 ][INFO    ][13337] File /etc/iptables/rules.v4 is in the correct state
2019-05-11 02:52:01,259 [salt.state       :1951][INFO    ][13337] Completed state [/etc/iptables/rules.v4] at time 02:52:01.259860 duration_in_ms=97.299
2019-05-11 02:52:01,261 [salt.state       :1780][INFO    ][13337] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:52:01.260966
2019-05-11 02:52:01,261 [salt.state       :1813][INFO    ][13337] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-05-11 02:52:01,262 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13337] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-05-11 02:52:01,282 [salt.state       :300 ][INFO    ][13337] onlyif execution failed
2019-05-11 02:52:01,283 [salt.state       :1951][INFO    ][13337] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:52:01.283256 duration_in_ms=22.29
2019-05-11 02:52:01,284 [salt.state       :1780][INFO    ][13337] Running state [netfilter-persistent] at time 02:52:01.284333
2019-05-11 02:52:01,284 [salt.state       :1813][INFO    ][13337] Executing state service.running for [netfilter-persistent]
2019-05-11 02:52:01,285 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13337] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-05-11 02:52:01,305 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13337] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-05-11 02:52:01,328 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13337] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-05-11 02:52:01,349 [salt.state       :300 ][INFO    ][13337] The service netfilter-persistent is already running
2019-05-11 02:52:01,350 [salt.state       :1951][INFO    ][13337] Completed state [netfilter-persistent] at time 02:52:01.350159 duration_in_ms=65.825
2019-05-11 02:52:01,351 [salt.state       :1780][INFO    ][13337] Running state [iptables_extra.remove_stale_tables] at time 02:52:01.351662
2019-05-11 02:52:01,352 [salt.state       :1813][INFO    ][13337] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-05-11 02:52:01,352 [salt.state       :300 ][INFO    ][13337] No changes made for iptables_extra.remove_stale_tables
2019-05-11 02:52:01,353 [salt.state       :1951][INFO    ][13337] Completed state [iptables_extra.remove_stale_tables] at time 02:52:01.353079 duration_in_ms=1.418
2019-05-11 02:52:01,353 [salt.state       :1780][INFO    ][13337] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:52:01.353497
2019-05-11 02:52:01,354 [salt.state       :1813][INFO    ][13337] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-05-11 02:52:01,355 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13337] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-05-11 02:52:01,375 [salt.state       :300 ][INFO    ][13337] onlyif execution failed
2019-05-11 02:52:01,376 [salt.state       :1951][INFO    ][13337] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:52:01.376066 duration_in_ms=22.568
2019-05-11 02:52:01,377 [salt.state       :1780][INFO    ][13337] Running state [/etc/iptables/rules.v6] at time 02:52:01.377622
2019-05-11 02:52:01,378 [salt.state       :1813][INFO    ][13337] Executing state file.absent for [/etc/iptables/rules.v6]
2019-05-11 02:52:01,378 [salt.state       :300 ][INFO    ][13337] File /etc/iptables/rules.v6 is not present
2019-05-11 02:52:01,379 [salt.state       :1951][INFO    ][13337] Completed state [/etc/iptables/rules.v6] at time 02:52:01.379165 duration_in_ms=1.543
2019-05-11 02:52:01,380 [salt.state       :1780][INFO    ][13337] Running state [iptables_extra.flush_all] at time 02:52:01.380316
2019-05-11 02:52:01,380 [salt.state       :1813][INFO    ][13337] Executing state module.wait for [iptables_extra.flush_all]
2019-05-11 02:52:01,381 [salt.state       :300 ][INFO    ][13337] No changes made for iptables_extra.flush_all
2019-05-11 02:52:01,381 [salt.state       :1951][INFO    ][13337] Completed state [iptables_extra.flush_all] at time 02:52:01.381509 duration_in_ms=1.192
2019-05-11 02:52:01,387 [salt.minion      :1711][INFO    ][13337] Returning information for job: 20190511025149349588
2019-05-11 02:52:01,941 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command state.apply with jid 20190511025201928542
2019-05-11 02:52:01,965 [salt.minion      :1432][INFO    ][13443] Starting a new job with PID 13443
2019-05-11 02:52:03,317 [salt.state       :915 ][INFO    ][13443] Loading fresh modules for state activity
2019-05-11 02:52:04,246 [salt.state       :1780][INFO    ][13443] Running state [maas-rack-controller] at time 02:52:04.246652
2019-05-11 02:52:04,247 [salt.state       :1813][INFO    ][13443] Executing state pkg.installed for [maas-rack-controller]
2019-05-11 02:52:04,247 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13443] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-11 02:52:04,367 [salt.state       :300 ][INFO    ][13443] All specified packages are already installed
2019-05-11 02:52:04,368 [salt.state       :1951][INFO    ][13443] Completed state [maas-rack-controller] at time 02:52:04.368512 duration_in_ms=121.859
2019-05-11 02:52:04,369 [salt.state       :1780][INFO    ][13443] Running state [ipmitool] at time 02:52:04.369265
2019-05-11 02:52:04,371 [salt.state       :1813][INFO    ][13443] Executing state pkg.installed for [ipmitool]
2019-05-11 02:52:04,379 [salt.state       :300 ][INFO    ][13443] All specified packages are already installed
2019-05-11 02:52:04,379 [salt.state       :1951][INFO    ][13443] Completed state [ipmitool] at time 02:52:04.379929 duration_in_ms=10.665
2019-05-11 02:52:04,385 [salt.state       :1780][INFO    ][13443] Running state [/etc/maas/rackd.conf] at time 02:52:04.385067
2019-05-11 02:52:04,385 [salt.state       :1813][INFO    ][13443] Executing state file.line for [/etc/maas/rackd.conf]
2019-05-11 02:52:04,386 [salt.state       :300 ][INFO    ][13443] No changes needed to be made
2019-05-11 02:52:04,387 [salt.state       :1951][INFO    ][13443] Completed state [/etc/maas/rackd.conf] at time 02:52:04.387013 duration_in_ms=1.946
2019-05-11 02:52:04,387 [salt.state       :1780][INFO    ][13443] Running state [/etc/maas/rackd.conf] at time 02:52:04.387249
2019-05-11 02:52:04,387 [salt.state       :1813][INFO    ][13443] Executing state file.managed for [/etc/maas/rackd.conf]
2019-05-11 02:52:04,387 [salt.loaded.int.states.file:2298][WARNING ][13443] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-05-11 02:52:04,388 [salt.state       :300 ][INFO    ][13443] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-05-11 02:52:04,388 [salt.state       :1951][INFO    ][13443] Completed state [/etc/maas/rackd.conf] at time 02:52:04.388738 duration_in_ms=1.489
2019-05-11 02:52:04,389 [salt.state       :1780][INFO    ][13443] Running state [maas-rackd] at time 02:52:04.389854
2019-05-11 02:52:04,390 [salt.state       :1813][INFO    ][13443] Executing state service.running for [maas-rackd]
2019-05-11 02:52:04,390 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13443] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-05-11 02:52:04,432 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13443] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-05-11 02:52:04,453 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13443] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-05-11 02:52:04,476 [salt.state       :300 ][INFO    ][13443] The service maas-rackd is already running
2019-05-11 02:52:04,477 [salt.state       :1951][INFO    ][13443] Completed state [maas-rackd] at time 02:52:04.477199 duration_in_ms=87.344
2019-05-11 02:52:04,480 [salt.minion      :1711][INFO    ][13443] Returning information for job: 20190511025201928542
2019-05-11 02:52:05,034 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command state.apply with jid 20190511025205022643
2019-05-11 02:52:05,061 [salt.minion      :1432][INFO    ][13466] Starting a new job with PID 13466
2019-05-11 02:52:06,126 [salt.state       :915 ][INFO    ][13466] Loading fresh modules for state activity
2019-05-11 02:52:07,189 [salt.state       :1780][INFO    ][13466] Running state [maas-region-controller] at time 02:52:07.188945
2019-05-11 02:52:07,189 [salt.state       :1813][INFO    ][13466] Executing state pkg.installed for [maas-region-controller]
2019-05-11 02:52:07,191 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13466] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-11 02:52:07,307 [salt.state       :300 ][INFO    ][13466] All specified packages are already installed
2019-05-11 02:52:07,308 [salt.state       :1951][INFO    ][13466] Completed state [maas-region-controller] at time 02:52:07.308201 duration_in_ms=119.256
2019-05-11 02:52:07,308 [salt.state       :1780][INFO    ][13466] Running state [python-oauth] at time 02:52:07.308519
2019-05-11 02:52:07,308 [salt.state       :1813][INFO    ][13466] Executing state pkg.installed for [python-oauth]
2019-05-11 02:52:07,318 [salt.state       :300 ][INFO    ][13466] All specified packages are already installed
2019-05-11 02:52:07,318 [salt.state       :1951][INFO    ][13466] Completed state [python-oauth] at time 02:52:07.318309 duration_in_ms=9.79
2019-05-11 02:52:07,321 [salt.state       :1780][INFO    ][13466] Running state [/etc/maas/regiond.conf] at time 02:52:07.321366
2019-05-11 02:52:07,321 [salt.state       :1813][INFO    ][13466] Executing state file.replace for [/etc/maas/regiond.conf]
2019-05-11 02:52:07,327 [salt.state       :300 ][INFO    ][13466] No changes needed to be made
2019-05-11 02:52:07,327 [salt.state       :1951][INFO    ][13466] Completed state [/etc/maas/regiond.conf] at time 02:52:07.327465 duration_in_ms=6.1
2019-05-11 02:52:07,327 [salt.state       :1780][INFO    ][13466] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:52:07.327943
2019-05-11 02:52:07,328 [salt.state       :1813][INFO    ][13466] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-05-11 02:52:07,385 [salt.state       :300 ][INFO    ][13466] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-05-11 02:52:07,385 [salt.state       :1951][INFO    ][13466] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:52:07.385378 duration_in_ms=57.435
2019-05-11 02:52:07,386 [salt.state       :1780][INFO    ][13466] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:52:07.386210
2019-05-11 02:52:07,386 [salt.state       :1813][INFO    ][13466] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-11 02:52:07,389 [salt.state       :300 ][INFO    ][13466] No changes needed to be made
2019-05-11 02:52:07,390 [salt.state       :1951][INFO    ][13466] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:52:07.390172 duration_in_ms=3.962
2019-05-11 02:52:07,390 [salt.state       :1780][INFO    ][13466] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:52:07.390658
2019-05-11 02:52:07,390 [salt.state       :1813][INFO    ][13466] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-11 02:52:07,395 [salt.state       :300 ][INFO    ][13466] No changes needed to be made
2019-05-11 02:52:07,395 [salt.state       :1951][INFO    ][13466] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:52:07.395421 duration_in_ms=4.763
2019-05-11 02:52:07,395 [salt.state       :1780][INFO    ][13466] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:52:07.395888
2019-05-11 02:52:07,396 [salt.state       :1813][INFO    ][13466] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-05-11 02:52:07,419 [salt.state       :300 ][INFO    ][13466] No changes needed to be made
2019-05-11 02:52:07,419 [salt.state       :1951][INFO    ][13466] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:52:07.419491 duration_in_ms=23.602
2019-05-11 02:52:07,420 [salt.state       :1780][INFO    ][13466] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:52:07.419969
2019-05-11 02:52:07,420 [salt.state       :1813][INFO    ][13466] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-05-11 02:52:07,437 [salt.state       :300 ][INFO    ][13466] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-05-11 02:52:07,438 [salt.state       :1951][INFO    ][13466] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:52:07.438011 duration_in_ms=18.041
2019-05-11 02:52:07,439 [salt.state       :1780][INFO    ][13466] Running state [a2enmod headers] at time 02:52:07.439543
2019-05-11 02:52:07,439 [salt.state       :1813][INFO    ][13466] Executing state cmd.run for [a2enmod headers]
2019-05-11 02:52:07,440 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13466] Executing command 'a2enmod headers' in directory '/root'
2019-05-11 02:52:07,506 [salt.state       :300 ][INFO    ][13466] {'pid': 13486, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-05-11 02:52:07,507 [salt.state       :1951][INFO    ][13466] Completed state [a2enmod headers] at time 02:52:07.507078 duration_in_ms=67.534
2019-05-11 02:52:07,507 [salt.state       :1780][INFO    ][13466] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:52:07.507740
2019-05-11 02:52:07,508 [salt.state       :1813][INFO    ][13466] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-05-11 02:52:07,536 [salt.state       :300 ][INFO    ][13466] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-05-11 02:52:07,536 [salt.state       :1951][INFO    ][13466] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:52:07.536724 duration_in_ms=28.984
2019-05-11 02:52:07,537 [salt.state       :1780][INFO    ][13466] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:52:07.537590
2019-05-11 02:52:07,538 [salt.state       :1813][INFO    ][13466] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-05-11 02:52:07,604 [salt.state       :300 ][INFO    ][13466] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-05-11 02:52:07,604 [salt.state       :1951][INFO    ][13466] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:52:07.604445 duration_in_ms=66.854
2019-05-11 02:52:07,605 [salt.state       :1780][INFO    ][13466] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:52:07.605392
2019-05-11 02:52:07,606 [salt.state       :1813][INFO    ][13466] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-05-11 02:52:07,675 [salt.state       :300 ][INFO    ][13466] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-05-11 02:52:07,675 [salt.state       :1951][INFO    ][13466] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:52:07.675629 duration_in_ms=70.238
2019-05-11 02:52:07,676 [salt.state       :1780][INFO    ][13466] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:52:07.676133
2019-05-11 02:52:07,676 [salt.state       :1813][INFO    ][13466] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-05-11 02:52:07,749 [salt.state       :300 ][INFO    ][13466] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-05-11 02:52:07,749 [salt.state       :1951][INFO    ][13466] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:52:07.749603 duration_in_ms=73.469
2019-05-11 02:52:07,750 [salt.state       :1780][INFO    ][13466] Running state [/root/.pgpass] at time 02:52:07.750051
2019-05-11 02:52:07,750 [salt.state       :1813][INFO    ][13466] Executing state file.managed for [/root/.pgpass]
2019-05-11 02:52:07,800 [salt.state       :300 ][INFO    ][13466] File /root/.pgpass is in the correct state
2019-05-11 02:52:07,800 [salt.state       :1951][INFO    ][13466] Completed state [/root/.pgpass] at time 02:52:07.800809 duration_in_ms=50.757
2019-05-11 02:52:07,806 [salt.state       :1780][INFO    ][13466] Running state [maas-region syncdb --noinput] at time 02:52:07.806771
2019-05-11 02:52:07,807 [salt.state       :1813][INFO    ][13466] Executing state cmd.run for [maas-region syncdb --noinput]
2019-05-11 02:52:07,807 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13466] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-05-11 02:52:10,258 [salt.state       :300 ][INFO    ][13466] {'pid': 13499, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: metadataserver, sites, auth, maasserver, contenttypes, piston3, sessions\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-05-11 02:52:10,259 [salt.state       :1951][INFO    ][13466] Completed state [maas-region syncdb --noinput] at time 02:52:10.259140 duration_in_ms=2452.367
2019-05-11 02:52:10,259 [salt.state       :2022][WARNING ][13466] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-11 02:52:10,264 [salt.state       :1780][INFO    ][13466] Running state [maas-regiond] at time 02:52:10.264396
2019-05-11 02:52:10,264 [salt.state       :1813][INFO    ][13466] Executing state service.running for [maas-regiond]
2019-05-11 02:52:10,265 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13466] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-05-11 02:52:10,312 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13466] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-05-11 02:52:10,337 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13466] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-05-11 02:52:10,360 [salt.state       :300 ][INFO    ][13466] The service maas-regiond is already running
2019-05-11 02:52:10,360 [salt.state       :1951][INFO    ][13466] Completed state [maas-regiond] at time 02:52:10.360720 duration_in_ms=96.323
2019-05-11 02:52:10,365 [salt.state       :1780][INFO    ][13466] Running state [bind9] at time 02:52:10.365375
2019-05-11 02:52:10,365 [salt.state       :1813][INFO    ][13466] Executing state service.running for [bind9]
2019-05-11 02:52:10,367 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13466] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-05-11 02:52:10,392 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13466] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-05-11 02:52:10,415 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13466] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-05-11 02:52:10,440 [salt.state       :300 ][INFO    ][13466] The service bind9 is already running
2019-05-11 02:52:10,441 [salt.state       :1951][INFO    ][13466] Completed state [bind9] at time 02:52:10.441022 duration_in_ms=75.647
2019-05-11 02:52:10,446 [salt.state       :1780][INFO    ][13466] Running state [apache2] at time 02:52:10.446422
2019-05-11 02:52:10,446 [salt.state       :1813][INFO    ][13466] Executing state service.running for [apache2]
2019-05-11 02:52:10,447 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13466] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-05-11 02:52:10,471 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13466] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-05-11 02:52:10,487 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13466] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-05-11 02:52:10,512 [salt.state       :300 ][INFO    ][13466] The service apache2 is already running
2019-05-11 02:52:10,513 [salt.state       :1951][INFO    ][13466] Completed state [apache2] at time 02:52:10.513442 duration_in_ms=67.019
2019-05-11 02:52:10,518 [salt.state       :1780][INFO    ][13466] Running state [maasng.wait_for_http_code] at time 02:52:10.518288
2019-05-11 02:52:10,518 [salt.state       :1813][INFO    ][13466] Executing state module.run for [maasng.wait_for_http_code]
2019-05-11 02:52:10,519 [salt.utils.decorators:613 ][WARNING ][13466] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-11 02:52:10,743 [salt.state       :300 ][INFO    ][13466] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-05-11 02:52:10,744 [salt.state       :1951][INFO    ][13466] Completed state [maasng.wait_for_http_code] at time 02:52:10.744043 duration_in_ms=225.756
2019-05-11 02:52:10,745 [salt.state       :1780][INFO    ][13466] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:52:10.744962
2019-05-11 02:52:10,745 [salt.state       :1813][INFO    ][13466] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-05-11 02:52:10,746 [salt.state       :300 ][INFO    ][13466] /var/lib/maas/.setup_admin exists
2019-05-11 02:52:10,746 [salt.state       :1951][INFO    ][13466] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:52:10.746813 duration_in_ms=1.851
2019-05-11 02:52:10,747 [salt.state       :1780][INFO    ][13466] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:52:10.747477
2019-05-11 02:52:10,747 [salt.state       :1813][INFO    ][13466] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-11 02:52:10,748 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13466] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-11 02:52:12,439 [salt.state       :300 ][INFO    ][13466] {'pid': 13526, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-11 02:52:12,440 [salt.state       :1951][INFO    ][13466] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:52:12.440595 duration_in_ms=1693.116
2019-05-11 02:52:12,452 [salt.state       :1780][INFO    ][13466] Running state [maas_region_boot_source_resources_mirror] at time 02:52:12.452150
2019-05-11 02:52:12,452 [salt.state       :1813][INFO    ][13466] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-05-11 02:52:12,550 [salt.state       :300 ][INFO    ][13466] {'changes': {}}
2019-05-11 02:52:12,550 [salt.state       :1951][INFO    ][13466] Completed state [maas_region_boot_source_resources_mirror] at time 02:52:12.550727 duration_in_ms=98.576
2019-05-11 02:52:12,552 [salt.state       :1780][INFO    ][13466] Running state [maasng.boot_resources_import] at time 02:52:12.551981
2019-05-11 02:52:12,552 [salt.state       :1813][INFO    ][13466] Executing state module.run for [maasng.boot_resources_import]
2019-05-11 02:52:12,553 [salt.utils.decorators:613 ][WARNING ][13466] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-11 02:52:12,657 [salt.loaded.ext.module.maasng:1600][INFO    ][13466] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-05-11 02:52:17,710 [salt.loaded.ext.module.maasng:1600][INFO    ][13466] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-05-11 02:52:20,082 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511025220065155
2019-05-11 02:52:20,107 [salt.minion      :1432][INFO    ][13561] Starting a new job with PID 13561
2019-05-11 02:52:20,135 [salt.minion      :1711][INFO    ][13561] Returning information for job: 20190511025220065155
2019-05-11 02:52:22,808 [salt.state       :300 ][INFO    ][13466] {'ret': True}
2019-05-11 02:52:22,808 [salt.state       :1951][INFO    ][13466] Completed state [maasng.boot_resources_import] at time 02:52:22.808574 duration_in_ms=10256.592
2019-05-11 02:52:22,810 [salt.state       :1780][INFO    ][13466] Running state [maas_region_boot_sources_selection_xenial] at time 02:52:22.810524
2019-05-11 02:52:22,811 [salt.state       :1813][INFO    ][13466] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-05-11 02:52:23,002 [salt.state       :300 ][INFO    ][13466] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-05-11 02:52:23,002 [salt.state       :1951][INFO    ][13466] Completed state [maas_region_boot_sources_selection_xenial] at time 02:52:23.002851 duration_in_ms=192.325
2019-05-11 02:52:23,004 [salt.state       :1780][INFO    ][13466] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 02:52:23.004423
2019-05-11 02:52:23,005 [salt.state       :1813][INFO    ][13466] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-05-11 02:52:23,007 [salt.utils.decorators:613 ][WARNING ][13466] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-11 02:52:23,007 [salt.loaded.ext.module.maasng:1771][INFO    ][13466] boot-sources sync initiated for ALL Rack's
2019-05-11 02:52:24,071 [salt.state       :300 ][INFO    ][13466] {'ret': True}
2019-05-11 02:52:24,071 [salt.state       :1951][INFO    ][13466] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 02:52:24.071529 duration_in_ms=1067.105
2019-05-11 02:52:24,073 [salt.state       :1780][INFO    ][13466] Running state [maas.process_maas_config] at time 02:52:24.073292
2019-05-11 02:52:24,075 [salt.state       :1813][INFO    ][13466] Executing state module.run for [maas.process_maas_config]
2019-05-11 02:52:24,075 [salt.utils.decorators:613 ][WARNING ][13466] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-11 02:52:24,076 [salt.loaded.ext.module.maas:92  ][INFO    ][13466] maasconfig name=enable_http_proxy value=True
2019-05-11 02:52:24,128 [salt.loaded.ext.module.maas:92  ][INFO    ][13466] maasconfig name=upstream_dns value=8.8.8.8
2019-05-11 02:52:24,186 [salt.loaded.ext.module.maas:92  ][INFO    ][13466] maasconfig name=commissioning_distro_series value=xenial
2019-05-11 02:52:24,252 [salt.loaded.ext.module.maas:92  ][INFO    ][13466] maasconfig name=default_osystem value=ubuntu
2019-05-11 02:52:24,315 [salt.loaded.ext.module.maas:92  ][INFO    ][13466] maasconfig name=active_discovery_interval value=600
2019-05-11 02:52:24,376 [salt.loaded.ext.module.maas:92  ][INFO    ][13466] maasconfig name=dnssec_validation value=no
2019-05-11 02:52:24,428 [salt.loaded.ext.module.maas:92  ][INFO    ][13466] maasconfig name=maas_name value=mas01
2019-05-11 02:52:24,480 [salt.loaded.ext.module.maas:92  ][INFO    ][13466] maasconfig name=network_discovery value=enabled
2019-05-11 02:52:24,576 [salt.loaded.ext.module.maas:92  ][INFO    ][13466] maasconfig name=enable_third_party_drivers value=True
2019-05-11 02:52:25,806 [salt.loaded.ext.module.maas:92  ][INFO    ][13466] maasconfig name=default_storage_layout value=lvm
2019-05-11 02:52:25,861 [salt.loaded.ext.module.maas:92  ][INFO    ][13466] maasconfig name=ntp_external_only value=True
2019-05-11 02:52:25,913 [salt.loaded.ext.module.maas:92  ][INFO    ][13466] maasconfig name=disk_erase_with_secure_erase value=False
2019-05-11 02:52:25,965 [salt.loaded.ext.module.maas:92  ][INFO    ][13466] maasconfig name=default_distro_series value=xenial
2019-05-11 02:52:26,021 [salt.loaded.ext.module.maas:92  ][INFO    ][13466] maasconfig name=default_min_hwe_kernel value=ga-16.04
2019-05-11 02:52:26,143 [salt.state       :300 ][INFO    ][13466] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-05-11 02:52:26,144 [salt.state       :1951][INFO    ][13466] Completed state [maas.process_maas_config] at time 02:52:26.144085 duration_in_ms=2070.793
2019-05-11 02:52:26,144 [salt.state       :1780][INFO    ][13466] Running state [pxe_admin] at time 02:52:26.144710
2019-05-11 02:52:26,145 [salt.state       :1813][INFO    ][13466] Executing state maasng.fabric_present for [pxe_admin]
2019-05-11 02:52:26,204 [salt.loaded.ext.module.maasng:945 ][INFO    ][13466] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'name': u'fabric-1', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'4677cy', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'name': u'pxe_admin', u'class_type': u''}]
2019-05-11 02:52:26,287 [salt.loaded.ext.module.maasng:1008][WARNING ][13466] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-05-11 02:52:26,287 [salt.loaded.ext.module.maasng:1011][WARNING ][13466] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-05-11 02:52:26,351 [salt.state       :300 ][INFO    ][13466] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-05-11 02:52:26,351 [salt.state       :1951][INFO    ][13466] Completed state [pxe_admin] at time 02:52:26.351819 duration_in_ms=207.108
2019-05-11 02:52:26,352 [salt.state       :1780][INFO    ][13466] Running state [vlan 0] at time 02:52:26.352184
2019-05-11 02:52:26,352 [salt.state       :1813][INFO    ][13466] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-11 02:52:26,407 [salt.loaded.ext.module.maasng:945 ][INFO    ][13466] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'4677cy', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'class_type': u''}]
2019-05-11 02:52:26,515 [salt.loaded.ext.module.maasng:945 ][INFO    ][13466] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'4677cy', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-11 02:52:26,791 [salt.loaded.ext.module.maasng:945 ][INFO    ][13466] [{u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'name': u'fabric-0', u'id': 0}, {u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'name': u'fabric-1', u'id': 1}, {u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'4677cy', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'name': u'pxe_admin', u'id': 2}]
2019-05-11 02:52:26,871 [salt.state       :300 ][INFO    ][13466] {'new': 'Vlan untagged was updated'}
2019-05-11 02:52:26,871 [salt.state       :1951][INFO    ][13466] Completed state [vlan 0] at time 02:52:26.871579 duration_in_ms=519.394
2019-05-11 02:52:26,873 [salt.state       :1780][INFO    ][13466] Running state [192.168.11.0/24] at time 02:52:26.873083
2019-05-11 02:52:26,873 [salt.state       :1813][INFO    ][13466] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-05-11 02:52:27,066 [salt.loaded.ext.module.maasng:945 ][INFO    ][13466] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'4677cy', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-11 02:52:27,067 [salt.loaded.ext.module.maasng:1235][WARNING ][13466] Ignoring parameter vlan:0
2019-05-11 02:52:27,144 [salt.state       :300 ][INFO    ][13466] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-05-11 02:52:27,144 [salt.state       :1951][INFO    ][13466] Completed state [192.168.11.0/24] at time 02:52:27.144740 duration_in_ms=271.656
2019-05-11 02:52:27,147 [salt.state       :1780][INFO    ][13466] Running state [maas_create_iprange_1] at time 02:52:27.147553
2019-05-11 02:52:27,148 [salt.state       :1813][INFO    ][13466] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-05-11 02:52:27,193 [salt.state       :300 ][INFO    ][13466] Iprange maas_create_iprange_1 already exist.
2019-05-11 02:52:27,195 [salt.state       :1951][INFO    ][13466] Completed state [maas_create_iprange_1] at time 02:52:27.195052 duration_in_ms=47.499
2019-05-11 02:52:27,195 [salt.state       :1780][INFO    ][13466] Running state [vlan 0] at time 02:52:27.195468
2019-05-11 02:52:27,195 [salt.state       :1813][INFO    ][13466] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-11 02:52:27,250 [salt.loaded.ext.module.maasng:945 ][INFO    ][13466] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'4677cy', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'class_type': u''}]
2019-05-11 02:52:27,368 [salt.loaded.ext.module.maasng:945 ][INFO    ][13466] [{u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'name': u'fabric-0', u'id': 0}, {u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'name': u'fabric-1', u'id': 1}, {u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'4677cy', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'name': u'pxe_admin', u'id': 2}]
2019-05-11 02:52:27,610 [salt.loaded.ext.module.maasng:945 ][INFO    ][13466] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'4677cy', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'class_type': u''}]
2019-05-11 02:52:27,726 [salt.state       :300 ][INFO    ][13466] {'new': 'Vlan untagged was updated'}
2019-05-11 02:52:27,727 [salt.state       :1951][INFO    ][13466] Completed state [vlan 0] at time 02:52:27.727247 duration_in_ms=531.778
2019-05-11 02:52:27,728 [salt.state       :1780][INFO    ][13466] Running state [opnfv] at time 02:52:27.728066
2019-05-11 02:52:27,728 [salt.state       :1813][INFO    ][13466] Executing state maasng.sshkey_present for [opnfv]
2019-05-11 02:52:27,779 [salt.loaded.ext.module.maasng:1903][INFO    ][13466] [{u'keysource': u'', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b', u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/'}]
2019-05-11 02:52:27,780 [salt.state       :300 ][INFO    ][13466] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-05-11 02:52:27,780 [salt.state       :1951][INFO    ][13466] Completed state [opnfv] at time 02:52:27.780353 duration_in_ms=52.287
2019-05-11 02:52:27,785 [salt.minion      :1711][INFO    ][13466] Returning information for job: 20190511025205022643
2019-05-11 02:52:28,402 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command state.apply with jid 20190511025228388487
2019-05-11 02:52:28,425 [salt.minion      :1432][INFO    ][13911] Starting a new job with PID 13911
2019-05-11 02:52:35,775 [salt.state       :915 ][INFO    ][13911] Loading fresh modules for state activity
2019-05-11 02:52:35,892 [salt.state       :1780][INFO    ][13911] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:52:35.892755
2019-05-11 02:52:35,893 [salt.state       :1813][INFO    ][13911] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-11 02:52:35,895 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13911] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-11 02:52:37,789 [salt.state       :300 ][INFO    ][13911] {'pid': 13936, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-11 02:52:37,792 [salt.state       :1951][INFO    ][13911] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:52:37.791922 duration_in_ms=1899.166
2019-05-11 02:52:37,795 [salt.state       :1780][INFO    ][13911] Running state [maas.process_machines] at time 02:52:37.795310
2019-05-11 02:52:37,795 [salt.state       :1813][INFO    ][13911] Executing state module.run for [maas.process_machines]
2019-05-11 02:52:37,797 [salt.utils.decorators:613 ][WARNING ][13911] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-11 02:52:38,369 [salt.loaded.ext.module.maas:412 ][WARNING ][13911] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-11 02:52:38,369 [salt.loaded.ext.module.maas:92  ][INFO    ][13911] 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=qahfrb architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-11 02:52:39,612 [salt.loaded.ext.module.maas:412 ][WARNING ][13911] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-11 02:52:39,612 [salt.loaded.ext.module.maas:92  ][INFO    ][13911] 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=cexsc8 architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-11 02:52:40,902 [salt.loaded.ext.module.maas:412 ][WARNING ][13911] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-11 02:52:40,903 [salt.loaded.ext.module.maas:92  ][INFO    ][13911] 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=sxkcb7 architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-11 02:52:42,143 [salt.loaded.ext.module.maas:412 ][WARNING ][13911] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-11 02:52:42,144 [salt.loaded.ext.module.maas:92  ][INFO    ][13911] 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=4heerr architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-11 02:52:43,383 [salt.loaded.ext.module.maas:412 ][WARNING ][13911] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-11 02:52:43,384 [salt.loaded.ext.module.maas:92  ][INFO    ][13911] 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=ekacmg architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-11 02:52:43,529 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511025243515243
2019-05-11 02:52:43,560 [salt.minion      :1432][INFO    ][14128] Starting a new job with PID 14128
2019-05-11 02:52:43,595 [salt.minion      :1711][INFO    ][14128] Returning information for job: 20190511025243515243
2019-05-11 02:52:44,607 [salt.state       :300 ][INFO    ][13911] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-05-11 02:52:44,608 [salt.state       :1951][INFO    ][13911] Completed state [maas.process_machines] at time 02:52:44.608426 duration_in_ms=6813.115
2019-05-11 02:52:44,613 [salt.minion      :1711][INFO    ][13911] Returning information for job: 20190511025228388487
2019-05-11 02:53:17,634 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command state.apply with jid 20190511025317616813
2019-05-11 02:53:17,662 [salt.minion      :1432][INFO    ][14212] Starting a new job with PID 14212
2019-05-11 02:53:25,128 [salt.state       :915 ][INFO    ][14212] Loading fresh modules for state activity
2019-05-11 02:53:25,231 [salt.state       :1780][INFO    ][14212] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:25.231356
2019-05-11 02:53:25,231 [salt.state       :1813][INFO    ][14212] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-11 02:53:25,233 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14212] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-11 02:53:27,132 [salt.state       :300 ][INFO    ][14212] {'pid': 14219, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-11 02:53:27,135 [salt.state       :1951][INFO    ][14212] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:27.133609 duration_in_ms=1902.253
2019-05-11 02:53:27,139 [salt.state       :1780][INFO    ][14212] Running state [maas.wait_for_machine_status] at time 02:53:27.139085
2019-05-11 02:53:27,139 [salt.state       :1813][INFO    ][14212] Executing state module.run for [maas.wait_for_machine_status]
2019-05-11 02:53:27,140 [salt.utils.decorators:613 ][WARNING ][14212] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-11 02:53:30,023 [salt.state       :300 ][INFO    ][14212] {'ret': True}
2019-05-11 02:53:30,024 [salt.state       :1951][INFO    ][14212] Completed state [maas.wait_for_machine_status] at time 02:53:30.024445 duration_in_ms=2885.357
2019-05-11 02:53:30,031 [salt.minion      :1711][INFO    ][14212] Returning information for job: 20190511025317616813
2019-05-11 02:53:30,601 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command state.apply with jid 20190511025330586085
2019-05-11 02:53:30,629 [salt.minion      :1432][INFO    ][14238] Starting a new job with PID 14238
2019-05-11 02:53:31,772 [salt.state       :915 ][INFO    ][14238] Loading fresh modules for state activity
2019-05-11 02:53:31,924 [salt.state       :1780][INFO    ][14238] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:31.924752
2019-05-11 02:53:31,925 [salt.state       :1813][INFO    ][14238] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-11 02:53:31,927 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14238] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-11 02:53:33,775 [salt.state       :300 ][INFO    ][14238] {'pid': 14245, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-11 02:53:33,776 [salt.state       :1951][INFO    ][14238] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:33.776690 duration_in_ms=1851.937
2019-05-11 02:53:33,780 [salt.state       :1780][INFO    ][14238] Running state [maas_machines_storage_cmp002_lvm] at time 02:53:33.780734
2019-05-11 02:53:33,781 [salt.state       :1813][INFO    ][14238] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-11 02:53:34,338 [salt.state       :300 ][INFO    ][14238] Machine cmp002 is not in Ready state.
2019-05-11 02:53:34,339 [salt.state       :1951][INFO    ][14238] Completed state [maas_machines_storage_cmp002_lvm] at time 02:53:34.339147 duration_in_ms=558.412
2019-05-11 02:53:34,339 [salt.state       :1780][INFO    ][14238] Running state [maas_machines_storage_cmp001_lvm] at time 02:53:34.339865
2019-05-11 02:53:34,340 [salt.state       :1813][INFO    ][14238] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-11 02:53:34,872 [salt.state       :300 ][INFO    ][14238] Machine cmp001 is not in Ready state.
2019-05-11 02:53:34,872 [salt.state       :1951][INFO    ][14238] Completed state [maas_machines_storage_cmp001_lvm] at time 02:53:34.872866 duration_in_ms=533.001
2019-05-11 02:53:34,879 [salt.minion      :1711][INFO    ][14238] Returning information for job: 20190511025330586085
2019-05-11 02:53:35,447 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command state.apply with jid 20190511025335431794
2019-05-11 02:53:35,476 [salt.minion      :1432][INFO    ][14255] Starting a new job with PID 14255
2019-05-11 02:53:36,541 [salt.state       :915 ][INFO    ][14255] Loading fresh modules for state activity
2019-05-11 02:53:36,648 [salt.state       :1780][INFO    ][14255] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:36.648499
2019-05-11 02:53:36,648 [salt.state       :1813][INFO    ][14255] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-11 02:53:36,650 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14255] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-11 02:53:38,500 [salt.state       :300 ][INFO    ][14255] {'pid': 14262, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-11 02:53:38,502 [salt.state       :1951][INFO    ][14255] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:38.502192 duration_in_ms=1853.693
2019-05-11 02:53:38,505 [salt.state       :1780][INFO    ][14255] Running state [maas.deploy_machines] at time 02:53:38.505003
2019-05-11 02:53:38,505 [salt.state       :1813][INFO    ][14255] Executing state module.run for [maas.deploy_machines]
2019-05-11 02:53:38,506 [salt.utils.decorators:613 ][WARNING ][14255] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-11 02:53:39,080 [salt.loaded.ext.module.maas:684 ][INFO    ][14255] deploymachines hwe_kernel=ga-16.04 system_id=ekacmg distro_series=xenial
2019-05-11 02:53:41,599 [salt.state       :300 ][INFO    ][14255] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-05-11 02:53:41,599 [salt.state       :1951][INFO    ][14255] Completed state [maas.deploy_machines] at time 02:53:41.599894 duration_in_ms=3094.89
2019-05-11 02:53:41,603 [salt.minion      :1711][INFO    ][14255] Returning information for job: 20190511025335431794
2019-05-11 02:53:42,179 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command state.apply with jid 20190511025342168182
2019-05-11 02:53:42,207 [salt.minion      :1432][INFO    ][14333] Starting a new job with PID 14333
2019-05-11 02:53:49,420 [salt.state       :915 ][INFO    ][14333] Loading fresh modules for state activity
2019-05-11 02:53:49,531 [salt.state       :1780][INFO    ][14333] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:49.531521
2019-05-11 02:53:49,531 [salt.state       :1813][INFO    ][14333] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-11 02:53:49,533 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14333] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-11 02:53:51,233 [salt.state       :300 ][INFO    ][14333] {'pid': 14347, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-11 02:53:51,235 [salt.state       :1951][INFO    ][14333] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:51.235201 duration_in_ms=1703.68
2019-05-11 02:53:51,239 [salt.state       :1780][INFO    ][14333] Running state [maas.wait_for_machine_status] at time 02:53:51.239269
2019-05-11 02:53:51,240 [salt.state       :1813][INFO    ][14333] Executing state module.run for [maas.wait_for_machine_status]
2019-05-11 02:53:51,240 [salt.utils.decorators:613 ][WARNING ][14333] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-11 02:53:54,119 [salt.loaded.ext.module.maas:1023][INFO    ][14333] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.14386606s left)
2019-05-11 02:53:57,284 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511025357271379
2019-05-11 02:53:57,308 [salt.minion      :1432][INFO    ][14386] Starting a new job with PID 14386
2019-05-11 02:53:57,334 [salt.minion      :1711][INFO    ][14386] Returning information for job: 20190511025357271379
2019-05-11 02:54:26,920 [salt.loaded.ext.module.maas:1023][INFO    ][14333] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.34247589s left)
2019-05-11 02:54:27,571 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511025427498835
2019-05-11 02:54:27,594 [salt.minion      :1432][INFO    ][14408] Starting a new job with PID 14408
2019-05-11 02:54:27,617 [salt.minion      :1711][INFO    ][14408] Returning information for job: 20190511025427498835
2019-05-11 02:54:57,677 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511025457662935
2019-05-11 02:54:57,702 [salt.minion      :1432][INFO    ][14474] Starting a new job with PID 14474
2019-05-11 02:54:57,730 [salt.minion      :1711][INFO    ][14474] Returning information for job: 20190511025457662935
2019-05-11 02:54:59,888 [salt.loaded.ext.module.maas:1023][INFO    ][14333] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2181.37441707s left)
2019-05-11 02:55:27,752 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511025527737122
2019-05-11 02:55:27,783 [salt.minion      :1432][INFO    ][14501] Starting a new job with PID 14501
2019-05-11 02:55:27,809 [salt.minion      :1711][INFO    ][14501] Returning information for job: 20190511025527737122
2019-05-11 02:55:32,760 [salt.loaded.ext.module.maas:1023][INFO    ][14333] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2148.50283885s left)
2019-05-11 02:55:57,859 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511025557844873
2019-05-11 02:55:57,885 [salt.minion      :1432][INFO    ][14556] Starting a new job with PID 14556
2019-05-11 02:55:57,909 [salt.minion      :1711][INFO    ][14556] Returning information for job: 20190511025557844873
2019-05-11 02:56:05,627 [salt.loaded.ext.module.maas:1023][INFO    ][14333] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2115.63567185s left)
2019-05-11 02:56:27,937 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511025627923880
2019-05-11 02:56:27,967 [salt.minion      :1432][INFO    ][14578] Starting a new job with PID 14578
2019-05-11 02:56:27,990 [salt.minion      :1711][INFO    ][14578] Returning information for job: 20190511025627923880
2019-05-11 02:56:38,421 [salt.loaded.ext.module.maas:1023][INFO    ][14333] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2082.84148407s left)
2019-05-11 02:56:58,027 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511025658013431
2019-05-11 02:56:58,056 [salt.minion      :1432][INFO    ][14635] Starting a new job with PID 14635
2019-05-11 02:56:58,085 [salt.minion      :1711][INFO    ][14635] Returning information for job: 20190511025658013431
2019-05-11 02:57:11,347 [salt.loaded.ext.module.maas:1023][INFO    ][14333] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2049.91609693s left)
2019-05-11 02:57:28,133 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511025728119338
2019-05-11 02:57:28,153 [salt.minion      :1432][INFO    ][14657] Starting a new job with PID 14657
2019-05-11 02:57:28,180 [salt.minion      :1711][INFO    ][14657] Returning information for job: 20190511025728119338
2019-05-11 02:57:44,093 [salt.loaded.ext.module.maas:1023][INFO    ][14333] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2017.16979694s left)
2019-05-11 02:57:58,229 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511025758216943
2019-05-11 02:57:58,252 [salt.minion      :1432][INFO    ][14724] Starting a new job with PID 14724
2019-05-11 02:57:58,276 [salt.minion      :1711][INFO    ][14724] Returning information for job: 20190511025758216943
2019-05-11 02:58:17,063 [salt.loaded.ext.module.maas:1023][INFO    ][14333] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1984.20005894s left)
2019-05-11 02:58:28,333 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511025828319029
2019-05-11 02:58:28,358 [salt.minion      :1432][INFO    ][14768] Starting a new job with PID 14768
2019-05-11 02:58:28,379 [salt.minion      :1711][INFO    ][14768] Returning information for job: 20190511025828319029
2019-05-11 02:58:50,044 [salt.loaded.ext.module.maas:1023][INFO    ][14333] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1951.21886086s left)
2019-05-11 02:58:58,469 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511025858457063
2019-05-11 02:58:58,496 [salt.minion      :1432][INFO    ][14942] Starting a new job with PID 14942
2019-05-11 02:58:58,524 [salt.minion      :1711][INFO    ][14942] Returning information for job: 20190511025858457063
2019-05-11 02:59:22,989 [salt.loaded.ext.module.maas:1023][INFO    ][14333] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1918.27359605s left)
2019-05-11 02:59:28,605 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511025928589686
2019-05-11 02:59:28,638 [salt.minion      :1432][INFO    ][14961] Starting a new job with PID 14961
2019-05-11 02:59:28,663 [salt.minion      :1711][INFO    ][14961] Returning information for job: 20190511025928589686
2019-05-11 02:59:55,986 [salt.loaded.ext.module.maas:1023][INFO    ][14333] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1885.27645588s left)
2019-05-11 02:59:58,749 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511025958736638
2019-05-11 02:59:58,776 [salt.minion      :1432][INFO    ][15062] Starting a new job with PID 15062
2019-05-11 02:59:58,805 [salt.minion      :1711][INFO    ][15062] Returning information for job: 20190511025958736638
2019-05-11 03:00:28,889 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511030028875459
2019-05-11 03:00:28,914 [salt.loaded.ext.module.maas:1023][INFO    ][14333] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1852.34887195s left)
2019-05-11 03:00:28,919 [salt.minion      :1432][INFO    ][15082] Starting a new job with PID 15082
2019-05-11 03:00:28,949 [salt.minion      :1711][INFO    ][15082] Returning information for job: 20190511030028875459
2019-05-11 03:00:59,053 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511030059037342
2019-05-11 03:00:59,076 [salt.minion      :1432][INFO    ][15219] Starting a new job with PID 15219
2019-05-11 03:00:59,106 [salt.minion      :1711][INFO    ][15219] Returning information for job: 20190511030059037342
2019-05-11 03:01:01,784 [salt.loaded.ext.module.maas:1023][INFO    ][14333] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1819.47847009s left)
2019-05-11 03:01:29,205 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511030129195482
2019-05-11 03:01:29,233 [salt.minion      :1432][INFO    ][15241] Starting a new job with PID 15241
2019-05-11 03:01:29,267 [salt.minion      :1711][INFO    ][15241] Returning information for job: 20190511030129195482
2019-05-11 03:01:34,913 [salt.loaded.ext.module.maas:1023][INFO    ][14333] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1786.34995508s left)
2019-05-11 03:01:59,382 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511030159368312
2019-05-11 03:01:59,415 [salt.minion      :1432][INFO    ][15382] Starting a new job with PID 15382
2019-05-11 03:01:59,445 [salt.minion      :1711][INFO    ][15382] Returning information for job: 20190511030159368312
2019-05-11 03:02:07,842 [salt.loaded.ext.module.maas:1023][INFO    ][14333] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1753.42090893s left)
2019-05-11 03:02:29,556 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511030229542082
2019-05-11 03:02:29,586 [salt.minion      :1432][INFO    ][15408] Starting a new job with PID 15408
2019-05-11 03:02:29,612 [salt.minion      :1711][INFO    ][15408] Returning information for job: 20190511030229542082
2019-05-11 03:02:40,760 [salt.loaded.ext.module.maas:1023][INFO    ][14333] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1720.50280905s left)
2019-05-11 03:02:59,731 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511030259718489
2019-05-11 03:02:59,755 [salt.minion      :1432][INFO    ][15495] Starting a new job with PID 15495
2019-05-11 03:02:59,778 [salt.minion      :1711][INFO    ][15495] Returning information for job: 20190511030259718489
2019-05-11 03:03:13,699 [salt.loaded.ext.module.maas:1023][INFO    ][14333] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1687.56385303s left)
2019-05-11 03:03:29,898 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511030329883006
2019-05-11 03:03:29,927 [salt.minion      :1432][INFO    ][15518] Starting a new job with PID 15518
2019-05-11 03:03:29,950 [salt.minion      :1711][INFO    ][15518] Returning information for job: 20190511030329883006
2019-05-11 03:03:46,688 [salt.loaded.ext.module.maas:1023][INFO    ][14333] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1654.57430696s left)
2019-05-11 03:04:00,080 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511030400065438
2019-05-11 03:04:00,112 [salt.minion      :1432][INFO    ][15574] Starting a new job with PID 15574
2019-05-11 03:04:00,138 [salt.minion      :1711][INFO    ][15574] Returning information for job: 20190511030400065438
2019-05-11 03:04:19,690 [salt.loaded.ext.module.maas:1023][INFO    ][14333] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1621.57221508s left)
2019-05-11 03:04:30,259 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511030430247209
2019-05-11 03:04:30,284 [salt.minion      :1432][INFO    ][15594] Starting a new job with PID 15594
2019-05-11 03:04:30,317 [salt.minion      :1711][INFO    ][15594] Returning information for job: 20190511030430247209
2019-05-11 03:04:52,650 [salt.loaded.ext.module.maas:1023][INFO    ][14333] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1588.61292601s left)
2019-05-11 03:05:00,449 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511030500440107
2019-05-11 03:05:00,474 [salt.minion      :1432][INFO    ][15672] Starting a new job with PID 15672
2019-05-11 03:05:00,496 [salt.minion      :1711][INFO    ][15672] Returning information for job: 20190511030500440107
2019-05-11 03:05:25,494 [salt.loaded.ext.module.maas:1023][INFO    ][14333] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1555.76934505s left)
2019-05-11 03:05:30,632 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511030530622729
2019-05-11 03:05:30,663 [salt.minion      :1432][INFO    ][15836] Starting a new job with PID 15836
2019-05-11 03:05:30,691 [salt.minion      :1711][INFO    ][15836] Returning information for job: 20190511030530622729
2019-05-11 03:05:58,738 [salt.loaded.ext.module.maas:1023][INFO    ][14333] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1522.5254879s left)
2019-05-11 03:06:00,856 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511030600839594
2019-05-11 03:06:00,886 [salt.minion      :1432][INFO    ][15891] Starting a new job with PID 15891
2019-05-11 03:06:00,918 [salt.minion      :1711][INFO    ][15891] Returning information for job: 20190511030600839594
2019-05-11 03:06:07,056 [salt.utils.schedule:1377][INFO    ][5668] Running scheduled job: __mine_interval
2019-05-11 03:06:31,056 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511030631047513
2019-05-11 03:06:31,086 [salt.minion      :1432][INFO    ][15946] Starting a new job with PID 15946
2019-05-11 03:06:31,119 [salt.minion      :1711][INFO    ][15946] Returning information for job: 20190511030631047513
2019-05-11 03:06:31,650 [salt.loaded.ext.module.maas:1023][INFO    ][14333] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1489.61226797s left)
2019-05-11 03:07:01,240 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command saltutil.find_job with jid 20190511030701225416
2019-05-11 03:07:01,272 [salt.minion      :1432][INFO    ][16097] Starting a new job with PID 16097
2019-05-11 03:07:01,301 [salt.minion      :1711][INFO    ][16097] Returning information for job: 20190511030701225416
2019-05-11 03:07:04,808 [salt.state       :300 ][INFO    ][14333] {'ret': True}
2019-05-11 03:07:04,808 [salt.state       :1951][INFO    ][14333] Completed state [maas.wait_for_machine_status] at time 03:07:04.808613 duration_in_ms=793569.338
2019-05-11 03:07:04,816 [salt.minion      :1711][INFO    ][14333] Returning information for job: 20190511025342168182
2019-05-11 04:06:07,056 [salt.utils.schedule:1377][INFO    ][5668] Running scheduled job: __mine_interval
2019-05-11 04:41:53,632 [salt.minion      :1308][INFO    ][5668] User sudo_ubuntu Executing command cp.push_dir with jid 20190511044153619366
2019-05-11 04:41:53,652 [salt.minion      :1432][INFO    ][22206] Starting a new job with PID 22206
