2019-07-09 05:04:20,604 [salt.utils.decorators:613 ][WARNING ][2331] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-09 05:04:21,524 [salt.utils.decorators:613 ][WARNING ][2331] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-09 05:04:25,243 [salt.loaded.int.states.file:2298][WARNING ][2544] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-07-09 05:04:47,890 [salt.state       :2022][WARNING ][2805] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-07-09 05:04:51,178 [salt.utils.decorators:613 ][WARNING ][2805] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-09 05:05:04,391 [salt.utils.decorators:613 ][WARNING ][2805] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-09 05:05:31,340 [salt.utils.decorators:613 ][WARNING ][2805] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-09 05:05:32,320 [salt.utils.decorators:613 ][WARNING ][2805] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-09 05:05:34,522 [salt.loaded.ext.module.maasng:1008][WARNING ][2805] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-07-09 05:05:34,522 [salt.loaded.ext.module.maasng:1011][WARNING ][2805] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-07-09 05:05:35,299 [salt.loaded.ext.module.maasng:1235][WARNING ][2805] Ignoring parameter vlan:0
2019-07-09 05:05:36,263 [salt.utils.decorators:613 ][WARNING ][2805] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-09 05:05:39,587 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5255] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-07-09 05:05:39,629 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5255] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-07-09 05:05:39,670 [salt.utils.parsers:1051][WARNING ][380] Minion received a SIGTERM. Exiting.
2019-07-09 05:05:40,778 [salt.cli.daemons :293 ][INFO    ][5328] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-07-09 05:05:40,952 [salt.cli.daemons :82  ][INFO    ][5328] Starting up the Salt Minion
2019-07-09 05:05:40,953 [salt.utils.event :1017][INFO    ][5328] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-07-09 05:05:42,223 [salt.minion      :976 ][INFO    ][5328] Creating minion process manager
2019-07-09 05:05:44,267 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][5328] Executing command ['date', '+%z'] in directory '/root'
2019-07-09 05:05:44,289 [salt.utils.schedule:568 ][INFO    ][5328] Updating job settings for scheduled job: __mine_interval
2019-07-09 05:05:44,290 [salt.minion      :1108][INFO    ][5328] Added mine.update to scheduler
2019-07-09 05:05:44,297 [salt.minion      :1975][INFO    ][5328] Minion is starting as user 'root'
2019-07-09 05:05:44,316 [salt.minion      :2336][INFO    ][5328] Minion is ready to receive requests!
2019-07-09 05:05:48,018 [salt.utils.decorators:613 ][WARNING ][5266] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-09 05:05:48,088 [salt.loaded.ext.module.maas:412 ][WARNING ][5266] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-09 05:05:49,633 [salt.loaded.ext.module.maas:412 ][WARNING ][5266] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-09 05:05:51,115 [salt.loaded.ext.module.maas:412 ][WARNING ][5266] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-09 05:05:52,255 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709050552241344
2019-07-09 05:05:52,279 [salt.minion      :1432][INFO    ][5588] Starting a new job with PID 5588
2019-07-09 05:05:52,308 [salt.minion      :1711][INFO    ][5588] Returning information for job: 20190709050552241344
2019-07-09 05:05:52,693 [salt.loaded.ext.module.maas:412 ][WARNING ][5266] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-09 05:05:54,180 [salt.loaded.ext.module.maas:412 ][WARNING ][5266] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-09 05:06:27,068 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command state.apply with jid 20190709050627053487
2019-07-09 05:06:27,093 [salt.minion      :1432][INFO    ][5744] Starting a new job with PID 5744
2019-07-09 05:06:35,286 [salt.state       :915 ][INFO    ][5744] Loading fresh modules for state activity
2019-07-09 05:06:35,346 [salt.fileclient  :1219][INFO    ][5744] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-07-09 05:06:35,409 [salt.state       :1780][INFO    ][5744] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:06:35.409085
2019-07-09 05:06:35,409 [salt.state       :1813][INFO    ][5744] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-09 05:06:35,412 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5744] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-09 05:06:37,268 [salt.state       :300 ][INFO    ][5744] {'pid': 5762, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-09 05:06:37,270 [salt.state       :1951][INFO    ][5744] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:06:37.269579 duration_in_ms=1860.494
2019-07-09 05:06:37,275 [salt.state       :1780][INFO    ][5744] Running state [maas.wait_for_machine_status] at time 05:06:37.275735
2019-07-09 05:06:37,276 [salt.state       :1813][INFO    ][5744] Executing state module.run for [maas.wait_for_machine_status]
2019-07-09 05:06:37,276 [salt.utils.decorators:613 ][WARNING ][5744] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-09 05:06:38,099 [salt.loaded.ext.module.maas:1023][INFO    ][5744] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.18930101s left)
2019-07-09 05:06:42,148 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709050642132701
2019-07-09 05:06:42,176 [salt.minion      :1432][INFO    ][5774] Starting a new job with PID 5774
2019-07-09 05:06:42,208 [salt.minion      :1711][INFO    ][5774] Returning information for job: 20190709050642132701
2019-07-09 05:07:08,947 [salt.loaded.ext.module.maas:1023][INFO    ][5744] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.34113503s left)
2019-07-09 05:07:12,252 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709050712236498
2019-07-09 05:07:12,283 [salt.minion      :1432][INFO    ][5817] Starting a new job with PID 5817
2019-07-09 05:07:12,313 [salt.minion      :1711][INFO    ][5817] Returning information for job: 20190709050712236498
2019-07-09 05:07:39,911 [salt.loaded.ext.module.maas:1023][INFO    ][5744] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.37800193s left)
2019-07-09 05:07:42,383 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709050742374175
2019-07-09 05:07:42,411 [salt.minion      :1432][INFO    ][5846] Starting a new job with PID 5846
2019-07-09 05:07:42,441 [salt.minion      :1711][INFO    ][5846] Returning information for job: 20190709050742374175
2019-07-09 05:08:10,788 [salt.loaded.ext.module.maas:1023][INFO    ][5744] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.50012183s left)
2019-07-09 05:08:12,480 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709050812461636
2019-07-09 05:08:12,509 [salt.minion      :1432][INFO    ][5893] Starting a new job with PID 5893
2019-07-09 05:08:12,536 [salt.minion      :1711][INFO    ][5893] Returning information for job: 20190709050812461636
2019-07-09 05:08:41,860 [salt.loaded.ext.module.maas:1023][INFO    ][5744] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.42862296s left)
2019-07-09 05:08:42,575 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709050842556465
2019-07-09 05:08:42,605 [salt.minion      :1432][INFO    ][5953] Starting a new job with PID 5953
2019-07-09 05:08:42,632 [salt.minion      :1711][INFO    ][5953] Returning information for job: 20190709050842556465
2019-07-09 05:09:12,685 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709050912667610
2019-07-09 05:09:12,706 [salt.minion      :1432][INFO    ][6091] Starting a new job with PID 6091
2019-07-09 05:09:12,732 [salt.minion      :1711][INFO    ][6091] Returning information for job: 20190709050912667610
2019-07-09 05:09:13,016 [salt.loaded.ext.module.maas:1023][INFO    ][5744] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.27208686s left)
2019-07-09 05:09:42,778 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709050942765237
2019-07-09 05:09:42,807 [salt.minion      :1432][INFO    ][6164] Starting a new job with PID 6164
2019-07-09 05:09:42,834 [salt.minion      :1711][INFO    ][6164] Returning information for job: 20190709050942765237
2019-07-09 05:09:44,201 [salt.loaded.ext.module.maas:1023][INFO    ][5744] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1313.08756804s left)
2019-07-09 05:10:12,905 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709051012891437
2019-07-09 05:10:12,924 [salt.minion      :1432][INFO    ][6511] Starting a new job with PID 6511
2019-07-09 05:10:12,952 [salt.minion      :1711][INFO    ][6511] Returning information for job: 20190709051012891437
2019-07-09 05:10:15,480 [salt.loaded.ext.module.maas:1023][INFO    ][5744] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1281.80821204s left)
2019-07-09 05:10:43,006 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709051042995001
2019-07-09 05:10:43,027 [salt.minion      :1432][INFO    ][6617] Starting a new job with PID 6617
2019-07-09 05:10:43,057 [salt.minion      :1711][INFO    ][6617] Returning information for job: 20190709051042995001
2019-07-09 05:10:47,420 [salt.loaded.ext.module.maas:1023][INFO    ][5744] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1249.86865783s left)
2019-07-09 05:11:13,186 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709051113175187
2019-07-09 05:11:13,208 [salt.minion      :1432][INFO    ][6959] Starting a new job with PID 6959
2019-07-09 05:11:13,235 [salt.minion      :1711][INFO    ][6959] Returning information for job: 20190709051113175187
2019-07-09 05:11:19,544 [salt.loaded.ext.module.maas:1023][INFO    ][5744] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1217.74451303s left)
2019-07-09 05:11:43,317 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709051143305382
2019-07-09 05:11:43,344 [salt.minion      :1432][INFO    ][7115] Starting a new job with PID 7115
2019-07-09 05:11:43,375 [salt.minion      :1711][INFO    ][7115] Returning information for job: 20190709051143305382
2019-07-09 05:11:52,209 [salt.loaded.ext.module.maas:1023][INFO    ][5744] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1185.07950282s left)
2019-07-09 05:12:13,463 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709051213447939
2019-07-09 05:12:13,490 [salt.minion      :1432][INFO    ][7289] Starting a new job with PID 7289
2019-07-09 05:12:13,524 [salt.minion      :1711][INFO    ][7289] Returning information for job: 20190709051213447939
2019-07-09 05:12:24,700 [salt.loaded.ext.module.maas:1023][INFO    ][5744] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1152.58878684s left)
2019-07-09 05:12:43,619 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709051243602165
2019-07-09 05:12:43,650 [salt.minion      :1432][INFO    ][7428] Starting a new job with PID 7428
2019-07-09 05:12:43,680 [salt.minion      :1711][INFO    ][7428] Returning information for job: 20190709051243602165
2019-07-09 05:12:57,778 [salt.state       :300 ][INFO    ][5744] {'ret': True}
2019-07-09 05:12:57,779 [salt.state       :1951][INFO    ][5744] Completed state [maas.wait_for_machine_status] at time 05:12:57.779212 duration_in_ms=380503.475
2019-07-09 05:12:57,785 [salt.minion      :1711][INFO    ][5744] Returning information for job: 20190709050627053487
2019-07-09 05:12:58,494 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command state.apply with jid 20190709051258484661
2019-07-09 05:12:58,513 [salt.minion      :1432][INFO    ][7500] Starting a new job with PID 7500
2019-07-09 05:13:06,721 [salt.state       :915 ][INFO    ][7500] Loading fresh modules for state activity
2019-07-09 05:13:06,800 [salt.fileclient  :1219][INFO    ][7500] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-07-09 05:13:06,912 [salt.state       :1780][INFO    ][7500] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:13:06.912259
2019-07-09 05:13:06,912 [salt.state       :1813][INFO    ][7500] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-09 05:13:06,915 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7500] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-09 05:13:08,754 [salt.state       :300 ][INFO    ][7500] {'pid': 7510, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-09 05:13:08,756 [salt.state       :1951][INFO    ][7500] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:13:08.755928 duration_in_ms=1843.668
2019-07-09 05:13:08,760 [salt.state       :1780][INFO    ][7500] Running state [maas_machines_storage_cmp002_lvm] at time 05:13:08.760196
2019-07-09 05:13:08,761 [salt.state       :1813][INFO    ][7500] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-07-09 05:13:10,113 [salt.loaded.ext.module.maasng:610 ][INFO    ][7500] tgrmf6
2019-07-09 05:13:10,114 [salt.loaded.ext.module.maasng:626 ][INFO    ][7500] sda
2019-07-09 05:13:10,713 [salt.loaded.ext.module.maasng:361 ][INFO    ][7500] tgrmf6
2019-07-09 05:13:10,812 [salt.loaded.ext.module.maasng:367 ][INFO    ][7500] [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 1, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'tgrmf6', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'888e7d2d-cca9-4963-9d62-df188c36dbe2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'tgrmf6', u'device_id': 1, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'03d48009-635d-40d9-abca-a116cfcac2ce', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/tgrmf6/blockdevices/1/partition/1'}], u'type': u'physical', u'resource_uri': u'/MAAS/api/2.0/nodes/tgrmf6/blockdevices/1/', u'size': 800109715456}, {u'model': None, u'block_size': 4096, u'available_size': 0, u'uuid': u'935acc74-27e1-4183-9102-a58d92691da3', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'id': 3, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'tgrmf6', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'5d183b6f-c71d-497b-92a0-a06aec8a7213', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'partitions': [], u'type': u'virtual', u'resource_uri': u'/MAAS/api/2.0/nodes/tgrmf6/blockdevices/3/', u'size': 800097042432}]
2019-07-09 05:13:10,812 [salt.loaded.ext.module.maasng:632 ][INFO    ][7500] vgroot
2019-07-09 05:13:10,813 [salt.loaded.ext.module.maasng:635 ][INFO    ][7500] lvroot
2019-07-09 05:13:10,813 [salt.loaded.ext.module.maasng:639 ][INFO    ][7500] 107374182400
2019-07-09 05:13:11,435 [salt.loaded.ext.module.maasng:645 ][INFO    ][7500] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'cpu_count': 40, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'min_hwe_kernel': u'hwe-16.04', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'commissioning_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'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'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'tgrmf6', 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'physicalblockdevice_set': [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 1, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'tgrmf6', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'06df724a-6e44-4510-9b37-baabbc84c2b9', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'tgrmf6', u'device_id': 1, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'fccfcdd4-1392-4269-b60b-d2a64b476398', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/tgrmf6/blockdevices/1/partition/6'}], u'type': u'physical', u'resource_uri': u'/MAAS/api/2.0/nodes/tgrmf6/blockdevices/1/', u'size': 800109715456}], u'blockdevice_set': [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 1, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'tgrmf6', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'06df724a-6e44-4510-9b37-baabbc84c2b9', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'tgrmf6', u'device_id': 1, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'fccfcdd4-1392-4269-b60b-d2a64b476398', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/tgrmf6/blockdevices/1/partition/6'}], u'type': u'physical', u'resource_uri': u'/MAAS/api/2.0/nodes/tgrmf6/blockdevices/1/', u'size': 800109715456}, {u'model': None, u'block_size': 4096, u'available_size': 0, u'uuid': u'42e53443-ab2b-4eff-9840-0a33bcd67004', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'id': 11, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'tgrmf6', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'bf933c6e-2f34-481c-8b4b-d4555581d161', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'partitions': [], u'type': u'virtual', u'resource_uri': u'/MAAS/api/2.0/nodes/tgrmf6/blockdevices/11/', u'size': 107374182400}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'tgrmf6', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'node_type_name': u'Machine', u'current_testing_result_id': 3, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'disable_ipv4': False, u'virtualblockdevice_set': [{u'model': None, u'block_size': 4096, u'available_size': 0, u'uuid': u'42e53443-ab2b-4eff-9840-0a33bcd67004', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'id': 11, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'tgrmf6', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'bf933c6e-2f34-481c-8b4b-d4555581d161', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'partitions': [], u'type': u'virtual', u'resource_uri': u'/MAAS/api/2.0/nodes/tgrmf6/blockdevices/11/', u'size': 107374182400}], 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'name': u'untagged', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'b7wtg6', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'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'name': u'untagged', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'b7wtg6', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 18}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:10:18', u'enabled': True, u'parents': [], 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'name': u'untagged', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'b7wtg6', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'system_id': u'tgrmf6', u'effective_mtu': 1500, u'id': 4, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/tgrmf6/interfaces/4/'}, u'boot_disk': {u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 1, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'tgrmf6', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'06df724a-6e44-4510-9b37-baabbc84c2b9', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'tgrmf6', u'device_id': 1, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'fccfcdd4-1392-4269-b60b-d2a64b476398', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/tgrmf6/blockdevices/1/partition/6'}], u'type': u'physical', u'resource_uri': u'/MAAS/api/2.0/nodes/tgrmf6/blockdevices/1/', u'size': 800109715456}, u'interface_set': [{u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'b7wtg6', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'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'name': u'untagged', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'b7wtg6', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 18}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:10:18', u'enabled': True, u'parents': [], 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'name': u'untagged', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'b7wtg6', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'system_id': u'tgrmf6', u'effective_mtu': 1500, u'id': 4, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/tgrmf6/interfaces/4/'}, {u'vlan': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:07:51', u'enabled': True, u'parents': [], u'discovered': None, u'system_id': u'tgrmf6', u'effective_mtu': 1500, u'id': 15, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/tgrmf6/interfaces/15/'}, {u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'b7wtg6', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'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'name': u'untagged', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'b7wtg6', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'mode': u'link_up', u'id': 19}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:10:1c', u'enabled': True, u'parents': [], 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'name': u'untagged', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'b7wtg6', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.40'}], u'system_id': u'tgrmf6', u'effective_mtu': 1500, u'id': 13, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/tgrmf6/interfaces/13/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 20}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:12:49', u'enabled': True, u'parents': [], u'discovered': None, u'system_id': u'tgrmf6', u'effective_mtu': 1500, u'id': 14, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/tgrmf6/interfaces/14/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 21}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:12:48', u'enabled': True, u'parents': [], u'discovered': None, u'system_id': u'tgrmf6', u'effective_mtu': 1500, u'id': 11, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/tgrmf6/interfaces/11/'}, {u'vlan': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:07:50', u'enabled': True, u'parents': [], u'discovered': None, u'system_id': u'tgrmf6', u'effective_mtu': 1500, u'id': 12, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/tgrmf6/interfaces/12/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/tgrmf6/'}
2019-07-09 05:13:11,437 [salt.state       :300 ][INFO    ][7500] {'new': {'storage_layout': 'lvm'}}
2019-07-09 05:13:11,437 [salt.state       :1951][INFO    ][7500] Completed state [maas_machines_storage_cmp002_lvm] at time 05:13:11.437535 duration_in_ms=2677.338
2019-07-09 05:13:11,440 [salt.state       :1780][INFO    ][7500] Running state [maas_machines_storage_cmp001_lvm] at time 05:13:11.440045
2019-07-09 05:13:11,440 [salt.state       :1813][INFO    ][7500] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-07-09 05:13:12,597 [salt.loaded.ext.module.maasng:610 ][INFO    ][7500] rp4b4m
2019-07-09 05:13:12,598 [salt.loaded.ext.module.maasng:626 ][INFO    ][7500] sda
2019-07-09 05:13:13,176 [salt.loaded.ext.module.maasng:361 ][INFO    ][7500] rp4b4m
2019-07-09 05:13:13,274 [salt.loaded.ext.module.maasng:367 ][INFO    ][7500] [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'rp4b4m', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/rp4b4m/blockdevices/2/', u'type': u'physical', u'id': 2, u'partitions': [{u'uuid': u'c9a040bb-627b-4380-a399-7f27723c899b', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'rp4b4m', u'device_id': 2, u'filesystem': {u'mount_options': None, u'uuid': u'3564579a-1ebe-4386-af2c-0b178530be07', u'fstype': u'lvm-pv', u'mount_point': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/rp4b4m/blockdevices/2/partition/2', u'type': u'partition', u'id': 2, u'size': 800101236736}]}, {u'size': 800097042432, u'model': None, u'block_size': 4096, u'available_size': 0, u'uuid': u'4029fc4e-2172-43c8-8694-e2f9d510505c', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'rp4b4m', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'02be89d2-ab23-4b13-8320-a011c7d0e304', u'fstype': u'ext4', u'mount_point': u'/', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/rp4b4m/blockdevices/4/', u'type': u'virtual', u'id': 4, u'partitions': []}]
2019-07-09 05:13:13,275 [salt.loaded.ext.module.maasng:632 ][INFO    ][7500] vgroot
2019-07-09 05:13:13,275 [salt.loaded.ext.module.maasng:635 ][INFO    ][7500] lvroot
2019-07-09 05:13:13,276 [salt.loaded.ext.module.maasng:639 ][INFO    ][7500] 107374182400
2019-07-09 05:13:13,576 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709051313565695
2019-07-09 05:13:13,596 [salt.minion      :1432][INFO    ][7526] Starting a new job with PID 7526
2019-07-09 05:13:13,625 [salt.minion      :1711][INFO    ][7526] Returning information for job: 20190709051313565695
2019-07-09 05:13:13,891 [salt.loaded.ext.module.maasng:645 ][INFO    ][7500] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.39', u'192.168.11.44'], u'cpu_count': 40, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'min_hwe_kernel': u'hwe-16.04', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'commissioning_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'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'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'rp4b4m', 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'physicalblockdevice_set': [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 2, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'rp4b4m', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'b20b33a4-cfb9-4c20-bff5-1b765df7b2de', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'rp4b4m', u'device_id': 2, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'a885c879-c8ed-43cb-80d3-179f0432e74f', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/rp4b4m/blockdevices/2/partition/7'}], u'type': u'physical', u'resource_uri': u'/MAAS/api/2.0/nodes/rp4b4m/blockdevices/2/', u'size': 800109715456}], u'blockdevice_set': [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 2, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'rp4b4m', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'b20b33a4-cfb9-4c20-bff5-1b765df7b2de', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'rp4b4m', u'device_id': 2, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'a885c879-c8ed-43cb-80d3-179f0432e74f', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/rp4b4m/blockdevices/2/partition/7'}], u'type': u'physical', u'resource_uri': u'/MAAS/api/2.0/nodes/rp4b4m/blockdevices/2/', u'size': 800109715456}, {u'model': None, u'block_size': 4096, u'available_size': 0, u'uuid': u'94df8f41-1972-4387-b75c-5379ed23d846', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'id': 12, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'rp4b4m', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'fdd2340a-ec7e-4e34-9749-8d6524679a80', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'partitions': [], u'type': u'virtual', u'resource_uri': u'/MAAS/api/2.0/nodes/rp4b4m/blockdevices/12/', u'size': 107374182400}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'rp4b4m', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'node_type_name': u'Machine', u'current_testing_result_id': 5, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'disable_ipv4': False, u'virtualblockdevice_set': [{u'model': None, u'block_size': 4096, u'available_size': 0, u'uuid': u'94df8f41-1972-4387-b75c-5379ed23d846', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'id': 12, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'rp4b4m', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'fdd2340a-ec7e-4e34-9749-8d6524679a80', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'partitions': [], u'type': u'virtual', u'resource_uri': u'/MAAS/api/2.0/nodes/rp4b4m/blockdevices/12/', u'size': 107374182400}], 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'name': u'untagged', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'b7wtg6', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'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'name': u'untagged', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'b7wtg6', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 24}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:95:a0', u'enabled': True, u'parents': [], 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'name': u'untagged', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'b7wtg6', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'system_id': u'rp4b4m', u'effective_mtu': 1500, u'id': 5, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rp4b4m/interfaces/5/'}, u'boot_disk': {u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 2, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'rp4b4m', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'b20b33a4-cfb9-4c20-bff5-1b765df7b2de', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'rp4b4m', u'device_id': 2, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'a885c879-c8ed-43cb-80d3-179f0432e74f', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/rp4b4m/blockdevices/2/partition/7'}], u'type': u'physical', u'resource_uri': u'/MAAS/api/2.0/nodes/rp4b4m/blockdevices/2/', u'size': 800109715456}, u'interface_set': [{u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'b7wtg6', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'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'name': u'untagged', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'b7wtg6', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 24}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:95:a0', u'enabled': True, u'parents': [], 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'name': u'untagged', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'b7wtg6', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'system_id': u'rp4b4m', u'effective_mtu': 1500, u'id': 5, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rp4b4m/interfaces/5/'}, {u'vlan': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:1f:d5', u'enabled': True, u'parents': [], u'discovered': None, u'system_id': u'rp4b4m', u'effective_mtu': 1500, u'id': 19, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rp4b4m/interfaces/19/'}, {u'vlan': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:1f:d4', u'enabled': True, u'parents': [], u'discovered': None, u'system_id': u'rp4b4m', u'effective_mtu': 1500, u'id': 20, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rp4b4m/interfaces/20/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 25}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:52:cd', u'enabled': True, u'parents': [], u'discovered': None, u'system_id': u'rp4b4m', u'effective_mtu': 1500, u'id': 16, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rp4b4m/interfaces/16/'}, {u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'b7wtg6', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'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'name': u'untagged', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'b7wtg6', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'mode': u'link_up', u'id': 26}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:95:a4', u'enabled': True, u'parents': [], 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'name': u'untagged', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'b7wtg6', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.44'}], u'system_id': u'rp4b4m', u'effective_mtu': 1500, u'id': 17, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rp4b4m/interfaces/17/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 27}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:52:cc', u'enabled': True, u'parents': [], u'discovered': None, u'system_id': u'rp4b4m', u'effective_mtu': 1500, u'id': 18, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rp4b4m/interfaces/18/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/rp4b4m/'}
2019-07-09 05:13:13,893 [salt.state       :300 ][INFO    ][7500] {'new': {'storage_layout': 'lvm'}}
2019-07-09 05:13:13,894 [salt.state       :1951][INFO    ][7500] Completed state [maas_machines_storage_cmp001_lvm] at time 05:13:13.894274 duration_in_ms=2454.226
2019-07-09 05:13:13,900 [salt.minion      :1711][INFO    ][7500] Returning information for job: 20190709051258484661
2019-07-09 05:13:14,635 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command state.apply with jid 20190709051314621423
2019-07-09 05:13:14,663 [salt.minion      :1432][INFO    ][7533] Starting a new job with PID 7533
2019-07-09 05:13:15,853 [salt.state       :915 ][INFO    ][7533] Loading fresh modules for state activity
2019-07-09 05:13:15,916 [salt.fileclient  :1219][INFO    ][7533] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-07-09 05:13:15,976 [salt.state       :1780][INFO    ][7533] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:13:15.976423
2019-07-09 05:13:15,977 [salt.state       :1813][INFO    ][7533] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-09 05:13:15,980 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7533] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-09 05:13:18,010 [salt.state       :300 ][INFO    ][7533] {'pid': 7541, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-09 05:13:18,011 [salt.state       :1951][INFO    ][7533] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:13:18.011782 duration_in_ms=2035.359
2019-07-09 05:13:18,016 [salt.state       :1780][INFO    ][7533] Running state [maas.deploy_machines] at time 05:13:18.016200
2019-07-09 05:13:18,017 [salt.state       :1813][INFO    ][7533] Executing state module.run for [maas.deploy_machines]
2019-07-09 05:13:18,019 [salt.utils.decorators:613 ][WARNING ][7533] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-09 05:13:18,609 [salt.loaded.ext.module.maas:684 ][INFO    ][7533] deploymachines hwe_kernel=hwe-16.04 system_id=tgrmf6 distro_series=xenial
2019-07-09 05:13:21,128 [salt.loaded.ext.module.maas:684 ][INFO    ][7533] deploymachines hwe_kernel=hwe-16.04 system_id=rp4b4m distro_series=xenial
2019-07-09 05:13:23,526 [salt.loaded.ext.module.maas:684 ][INFO    ][7533] deploymachines hwe_kernel=hwe-16.04 system_id=8sgfm8 distro_series=xenial
2019-07-09 05:13:25,885 [salt.loaded.ext.module.maas:684 ][INFO    ][7533] deploymachines hwe_kernel=hwe-16.04 system_id=dggwqa distro_series=xenial
2019-07-09 05:13:28,288 [salt.loaded.ext.module.maas:684 ][INFO    ][7533] deploymachines hwe_kernel=hwe-16.04 system_id=7es4pk distro_series=xenial
2019-07-09 05:13:29,697 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709051329688417
2019-07-09 05:13:29,722 [salt.minion      :1432][INFO    ][7799] Starting a new job with PID 7799
2019-07-09 05:13:29,751 [salt.minion      :1711][INFO    ][7799] Returning information for job: 20190709051329688417
2019-07-09 05:13:30,876 [salt.state       :300 ][INFO    ][7533] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-07-09 05:13:30,877 [salt.state       :1951][INFO    ][7533] Completed state [maas.deploy_machines] at time 05:13:30.876981 duration_in_ms=12860.781
2019-07-09 05:13:30,882 [salt.minion      :1711][INFO    ][7533] Returning information for job: 20190709051314621423
2019-07-09 05:13:31,605 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command state.apply with jid 20190709051331591989
2019-07-09 05:13:31,633 [salt.minion      :1432][INFO    ][7817] Starting a new job with PID 7817
2019-07-09 05:13:40,040 [salt.state       :915 ][INFO    ][7817] Loading fresh modules for state activity
2019-07-09 05:13:40,101 [salt.fileclient  :1219][INFO    ][7817] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-07-09 05:13:40,150 [salt.state       :1780][INFO    ][7817] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:13:40.150860
2019-07-09 05:13:40,151 [salt.state       :1813][INFO    ][7817] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-09 05:13:40,153 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7817] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-09 05:13:42,058 [salt.state       :300 ][INFO    ][7817] {'pid': 7844, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-09 05:13:42,059 [salt.state       :1951][INFO    ][7817] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:13:42.059170 duration_in_ms=1908.309
2019-07-09 05:13:42,064 [salt.state       :1780][INFO    ][7817] Running state [maas.wait_for_machine_status] at time 05:13:42.064774
2019-07-09 05:13:42,065 [salt.state       :1813][INFO    ][7817] Executing state module.run for [maas.wait_for_machine_status]
2019-07-09 05:13:42,066 [salt.utils.decorators:613 ][WARNING ][7817] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-09 05:13:44,985 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.10023522s left)
2019-07-09 05:13:46,696 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709051346677423
2019-07-09 05:13:46,723 [salt.minion      :1432][INFO    ][7856] Starting a new job with PID 7856
2019-07-09 05:13:46,751 [salt.minion      :1711][INFO    ][7856] Returning information for job: 20190709051346677423
2019-07-09 05:14:16,794 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709051416779562
2019-07-09 05:14:16,816 [salt.minion      :1432][INFO    ][7900] Starting a new job with PID 7900
2019-07-09 05:14:16,846 [salt.minion      :1711][INFO    ][7900] Returning information for job: 20190709051416779562
2019-07-09 05:14:17,927 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.15791702s left)
2019-07-09 05:14:46,912 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709051446896567
2019-07-09 05:14:46,941 [salt.minion      :1432][INFO    ][7945] Starting a new job with PID 7945
2019-07-09 05:14:46,972 [salt.minion      :1711][INFO    ][7945] Returning information for job: 20190709051446896567
2019-07-09 05:14:51,068 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.01739001s left)
2019-07-09 05:15:17,013 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709051516996937
2019-07-09 05:15:17,044 [salt.minion      :1432][INFO    ][8002] Starting a new job with PID 8002
2019-07-09 05:15:17,081 [salt.minion      :1711][INFO    ][8002] Returning information for job: 20190709051516996937
2019-07-09 05:15:24,088 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2147.99732423s left)
2019-07-09 05:15:47,116 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709051547100217
2019-07-09 05:15:47,146 [salt.minion      :1432][INFO    ][8040] Starting a new job with PID 8040
2019-07-09 05:15:47,173 [salt.minion      :1711][INFO    ][8040] Returning information for job: 20190709051547100217
2019-07-09 05:15:57,147 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2114.93776703s left)
2019-07-09 05:16:17,227 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709051617211688
2019-07-09 05:16:17,255 [salt.minion      :1432][INFO    ][8108] Starting a new job with PID 8108
2019-07-09 05:16:17,286 [salt.minion      :1711][INFO    ][8108] Returning information for job: 20190709051617211688
2019-07-09 05:16:30,088 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2081.9967711s left)
2019-07-09 05:16:47,324 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709051647310812
2019-07-09 05:16:47,352 [salt.minion      :1432][INFO    ][8187] Starting a new job with PID 8187
2019-07-09 05:16:47,384 [salt.minion      :1711][INFO    ][8187] Returning information for job: 20190709051647310812
2019-07-09 05:17:03,132 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2048.95290303s left)
2019-07-09 05:17:17,479 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709051717461623
2019-07-09 05:17:17,507 [salt.minion      :1432][INFO    ][8395] Starting a new job with PID 8395
2019-07-09 05:17:17,545 [salt.minion      :1711][INFO    ][8395] Returning information for job: 20190709051717461623
2019-07-09 05:17:36,388 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2015.69752908s left)
2019-07-09 05:17:47,599 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709051747589092
2019-07-09 05:17:47,613 [salt.minion      :1432][INFO    ][8469] Starting a new job with PID 8469
2019-07-09 05:17:47,640 [salt.minion      :1711][INFO    ][8469] Returning information for job: 20190709051747589092
2019-07-09 05:18:09,351 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1982.7338531s left)
2019-07-09 05:18:17,746 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709051817731148
2019-07-09 05:18:17,773 [salt.minion      :1432][INFO    ][8758] Starting a new job with PID 8758
2019-07-09 05:18:17,807 [salt.minion      :1711][INFO    ][8758] Returning information for job: 20190709051817731148
2019-07-09 05:18:42,411 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1949.67396116s left)
2019-07-09 05:18:47,892 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709051847874988
2019-07-09 05:18:47,932 [salt.minion      :1432][INFO    ][8800] Starting a new job with PID 8800
2019-07-09 05:18:47,964 [salt.minion      :1711][INFO    ][8800] Returning information for job: 20190709051847874988
2019-07-09 05:19:15,586 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1916.49899721s left)
2019-07-09 05:19:18,069 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709051918051017
2019-07-09 05:19:18,101 [salt.minion      :1432][INFO    ][9090] Starting a new job with PID 9090
2019-07-09 05:19:18,137 [salt.minion      :1711][INFO    ][9090] Returning information for job: 20190709051918051017
2019-07-09 05:19:48,240 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709051948225075
2019-07-09 05:19:48,260 [salt.minion      :1432][INFO    ][9144] Starting a new job with PID 9144
2019-07-09 05:19:48,294 [salt.minion      :1711][INFO    ][9144] Returning information for job: 20190709051948225075
2019-07-09 05:19:48,300 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1883.784899s left)
2019-07-09 05:20:18,441 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709052018427038
2019-07-09 05:20:18,465 [salt.minion      :1432][INFO    ][9481] Starting a new job with PID 9481
2019-07-09 05:20:18,500 [salt.minion      :1711][INFO    ][9481] Returning information for job: 20190709052018427038
2019-07-09 05:20:21,441 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1850.64413214s left)
2019-07-09 05:20:48,606 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709052048590628
2019-07-09 05:20:48,635 [salt.minion      :1432][INFO    ][9524] Starting a new job with PID 9524
2019-07-09 05:20:48,667 [salt.minion      :1711][INFO    ][9524] Returning information for job: 20190709052048590628
2019-07-09 05:20:56,214 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1815.87058806s left)
2019-07-09 05:21:18,829 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709052118818505
2019-07-09 05:21:18,861 [salt.minion      :1432][INFO    ][9936] Starting a new job with PID 9936
2019-07-09 05:21:18,889 [salt.minion      :1711][INFO    ][9936] Returning information for job: 20190709052118818505
2019-07-09 05:21:29,074 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1783.01097417s left)
2019-07-09 05:21:48,992 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709052148982509
2019-07-09 05:21:49,019 [salt.minion      :1432][INFO    ][9996] Starting a new job with PID 9996
2019-07-09 05:21:49,053 [salt.minion      :1711][INFO    ][9996] Returning information for job: 20190709052148982509
2019-07-09 05:22:02,191 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1749.89457107s left)
2019-07-09 05:22:19,184 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709052219174306
2019-07-09 05:22:19,211 [salt.minion      :1432][INFO    ][10253] Starting a new job with PID 10253
2019-07-09 05:22:19,252 [salt.minion      :1711][INFO    ][10253] Returning information for job: 20190709052219174306
2019-07-09 05:22:35,141 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1716.94406414s left)
2019-07-09 05:22:49,377 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709052249360131
2019-07-09 05:22:49,405 [salt.minion      :1432][INFO    ][10301] Starting a new job with PID 10301
2019-07-09 05:22:49,435 [salt.minion      :1711][INFO    ][10301] Returning information for job: 20190709052249360131
2019-07-09 05:23:08,230 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1683.85532117s left)
2019-07-09 05:23:19,421 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709052319413459
2019-07-09 05:23:19,447 [salt.minion      :1432][INFO    ][10371] Starting a new job with PID 10371
2019-07-09 05:23:19,476 [salt.minion      :1711][INFO    ][10371] Returning information for job: 20190709052319413459
2019-07-09 05:23:41,312 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1650.77348113s left)
2019-07-09 05:23:49,597 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709052349582150
2019-07-09 05:23:49,626 [salt.minion      :1432][INFO    ][10428] Starting a new job with PID 10428
2019-07-09 05:23:49,662 [salt.minion      :1711][INFO    ][10428] Returning information for job: 20190709052349582150
2019-07-09 05:24:14,256 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1617.828933s left)
2019-07-09 05:24:19,819 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709052419802800
2019-07-09 05:24:19,848 [salt.minion      :1432][INFO    ][10718] Starting a new job with PID 10718
2019-07-09 05:24:19,877 [salt.minion      :1711][INFO    ][10718] Returning information for job: 20190709052419802800
2019-07-09 05:24:47,136 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1584.94925117s left)
2019-07-09 05:24:50,007 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709052449992238
2019-07-09 05:24:50,034 [salt.minion      :1432][INFO    ][10885] Starting a new job with PID 10885
2019-07-09 05:24:50,068 [salt.minion      :1711][INFO    ][10885] Returning information for job: 20190709052449992238
2019-07-09 05:25:20,100 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1551.98554707s left)
2019-07-09 05:25:20,213 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709052520199435
2019-07-09 05:25:20,240 [salt.minion      :1432][INFO    ][10942] Starting a new job with PID 10942
2019-07-09 05:25:20,271 [salt.minion      :1711][INFO    ][10942] Returning information for job: 20190709052520199435
2019-07-09 05:25:50,436 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709052550418106
2019-07-09 05:25:50,460 [salt.minion      :1432][INFO    ][10986] Starting a new job with PID 10986
2019-07-09 05:25:50,493 [salt.minion      :1711][INFO    ][10986] Returning information for job: 20190709052550418106
2019-07-09 05:25:53,277 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1518.80763221s left)
2019-07-09 05:26:20,608 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709052620592837
2019-07-09 05:26:20,635 [salt.minion      :1432][INFO    ][11099] Starting a new job with PID 11099
2019-07-09 05:26:20,665 [salt.minion      :1711][INFO    ][11099] Returning information for job: 20190709052620592837
2019-07-09 05:26:26,197 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1485.88803911s left)
2019-07-09 05:26:50,756 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709052650737447
2019-07-09 05:26:50,789 [salt.minion      :1432][INFO    ][11144] Starting a new job with PID 11144
2019-07-09 05:26:50,818 [salt.minion      :1711][INFO    ][11144] Returning information for job: 20190709052650737447
2019-07-09 05:26:59,380 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1452.70526814s left)
2019-07-09 05:27:20,814 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709052720793987
2019-07-09 05:27:20,843 [salt.minion      :1432][INFO    ][11333] Starting a new job with PID 11333
2019-07-09 05:27:20,872 [salt.minion      :1711][INFO    ][11333] Returning information for job: 20190709052720793987
2019-07-09 05:27:32,217 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1419.86784005s left)
2019-07-09 05:27:50,854 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709052750837513
2019-07-09 05:27:50,885 [salt.minion      :1432][INFO    ][11364] Starting a new job with PID 11364
2019-07-09 05:27:50,916 [salt.minion      :1711][INFO    ][11364] Returning information for job: 20190709052750837513
2019-07-09 05:28:05,144 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1386.94133306s left)
2019-07-09 05:28:20,913 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709052820902927
2019-07-09 05:28:20,933 [salt.minion      :1432][INFO    ][11415] Starting a new job with PID 11415
2019-07-09 05:28:20,960 [salt.minion      :1711][INFO    ][11415] Returning information for job: 20190709052820902927
2019-07-09 05:28:38,128 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1353.95705009s left)
2019-07-09 05:28:50,972 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709052850958829
2019-07-09 05:28:51,005 [salt.minion      :1432][INFO    ][11446] Starting a new job with PID 11446
2019-07-09 05:28:51,035 [salt.minion      :1711][INFO    ][11446] Returning information for job: 20190709052850958829
2019-07-09 05:29:11,157 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1320.92831516s left)
2019-07-09 05:29:21,078 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709052921058969
2019-07-09 05:29:21,106 [salt.minion      :1432][INFO    ][11503] Starting a new job with PID 11503
2019-07-09 05:29:21,138 [salt.minion      :1711][INFO    ][11503] Returning information for job: 20190709052921058969
2019-07-09 05:29:44,029 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1288.05619001s left)
2019-07-09 05:29:51,152 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709052951140279
2019-07-09 05:29:51,175 [salt.minion      :1432][INFO    ][11530] Starting a new job with PID 11530
2019-07-09 05:29:51,203 [salt.minion      :1711][INFO    ][11530] Returning information for job: 20190709052951140279
2019-07-09 05:30:16,774 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1255.31087518s left)
2019-07-09 05:30:21,235 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709053021222986
2019-07-09 05:30:21,265 [salt.minion      :1432][INFO    ][11583] Starting a new job with PID 11583
2019-07-09 05:30:21,295 [salt.minion      :1711][INFO    ][11583] Returning information for job: 20190709053021222986
2019-07-09 05:30:49,882 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1222.20352817s left)
2019-07-09 05:30:51,357 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709053051343274
2019-07-09 05:30:51,385 [salt.minion      :1432][INFO    ][11622] Starting a new job with PID 11622
2019-07-09 05:30:51,420 [salt.minion      :1711][INFO    ][11622] Returning information for job: 20190709053051343274
2019-07-09 05:31:21,463 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709053121451224
2019-07-09 05:31:21,487 [salt.minion      :1432][INFO    ][11669] Starting a new job with PID 11669
2019-07-09 05:31:21,515 [salt.minion      :1711][INFO    ][11669] Returning information for job: 20190709053121451224
2019-07-09 05:31:22,684 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1189.400769s left)
2019-07-09 05:31:51,619 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709053151607767
2019-07-09 05:31:51,642 [salt.minion      :1432][INFO    ][11714] Starting a new job with PID 11714
2019-07-09 05:31:51,677 [salt.minion      :1711][INFO    ][11714] Returning information for job: 20190709053151607767
2019-07-09 05:31:55,580 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1156.50502515s left)
2019-07-09 05:32:21,756 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709053221747555
2019-07-09 05:32:21,787 [salt.minion      :1432][INFO    ][11745] Starting a new job with PID 11745
2019-07-09 05:32:21,816 [salt.minion      :1711][INFO    ][11745] Returning information for job: 20190709053221747555
2019-07-09 05:32:28,351 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1123.734303s left)
2019-07-09 05:32:51,937 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709053251923738
2019-07-09 05:32:51,966 [salt.minion      :1432][INFO    ][11793] Starting a new job with PID 11793
2019-07-09 05:32:51,995 [salt.minion      :1711][INFO    ][11793] Returning information for job: 20190709053251923738
2019-07-09 05:33:01,124 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1090.9609201s left)
2019-07-09 05:33:22,087 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709053322074269
2019-07-09 05:33:22,115 [salt.minion      :1432][INFO    ][11823] Starting a new job with PID 11823
2019-07-09 05:33:22,147 [salt.minion      :1711][INFO    ][11823] Returning information for job: 20190709053322074269
2019-07-09 05:33:34,113 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1057.97219801s left)
2019-07-09 05:33:52,296 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709053352283422
2019-07-09 05:33:52,320 [salt.minion      :1432][INFO    ][11871] Starting a new job with PID 11871
2019-07-09 05:33:52,351 [salt.minion      :1711][INFO    ][11871] Returning information for job: 20190709053352283422
2019-07-09 05:34:07,220 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1024.86493707s left)
2019-07-09 05:34:22,472 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709053422461527
2019-07-09 05:34:22,499 [salt.minion      :1432][INFO    ][11900] Starting a new job with PID 11900
2019-07-09 05:34:22,529 [salt.minion      :1711][INFO    ][11900] Returning information for job: 20190709053422461527
2019-07-09 05:34:40,087 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (991.998522997s left)
2019-07-09 05:34:52,501 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709053452487771
2019-07-09 05:34:52,528 [salt.minion      :1432][INFO    ][11965] Starting a new job with PID 11965
2019-07-09 05:34:52,557 [salt.minion      :1711][INFO    ][11965] Returning information for job: 20190709053452487771
2019-07-09 05:35:12,800 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (959.284840107s left)
2019-07-09 05:35:22,724 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709053522714406
2019-07-09 05:35:22,745 [salt.minion      :1432][INFO    ][12001] Starting a new job with PID 12001
2019-07-09 05:35:22,774 [salt.minion      :1711][INFO    ][12001] Returning information for job: 20190709053522714406
2019-07-09 05:35:45,514 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (926.571010113s left)
2019-07-09 05:35:52,753 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709053552738701
2019-07-09 05:35:52,781 [salt.minion      :1432][INFO    ][12052] Starting a new job with PID 12052
2019-07-09 05:35:52,813 [salt.minion      :1711][INFO    ][12052] Returning information for job: 20190709053552738701
2019-07-09 05:36:18,406 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (893.678655148s left)
2019-07-09 05:36:22,817 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709053622801127
2019-07-09 05:36:22,847 [salt.minion      :1432][INFO    ][12085] Starting a new job with PID 12085
2019-07-09 05:36:22,877 [salt.minion      :1711][INFO    ][12085] Returning information for job: 20190709053622801127
2019-07-09 05:36:51,258 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (860.827155113s left)
2019-07-09 05:36:52,892 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709053652878710
2019-07-09 05:36:52,919 [salt.minion      :1432][INFO    ][12136] Starting a new job with PID 12136
2019-07-09 05:36:52,951 [salt.minion      :1711][INFO    ][12136] Returning information for job: 20190709053652878710
2019-07-09 05:37:22,985 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709053722974293
2019-07-09 05:37:23,005 [salt.minion      :1432][INFO    ][12159] Starting a new job with PID 12159
2019-07-09 05:37:23,038 [salt.minion      :1711][INFO    ][12159] Returning information for job: 20190709053722974293
2019-07-09 05:37:24,180 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (827.905423164s left)
2019-07-09 05:37:53,081 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709053753067232
2019-07-09 05:37:53,106 [salt.minion      :1432][INFO    ][12211] Starting a new job with PID 12211
2019-07-09 05:37:53,142 [salt.minion      :1711][INFO    ][12211] Returning information for job: 20190709053753067232
2019-07-09 05:37:57,076 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (795.008724213s left)
2019-07-09 05:38:23,237 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709053823220885
2019-07-09 05:38:23,267 [salt.minion      :1432][INFO    ][12234] Starting a new job with PID 12234
2019-07-09 05:38:23,298 [salt.minion      :1711][INFO    ][12234] Returning information for job: 20190709053823220885
2019-07-09 05:38:30,048 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (762.036999226s left)
2019-07-09 05:38:53,345 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709053853329022
2019-07-09 05:38:53,372 [salt.minion      :1432][INFO    ][12291] Starting a new job with PID 12291
2019-07-09 05:38:53,414 [salt.minion      :1711][INFO    ][12291] Returning information for job: 20190709053853329022
2019-07-09 05:39:02,784 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (729.30123806s left)
2019-07-09 05:39:23,527 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709053923511386
2019-07-09 05:39:23,555 [salt.minion      :1432][INFO    ][12315] Starting a new job with PID 12315
2019-07-09 05:39:23,587 [salt.minion      :1711][INFO    ][12315] Returning information for job: 20190709053923511386
2019-07-09 05:39:35,788 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (696.297021151s left)
2019-07-09 05:39:53,689 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709053953677344
2019-07-09 05:39:53,716 [salt.minion      :1432][INFO    ][12372] Starting a new job with PID 12372
2019-07-09 05:39:53,755 [salt.minion      :1711][INFO    ][12372] Returning information for job: 20190709053953677344
2019-07-09 05:40:08,697 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (663.388203144s left)
2019-07-09 05:40:23,730 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709054023713402
2019-07-09 05:40:23,759 [salt.minion      :1432][INFO    ][12392] Starting a new job with PID 12392
2019-07-09 05:40:23,793 [salt.minion      :1711][INFO    ][12392] Returning information for job: 20190709054023713402
2019-07-09 05:40:41,527 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (630.557952166s left)
2019-07-09 05:40:53,758 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709054053746123
2019-07-09 05:40:53,784 [salt.minion      :1432][INFO    ][12453] Starting a new job with PID 12453
2019-07-09 05:40:53,814 [salt.minion      :1711][INFO    ][12453] Returning information for job: 20190709054053746123
2019-07-09 05:41:14,298 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (597.786702156s left)
2019-07-09 05:41:23,808 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709054123796065
2019-07-09 05:41:23,832 [salt.minion      :1432][INFO    ][12476] Starting a new job with PID 12476
2019-07-09 05:41:23,864 [salt.minion      :1711][INFO    ][12476] Returning information for job: 20190709054123796065
2019-07-09 05:41:47,035 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (565.050398111s left)
2019-07-09 05:41:53,884 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709054153871116
2019-07-09 05:41:53,903 [salt.minion      :1432][INFO    ][12530] Starting a new job with PID 12530
2019-07-09 05:41:53,933 [salt.minion      :1711][INFO    ][12530] Returning information for job: 20190709054153871116
2019-07-09 05:42:20,002 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (532.083171129s left)
2019-07-09 05:42:23,938 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709054223922238
2019-07-09 05:42:23,968 [salt.minion      :1432][INFO    ][12553] Starting a new job with PID 12553
2019-07-09 05:42:24,000 [salt.minion      :1711][INFO    ][12553] Returning information for job: 20190709054223922238
2019-07-09 05:42:52,957 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (499.127895117s left)
2019-07-09 05:42:54,068 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709054254052288
2019-07-09 05:42:54,095 [salt.minion      :1432][INFO    ][12606] Starting a new job with PID 12606
2019-07-09 05:42:54,125 [salt.minion      :1711][INFO    ][12606] Returning information for job: 20190709054254052288
2019-07-09 05:43:24,148 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709054324132234
2019-07-09 05:43:24,167 [salt.minion      :1432][INFO    ][12623] Starting a new job with PID 12623
2019-07-09 05:43:24,201 [salt.minion      :1711][INFO    ][12623] Returning information for job: 20190709054324132234
2019-07-09 05:43:25,943 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (466.142273188s left)
2019-07-09 05:43:54,300 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709054354284553
2019-07-09 05:43:54,333 [salt.minion      :1432][INFO    ][12678] Starting a new job with PID 12678
2019-07-09 05:43:54,364 [salt.minion      :1711][INFO    ][12678] Returning information for job: 20190709054354284553
2019-07-09 05:43:58,781 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (433.303921223s left)
2019-07-09 05:44:24,427 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709054424413373
2019-07-09 05:44:24,456 [salt.minion      :1432][INFO    ][12698] Starting a new job with PID 12698
2019-07-09 05:44:24,486 [salt.minion      :1711][INFO    ][12698] Returning information for job: 20190709054424413373
2019-07-09 05:44:31,557 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (400.527895212s left)
2019-07-09 05:44:54,632 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709054454616969
2019-07-09 05:44:54,661 [salt.minion      :1432][INFO    ][12894] Starting a new job with PID 12894
2019-07-09 05:44:54,699 [salt.minion      :1711][INFO    ][12894] Returning information for job: 20190709054454616969
2019-07-09 05:45:04,455 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (367.629789114s left)
2019-07-09 05:45:24,847 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709054524831130
2019-07-09 05:45:24,880 [salt.minion      :1432][INFO    ][12922] Starting a new job with PID 12922
2019-07-09 05:45:24,915 [salt.minion      :1711][INFO    ][12922] Returning information for job: 20190709054524831130
2019-07-09 05:45:37,188 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (334.897366047s left)
2019-07-09 05:45:54,884 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709054554872580
2019-07-09 05:45:54,912 [salt.minion      :1432][INFO    ][12979] Starting a new job with PID 12979
2019-07-09 05:45:54,945 [salt.minion      :1711][INFO    ][12979] Returning information for job: 20190709054554872580
2019-07-09 05:46:09,997 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (302.087937117s left)
2019-07-09 05:46:24,904 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709054624889095
2019-07-09 05:46:24,931 [salt.minion      :1432][INFO    ][13004] Starting a new job with PID 13004
2019-07-09 05:46:24,959 [salt.minion      :1711][INFO    ][13004] Returning information for job: 20190709054624889095
2019-07-09 05:46:42,718 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (269.367057085s left)
2019-07-09 05:46:55,001 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709054654984025
2019-07-09 05:46:55,025 [salt.minion      :1432][INFO    ][13060] Starting a new job with PID 13060
2019-07-09 05:46:55,055 [salt.minion      :1711][INFO    ][13060] Returning information for job: 20190709054654984025
2019-07-09 05:47:15,593 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (236.492514133s left)
2019-07-09 05:47:25,085 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709054725070597
2019-07-09 05:47:25,105 [salt.minion      :1432][INFO    ][13082] Starting a new job with PID 13082
2019-07-09 05:47:25,135 [salt.minion      :1711][INFO    ][13082] Returning information for job: 20190709054725070597
2019-07-09 05:47:48,301 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (203.784069061s left)
2019-07-09 05:47:55,212 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709054755196907
2019-07-09 05:47:55,241 [salt.minion      :1432][INFO    ][13136] Starting a new job with PID 13136
2019-07-09 05:47:55,272 [salt.minion      :1711][INFO    ][13136] Returning information for job: 20190709054755196907
2019-07-09 05:48:21,356 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (170.729520082s left)
2019-07-09 05:48:25,349 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709054825333478
2019-07-09 05:48:25,380 [salt.minion      :1432][INFO    ][13156] Starting a new job with PID 13156
2019-07-09 05:48:25,413 [salt.minion      :1711][INFO    ][13156] Returning information for job: 20190709054825333478
2019-07-09 05:48:54,567 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (137.518183231s left)
2019-07-09 05:48:55,421 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709054855404272
2019-07-09 05:48:55,446 [salt.minion      :1432][INFO    ][13210] Starting a new job with PID 13210
2019-07-09 05:48:55,479 [salt.minion      :1711][INFO    ][13210] Returning information for job: 20190709054855404272
2019-07-09 05:49:25,601 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709054925586759
2019-07-09 05:49:25,626 [salt.minion      :1432][INFO    ][13235] Starting a new job with PID 13235
2019-07-09 05:49:25,660 [salt.minion      :1711][INFO    ][13235] Returning information for job: 20190709054925586759
2019-07-09 05:49:27,357 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (104.727858067s left)
2019-07-09 05:49:55,769 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709054955754848
2019-07-09 05:49:55,798 [salt.minion      :1432][INFO    ][13287] Starting a new job with PID 13287
2019-07-09 05:49:55,829 [salt.minion      :1711][INFO    ][13287] Returning information for job: 20190709054955754848
2019-07-09 05:50:00,108 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (71.9768610001s left)
2019-07-09 05:50:25,937 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709055025921765
2019-07-09 05:50:25,965 [salt.minion      :1432][INFO    ][13306] Starting a new job with PID 13306
2019-07-09 05:50:25,999 [salt.minion      :1711][INFO    ][13306] Returning information for job: 20190709055025921765
2019-07-09 05:50:32,807 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (39.2782301903s left)
2019-07-09 05:50:56,044 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709055056027790
2019-07-09 05:50:56,068 [salt.minion      :1432][INFO    ][13361] Starting a new job with PID 13361
2019-07-09 05:50:56,101 [salt.minion      :1711][INFO    ][13361] Returning information for job: 20190709055056027790
2019-07-09 05:51:05,853 [salt.loaded.ext.module.maas:1023][INFO    ][7817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (6.23238611221s left)
2019-07-09 05:51:26,248 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709055126235104
2019-07-09 05:51:26,274 [salt.minion      :1432][INFO    ][13382] Starting a new job with PID 13382
2019-07-09 05:51:26,308 [salt.minion      :1711][INFO    ][13382] Returning information for job: 20190709055126235104
2019-07-09 05:51:38,694 [salt.state       :302 ][ERROR   ][7817] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-07-09 05:51:38,695 [salt.state       :1951][INFO    ][7817] Completed state [maas.wait_for_machine_status] at time 05:51:38.695047 duration_in_ms=2276630.269
2019-07-09 05:51:38,700 [salt.minion      :1711][INFO    ][7817] Returning information for job: 20190709051331591989
2019-07-09 05:51:49,609 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command pillar.get with jid 20190709055149596624
2019-07-09 05:51:49,630 [salt.minion      :1432][INFO    ][13409] Starting a new job with PID 13409
2019-07-09 05:51:49,640 [salt.minion      :1711][INFO    ][13409] Returning information for job: 20190709055149596624
2019-07-09 05:51:50,345 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command service.status with jid 20190709055150333672
2019-07-09 05:51:50,364 [salt.minion      :1432][INFO    ][13414] Starting a new job with PID 13414
2019-07-09 05:51:51,135 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13414] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-07-09 05:51:51,180 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13414] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-07-09 05:51:51,200 [salt.minion      :1711][INFO    ][13414] Returning information for job: 20190709055150333672
2019-07-09 05:51:51,988 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command state.apply with jid 20190709055151973363
2019-07-09 05:51:52,014 [salt.minion      :1432][INFO    ][13447] Starting a new job with PID 13447
2019-07-09 05:52:00,263 [salt.state       :915 ][INFO    ][13447] Loading fresh modules for state activity
2019-07-09 05:52:00,932 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13447] Executing command 'salt-minion --version' in directory '/root'
2019-07-09 05:52:01,280 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13447] Executing command 'salt-minion --version' in directory '/root'
2019-07-09 05:52:02,403 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13447] Executing command 'salt-minion --version' in directory '/root'
2019-07-09 05:52:02,756 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13447] Executing command 'salt-minion --version' in directory '/root'
2019-07-09 05:52:05,092 [salt.state       :1780][INFO    ][13447] Running state [salt-minion] at time 05:52:05.092185
2019-07-09 05:52:05,092 [salt.state       :1813][INFO    ][13447] Executing state pkg.installed for [salt-minion]
2019-07-09 05:52:05,093 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13447] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-07-09 05:52:05,215 [salt.state       :300 ][INFO    ][13447] All specified packages are already installed
2019-07-09 05:52:05,215 [salt.state       :1951][INFO    ][13447] Completed state [salt-minion] at time 05:52:05.215666 duration_in_ms=123.481
2019-07-09 05:52:05,216 [salt.state       :1780][INFO    ][13447] Running state [salt_minion_dependency_packages] at time 05:52:05.216056
2019-07-09 05:52:05,216 [salt.state       :1813][INFO    ][13447] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-07-09 05:52:05,226 [salt.state       :300 ][INFO    ][13447] All specified packages are already installed
2019-07-09 05:52:05,227 [salt.state       :1951][INFO    ][13447] Completed state [salt_minion_dependency_packages] at time 05:52:05.227100 duration_in_ms=11.044
2019-07-09 05:52:05,230 [salt.state       :1780][INFO    ][13447] Running state [/etc/salt/minion.d/minion.conf] at time 05:52:05.230234
2019-07-09 05:52:05,230 [salt.state       :1813][INFO    ][13447] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-07-09 05:52:05,526 [salt.state       :300 ][INFO    ][13447] File /etc/salt/minion.d/minion.conf is in the correct state
2019-07-09 05:52:05,526 [salt.state       :1951][INFO    ][13447] Completed state [/etc/salt/minion.d/minion.conf] at time 05:52:05.526818 duration_in_ms=296.584
2019-07-09 05:52:05,527 [salt.state       :1780][INFO    ][13447] Running state [python-netaddr] at time 05:52:05.527118
2019-07-09 05:52:05,527 [salt.state       :1813][INFO    ][13447] Executing state pkg.installed for [python-netaddr]
2019-07-09 05:52:05,536 [salt.state       :300 ][INFO    ][13447] All specified packages are already installed
2019-07-09 05:52:05,536 [salt.state       :1951][INFO    ][13447] Completed state [python-netaddr] at time 05:52:05.536655 duration_in_ms=9.537
2019-07-09 05:52:05,539 [salt.state       :1780][INFO    ][13447] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 05:52:05.539833
2019-07-09 05:52:05,540 [salt.state       :1813][INFO    ][13447] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-07-09 05:52:05,553 [salt.state       :300 ][INFO    ][13447] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-07-09 05:52:05,553 [salt.state       :1951][INFO    ][13447] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 05:52:05.553509 duration_in_ms=13.675
2019-07-09 05:52:05,555 [salt.state       :1780][INFO    ][13447] Running state [salt-minion] at time 05:52:05.555002
2019-07-09 05:52:05,555 [salt.state       :1813][INFO    ][13447] Executing state service.running for [salt-minion]
2019-07-09 05:52:05,556 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13447] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-07-09 05:52:05,604 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13447] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-07-09 05:52:05,627 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13447] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-07-09 05:52:05,651 [salt.state       :300 ][INFO    ][13447] The service salt-minion is already running
2019-07-09 05:52:05,651 [salt.state       :1951][INFO    ][13447] Completed state [salt-minion] at time 05:52:05.651696 duration_in_ms=96.693
2019-07-09 05:52:05,656 [salt.state       :1780][INFO    ][13447] Running state [/etc/salt/grains.d] at time 05:52:05.656727
2019-07-09 05:52:05,657 [salt.state       :1813][INFO    ][13447] Executing state file.directory for [/etc/salt/grains.d]
2019-07-09 05:52:05,659 [salt.state       :300 ][INFO    ][13447] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-07-09 05:52:05,659 [salt.state       :1951][INFO    ][13447] Completed state [/etc/salt/grains.d] at time 05:52:05.659355 duration_in_ms=2.628
2019-07-09 05:52:05,660 [salt.state       :1780][INFO    ][13447] Running state [/etc/salt/grains] at time 05:52:05.660477
2019-07-09 05:52:05,661 [salt.state       :1813][INFO    ][13447] Executing state file.managed for [/etc/salt/grains]
2019-07-09 05:52:05,661 [salt.state       :300 ][INFO    ][13447] File /etc/salt/grains exists with proper permissions. No changes made.
2019-07-09 05:52:05,662 [salt.state       :1951][INFO    ][13447] Completed state [/etc/salt/grains] at time 05:52:05.662220 duration_in_ms=1.744
2019-07-09 05:52:05,663 [salt.state       :1780][INFO    ][13447] Running state [/etc/salt/grains.d/placeholder] at time 05:52:05.663032
2019-07-09 05:52:05,663 [salt.state       :1813][INFO    ][13447] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-07-09 05:52:05,664 [salt.state       :300 ][INFO    ][13447] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-07-09 05:52:05,664 [salt.state       :1951][INFO    ][13447] Completed state [/etc/salt/grains.d/placeholder] at time 05:52:05.664745 duration_in_ms=1.713
2019-07-09 05:52:05,665 [salt.state       :1780][INFO    ][13447] Running state [/etc/salt/grains.d/sphinx] at time 05:52:05.665553
2019-07-09 05:52:05,667 [salt.state       :1813][INFO    ][13447] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-07-09 05:52:05,669 [salt.state       :300 ][INFO    ][13447] File /etc/salt/grains.d/sphinx is in the correct state
2019-07-09 05:52:05,669 [salt.state       :1951][INFO    ][13447] Completed state [/etc/salt/grains.d/sphinx] at time 05:52:05.669537 duration_in_ms=3.984
2019-07-09 05:52:05,673 [salt.state       :1780][INFO    ][13447] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 05:52:05.673803
2019-07-09 05:52:05,674 [salt.state       :1813][INFO    ][13447] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-07-09 05:52:05,674 [salt.state       :300 ][INFO    ][13447] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-07-09 05:52:05,675 [salt.state       :1951][INFO    ][13447] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 05:52:05.675082 duration_in_ms=1.28
2019-07-09 05:52:05,675 [salt.state       :1780][INFO    ][13447] Running state [/etc/salt/grains.d/dns_records] at time 05:52:05.675816
2019-07-09 05:52:05,676 [salt.state       :1813][INFO    ][13447] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-07-09 05:52:05,679 [salt.state       :300 ][INFO    ][13447] File /etc/salt/grains.d/dns_records is in the correct state
2019-07-09 05:52:05,679 [salt.state       :1951][INFO    ][13447] Completed state [/etc/salt/grains.d/dns_records] at time 05:52:05.679840 duration_in_ms=4.025
2019-07-09 05:52:05,680 [salt.state       :1780][INFO    ][13447] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 05:52:05.680814
2019-07-09 05:52:05,681 [salt.state       :1813][INFO    ][13447] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-07-09 05:52:05,681 [salt.state       :300 ][INFO    ][13447] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-07-09 05:52:05,681 [salt.state       :1951][INFO    ][13447] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 05:52:05.681694 duration_in_ms=0.88
2019-07-09 05:52:05,682 [salt.state       :1780][INFO    ][13447] Running state [/etc/salt/grains.d/salt] at time 05:52:05.682232
2019-07-09 05:52:05,682 [salt.state       :1813][INFO    ][13447] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-07-09 05:52:05,683 [salt.state       :300 ][INFO    ][13447] File /etc/salt/grains.d/salt is in the correct state
2019-07-09 05:52:05,683 [salt.state       :1951][INFO    ][13447] Completed state [/etc/salt/grains.d/salt] at time 05:52:05.683843 duration_in_ms=1.611
2019-07-09 05:52:05,684 [salt.state       :1780][INFO    ][13447] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 05:52:05.684761
2019-07-09 05:52:05,685 [salt.state       :1813][INFO    ][13447] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-07-09 05:52:05,685 [salt.state       :300 ][INFO    ][13447] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-07-09 05:52:05,685 [salt.state       :1951][INFO    ][13447] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 05:52:05.685620 duration_in_ms=0.86
2019-07-09 05:52:05,687 [salt.state       :1780][INFO    ][13447] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 05:52:05.687629
2019-07-09 05:52:05,687 [salt.state       :1813][INFO    ][13447] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-07-09 05:52:05,688 [salt.state       :300 ][INFO    ][13447] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-07-09 05:52:05,688 [salt.state       :1951][INFO    ][13447] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 05:52:05.688495 duration_in_ms=0.866
2019-07-09 05:52:05,689 [salt.state       :1780][INFO    ][13447] Running state [mine.update] at time 05:52:05.689204
2019-07-09 05:52:05,689 [salt.state       :1813][INFO    ][13447] Executing state module.wait for [mine.update]
2019-07-09 05:52:05,690 [salt.state       :300 ][INFO    ][13447] No changes made for mine.update
2019-07-09 05:52:05,690 [salt.state       :1951][INFO    ][13447] Completed state [mine.update] at time 05:52:05.690334 duration_in_ms=1.13
2019-07-09 05:52:05,690 [salt.state       :1780][INFO    ][13447] Running state [ca-certificates] at time 05:52:05.690618
2019-07-09 05:52:05,690 [salt.state       :1813][INFO    ][13447] Executing state pkg.installed for [ca-certificates]
2019-07-09 05:52:05,701 [salt.state       :300 ][INFO    ][13447] All specified packages are already installed
2019-07-09 05:52:05,701 [salt.state       :1951][INFO    ][13447] Completed state [ca-certificates] at time 05:52:05.701492 duration_in_ms=10.874
2019-07-09 05:52:05,702 [salt.state       :1780][INFO    ][13447] Running state [update-ca-certificates] at time 05:52:05.702578
2019-07-09 05:52:05,702 [salt.state       :1813][INFO    ][13447] Executing state cmd.wait for [update-ca-certificates]
2019-07-09 05:52:05,703 [salt.state       :300 ][INFO    ][13447] No changes made for update-ca-certificates
2019-07-09 05:52:05,703 [salt.state       :1951][INFO    ][13447] Completed state [update-ca-certificates] at time 05:52:05.703396 duration_in_ms=0.817
2019-07-09 05:52:05,703 [salt.state       :1780][INFO    ][13447] Running state [iptables] at time 05:52:05.703678
2019-07-09 05:52:05,703 [salt.state       :1813][INFO    ][13447] Executing state pkg.installed for [iptables]
2019-07-09 05:52:05,714 [salt.state       :300 ][INFO    ][13447] All specified packages are already installed
2019-07-09 05:52:05,714 [salt.state       :1951][INFO    ][13447] Completed state [iptables] at time 05:52:05.714474 duration_in_ms=10.796
2019-07-09 05:52:05,714 [salt.state       :1780][INFO    ][13447] Running state [iptables-persistent] at time 05:52:05.714757
2019-07-09 05:52:05,715 [salt.state       :1813][INFO    ][13447] Executing state pkg.installed for [iptables-persistent]
2019-07-09 05:52:05,724 [salt.state       :300 ][INFO    ][13447] All specified packages are already installed
2019-07-09 05:52:05,724 [salt.state       :1951][INFO    ][13447] Completed state [iptables-persistent] at time 05:52:05.724852 duration_in_ms=10.095
2019-07-09 05:52:05,726 [salt.state       :1780][INFO    ][13447] Running state [iptables_modules_v4_load] at time 05:52:05.726464
2019-07-09 05:52:05,726 [salt.state       :1813][INFO    ][13447] Executing state kmod.present for [iptables_modules_v4_load]
2019-07-09 05:52:05,727 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13447] Executing command 'lsmod' in directory '/root'
2019-07-09 05:52:05,753 [salt.state       :300 ][INFO    ][13447] Kernel modules iptable_filter, ip_tables are already present
2019-07-09 05:52:05,754 [salt.state       :1951][INFO    ][13447] Completed state [iptables_modules_v4_load] at time 05:52:05.754534 duration_in_ms=28.069
2019-07-09 05:52:05,756 [salt.state       :1780][INFO    ][13447] Running state [/etc/iptables/rules.v4] at time 05:52:05.755899
2019-07-09 05:52:05,756 [salt.state       :1813][INFO    ][13447] Executing state file.managed for [/etc/iptables/rules.v4]
2019-07-09 05:52:05,879 [salt.state       :300 ][INFO    ][13447] File /etc/iptables/rules.v4 is in the correct state
2019-07-09 05:52:05,879 [salt.state       :1951][INFO    ][13447] Completed state [/etc/iptables/rules.v4] at time 05:52:05.879828 duration_in_ms=123.93
2019-07-09 05:52:05,881 [salt.state       :1780][INFO    ][13447] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 05:52:05.880996
2019-07-09 05:52:05,881 [salt.state       :1813][INFO    ][13447] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-07-09 05:52:05,882 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13447] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-07-09 05:52:05,902 [salt.state       :300 ][INFO    ][13447] onlyif execution failed
2019-07-09 05:52:05,903 [salt.state       :1951][INFO    ][13447] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 05:52:05.903436 duration_in_ms=22.439
2019-07-09 05:52:05,907 [salt.state       :1780][INFO    ][13447] Running state [netfilter-persistent] at time 05:52:05.905624
2019-07-09 05:52:05,908 [salt.state       :1813][INFO    ][13447] Executing state service.running for [netfilter-persistent]
2019-07-09 05:52:05,909 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13447] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-07-09 05:52:05,936 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13447] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-07-09 05:52:05,956 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13447] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-07-09 05:52:05,980 [salt.state       :300 ][INFO    ][13447] The service netfilter-persistent is already running
2019-07-09 05:52:05,980 [salt.state       :1951][INFO    ][13447] Completed state [netfilter-persistent] at time 05:52:05.980380 duration_in_ms=74.757
2019-07-09 05:52:05,981 [salt.state       :1780][INFO    ][13447] Running state [iptables_extra.remove_stale_tables] at time 05:52:05.981661
2019-07-09 05:52:05,982 [salt.state       :1813][INFO    ][13447] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-07-09 05:52:05,982 [salt.state       :300 ][INFO    ][13447] No changes made for iptables_extra.remove_stale_tables
2019-07-09 05:52:05,982 [salt.state       :1951][INFO    ][13447] Completed state [iptables_extra.remove_stale_tables] at time 05:52:05.982799 duration_in_ms=1.139
2019-07-09 05:52:05,983 [salt.state       :1780][INFO    ][13447] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 05:52:05.983132
2019-07-09 05:52:05,983 [salt.state       :1813][INFO    ][13447] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-07-09 05:52:05,984 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13447] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-07-09 05:52:06,005 [salt.state       :300 ][INFO    ][13447] onlyif execution failed
2019-07-09 05:52:06,006 [salt.state       :1951][INFO    ][13447] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 05:52:06.006758 duration_in_ms=23.625
2019-07-09 05:52:06,008 [salt.state       :1780][INFO    ][13447] Running state [/etc/iptables/rules.v6] at time 05:52:06.008509
2019-07-09 05:52:06,009 [salt.state       :1813][INFO    ][13447] Executing state file.absent for [/etc/iptables/rules.v6]
2019-07-09 05:52:06,009 [salt.state       :300 ][INFO    ][13447] File /etc/iptables/rules.v6 is not present
2019-07-09 05:52:06,010 [salt.state       :1951][INFO    ][13447] Completed state [/etc/iptables/rules.v6] at time 05:52:06.010213 duration_in_ms=1.705
2019-07-09 05:52:06,011 [salt.state       :1780][INFO    ][13447] Running state [iptables_extra.flush_all] at time 05:52:06.011486
2019-07-09 05:52:06,012 [salt.state       :1813][INFO    ][13447] Executing state module.wait for [iptables_extra.flush_all]
2019-07-09 05:52:06,012 [salt.state       :300 ][INFO    ][13447] No changes made for iptables_extra.flush_all
2019-07-09 05:52:06,012 [salt.state       :1951][INFO    ][13447] Completed state [iptables_extra.flush_all] at time 05:52:06.012837 duration_in_ms=1.352
2019-07-09 05:52:06,019 [salt.minion      :1711][INFO    ][13447] Returning information for job: 20190709055151973363
2019-07-09 05:52:06,740 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command state.apply with jid 20190709055206727128
2019-07-09 05:52:06,766 [salt.minion      :1432][INFO    ][13535] Starting a new job with PID 13535
2019-07-09 05:52:07,887 [salt.state       :915 ][INFO    ][13535] Loading fresh modules for state activity
2019-07-09 05:52:09,202 [salt.state       :1780][INFO    ][13535] Running state [maas-rack-controller] at time 05:52:09.202060
2019-07-09 05:52:09,202 [salt.state       :1813][INFO    ][13535] Executing state pkg.installed for [maas-rack-controller]
2019-07-09 05:52:09,203 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13535] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-07-09 05:52:09,335 [salt.state       :300 ][INFO    ][13535] All specified packages are already installed
2019-07-09 05:52:09,335 [salt.state       :1951][INFO    ][13535] Completed state [maas-rack-controller] at time 05:52:09.335646 duration_in_ms=133.586
2019-07-09 05:52:09,336 [salt.state       :1780][INFO    ][13535] Running state [ipmitool] at time 05:52:09.336018
2019-07-09 05:52:09,336 [salt.state       :1813][INFO    ][13535] Executing state pkg.installed for [ipmitool]
2019-07-09 05:52:09,346 [salt.state       :300 ][INFO    ][13535] All specified packages are already installed
2019-07-09 05:52:09,346 [salt.state       :1951][INFO    ][13535] Completed state [ipmitool] at time 05:52:09.346660 duration_in_ms=10.641
2019-07-09 05:52:09,350 [salt.state       :1780][INFO    ][13535] Running state [/etc/maas/rackd.conf] at time 05:52:09.349646
2019-07-09 05:52:09,350 [salt.state       :1813][INFO    ][13535] Executing state file.line for [/etc/maas/rackd.conf]
2019-07-09 05:52:09,351 [salt.state       :300 ][INFO    ][13535] No changes needed to be made
2019-07-09 05:52:09,352 [salt.state       :1951][INFO    ][13535] Completed state [/etc/maas/rackd.conf] at time 05:52:09.352089 duration_in_ms=2.443
2019-07-09 05:52:09,352 [salt.state       :1780][INFO    ][13535] Running state [/etc/maas/rackd.conf] at time 05:52:09.352372
2019-07-09 05:52:09,352 [salt.state       :1813][INFO    ][13535] Executing state file.managed for [/etc/maas/rackd.conf]
2019-07-09 05:52:09,353 [salt.loaded.int.states.file:2298][WARNING ][13535] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-07-09 05:52:09,353 [salt.state       :300 ][INFO    ][13535] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-07-09 05:52:09,354 [salt.state       :1951][INFO    ][13535] Completed state [/etc/maas/rackd.conf] at time 05:52:09.354181 duration_in_ms=1.81
2019-07-09 05:52:09,355 [salt.state       :1780][INFO    ][13535] Running state [maas-rackd] at time 05:52:09.355228
2019-07-09 05:52:09,355 [salt.state       :1813][INFO    ][13535] Executing state service.running for [maas-rackd]
2019-07-09 05:52:09,356 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13535] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-07-09 05:52:09,400 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13535] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-07-09 05:52:09,420 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13535] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-07-09 05:52:09,443 [salt.state       :300 ][INFO    ][13535] The service maas-rackd is already running
2019-07-09 05:52:09,444 [salt.state       :1951][INFO    ][13535] Completed state [maas-rackd] at time 05:52:09.444414 duration_in_ms=89.186
2019-07-09 05:52:09,448 [salt.minion      :1711][INFO    ][13535] Returning information for job: 20190709055206727128
2019-07-09 05:52:10,149 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command state.apply with jid 20190709055210138277
2019-07-09 05:52:10,173 [salt.minion      :1432][INFO    ][13560] Starting a new job with PID 13560
2019-07-09 05:52:11,359 [salt.state       :915 ][INFO    ][13560] Loading fresh modules for state activity
2019-07-09 05:52:12,799 [salt.state       :1780][INFO    ][13560] Running state [maas-region-controller] at time 05:52:12.799081
2019-07-09 05:52:12,799 [salt.state       :1813][INFO    ][13560] Executing state pkg.installed for [maas-region-controller]
2019-07-09 05:52:12,800 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13560] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-07-09 05:52:12,931 [salt.state       :300 ][INFO    ][13560] All specified packages are already installed
2019-07-09 05:52:12,932 [salt.state       :1951][INFO    ][13560] Completed state [maas-region-controller] at time 05:52:12.932015 duration_in_ms=132.935
2019-07-09 05:52:12,932 [salt.state       :1780][INFO    ][13560] Running state [python-oauth] at time 05:52:12.932415
2019-07-09 05:52:12,932 [salt.state       :1813][INFO    ][13560] Executing state pkg.installed for [python-oauth]
2019-07-09 05:52:12,946 [salt.state       :300 ][INFO    ][13560] All specified packages are already installed
2019-07-09 05:52:12,947 [salt.state       :1951][INFO    ][13560] Completed state [python-oauth] at time 05:52:12.947153 duration_in_ms=14.738
2019-07-09 05:52:12,952 [salt.state       :1780][INFO    ][13560] Running state [/etc/maas/regiond.conf] at time 05:52:12.952006
2019-07-09 05:52:12,952 [salt.state       :1813][INFO    ][13560] Executing state file.replace for [/etc/maas/regiond.conf]
2019-07-09 05:52:12,957 [salt.state       :300 ][INFO    ][13560] No changes needed to be made
2019-07-09 05:52:12,957 [salt.state       :1951][INFO    ][13560] Completed state [/etc/maas/regiond.conf] at time 05:52:12.957603 duration_in_ms=5.597
2019-07-09 05:52:12,958 [salt.state       :1780][INFO    ][13560] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:52:12.958448
2019-07-09 05:52:12,958 [salt.state       :1813][INFO    ][13560] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-07-09 05:52:13,031 [salt.state       :300 ][INFO    ][13560] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-07-09 05:52:13,032 [salt.state       :1951][INFO    ][13560] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:52:13.032147 duration_in_ms=73.699
2019-07-09 05:52:13,032 [salt.state       :1780][INFO    ][13560] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:52:13.032733
2019-07-09 05:52:13,033 [salt.state       :1813][INFO    ][13560] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-07-09 05:52:13,039 [salt.state       :300 ][INFO    ][13560] No changes needed to be made
2019-07-09 05:52:13,039 [salt.state       :1951][INFO    ][13560] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:52:13.039846 duration_in_ms=7.113
2019-07-09 05:52:13,040 [salt.state       :1780][INFO    ][13560] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:52:13.040376
2019-07-09 05:52:13,040 [salt.state       :1813][INFO    ][13560] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-07-09 05:52:13,045 [salt.state       :300 ][INFO    ][13560] No changes needed to be made
2019-07-09 05:52:13,046 [salt.state       :1951][INFO    ][13560] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:52:13.045691 duration_in_ms=5.315
2019-07-09 05:52:13,047 [salt.state       :1780][INFO    ][13560] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:52:13.047098
2019-07-09 05:52:13,047 [salt.state       :1813][INFO    ][13560] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-07-09 05:52:13,083 [salt.state       :300 ][INFO    ][13560] No changes needed to be made
2019-07-09 05:52:13,083 [salt.state       :1951][INFO    ][13560] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:52:13.083643 duration_in_ms=36.544
2019-07-09 05:52:13,084 [salt.state       :1780][INFO    ][13560] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:52:13.084228
2019-07-09 05:52:13,084 [salt.state       :1813][INFO    ][13560] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-07-09 05:52:13,101 [salt.state       :300 ][INFO    ][13560] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-07-09 05:52:13,101 [salt.state       :1951][INFO    ][13560] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:52:13.101499 duration_in_ms=17.271
2019-07-09 05:52:13,102 [salt.state       :1780][INFO    ][13560] Running state [a2enmod headers] at time 05:52:13.102867
2019-07-09 05:52:13,103 [salt.state       :1813][INFO    ][13560] Executing state cmd.run for [a2enmod headers]
2019-07-09 05:52:13,103 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13560] Executing command 'a2enmod headers' in directory '/root'
2019-07-09 05:52:13,177 [salt.state       :300 ][INFO    ][13560] {'pid': 13579, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-07-09 05:52:13,181 [salt.state       :1951][INFO    ][13560] Completed state [a2enmod headers] at time 05:52:13.181402 duration_in_ms=78.534
2019-07-09 05:52:13,182 [salt.state       :1780][INFO    ][13560] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:52:13.182032
2019-07-09 05:52:13,182 [salt.state       :1813][INFO    ][13560] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-07-09 05:52:13,207 [salt.state       :300 ][INFO    ][13560] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-07-09 05:52:13,208 [salt.state       :1951][INFO    ][13560] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:52:13.208354 duration_in_ms=26.322
2019-07-09 05:52:13,209 [salt.state       :1780][INFO    ][13560] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:52:13.209502
2019-07-09 05:52:13,210 [salt.state       :1813][INFO    ][13560] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-07-09 05:52:13,276 [salt.state       :300 ][INFO    ][13560] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-07-09 05:52:13,276 [salt.state       :1951][INFO    ][13560] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:52:13.276789 duration_in_ms=67.287
2019-07-09 05:52:13,277 [salt.state       :1780][INFO    ][13560] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:52:13.277338
2019-07-09 05:52:13,277 [salt.state       :1813][INFO    ][13560] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-07-09 05:52:13,341 [salt.state       :300 ][INFO    ][13560] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-07-09 05:52:13,341 [salt.state       :1951][INFO    ][13560] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:52:13.341568 duration_in_ms=64.23
2019-07-09 05:52:13,342 [salt.state       :1780][INFO    ][13560] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:52:13.342190
2019-07-09 05:52:13,342 [salt.state       :1813][INFO    ][13560] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-07-09 05:52:13,424 [salt.state       :300 ][INFO    ][13560] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-07-09 05:52:13,424 [salt.state       :1951][INFO    ][13560] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:52:13.424708 duration_in_ms=82.517
2019-07-09 05:52:13,425 [salt.state       :1780][INFO    ][13560] Running state [/root/.pgpass] at time 05:52:13.425264
2019-07-09 05:52:13,426 [salt.state       :1813][INFO    ][13560] Executing state file.managed for [/root/.pgpass]
2019-07-09 05:52:13,483 [salt.state       :300 ][INFO    ][13560] File /root/.pgpass is in the correct state
2019-07-09 05:52:13,483 [salt.state       :1951][INFO    ][13560] Completed state [/root/.pgpass] at time 05:52:13.483818 duration_in_ms=58.555
2019-07-09 05:52:13,489 [salt.state       :1780][INFO    ][13560] Running state [maas-region syncdb --noinput] at time 05:52:13.489149
2019-07-09 05:52:13,489 [salt.state       :1813][INFO    ][13560] Executing state cmd.run for [maas-region syncdb --noinput]
2019-07-09 05:52:13,491 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13560] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-07-09 05:52:16,322 [salt.state       :300 ][INFO    ][13560] {'pid': 13592, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: contenttypes, auth, piston3, metadataserver, sites, sessions, maasserver\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-07-09 05:52:16,323 [salt.state       :1951][INFO    ][13560] Completed state [maas-region syncdb --noinput] at time 05:52:16.323432 duration_in_ms=2834.282
2019-07-09 05:52:16,324 [salt.state       :2022][WARNING ][13560] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-07-09 05:52:16,328 [salt.state       :1780][INFO    ][13560] Running state [maas-regiond] at time 05:52:16.328184
2019-07-09 05:52:16,328 [salt.state       :1813][INFO    ][13560] Executing state service.running for [maas-regiond]
2019-07-09 05:52:16,330 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13560] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-07-09 05:52:16,377 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13560] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-07-09 05:52:16,400 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13560] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-07-09 05:52:16,420 [salt.state       :300 ][INFO    ][13560] The service maas-regiond is already running
2019-07-09 05:52:16,421 [salt.state       :1951][INFO    ][13560] Completed state [maas-regiond] at time 05:52:16.421264 duration_in_ms=93.08
2019-07-09 05:52:16,424 [salt.state       :1780][INFO    ][13560] Running state [bind9] at time 05:52:16.424616
2019-07-09 05:52:16,425 [salt.state       :1813][INFO    ][13560] Executing state service.running for [bind9]
2019-07-09 05:52:16,429 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13560] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-07-09 05:52:16,459 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13560] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-07-09 05:52:16,484 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13560] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-07-09 05:52:16,504 [salt.state       :300 ][INFO    ][13560] The service bind9 is already running
2019-07-09 05:52:16,505 [salt.state       :1951][INFO    ][13560] Completed state [bind9] at time 05:52:16.505163 duration_in_ms=80.536
2019-07-09 05:52:16,509 [salt.state       :1780][INFO    ][13560] Running state [apache2] at time 05:52:16.509388
2019-07-09 05:52:16,510 [salt.state       :1813][INFO    ][13560] Executing state service.running for [apache2]
2019-07-09 05:52:16,512 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13560] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-07-09 05:52:16,540 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13560] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-07-09 05:52:16,567 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13560] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-07-09 05:52:16,600 [salt.state       :300 ][INFO    ][13560] The service apache2 is already running
2019-07-09 05:52:16,601 [salt.state       :1951][INFO    ][13560] Completed state [apache2] at time 05:52:16.601144 duration_in_ms=91.757
2019-07-09 05:52:16,603 [salt.state       :1780][INFO    ][13560] Running state [maasng.wait_for_http_code] at time 05:52:16.603303
2019-07-09 05:52:16,603 [salt.state       :1813][INFO    ][13560] Executing state module.run for [maasng.wait_for_http_code]
2019-07-09 05:52:16,604 [salt.utils.decorators:613 ][WARNING ][13560] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-09 05:52:16,773 [salt.state       :300 ][INFO    ][13560] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-07-09 05:52:16,774 [salt.state       :1951][INFO    ][13560] Completed state [maasng.wait_for_http_code] at time 05:52:16.773672 duration_in_ms=170.368
2019-07-09 05:52:16,775 [salt.state       :1780][INFO    ][13560] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:52:16.775684
2019-07-09 05:52:16,776 [salt.state       :1813][INFO    ][13560] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-07-09 05:52:16,777 [salt.state       :300 ][INFO    ][13560] /var/lib/maas/.setup_admin exists
2019-07-09 05:52:16,780 [salt.state       :1951][INFO    ][13560] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:52:16.777644 duration_in_ms=1.96
2019-07-09 05:52:16,780 [salt.state       :1780][INFO    ][13560] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:52:16.780668
2019-07-09 05:52:16,781 [salt.state       :1813][INFO    ][13560] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-09 05:52:16,781 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13560] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-09 05:52:18,636 [salt.state       :300 ][INFO    ][13560] {'pid': 13613, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-09 05:52:18,639 [salt.state       :1951][INFO    ][13560] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:52:18.637644 duration_in_ms=1856.974
2019-07-09 05:52:18,651 [salt.state       :1780][INFO    ][13560] Running state [maas_region_boot_source_resources_mirror] at time 05:52:18.649607
2019-07-09 05:52:18,651 [salt.state       :1813][INFO    ][13560] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-07-09 05:52:18,744 [salt.state       :300 ][INFO    ][13560] {'changes': {}}
2019-07-09 05:52:18,744 [salt.state       :1951][INFO    ][13560] Completed state [maas_region_boot_source_resources_mirror] at time 05:52:18.744753 duration_in_ms=95.147
2019-07-09 05:52:18,747 [salt.state       :1780][INFO    ][13560] Running state [maasng.boot_resources_import] at time 05:52:18.747480
2019-07-09 05:52:18,748 [salt.state       :1813][INFO    ][13560] Executing state module.run for [maasng.boot_resources_import]
2019-07-09 05:52:18,748 [salt.utils.decorators:613 ][WARNING ][13560] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-09 05:52:18,840 [salt.loaded.ext.module.maasng:1600][INFO    ][13560] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-07-09 05:52:23,892 [salt.loaded.ext.module.maasng:1600][INFO    ][13560] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-07-09 05:52:25,205 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709055225191996
2019-07-09 05:52:25,229 [salt.minion      :1432][INFO    ][13648] Starting a new job with PID 13648
2019-07-09 05:52:25,260 [salt.minion      :1711][INFO    ][13648] Returning information for job: 20190709055225191996
2019-07-09 05:52:29,001 [salt.state       :300 ][INFO    ][13560] {'ret': True}
2019-07-09 05:52:29,001 [salt.state       :1951][INFO    ][13560] Completed state [maasng.boot_resources_import] at time 05:52:29.001483 duration_in_ms=10254.002
2019-07-09 05:52:29,003 [salt.state       :1780][INFO    ][13560] Running state [maas_region_boot_sources_selection_xenial] at time 05:52:29.003697
2019-07-09 05:52:29,004 [salt.state       :1813][INFO    ][13560] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-07-09 05:52:29,190 [salt.state       :300 ][INFO    ][13560] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-07-09 05:52:29,190 [salt.state       :1951][INFO    ][13560] Completed state [maas_region_boot_sources_selection_xenial] at time 05:52:29.190701 duration_in_ms=187.004
2019-07-09 05:52:29,192 [salt.state       :1780][INFO    ][13560] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 05:52:29.192111
2019-07-09 05:52:29,192 [salt.state       :1813][INFO    ][13560] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-07-09 05:52:29,193 [salt.utils.decorators:613 ][WARNING ][13560] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-09 05:52:29,194 [salt.loaded.ext.module.maasng:1771][INFO    ][13560] boot-sources sync initiated for ALL Rack's
2019-07-09 05:52:30,293 [salt.state       :300 ][INFO    ][13560] {'ret': True}
2019-07-09 05:52:30,295 [salt.state       :1951][INFO    ][13560] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 05:52:30.295165 duration_in_ms=1103.054
2019-07-09 05:52:30,297 [salt.state       :1780][INFO    ][13560] Running state [maas.process_maas_config] at time 05:52:30.297351
2019-07-09 05:52:30,298 [salt.state       :1813][INFO    ][13560] Executing state module.run for [maas.process_maas_config]
2019-07-09 05:52:30,299 [salt.utils.decorators:613 ][WARNING ][13560] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-09 05:52:30,300 [salt.loaded.ext.module.maas:92  ][INFO    ][13560] maasconfig name=enable_http_proxy value=True
2019-07-09 05:52:30,356 [salt.loaded.ext.module.maas:92  ][INFO    ][13560] maasconfig name=upstream_dns value=8.8.8.8
2019-07-09 05:52:30,415 [salt.loaded.ext.module.maas:92  ][INFO    ][13560] maasconfig name=commissioning_distro_series value=xenial
2019-07-09 05:52:30,479 [salt.loaded.ext.module.maas:92  ][INFO    ][13560] maasconfig name=default_osystem value=ubuntu
2019-07-09 05:52:30,546 [salt.loaded.ext.module.maas:92  ][INFO    ][13560] maasconfig name=active_discovery_interval value=600
2019-07-09 05:52:31,926 [salt.loaded.ext.module.maas:92  ][INFO    ][13560] maasconfig name=dnssec_validation value=no
2019-07-09 05:52:31,977 [salt.loaded.ext.module.maas:92  ][INFO    ][13560] maasconfig name=maas_name value=mas01
2019-07-09 05:52:32,031 [salt.loaded.ext.module.maas:92  ][INFO    ][13560] maasconfig name=network_discovery value=enabled
2019-07-09 05:52:32,129 [salt.loaded.ext.module.maas:92  ][INFO    ][13560] maasconfig name=enable_third_party_drivers value=True
2019-07-09 05:52:32,194 [salt.loaded.ext.module.maas:92  ][INFO    ][13560] maasconfig name=default_storage_layout value=lvm
2019-07-09 05:52:32,252 [salt.loaded.ext.module.maas:92  ][INFO    ][13560] maasconfig name=ntp_external_only value=True
2019-07-09 05:52:32,306 [salt.loaded.ext.module.maas:92  ][INFO    ][13560] maasconfig name=disk_erase_with_secure_erase value=False
2019-07-09 05:52:32,350 [salt.loaded.ext.module.maas:92  ][INFO    ][13560] maasconfig name=default_distro_series value=xenial
2019-07-09 05:52:32,424 [salt.loaded.ext.module.maas:92  ][INFO    ][13560] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-07-09 05:52:32,540 [salt.state       :300 ][INFO    ][13560] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-07-09 05:52:32,541 [salt.state       :1951][INFO    ][13560] Completed state [maas.process_maas_config] at time 05:52:32.541017 duration_in_ms=2243.666
2019-07-09 05:52:32,542 [salt.state       :1780][INFO    ][13560] Running state [pxe_admin] at time 05:52:32.542398
2019-07-09 05:52:32,543 [salt.state       :1813][INFO    ][13560] Executing state maasng.fabric_present for [pxe_admin]
2019-07-09 05:52:32,598 [salt.loaded.ext.module.maasng:945 ][INFO    ][13560] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'b7wtg6', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}]
2019-07-09 05:52:32,665 [salt.loaded.ext.module.maasng:1008][WARNING ][13560] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-07-09 05:52:32,665 [salt.loaded.ext.module.maasng:1011][WARNING ][13560] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-07-09 05:52:32,758 [salt.state       :300 ][INFO    ][13560] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-07-09 05:52:32,758 [salt.state       :1951][INFO    ][13560] Completed state [pxe_admin] at time 05:52:32.758463 duration_in_ms=216.065
2019-07-09 05:52:32,759 [salt.state       :1780][INFO    ][13560] Running state [vlan 0] at time 05:52:32.758928
2019-07-09 05:52:32,759 [salt.state       :1813][INFO    ][13560] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-07-09 05:52:32,817 [salt.loaded.ext.module.maasng:945 ][INFO    ][13560] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'b7wtg6', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u''}]
2019-07-09 05:52:32,924 [salt.loaded.ext.module.maasng:945 ][INFO    ][13560] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'b7wtg6', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-07-09 05:52:33,177 [salt.loaded.ext.module.maasng:945 ][INFO    ][13560] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'b7wtg6', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}]
2019-07-09 05:52:33,257 [salt.state       :300 ][INFO    ][13560] {'new': 'Vlan untagged was updated'}
2019-07-09 05:52:33,258 [salt.state       :1951][INFO    ][13560] Completed state [vlan 0] at time 05:52:33.258179 duration_in_ms=499.25
2019-07-09 05:52:33,259 [salt.state       :1780][INFO    ][13560] Running state [192.168.11.0/24] at time 05:52:33.259341
2019-07-09 05:52:33,259 [salt.state       :1813][INFO    ][13560] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-07-09 05:52:33,450 [salt.loaded.ext.module.maasng:945 ][INFO    ][13560] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'b7wtg6', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-07-09 05:52:33,450 [salt.loaded.ext.module.maasng:1235][WARNING ][13560] Ignoring parameter vlan:0
2019-07-09 05:52:33,515 [salt.state       :300 ][INFO    ][13560] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-07-09 05:52:33,515 [salt.state       :1951][INFO    ][13560] Completed state [192.168.11.0/24] at time 05:52:33.515850 duration_in_ms=256.509
2019-07-09 05:52:33,516 [salt.state       :1780][INFO    ][13560] Running state [maas_create_iprange_1] at time 05:52:33.516686
2019-07-09 05:52:33,517 [salt.state       :1813][INFO    ][13560] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-07-09 05:52:33,569 [salt.state       :300 ][INFO    ][13560] Iprange maas_create_iprange_1 already exist.
2019-07-09 05:52:33,570 [salt.state       :1951][INFO    ][13560] Completed state [maas_create_iprange_1] at time 05:52:33.570392 duration_in_ms=53.706
2019-07-09 05:52:33,570 [salt.state       :1780][INFO    ][13560] Running state [vlan 0] at time 05:52:33.570692
2019-07-09 05:52:33,571 [salt.state       :1813][INFO    ][13560] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-07-09 05:52:33,646 [salt.loaded.ext.module.maasng:945 ][INFO    ][13560] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'b7wtg6', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-07-09 05:52:33,745 [salt.loaded.ext.module.maasng:945 ][INFO    ][13560] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'b7wtg6', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u''}]
2019-07-09 05:52:34,043 [salt.loaded.ext.module.maasng:945 ][INFO    ][13560] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'b7wtg6', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-07-09 05:52:34,172 [salt.state       :300 ][INFO    ][13560] {'new': 'Vlan untagged was updated'}
2019-07-09 05:52:34,173 [salt.state       :1951][INFO    ][13560] Completed state [vlan 0] at time 05:52:34.173027 duration_in_ms=602.334
2019-07-09 05:52:34,174 [salt.state       :1780][INFO    ][13560] Running state [opnfv] at time 05:52:34.174532
2019-07-09 05:52:34,175 [salt.state       :1813][INFO    ][13560] Executing state maasng.sshkey_present for [opnfv]
2019-07-09 05:52:34,258 [salt.loaded.ext.module.maasng:1903][INFO    ][13560] [{u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b', u'keysource': u''}]
2019-07-09 05:52:34,259 [salt.state       :300 ][INFO    ][13560] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-07-09 05:52:34,259 [salt.state       :1951][INFO    ][13560] Completed state [opnfv] at time 05:52:34.259287 duration_in_ms=84.754
2019-07-09 05:52:34,260 [salt.state       :1780][INFO    ][13560] Running state [maas.process_tags] at time 05:52:34.260099
2019-07-09 05:52:34,260 [salt.state       :1813][INFO    ][13560] Executing state module.run for [maas.process_tags]
2019-07-09 05:52:34,261 [salt.utils.decorators:613 ][WARNING ][13560] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-09 05:52:34,335 [salt.loaded.ext.module.maas:92  ][INFO    ][13560] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-07-09 05:52:34,390 [salt.state       :300 ][INFO    ][13560] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-07-09 05:52:34,390 [salt.state       :1951][INFO    ][13560] Completed state [maas.process_tags] at time 05:52:34.390693 duration_in_ms=130.594
2019-07-09 05:52:34,396 [salt.minion      :1711][INFO    ][13560] Returning information for job: 20190709055210138277
2019-07-09 05:52:35,172 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command state.apply with jid 20190709055235165342
2019-07-09 05:52:35,194 [salt.minion      :1432][INFO    ][14017] Starting a new job with PID 14017
2019-07-09 05:52:43,471 [salt.state       :915 ][INFO    ][14017] Loading fresh modules for state activity
2019-07-09 05:52:43,588 [salt.state       :1780][INFO    ][14017] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:52:43.588836
2019-07-09 05:52:43,589 [salt.state       :1813][INFO    ][14017] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-09 05:52:43,591 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14017] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-09 05:52:45,452 [salt.state       :300 ][INFO    ][14017] {'pid': 14041, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-09 05:52:45,454 [salt.state       :1951][INFO    ][14017] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:52:45.453548 duration_in_ms=1864.71
2019-07-09 05:52:45,456 [salt.state       :1780][INFO    ][14017] Running state [maas.process_machines] at time 05:52:45.456741
2019-07-09 05:52:45,457 [salt.state       :1813][INFO    ][14017] Executing state module.run for [maas.process_machines]
2019-07-09 05:52:45,459 [salt.utils.decorators:613 ][WARNING ][14017] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-09 05:52:46,156 [salt.loaded.ext.module.maas:412 ][WARNING ][14017] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-09 05:52:46,157 [salt.loaded.ext.module.maas:92  ][INFO    ][14017] 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=tgrmf6 architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-09 05:52:47,397 [salt.loaded.ext.module.maas:412 ][WARNING ][14017] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-09 05:52:47,400 [salt.loaded.ext.module.maas:92  ][INFO    ][14017] 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=rp4b4m architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-09 05:52:48,617 [salt.loaded.ext.module.maas:412 ][WARNING ][14017] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-09 05:52:48,619 [salt.loaded.ext.module.maas:92  ][INFO    ][14017] 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=8sgfm8 architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-09 05:52:49,911 [salt.loaded.ext.module.maas:412 ][WARNING ][14017] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-09 05:52:49,912 [salt.loaded.ext.module.maas:92  ][INFO    ][14017] 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=dggwqa architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-09 05:52:50,228 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709055250209223
2019-07-09 05:52:50,265 [salt.minion      :1432][INFO    ][14194] Starting a new job with PID 14194
2019-07-09 05:52:50,309 [salt.minion      :1711][INFO    ][14194] Returning information for job: 20190709055250209223
2019-07-09 05:52:51,184 [salt.loaded.ext.module.maas:412 ][WARNING ][14017] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-09 05:52:51,185 [salt.loaded.ext.module.maas:92  ][INFO    ][14017] 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=7es4pk architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-09 05:52:52,389 [salt.state       :300 ][INFO    ][14017] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-07-09 05:52:52,389 [salt.state       :1951][INFO    ][14017] Completed state [maas.process_machines] at time 05:52:52.389553 duration_in_ms=6932.81
2019-07-09 05:52:52,396 [salt.minion      :1711][INFO    ][14017] Returning information for job: 20190709055235165342
2019-07-09 05:53:26,339 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command state.apply with jid 20190709055326323902
2019-07-09 05:53:26,367 [salt.minion      :1432][INFO    ][14307] Starting a new job with PID 14307
2019-07-09 05:53:34,580 [salt.state       :915 ][INFO    ][14307] Loading fresh modules for state activity
2019-07-09 05:53:34,692 [salt.state       :1780][INFO    ][14307] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:53:34.692289
2019-07-09 05:53:34,693 [salt.state       :1813][INFO    ][14307] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-09 05:53:34,696 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14307] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-09 05:53:36,687 [salt.state       :300 ][INFO    ][14307] {'pid': 14329, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-09 05:53:36,688 [salt.state       :1951][INFO    ][14307] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:53:36.688002 duration_in_ms=1995.713
2019-07-09 05:53:36,691 [salt.state       :1780][INFO    ][14307] Running state [maas.wait_for_machine_status] at time 05:53:36.691195
2019-07-09 05:53:36,691 [salt.state       :1813][INFO    ][14307] Executing state module.run for [maas.wait_for_machine_status]
2019-07-09 05:53:36,692 [salt.utils.decorators:613 ][WARNING ][14307] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-09 05:53:39,443 [salt.state       :300 ][INFO    ][14307] {'ret': True}
2019-07-09 05:53:39,444 [salt.state       :1951][INFO    ][14307] Completed state [maas.wait_for_machine_status] at time 05:53:39.444279 duration_in_ms=2753.082
2019-07-09 05:53:39,449 [salt.minion      :1711][INFO    ][14307] Returning information for job: 20190709055326323902
2019-07-09 05:53:40,148 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command state.apply with jid 20190709055340134856
2019-07-09 05:53:40,175 [salt.minion      :1432][INFO    ][14345] Starting a new job with PID 14345
2019-07-09 05:53:41,284 [salt.state       :915 ][INFO    ][14345] Loading fresh modules for state activity
2019-07-09 05:53:41,438 [salt.state       :1780][INFO    ][14345] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:53:41.438862
2019-07-09 05:53:41,439 [salt.state       :1813][INFO    ][14345] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-09 05:53:41,441 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14345] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-09 05:53:43,304 [salt.state       :300 ][INFO    ][14345] {'pid': 14352, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-09 05:53:43,305 [salt.state       :1951][INFO    ][14345] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:53:43.305369 duration_in_ms=1866.506
2019-07-09 05:53:43,311 [salt.state       :1780][INFO    ][14345] Running state [maas_machines_storage_cmp002_lvm] at time 05:53:43.311707
2019-07-09 05:53:43,312 [salt.state       :1813][INFO    ][14345] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-07-09 05:53:43,871 [salt.state       :300 ][INFO    ][14345] Machine cmp002 is not in Ready state.
2019-07-09 05:53:43,871 [salt.state       :1951][INFO    ][14345] Completed state [maas_machines_storage_cmp002_lvm] at time 05:53:43.871447 duration_in_ms=559.738
2019-07-09 05:53:43,872 [salt.state       :1780][INFO    ][14345] Running state [maas_machines_storage_cmp001_lvm] at time 05:53:43.872078
2019-07-09 05:53:43,872 [salt.state       :1813][INFO    ][14345] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-07-09 05:53:44,428 [salt.state       :300 ][INFO    ][14345] Machine cmp001 is not in Ready state.
2019-07-09 05:53:44,429 [salt.state       :1951][INFO    ][14345] Completed state [maas_machines_storage_cmp001_lvm] at time 05:53:44.429122 duration_in_ms=557.043
2019-07-09 05:53:44,434 [salt.minion      :1711][INFO    ][14345] Returning information for job: 20190709055340134856
2019-07-09 05:53:45,141 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command state.apply with jid 20190709055345126408
2019-07-09 05:53:45,168 [salt.minion      :1432][INFO    ][14362] Starting a new job with PID 14362
2019-07-09 05:53:46,302 [salt.state       :915 ][INFO    ][14362] Loading fresh modules for state activity
2019-07-09 05:53:46,411 [salt.state       :1780][INFO    ][14362] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:53:46.411657
2019-07-09 05:53:46,412 [salt.state       :1813][INFO    ][14362] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-09 05:53:46,413 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14362] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-09 05:53:48,381 [salt.state       :300 ][INFO    ][14362] {'pid': 14369, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-09 05:53:48,383 [salt.state       :1951][INFO    ][14362] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:53:48.382930 duration_in_ms=1971.272
2019-07-09 05:53:48,386 [salt.state       :1780][INFO    ][14362] Running state [maas.deploy_machines] at time 05:53:48.386710
2019-07-09 05:53:48,387 [salt.state       :1813][INFO    ][14362] Executing state module.run for [maas.deploy_machines]
2019-07-09 05:53:48,388 [salt.utils.decorators:613 ][WARNING ][14362] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-09 05:53:48,957 [salt.loaded.ext.module.maas:684 ][INFO    ][14362] deploymachines hwe_kernel=hwe-16.04 system_id=7es4pk distro_series=xenial
2019-07-09 05:53:51,540 [salt.state       :300 ][INFO    ][14362] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-07-09 05:53:51,541 [salt.state       :1951][INFO    ][14362] Completed state [maas.deploy_machines] at time 05:53:51.541001 duration_in_ms=3154.291
2019-07-09 05:53:51,551 [salt.minion      :1711][INFO    ][14362] Returning information for job: 20190709055345126408
2019-07-09 05:53:52,323 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command state.apply with jid 20190709055352313524
2019-07-09 05:53:52,342 [salt.minion      :1432][INFO    ][14452] Starting a new job with PID 14452
2019-07-09 05:54:00,723 [salt.state       :915 ][INFO    ][14452] Loading fresh modules for state activity
2019-07-09 05:54:00,835 [salt.state       :1780][INFO    ][14452] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:54:00.835061
2019-07-09 05:54:00,835 [salt.state       :1813][INFO    ][14452] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-09 05:54:00,837 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14452] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-09 05:54:02,719 [salt.state       :300 ][INFO    ][14452] {'pid': 14472, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-09 05:54:02,720 [salt.state       :1951][INFO    ][14452] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:54:02.720536 duration_in_ms=1885.474
2019-07-09 05:54:02,723 [salt.state       :1780][INFO    ][14452] Running state [maas.wait_for_machine_status] at time 05:54:02.723652
2019-07-09 05:54:02,724 [salt.state       :1813][INFO    ][14452] Executing state module.run for [maas.wait_for_machine_status]
2019-07-09 05:54:02,725 [salt.utils.decorators:613 ][WARNING ][14452] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-09 05:54:05,677 [salt.loaded.ext.module.maas:1023][INFO    ][14452] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.06342316s left)
2019-07-09 05:54:07,450 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709055407399124
2019-07-09 05:54:07,481 [salt.minion      :1432][INFO    ][14489] Starting a new job with PID 14489
2019-07-09 05:54:07,511 [salt.minion      :1711][INFO    ][14489] Returning information for job: 20190709055407399124
2019-07-09 05:54:37,534 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709055437525495
2019-07-09 05:54:37,555 [salt.minion      :1432][INFO    ][14522] Starting a new job with PID 14522
2019-07-09 05:54:37,584 [salt.minion      :1711][INFO    ][14522] Returning information for job: 20190709055437525495
2019-07-09 05:54:38,504 [salt.loaded.ext.module.maas:1023][INFO    ][14452] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.23632216s left)
2019-07-09 05:55:07,615 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709055507605288
2019-07-09 05:55:07,638 [salt.minion      :1432][INFO    ][14585] Starting a new job with PID 14585
2019-07-09 05:55:07,667 [salt.minion      :1711][INFO    ][14585] Returning information for job: 20190709055507605288
2019-07-09 05:55:11,357 [salt.loaded.ext.module.maas:1023][INFO    ][14452] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2181.38374305s left)
2019-07-09 05:55:37,703 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709055537689458
2019-07-09 05:55:37,733 [salt.minion      :1432][INFO    ][14614] Starting a new job with PID 14614
2019-07-09 05:55:37,763 [salt.minion      :1711][INFO    ][14614] Returning information for job: 20190709055537689458
2019-07-09 05:55:44,177 [salt.loaded.ext.module.maas:1023][INFO    ][14452] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2148.56367517s left)
2019-07-09 05:56:07,804 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709055607789191
2019-07-09 05:56:07,830 [salt.minion      :1432][INFO    ][14663] Starting a new job with PID 14663
2019-07-09 05:56:07,862 [salt.minion      :1711][INFO    ][14663] Returning information for job: 20190709055607789191
2019-07-09 05:56:16,939 [salt.loaded.ext.module.maas:1023][INFO    ][14452] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2115.8013742s left)
2019-07-09 05:56:37,883 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709055637863652
2019-07-09 05:56:37,913 [salt.minion      :1432][INFO    ][14694] Starting a new job with PID 14694
2019-07-09 05:56:37,950 [salt.minion      :1711][INFO    ][14694] Returning information for job: 20190709055637863652
2019-07-09 05:56:49,680 [salt.loaded.ext.module.maas:1023][INFO    ][14452] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2083.06037617s left)
2019-07-09 05:57:07,999 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709055707984021
2019-07-09 05:57:08,022 [salt.minion      :1432][INFO    ][14743] Starting a new job with PID 14743
2019-07-09 05:57:08,052 [salt.minion      :1711][INFO    ][14743] Returning information for job: 20190709055707984021
2019-07-09 05:57:22,453 [salt.loaded.ext.module.maas:1023][INFO    ][14452] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2050.28774619s left)
2019-07-09 05:57:38,105 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709055738086683
2019-07-09 05:57:38,134 [salt.minion      :1432][INFO    ][14773] Starting a new job with PID 14773
2019-07-09 05:57:38,167 [salt.minion      :1711][INFO    ][14773] Returning information for job: 20190709055738086683
2019-07-09 05:57:55,648 [salt.loaded.ext.module.maas:1023][INFO    ][14452] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2017.09265804s left)
2019-07-09 05:58:08,224 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709055808214321
2019-07-09 05:58:08,241 [salt.minion      :1432][INFO    ][14845] Starting a new job with PID 14845
2019-07-09 05:58:08,280 [salt.minion      :1711][INFO    ][14845] Returning information for job: 20190709055808214321
2019-07-09 05:58:28,562 [salt.loaded.ext.module.maas:1023][INFO    ][14452] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1984.17792916s left)
2019-07-09 05:58:38,333 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709055838318962
2019-07-09 05:58:38,363 [salt.minion      :1432][INFO    ][14879] Starting a new job with PID 14879
2019-07-09 05:58:38,396 [salt.minion      :1711][INFO    ][14879] Returning information for job: 20190709055838318962
2019-07-09 05:59:01,535 [salt.loaded.ext.module.maas:1023][INFO    ][14452] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1951.20519018s left)
2019-07-09 05:59:08,480 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709055908467271
2019-07-09 05:59:08,509 [salt.minion      :1432][INFO    ][15008] Starting a new job with PID 15008
2019-07-09 05:59:08,543 [salt.minion      :1711][INFO    ][15008] Returning information for job: 20190709055908467271
2019-07-09 05:59:34,439 [salt.loaded.ext.module.maas:1023][INFO    ][14452] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1918.30107808s left)
2019-07-09 05:59:38,598 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709055938585156
2019-07-09 05:59:38,623 [salt.minion      :1432][INFO    ][15041] Starting a new job with PID 15041
2019-07-09 05:59:38,659 [salt.minion      :1711][INFO    ][15041] Returning information for job: 20190709055938585156
2019-07-09 06:00:07,488 [salt.loaded.ext.module.maas:1023][INFO    ][14452] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1885.25213003s left)
2019-07-09 06:00:08,742 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709060008729431
2019-07-09 06:00:08,772 [salt.minion      :1432][INFO    ][15181] Starting a new job with PID 15181
2019-07-09 06:00:08,804 [salt.minion      :1711][INFO    ][15181] Returning information for job: 20190709060008729431
2019-07-09 06:00:38,885 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709060038867340
2019-07-09 06:00:38,913 [salt.minion      :1432][INFO    ][15213] Starting a new job with PID 15213
2019-07-09 06:00:38,944 [salt.minion      :1711][INFO    ][15213] Returning information for job: 20190709060038867340
2019-07-09 06:00:40,340 [salt.loaded.ext.module.maas:1023][INFO    ][14452] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1852.40017819s left)
2019-07-09 06:01:09,048 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709060109032051
2019-07-09 06:01:09,078 [salt.minion      :1432][INFO    ][15262] Starting a new job with PID 15262
2019-07-09 06:01:09,111 [salt.minion      :1711][INFO    ][15262] Returning information for job: 20190709060109032051
2019-07-09 06:01:13,313 [salt.loaded.ext.module.maas:1023][INFO    ][14452] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1819.42765212s left)
2019-07-09 06:01:39,212 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709060139202590
2019-07-09 06:01:39,235 [salt.minion      :1432][INFO    ][15297] Starting a new job with PID 15297
2019-07-09 06:01:39,264 [salt.minion      :1711][INFO    ][15297] Returning information for job: 20190709060139202590
2019-07-09 06:01:46,210 [salt.loaded.ext.module.maas:1023][INFO    ][14452] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1786.53064919s left)
2019-07-09 06:02:09,372 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709060209358584
2019-07-09 06:02:09,401 [salt.minion      :1432][INFO    ][15500] Starting a new job with PID 15500
2019-07-09 06:02:09,432 [salt.minion      :1711][INFO    ][15500] Returning information for job: 20190709060209358584
2019-07-09 06:02:19,210 [salt.loaded.ext.module.maas:1023][INFO    ][14452] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1753.53017211s left)
2019-07-09 06:02:39,561 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709060239543966
2019-07-09 06:02:39,591 [salt.minion      :1432][INFO    ][15532] Starting a new job with PID 15532
2019-07-09 06:02:39,623 [salt.minion      :1711][INFO    ][15532] Returning information for job: 20190709060239543966
2019-07-09 06:02:52,171 [salt.loaded.ext.module.maas:1023][INFO    ][14452] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1720.5688231s left)
2019-07-09 06:03:09,748 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709060309731024
2019-07-09 06:03:09,777 [salt.minion      :1432][INFO    ][15609] Starting a new job with PID 15609
2019-07-09 06:03:09,808 [salt.minion      :1711][INFO    ][15609] Returning information for job: 20190709060309731024
2019-07-09 06:03:25,020 [salt.loaded.ext.module.maas:1023][INFO    ][14452] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1687.72052503s left)
2019-07-09 06:03:39,932 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709060339921582
2019-07-09 06:03:39,960 [salt.minion      :1432][INFO    ][15638] Starting a new job with PID 15638
2019-07-09 06:03:39,990 [salt.minion      :1711][INFO    ][15638] Returning information for job: 20190709060339921582
2019-07-09 06:03:58,043 [salt.loaded.ext.module.maas:1023][INFO    ][14452] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1654.69714308s left)
2019-07-09 06:04:10,108 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709060410092360
2019-07-09 06:04:10,139 [salt.minion      :1432][INFO    ][15684] Starting a new job with PID 15684
2019-07-09 06:04:10,169 [salt.minion      :1711][INFO    ][15684] Returning information for job: 20190709060410092360
2019-07-09 06:04:31,257 [salt.loaded.ext.module.maas:1023][INFO    ][14452] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1621.48376918s left)
2019-07-09 06:04:40,300 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709060440283090
2019-07-09 06:04:40,328 [salt.minion      :1432][INFO    ][15720] Starting a new job with PID 15720
2019-07-09 06:04:40,356 [salt.minion      :1711][INFO    ][15720] Returning information for job: 20190709060440283090
2019-07-09 06:05:04,357 [salt.loaded.ext.module.maas:1023][INFO    ][14452] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1588.38318801s left)
2019-07-09 06:05:10,475 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709060510461101
2019-07-09 06:05:10,505 [salt.minion      :1432][INFO    ][15932] Starting a new job with PID 15932
2019-07-09 06:05:10,534 [salt.minion      :1711][INFO    ][15932] Returning information for job: 20190709060510461101
2019-07-09 06:05:37,173 [salt.loaded.ext.module.maas:1023][INFO    ][14452] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1555.56738305s left)
2019-07-09 06:05:40,676 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709060540666727
2019-07-09 06:05:40,708 [salt.minion      :1432][INFO    ][15964] Starting a new job with PID 15964
2019-07-09 06:05:40,740 [salt.minion      :1711][INFO    ][15964] Returning information for job: 20190709060540666727
2019-07-09 06:05:45,324 [salt.utils.schedule:1377][INFO    ][5328] Running scheduled job: __mine_interval
2019-07-09 06:06:10,000 [salt.loaded.ext.module.maas:1023][INFO    ][14452] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1522.74064016s left)
2019-07-09 06:06:10,891 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709060610878997
2019-07-09 06:06:10,917 [salt.minion      :1432][INFO    ][16020] Starting a new job with PID 16020
2019-07-09 06:06:10,943 [salt.minion      :1711][INFO    ][16020] Returning information for job: 20190709060610878997
2019-07-09 06:06:41,094 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709060641086133
2019-07-09 06:06:41,111 [salt.minion      :1432][INFO    ][16047] Starting a new job with PID 16047
2019-07-09 06:06:41,146 [salt.minion      :1711][INFO    ][16047] Returning information for job: 20190709060641086133
2019-07-09 06:06:42,671 [salt.loaded.ext.module.maas:1023][INFO    ][14452] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1490.06946802s left)
2019-07-09 06:07:11,193 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command saltutil.find_job with jid 20190709060711184369
2019-07-09 06:07:11,215 [salt.minion      :1432][INFO    ][16133] Starting a new job with PID 16133
2019-07-09 06:07:11,242 [salt.minion      :1711][INFO    ][16133] Returning information for job: 20190709060711184369
2019-07-09 06:07:15,711 [salt.state       :300 ][INFO    ][14452] {'ret': True}
2019-07-09 06:07:15,711 [salt.state       :1951][INFO    ][14452] Completed state [maas.wait_for_machine_status] at time 06:07:15.711809 duration_in_ms=792988.153
2019-07-09 06:07:15,718 [salt.minion      :1711][INFO    ][14452] Returning information for job: 20190709055352313524
2019-07-09 07:05:45,323 [salt.utils.schedule:1377][INFO    ][5328] Running scheduled job: __mine_interval
2019-07-09 07:55:34,522 [salt.minion      :1308][INFO    ][5328] User sudo_ubuntu Executing command cp.push_dir with jid 20190709075534507573
2019-07-09 07:55:34,545 [salt.minion      :1432][INFO    ][23703] Starting a new job with PID 23703
