2019-06-30 20:10:37,883 [salt.minion      :870 ][ERROR   ][388] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-06-30 20:12:03,239 [salt.utils.decorators:613 ][WARNING ][2110] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-30 20:12:04,367 [salt.utils.decorators:613 ][WARNING ][2110] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-30 20:12:08,282 [salt.loaded.int.states.file:2298][WARNING ][2477] 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-06-30 20:12:36,028 [salt.state       :2022][WARNING ][2865] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-06-30 20:12:39,309 [salt.utils.decorators:613 ][WARNING ][2865] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-30 20:12:50,264 [salt.utils.decorators:613 ][WARNING ][2865] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-30 20:13:17,239 [salt.utils.decorators:613 ][WARNING ][2865] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-30 20:13:18,217 [salt.utils.decorators:613 ][WARNING ][2865] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-30 20:13:20,984 [salt.loaded.ext.module.maasng:1008][WARNING ][2865] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-06-30 20:13:20,984 [salt.loaded.ext.module.maasng:1011][WARNING ][2865] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-06-30 20:13:21,669 [salt.loaded.ext.module.maasng:1235][WARNING ][2865] Ignoring parameter vlan:0
2019-06-30 20:13:25,853 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5074] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-06-30 20:13:25,889 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5074] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-06-30 20:13:25,935 [salt.utils.parsers:1051][WARNING ][388] Minion received a SIGTERM. Exiting.
2019-06-30 20:13:27,084 [salt.cli.daemons :293 ][INFO    ][5150] Setting up the Salt Minion "mas01.mcp-ovs-ha.local"
2019-06-30 20:13:27,264 [salt.cli.daemons :82  ][INFO    ][5150] Starting up the Salt Minion
2019-06-30 20:13:27,264 [salt.utils.event :1017][INFO    ][5150] Starting pull socket on /var/run/salt/minion/minion_event_501f9ec045_pull.ipc
2019-06-30 20:13:28,565 [salt.minion      :976 ][INFO    ][5150] Creating minion process manager
2019-06-30 20:13:30,757 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][5150] Executing command ['date', '+%z'] in directory '/root'
2019-06-30 20:13:30,787 [salt.utils.schedule:568 ][INFO    ][5150] Updating job settings for scheduled job: __mine_interval
2019-06-30 20:13:30,789 [salt.minion      :1108][INFO    ][5150] Added mine.update to scheduler
2019-06-30 20:13:30,795 [salt.minion      :1975][INFO    ][5150] Minion is starting as user 'root'
2019-06-30 20:13:30,812 [salt.minion      :2336][INFO    ][5150] Minion is ready to receive requests!
2019-06-30 20:13:34,455 [salt.utils.decorators:613 ][WARNING ][5084] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-30 20:13:34,523 [salt.loaded.ext.module.maas:412 ][WARNING ][5084] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-30 20:13:35,976 [salt.loaded.ext.module.maas:412 ][WARNING ][5084] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-30 20:13:37,296 [salt.loaded.ext.module.maas:412 ][WARNING ][5084] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-30 20:13:38,617 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630201338603939
2019-06-30 20:13:38,641 [salt.minion      :1432][INFO    ][5391] Starting a new job with PID 5391
2019-06-30 20:13:38,677 [salt.minion      :1711][INFO    ][5391] Returning information for job: 20190630201338603939
2019-06-30 20:13:38,800 [salt.loaded.ext.module.maas:412 ][WARNING ][5084] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-30 20:13:40,344 [salt.loaded.ext.module.maas:412 ][WARNING ][5084] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-30 20:14:13,339 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command state.apply with jid 20190630201413326055
2019-06-30 20:14:13,364 [salt.minion      :1432][INFO    ][5574] Starting a new job with PID 5574
2019-06-30 20:14:21,547 [salt.state       :915 ][INFO    ][5574] Loading fresh modules for state activity
2019-06-30 20:14:21,604 [salt.fileclient  :1219][INFO    ][5574] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-06-30 20:14:21,667 [salt.state       :1780][INFO    ][5574] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:14:21.667251
2019-06-30 20:14:21,667 [salt.state       :1813][INFO    ][5574] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-30 20:14:21,669 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5574] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-30 20:14:23,601 [salt.state       :300 ][INFO    ][5574] {'pid': 5581, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-30 20:14:23,602 [salt.state       :1951][INFO    ][5574] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:14:23.602826 duration_in_ms=1935.575
2019-06-30 20:14:23,606 [salt.state       :1780][INFO    ][5574] Running state [maas.wait_for_machine_status] at time 20:14:23.606519
2019-06-30 20:14:23,607 [salt.state       :1813][INFO    ][5574] Executing state module.run for [maas.wait_for_machine_status]
2019-06-30 20:14:23,607 [salt.utils.decorators:613 ][WARNING ][5574] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-30 20:14:24,444 [salt.loaded.ext.module.maas:1023][INFO    ][5574] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.18138599s left)
2019-06-30 20:14:28,409 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630201428396201
2019-06-30 20:14:28,435 [salt.minion      :1432][INFO    ][5606] Starting a new job with PID 5606
2019-06-30 20:14:28,467 [salt.minion      :1711][INFO    ][5606] Returning information for job: 20190630201428396201
2019-06-30 20:14:55,307 [salt.loaded.ext.module.maas:1023][INFO    ][5574] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.31849217s left)
2019-06-30 20:14:58,500 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630201458484378
2019-06-30 20:14:58,527 [salt.minion      :1432][INFO    ][5653] Starting a new job with PID 5653
2019-06-30 20:14:58,559 [salt.minion      :1711][INFO    ][5653] Returning information for job: 20190630201458484378
2019-06-30 20:15:26,193 [salt.loaded.ext.module.maas:1023][INFO    ][5574] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.43225813s left)
2019-06-30 20:15:28,615 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630201528603239
2019-06-30 20:15:28,640 [salt.minion      :1432][INFO    ][5683] Starting a new job with PID 5683
2019-06-30 20:15:28,671 [salt.minion      :1711][INFO    ][5683] Returning information for job: 20190630201528603239
2019-06-30 20:15:57,029 [salt.loaded.ext.module.maas:1023][INFO    ][5574] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.59587598s left)
2019-06-30 20:15:58,690 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630201558676970
2019-06-30 20:15:58,711 [salt.minion      :1432][INFO    ][5732] Starting a new job with PID 5732
2019-06-30 20:15:58,741 [salt.minion      :1711][INFO    ][5732] Returning information for job: 20190630201558676970
2019-06-30 20:16:28,161 [salt.loaded.ext.module.maas:1023][INFO    ][5574] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.46421409s left)
2019-06-30 20:16:28,775 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630201628763635
2019-06-30 20:16:28,793 [salt.minion      :1432][INFO    ][5819] Starting a new job with PID 5819
2019-06-30 20:16:28,821 [salt.minion      :1711][INFO    ][5819] Returning information for job: 20190630201628763635
2019-06-30 20:16:58,885 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630201658867587
2019-06-30 20:16:58,917 [salt.minion      :1432][INFO    ][5966] Starting a new job with PID 5966
2019-06-30 20:16:58,949 [salt.minion      :1711][INFO    ][5966] Returning information for job: 20190630201658867587
2019-06-30 20:16:59,328 [salt.loaded.ext.module.maas:1023][INFO    ][5574] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.29693818s left)
2019-06-30 20:17:29,004 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630201728991779
2019-06-30 20:17:29,035 [salt.minion      :1432][INFO    ][6044] Starting a new job with PID 6044
2019-06-30 20:17:29,067 [salt.minion      :1711][INFO    ][6044] Returning information for job: 20190630201728991779
2019-06-30 20:17:30,499 [salt.loaded.ext.module.maas:1023][INFO    ][5574] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1313.12597704s left)
2019-06-30 20:17:59,142 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630201759130665
2019-06-30 20:17:59,156 [salt.minion      :1432][INFO    ][6333] Starting a new job with PID 6333
2019-06-30 20:17:59,189 [salt.minion      :1711][INFO    ][6333] Returning information for job: 20190630201759130665
2019-06-30 20:18:01,795 [salt.loaded.ext.module.maas:1023][INFO    ][5574] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1281.82983518s left)
2019-06-30 20:18:29,257 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630201829245438
2019-06-30 20:18:29,277 [salt.minion      :1432][INFO    ][6470] Starting a new job with PID 6470
2019-06-30 20:18:29,310 [salt.minion      :1711][INFO    ][6470] Returning information for job: 20190630201829245438
2019-06-30 20:18:33,616 [salt.loaded.ext.module.maas:1023][INFO    ][5574] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1250.00893402s left)
2019-06-30 20:18:59,427 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630201859413331
2019-06-30 20:18:59,457 [salt.minion      :1432][INFO    ][6774] Starting a new job with PID 6774
2019-06-30 20:18:59,489 [salt.minion      :1711][INFO    ][6774] Returning information for job: 20190630201859413331
2019-06-30 20:19:05,662 [salt.loaded.ext.module.maas:1023][INFO    ][5574] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1217.96265316s left)
2019-06-30 20:19:29,569 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630201929553323
2019-06-30 20:19:29,598 [salt.minion      :1432][INFO    ][6903] Starting a new job with PID 6903
2019-06-30 20:19:29,626 [salt.minion      :1711][INFO    ][6903] Returning information for job: 20190630201929553323
2019-06-30 20:19:38,278 [salt.loaded.ext.module.maas:1023][INFO    ][5574] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1185.34784412s left)
2019-06-30 20:19:59,746 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630201959730970
2019-06-30 20:19:59,777 [salt.minion      :1432][INFO    ][7173] Starting a new job with PID 7173
2019-06-30 20:19:59,808 [salt.minion      :1711][INFO    ][7173] Returning information for job: 20190630201959730970
2019-06-30 20:20:11,334 [salt.loaded.ext.module.maas:1023][INFO    ][5574] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1152.29175115s left)
2019-06-30 20:20:29,902 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630202029889625
2019-06-30 20:20:29,934 [salt.minion      :1432][INFO    ][7336] Starting a new job with PID 7336
2019-06-30 20:20:29,968 [salt.minion      :1711][INFO    ][7336] Returning information for job: 20190630202029889625
2019-06-30 20:20:44,339 [salt.state       :300 ][INFO    ][5574] {'ret': True}
2019-06-30 20:20:44,340 [salt.state       :1951][INFO    ][5574] Completed state [maas.wait_for_machine_status] at time 20:20:44.340049 duration_in_ms=380733.529
2019-06-30 20:20:44,347 [salt.minion      :1711][INFO    ][5574] Returning information for job: 20190630201413326055
2019-06-30 20:20:45,048 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command state.apply with jid 20190630202045039856
2019-06-30 20:20:45,076 [salt.minion      :1432][INFO    ][7436] Starting a new job with PID 7436
2019-06-30 20:20:53,275 [salt.state       :915 ][INFO    ][7436] Loading fresh modules for state activity
2019-06-30 20:20:53,338 [salt.fileclient  :1219][INFO    ][7436] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-06-30 20:20:53,461 [salt.state       :1780][INFO    ][7436] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:20:53.460991
2019-06-30 20:20:53,461 [salt.state       :1813][INFO    ][7436] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-30 20:20:53,463 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7436] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-30 20:20:55,467 [salt.state       :300 ][INFO    ][7436] {'pid': 7454, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-30 20:20:55,468 [salt.state       :1951][INFO    ][7436] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:20:55.467807 duration_in_ms=2006.815
2019-06-30 20:20:55,471 [salt.state       :1780][INFO    ][7436] Running state [maas_machines_storage_cmp002_lvm] at time 20:20:55.471413
2019-06-30 20:20:55,472 [salt.state       :1813][INFO    ][7436] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-06-30 20:20:56,540 [salt.loaded.ext.module.maasng:610 ][INFO    ][7436] 8kr3ec
2019-06-30 20:20:56,541 [salt.loaded.ext.module.maasng:626 ][INFO    ][7436] sda
2019-06-30 20:20:57,076 [salt.loaded.ext.module.maasng:361 ][INFO    ][7436] 8kr3ec
2019-06-30 20:20:57,181 [salt.loaded.ext.module.maasng:367 ][INFO    ][7436] [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'uuid': None, u'resource_uri': u'/MAAS/api/2.0/nodes/8kr3ec/blockdevices/1/', u'used_size': 800106479616, u'partitions': [{u'uuid': u'31c38e73-760a-4a42-9267-24a2cf8e613c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'8kr3ec', u'device_id': 1, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'b6eae964-6b0c-4419-9e8f-adf54bca0a72', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/8kr3ec/blockdevices/1/partition/1', u'type': u'partition', u'id': 1, u'size': 800101236736}], u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'8kr3ec', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'type': u'physical', u'id': 1, u'name': u'sda'}, {u'size': 800097042432, u'model': None, u'uuid': u'd38ec0bc-8a50-45cf-9f62-79f68d372d2a', u'resource_uri': u'/MAAS/api/2.0/nodes/8kr3ec/blockdevices/3/', u'used_size': 800097042432, u'partitions': [], u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'8kr3ec', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'db9b6bea-059d-4cf2-87da-c7385068487f', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 3, u'name': u'vgroot-lvroot'}]
2019-06-30 20:20:57,182 [salt.loaded.ext.module.maasng:632 ][INFO    ][7436] vgroot
2019-06-30 20:20:57,182 [salt.loaded.ext.module.maasng:635 ][INFO    ][7436] lvroot
2019-06-30 20:20:57,183 [salt.loaded.ext.module.maasng:639 ][INFO    ][7436] 107374182400
2019-06-30 20:20:57,788 [salt.loaded.ext.module.maasng:645 ][INFO    ][7436] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'anbk8e', u'fabric': u'pxe_admin', 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'id': 18, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'anbk8e', u'fabric': u'pxe_admin', 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'enabled': True, u'effective_mtu': 1500, 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'anbk8e', u'fabric': u'pxe_admin', 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'children': [], u'system_id': u'8kr3ec', u'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/8kr3ec/interfaces/4/'}, u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'status_action': u'', u'blockdevice_set': [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'c02f5c8d-2267-43c8-a324-7af98ee583fe', u'resource_uri': u'/MAAS/api/2.0/nodes/8kr3ec/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'8kr3ec', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'bcc80d30-39f5-4b6e-91a9-e33051da9149', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'size': 800101236736}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'8kr3ec', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456, u'type': u'physical', u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/8kr3ec/blockdevices/1/'}, {u'model': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'uuid': u'6817afbe-796d-4a72-b185-10f42b1a3b1a', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'8kr3ec', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'c668bfd3-c236-49a8-8b19-9d8070879b9a', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'size': 107374182400, u'type': u'virtual', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/8kr3ec/blockdevices/11/'}], u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/8kr3ec/', u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'8kr3ec', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'architecture': u'amd64/generic', u'status': 4, u'bcaches': [], u'cpu_count': 40, u'power_state': u'off', u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'8kr3ec', 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'physicalblockdevice_set': [{u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'c02f5c8d-2267-43c8-a324-7af98ee583fe', u'resource_uri': u'/MAAS/api/2.0/nodes/8kr3ec/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'8kr3ec', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'bcc80d30-39f5-4b6e-91a9-e33051da9149', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'size': 800101236736}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'8kr3ec', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'size': 800109715456, u'type': u'physical', u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/8kr3ec/blockdevices/1/'}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'virtualblockdevice_set': [{u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'uuid': u'6817afbe-796d-4a72-b185-10f42b1a3b1a', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'8kr3ec', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'c668bfd3-c236-49a8-8b19-9d8070879b9a', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'size': 107374182400, u'type': u'virtual', u'id': 11, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/8kr3ec/blockdevices/11/'}], u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'commissioning_status_name': u'Passed', u'boot_disk': {u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'c02f5c8d-2267-43c8-a324-7af98ee583fe', u'resource_uri': u'/MAAS/api/2.0/nodes/8kr3ec/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'8kr3ec', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'bcc80d30-39f5-4b6e-91a9-e33051da9149', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'size': 800101236736}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'8kr3ec', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'size': 800109715456, u'type': u'physical', u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/8kr3ec/blockdevices/1/'}, u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'anbk8e', u'fabric': u'pxe_admin', 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'id': 18, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'anbk8e', u'fabric': u'pxe_admin', 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'enabled': True, u'effective_mtu': 1500, 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'anbk8e', u'fabric': u'pxe_admin', 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'children': [], u'system_id': u'8kr3ec', u'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/8kr3ec/interfaces/4/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'children': [], u'system_id': u'8kr3ec', u'mac_address': u'38:ea:a7:8f:07:51', u'params': u'', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/8kr3ec/interfaces/12/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'children': [], u'system_id': u'8kr3ec', u'mac_address': u'38:ea:a7:8f:07:50', u'params': u'', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/8kr3ec/interfaces/13/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'anbk8e', u'fabric': u'pxe_admin', 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'id': 19, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'anbk8e', u'fabric': u'pxe_admin', 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'enabled': True, u'effective_mtu': 1500, 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'anbk8e', u'fabric': u'pxe_admin', 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'children': [], u'system_id': u'8kr3ec', u'mac_address': u'9c:b6:54:8a:10:1c', u'params': u'', u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/8kr3ec/interfaces/14/'}, {u'name': u'ens2f0', u'links': [{u'id': 20, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', 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'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'children': [], u'system_id': u'8kr3ec', u'mac_address': u'38:ea:a7:8f:12:48', u'params': u'', u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/8kr3ec/interfaces/15/'}, {u'name': u'ens2f1', u'links': [{u'id': 21, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', 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'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'children': [], u'system_id': u'8kr3ec', u'mac_address': u'38:ea:a7:8f:12:49', u'params': u'', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/8kr3ec/interfaces/11/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-06-30 20:20:57,790 [salt.state       :300 ][INFO    ][7436] {'new': {'storage_layout': 'lvm'}}
2019-06-30 20:20:57,790 [salt.state       :1951][INFO    ][7436] Completed state [maas_machines_storage_cmp002_lvm] at time 20:20:57.790578 duration_in_ms=2319.165
2019-06-30 20:20:57,791 [salt.state       :1780][INFO    ][7436] Running state [maas_machines_storage_cmp001_lvm] at time 20:20:57.791086
2019-06-30 20:20:57,791 [salt.state       :1813][INFO    ][7436] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-06-30 20:20:58,943 [salt.loaded.ext.module.maasng:610 ][INFO    ][7436] qycw3y
2019-06-30 20:20:58,943 [salt.loaded.ext.module.maasng:626 ][INFO    ][7436] sda
2019-06-30 20:20:59,532 [salt.loaded.ext.module.maasng:361 ][INFO    ][7436] qycw3y
2019-06-30 20:20:59,614 [salt.loaded.ext.module.maasng:367 ][INFO    ][7436] [{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'system_id': u'qycw3y', u'used_size': 800106479616, u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/qycw3y/blockdevices/2/', 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'size': 800109715456, u'type': u'physical', u'id': 2, u'partitions': [{u'size': 800101236736, u'uuid': u'091221e8-82cf-4e0d-aec3-fa83b67ca5cd', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qycw3y', u'filesystem': {u'mount_options': None, u'uuid': u'88b33869-df34-40d6-8473-9e6877b80aff', 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/qycw3y/blockdevices/2/partition/2', u'type': u'partition', u'id': 2, u'device_id': 2}]}, {u'model': None, u'block_size': 4096, u'available_size': 0, u'uuid': u'a7cae6ce-1750-4e1b-b734-70fabbb4e6cb', u'name': u'vgroot-lvroot', u'tags': [], u'system_id': u'qycw3y', u'used_size': 800097042432, u'used_for': u'ext4 formatted filesystem mounted at /', u'resource_uri': u'/MAAS/api/2.0/nodes/qycw3y/blockdevices/4/', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'13601377-2a03-41e7-a2fa-edbd4ed8d05f', 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'size': 800097042432, u'type': u'virtual', u'id': 4, u'partitions': []}]
2019-06-30 20:20:59,615 [salt.loaded.ext.module.maasng:632 ][INFO    ][7436] vgroot
2019-06-30 20:20:59,615 [salt.loaded.ext.module.maasng:635 ][INFO    ][7436] lvroot
2019-06-30 20:20:59,615 [salt.loaded.ext.module.maasng:639 ][INFO    ][7436] 107374182400
2019-06-30 20:21:00,136 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630202100125616
2019-06-30 20:21:00,149 [salt.minion      :1432][INFO    ][7470] Starting a new job with PID 7470
2019-06-30 20:21:00,177 [salt.minion      :1711][INFO    ][7470] Returning information for job: 20190630202100125616
2019-06-30 20:21:00,233 [salt.loaded.ext.module.maasng:645 ][INFO    ][7436] {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.43'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'commissioning_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'zone': {u'description': u'', u'id': 1, u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'current_commissioning_result_id': 4, u'hostname': u'cmp001', u'storage': 800109.715456, u'node_type': 0, u'testing_status': 2, u'system_id': u'qycw3y', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'block_size': 4096, u'available_size': 0, u'uuid': u'3510c022-aecf-49ca-aac8-0d446ea8c03a', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'qycw3y', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'3427467f-3f1a-40b9-aedf-0960d6d38109', u'fstype': u'ext4', u'mount_point': u'/', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/qycw3y/blockdevices/12/', u'type': u'virtual', u'id': 12, u'partitions': []}], 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'system_id': u'qycw3y', u'used_size': 800106479616, u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/qycw3y/blockdevices/2/', 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'size': 800109715456, u'type': u'physical', u'id': 2, u'partitions': [{u'size': 800101236736, u'uuid': u'e0e4de12-a198-4040-91c4-eaf295484193', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qycw3y', u'filesystem': {u'mount_options': None, u'uuid': u'184951b1-7a1a-4044-93af-eecf7e0d432d', 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/qycw3y/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'device_id': 2}]}, {u'model': None, u'block_size': 4096, u'available_size': 0, u'uuid': u'3510c022-aecf-49ca-aac8-0d446ea8c03a', u'name': u'vgroot-lvroot', u'tags': [], u'system_id': u'qycw3y', u'used_size': 107374182400, u'used_for': u'ext4 formatted filesystem mounted at /', u'resource_uri': u'/MAAS/api/2.0/nodes/qycw3y/blockdevices/12/', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'3427467f-3f1a-40b9-aedf-0960d6d38109', 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'size': 107374182400, u'type': u'virtual', u'id': 12, u'partitions': []}], u'status': 4, u'bcaches': [], u'cpu_count': 40, u'raids': [], u'physicalblockdevice_set': [{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'qycw3y', 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/qycw3y/blockdevices/2/', u'type': u'physical', u'id': 2, u'partitions': [{u'size': 800101236736, u'uuid': u'e0e4de12-a198-4040-91c4-eaf295484193', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qycw3y', u'filesystem': {u'mount_options': None, u'uuid': u'184951b1-7a1a-4044-93af-eecf7e0d432d', 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/qycw3y/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'device_id': 2}]}], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'qycw3y', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'node_type_name': u'Machine', u'current_testing_result_id': 5, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'disable_ipv4': False, u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'anbk8e', 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'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 24}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'anbk8e', 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'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'anbk8e', 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'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'qycw3y', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qycw3y/interfaces/5/'}, u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'anbk8e', 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'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 24}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'anbk8e', 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'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'anbk8e', 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'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'qycw3y', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qycw3y/interfaces/5/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'id': 17, u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d4', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'qycw3y', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qycw3y/interfaces/17/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'id': 20, u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d5', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'qycw3y', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qycw3y/interfaces/20/'}, {u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 25}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'id': 16, u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cd', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'qycw3y', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qycw3y/interfaces/16/'}, {u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 26}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'enabled': True, u'id': 18, u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cc', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'qycw3y', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qycw3y/interfaces/18/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'anbk8e', 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'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': 27}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'anbk8e', 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'enabled': True, u'id': 19, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'anbk8e', 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'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.43'}], u'mac_address': u'9c:b6:54:8a:95:a4', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'qycw3y', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qycw3y/interfaces/19/'}], u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/qycw3y/', u'distro_series': u'', u'boot_disk': {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'qycw3y', 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/qycw3y/blockdevices/2/', u'type': u'physical', u'id': 2, u'partitions': [{u'size': 800101236736, u'uuid': u'e0e4de12-a198-4040-91c4-eaf295484193', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qycw3y', u'filesystem': {u'mount_options': None, u'uuid': u'184951b1-7a1a-4044-93af-eecf7e0d432d', 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/qycw3y/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'device_id': 2}]}}
2019-06-30 20:21:00,235 [salt.state       :300 ][INFO    ][7436] {'new': {'storage_layout': 'lvm'}}
2019-06-30 20:21:00,235 [salt.state       :1951][INFO    ][7436] Completed state [maas_machines_storage_cmp001_lvm] at time 20:21:00.235742 duration_in_ms=2444.655
2019-06-30 20:21:00,238 [salt.minion      :1711][INFO    ][7436] Returning information for job: 20190630202045039856
2019-06-30 20:21:00,970 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command state.apply with jid 20190630202100959935
2019-06-30 20:21:00,993 [salt.minion      :1432][INFO    ][7477] Starting a new job with PID 7477
2019-06-30 20:21:02,200 [salt.state       :915 ][INFO    ][7477] Loading fresh modules for state activity
2019-06-30 20:21:02,264 [salt.fileclient  :1219][INFO    ][7477] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-06-30 20:21:02,321 [salt.state       :1780][INFO    ][7477] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:21:02.321451
2019-06-30 20:21:02,321 [salt.state       :1813][INFO    ][7477] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-30 20:21:02,324 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7477] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-30 20:21:04,206 [salt.state       :300 ][INFO    ][7477] {'pid': 7484, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-30 20:21:04,207 [salt.state       :1951][INFO    ][7477] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:21:04.207732 duration_in_ms=1886.28
2019-06-30 20:21:04,212 [salt.state       :1780][INFO    ][7477] Running state [maas.deploy_machines] at time 20:21:04.211998
2019-06-30 20:21:04,212 [salt.state       :1813][INFO    ][7477] Executing state module.run for [maas.deploy_machines]
2019-06-30 20:21:04,215 [salt.utils.decorators:613 ][WARNING ][7477] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-30 20:21:04,799 [salt.loaded.ext.module.maas:684 ][INFO    ][7477] deploymachines hwe_kernel=hwe-16.04 system_id=8kr3ec distro_series=xenial
2019-06-30 20:21:07,255 [salt.loaded.ext.module.maas:684 ][INFO    ][7477] deploymachines hwe_kernel=hwe-16.04 system_id=qycw3y distro_series=xenial
2019-06-30 20:21:09,785 [salt.loaded.ext.module.maas:684 ][INFO    ][7477] deploymachines hwe_kernel=hwe-16.04 system_id=twe3hq distro_series=xenial
2019-06-30 20:21:12,281 [salt.loaded.ext.module.maas:684 ][INFO    ][7477] deploymachines hwe_kernel=hwe-16.04 system_id=get4hs distro_series=xenial
2019-06-30 20:21:14,697 [salt.loaded.ext.module.maas:684 ][INFO    ][7477] deploymachines hwe_kernel=hwe-16.04 system_id=s44dr4 distro_series=xenial
2019-06-30 20:21:16,032 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630202116019241
2019-06-30 20:21:16,052 [salt.minion      :1432][INFO    ][7746] Starting a new job with PID 7746
2019-06-30 20:21:16,081 [salt.minion      :1711][INFO    ][7746] Returning information for job: 20190630202116019241
2019-06-30 20:21:16,954 [salt.state       :300 ][INFO    ][7477] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-06-30 20:21:16,955 [salt.state       :1951][INFO    ][7477] Completed state [maas.deploy_machines] at time 20:21:16.955409 duration_in_ms=12743.411
2019-06-30 20:21:16,966 [salt.minion      :1711][INFO    ][7477] Returning information for job: 20190630202100959935
2019-06-30 20:21:17,680 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command state.apply with jid 20190630202117668547
2019-06-30 20:21:17,706 [salt.minion      :1432][INFO    ][7764] Starting a new job with PID 7764
2019-06-30 20:21:26,056 [salt.state       :915 ][INFO    ][7764] Loading fresh modules for state activity
2019-06-30 20:21:26,119 [salt.fileclient  :1219][INFO    ][7764] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-06-30 20:21:26,181 [salt.state       :1780][INFO    ][7764] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:21:26.181741
2019-06-30 20:21:26,182 [salt.state       :1813][INFO    ][7764] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-30 20:21:26,184 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7764] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-30 20:21:28,215 [salt.state       :300 ][INFO    ][7764] {'pid': 7797, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-30 20:21:28,216 [salt.state       :1951][INFO    ][7764] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:21:28.215945 duration_in_ms=2034.205
2019-06-30 20:21:28,219 [salt.state       :1780][INFO    ][7764] Running state [maas.wait_for_machine_status] at time 20:21:28.218958
2019-06-30 20:21:28,219 [salt.state       :1813][INFO    ][7764] Executing state module.run for [maas.wait_for_machine_status]
2019-06-30 20:21:28,219 [salt.utils.decorators:613 ][WARNING ][7764] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-30 20:21:31,200 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.03143787s left)
2019-06-30 20:21:32,811 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630202132791656
2019-06-30 20:21:32,835 [salt.minion      :1432][INFO    ][7811] Starting a new job with PID 7811
2019-06-30 20:21:32,860 [salt.minion      :1711][INFO    ][7811] Returning information for job: 20190630202132791656
2019-06-30 20:22:02,885 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630202202871183
2019-06-30 20:22:02,905 [salt.minion      :1432][INFO    ][7856] Starting a new job with PID 7856
2019-06-30 20:22:02,939 [salt.minion      :1711][INFO    ][7856] Returning information for job: 20190630202202871183
2019-06-30 20:22:04,256 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.97520995s left)
2019-06-30 20:22:32,976 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630202232965587
2019-06-30 20:22:33,004 [salt.minion      :1432][INFO    ][7892] Starting a new job with PID 7892
2019-06-30 20:22:33,031 [salt.minion      :1711][INFO    ][7892] Returning information for job: 20190630202232965587
2019-06-30 20:22:37,480 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2180.75082779s left)
2019-06-30 20:23:03,064 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630202303049607
2019-06-30 20:23:03,092 [salt.minion      :1432][INFO    ][7954] Starting a new job with PID 7954
2019-06-30 20:23:03,121 [salt.minion      :1711][INFO    ][7954] Returning information for job: 20190630202303049607
2019-06-30 20:23:10,368 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2147.86305881s left)
2019-06-30 20:23:33,154 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630202333139259
2019-06-30 20:23:33,186 [salt.minion      :1432][INFO    ][7980] Starting a new job with PID 7980
2019-06-30 20:23:33,217 [salt.minion      :1711][INFO    ][7980] Returning information for job: 20190630202333139259
2019-06-30 20:23:43,278 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2114.95263982s left)
2019-06-30 20:24:03,263 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630202403254839
2019-06-30 20:24:03,288 [salt.minion      :1432][INFO    ][8044] Starting a new job with PID 8044
2019-06-30 20:24:03,320 [salt.minion      :1711][INFO    ][8044] Returning information for job: 20190630202403254839
2019-06-30 20:24:16,499 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2081.73236179s left)
2019-06-30 20:24:33,343 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630202433328637
2019-06-30 20:24:33,364 [salt.minion      :1432][INFO    ][8118] Starting a new job with PID 8118
2019-06-30 20:24:33,393 [salt.minion      :1711][INFO    ][8118] Returning information for job: 20190630202433328637
2019-06-30 20:24:49,577 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2048.65392399s left)
2019-06-30 20:25:03,477 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630202503459705
2019-06-30 20:25:03,505 [salt.minion      :1432][INFO    ][8314] Starting a new job with PID 8314
2019-06-30 20:25:03,534 [salt.minion      :1711][INFO    ][8314] Returning information for job: 20190630202503459705
2019-06-30 20:25:22,888 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2015.34261584s left)
2019-06-30 20:25:33,560 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630202533552510
2019-06-30 20:25:33,592 [salt.minion      :1432][INFO    ][8386] Starting a new job with PID 8386
2019-06-30 20:25:33,630 [salt.minion      :1711][INFO    ][8386] Returning information for job: 20190630202533552510
2019-06-30 20:25:55,819 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1982.41232681s left)
2019-06-30 20:26:03,708 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630202603697647
2019-06-30 20:26:03,742 [salt.minion      :1432][INFO    ][8669] Starting a new job with PID 8669
2019-06-30 20:26:03,791 [salt.minion      :1711][INFO    ][8669] Returning information for job: 20190630202603697647
2019-06-30 20:26:29,107 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1949.12361097s left)
2019-06-30 20:26:33,865 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630202633849423
2019-06-30 20:26:33,889 [salt.minion      :1432][INFO    ][8719] Starting a new job with PID 8719
2019-06-30 20:26:33,920 [salt.minion      :1711][INFO    ][8719] Returning information for job: 20190630202633849423
2019-06-30 20:27:02,177 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1916.05410099s left)
2019-06-30 20:27:04,044 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630202704028986
2019-06-30 20:27:04,073 [salt.minion      :1432][INFO    ][9021] Starting a new job with PID 9021
2019-06-30 20:27:04,111 [salt.minion      :1711][INFO    ][9021] Returning information for job: 20190630202704028986
2019-06-30 20:27:34,185 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630202734173556
2019-06-30 20:27:34,206 [salt.minion      :1432][INFO    ][9058] Starting a new job with PID 9058
2019-06-30 20:27:34,236 [salt.minion      :1711][INFO    ][9058] Returning information for job: 20190630202734173556
2019-06-30 20:27:35,155 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1883.07589984s left)
2019-06-30 20:28:04,364 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630202804347903
2019-06-30 20:28:04,393 [salt.minion      :1432][INFO    ][9406] Starting a new job with PID 9406
2019-06-30 20:28:04,425 [salt.minion      :1711][INFO    ][9406] Returning information for job: 20190630202804347903
2019-06-30 20:28:08,084 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1850.1471839s left)
2019-06-30 20:28:34,519 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630202834508535
2019-06-30 20:28:34,537 [salt.minion      :1432][INFO    ][9451] Starting a new job with PID 9451
2019-06-30 20:28:34,569 [salt.minion      :1711][INFO    ][9451] Returning information for job: 20190630202834508535
2019-06-30 20:28:42,079 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1816.15230393s left)
2019-06-30 20:29:04,708 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630202904691176
2019-06-30 20:29:04,738 [salt.minion      :1432][INFO    ][9727] Starting a new job with PID 9727
2019-06-30 20:29:04,767 [salt.minion      :1711][INFO    ][9727] Returning information for job: 20190630202904691176
2019-06-30 20:29:15,102 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1783.12887788s left)
2019-06-30 20:29:34,876 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630202934866634
2019-06-30 20:29:34,905 [salt.minion      :1432][INFO    ][9779] Starting a new job with PID 9779
2019-06-30 20:29:34,936 [salt.minion      :1711][INFO    ][9779] Returning information for job: 20190630202934866634
2019-06-30 20:29:48,169 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1750.06236291s left)
2019-06-30 20:30:05,084 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630203005077119
2019-06-30 20:30:05,107 [salt.minion      :1432][INFO    ][10095] Starting a new job with PID 10095
2019-06-30 20:30:05,136 [salt.minion      :1711][INFO    ][10095] Returning information for job: 20190630203005077119
2019-06-30 20:30:20,962 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1717.2691648s left)
2019-06-30 20:30:35,254 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630203035242897
2019-06-30 20:30:35,285 [salt.minion      :1432][INFO    ][10156] Starting a new job with PID 10156
2019-06-30 20:30:35,315 [salt.minion      :1711][INFO    ][10156] Returning information for job: 20190630203035242897
2019-06-30 20:30:53,871 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1684.36030984s left)
2019-06-30 20:31:05,347 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630203105337872
2019-06-30 20:31:05,368 [salt.minion      :1432][INFO    ][10278] Starting a new job with PID 10278
2019-06-30 20:31:05,399 [salt.minion      :1711][INFO    ][10278] Returning information for job: 20190630203105337872
2019-06-30 20:31:26,733 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1651.49804878s left)
2019-06-30 20:31:35,548 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630203135534569
2019-06-30 20:31:35,576 [salt.minion      :1432][INFO    ][10326] Starting a new job with PID 10326
2019-06-30 20:31:35,608 [salt.minion      :1711][INFO    ][10326] Returning information for job: 20190630203135534569
2019-06-30 20:31:59,632 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1618.59924293s left)
2019-06-30 20:32:05,755 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630203205739214
2019-06-30 20:32:05,784 [salt.minion      :1432][INFO    ][10532] Starting a new job with PID 10532
2019-06-30 20:32:05,814 [salt.minion      :1711][INFO    ][10532] Returning information for job: 20190630203205739214
2019-06-30 20:32:32,620 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1585.61094499s left)
2019-06-30 20:32:35,928 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630203235912747
2019-06-30 20:32:35,956 [salt.minion      :1432][INFO    ][10697] Starting a new job with PID 10697
2019-06-30 20:32:35,994 [salt.minion      :1711][INFO    ][10697] Returning information for job: 20190630203235912747
2019-06-30 20:33:05,556 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1552.67500186s left)
2019-06-30 20:33:06,128 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630203306117272
2019-06-30 20:33:06,155 [salt.minion      :1432][INFO    ][10758] Starting a new job with PID 10758
2019-06-30 20:33:06,186 [salt.minion      :1711][INFO    ][10758] Returning information for job: 20190630203306117272
2019-06-30 20:33:36,341 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630203336328073
2019-06-30 20:33:36,365 [salt.minion      :1432][INFO    ][10784] Starting a new job with PID 10784
2019-06-30 20:33:36,397 [salt.minion      :1711][INFO    ][10784] Returning information for job: 20190630203336328073
2019-06-30 20:33:38,419 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1519.8115778s left)
2019-06-30 20:34:06,369 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630203406356352
2019-06-30 20:34:06,404 [salt.minion      :1432][INFO    ][10897] Starting a new job with PID 10897
2019-06-30 20:34:06,439 [salt.minion      :1711][INFO    ][10897] Returning information for job: 20190630203406356352
2019-06-30 20:34:11,303 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1486.92834401s left)
2019-06-30 20:34:36,528 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630203436513992
2019-06-30 20:34:36,556 [salt.minion      :1432][INFO    ][10958] Starting a new job with PID 10958
2019-06-30 20:34:36,586 [salt.minion      :1711][INFO    ][10958] Returning information for job: 20190630203436513992
2019-06-30 20:34:44,721 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1453.51002479s left)
2019-06-30 20:35:06,580 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630203506563310
2019-06-30 20:35:06,609 [salt.minion      :1432][INFO    ][11160] Starting a new job with PID 11160
2019-06-30 20:35:06,639 [salt.minion      :1711][INFO    ][11160] Returning information for job: 20190630203506563310
2019-06-30 20:35:17,593 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1420.63824797s left)
2019-06-30 20:35:36,790 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630203536774011
2019-06-30 20:35:36,821 [salt.minion      :1432][INFO    ][11188] Starting a new job with PID 11188
2019-06-30 20:35:36,850 [salt.minion      :1711][INFO    ][11188] Returning information for job: 20190630203536774011
2019-06-30 20:35:50,514 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1387.71713591s left)
2019-06-30 20:36:06,832 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630203606822840
2019-06-30 20:36:06,860 [salt.minion      :1432][INFO    ][11236] Starting a new job with PID 11236
2019-06-30 20:36:06,891 [salt.minion      :1711][INFO    ][11236] Returning information for job: 20190630203606822840
2019-06-30 20:36:23,416 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1354.81527185s left)
2019-06-30 20:36:36,891 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630203636881522
2019-06-30 20:36:36,910 [salt.minion      :1432][INFO    ][11265] Starting a new job with PID 11265
2019-06-30 20:36:36,940 [salt.minion      :1711][INFO    ][11265] Returning information for job: 20190630203636881522
2019-06-30 20:36:56,388 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1321.842731s left)
2019-06-30 20:37:06,979 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630203706959864
2019-06-30 20:37:07,008 [salt.minion      :1432][INFO    ][11320] Starting a new job with PID 11320
2019-06-30 20:37:07,038 [salt.minion      :1711][INFO    ][11320] Returning information for job: 20190630203706959864
2019-06-30 20:37:29,168 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1289.06296182s left)
2019-06-30 20:37:37,067 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630203737058533
2019-06-30 20:37:37,093 [salt.minion      :1432][INFO    ][11349] Starting a new job with PID 11349
2019-06-30 20:37:37,126 [salt.minion      :1711][INFO    ][11349] Returning information for job: 20190630203737058533
2019-06-30 20:38:01,972 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1256.25949097s left)
2019-06-30 20:38:07,139 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630203807122437
2019-06-30 20:38:07,172 [salt.minion      :1432][INFO    ][11395] Starting a new job with PID 11395
2019-06-30 20:38:07,208 [salt.minion      :1711][INFO    ][11395] Returning information for job: 20190630203807122437
2019-06-30 20:38:34,861 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1223.37012482s left)
2019-06-30 20:38:37,255 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630203837243990
2019-06-30 20:38:37,279 [salt.minion      :1432][INFO    ][11424] Starting a new job with PID 11424
2019-06-30 20:38:37,307 [salt.minion      :1711][INFO    ][11424] Returning information for job: 20190630203837243990
2019-06-30 20:39:07,342 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630203907327832
2019-06-30 20:39:07,363 [salt.minion      :1432][INFO    ][11482] Starting a new job with PID 11482
2019-06-30 20:39:07,396 [salt.minion      :1711][INFO    ][11482] Returning information for job: 20190630203907327832
2019-06-30 20:39:07,805 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1190.42617893s left)
2019-06-30 20:39:37,484 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630203937469704
2019-06-30 20:39:37,510 [salt.minion      :1432][INFO    ][11504] Starting a new job with PID 11504
2019-06-30 20:39:37,542 [salt.minion      :1711][INFO    ][11504] Returning information for job: 20190630203937469704
2019-06-30 20:39:40,853 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1157.37827182s left)
2019-06-30 20:40:07,604 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630204007592272
2019-06-30 20:40:07,630 [salt.minion      :1432][INFO    ][11549] Starting a new job with PID 11549
2019-06-30 20:40:07,662 [salt.minion      :1711][INFO    ][11549] Returning information for job: 20190630204007592272
2019-06-30 20:40:13,752 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1124.47905397s left)
2019-06-30 20:40:37,767 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630204037751349
2019-06-30 20:40:37,789 [salt.minion      :1432][INFO    ][11577] Starting a new job with PID 11577
2019-06-30 20:40:37,820 [salt.minion      :1711][INFO    ][11577] Returning information for job: 20190630204037751349
2019-06-30 20:40:46,643 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1091.58758998s left)
2019-06-30 20:41:07,931 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630204107923297
2019-06-30 20:41:07,954 [salt.minion      :1432][INFO    ][11622] Starting a new job with PID 11622
2019-06-30 20:41:07,991 [salt.minion      :1711][INFO    ][11622] Returning information for job: 20190630204107923297
2019-06-30 20:41:19,387 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1058.84360385s left)
2019-06-30 20:41:38,122 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630204138106265
2019-06-30 20:41:38,152 [salt.minion      :1432][INFO    ][11649] Starting a new job with PID 11649
2019-06-30 20:41:38,185 [salt.minion      :1711][INFO    ][11649] Returning information for job: 20190630204138106265
2019-06-30 20:41:52,146 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1026.08452582s left)
2019-06-30 20:42:08,333 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630204208321331
2019-06-30 20:42:08,357 [salt.minion      :1432][INFO    ][11693] Starting a new job with PID 11693
2019-06-30 20:42:08,388 [salt.minion      :1711][INFO    ][11693] Returning information for job: 20190630204208321331
2019-06-30 20:42:25,124 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (993.106543779s left)
2019-06-30 20:42:38,547 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630204238536825
2019-06-30 20:42:38,574 [salt.minion      :1432][INFO    ][11736] Starting a new job with PID 11736
2019-06-30 20:42:38,607 [salt.minion      :1711][INFO    ][11736] Returning information for job: 20190630204238536825
2019-06-30 20:42:57,971 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (960.259724855s left)
2019-06-30 20:43:08,588 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630204308574052
2019-06-30 20:43:08,620 [salt.minion      :1432][INFO    ][11795] Starting a new job with PID 11795
2019-06-30 20:43:08,648 [salt.minion      :1711][INFO    ][11795] Returning information for job: 20190630204308574052
2019-06-30 20:43:30,701 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (927.529997826s left)
2019-06-30 20:43:38,611 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630204338600504
2019-06-30 20:43:38,629 [salt.minion      :1432][INFO    ][11828] Starting a new job with PID 11828
2019-06-30 20:43:38,656 [salt.minion      :1711][INFO    ][11828] Returning information for job: 20190630204338600504
2019-06-30 20:44:03,684 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (894.547621012s left)
2019-06-30 20:44:08,644 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630204408627230
2019-06-30 20:44:08,671 [salt.minion      :1432][INFO    ][11874] Starting a new job with PID 11874
2019-06-30 20:44:08,695 [salt.minion      :1711][INFO    ][11874] Returning information for job: 20190630204408627230
2019-06-30 20:44:36,420 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (861.81061697s left)
2019-06-30 20:44:38,879 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630204438860797
2019-06-30 20:44:38,907 [salt.minion      :1432][INFO    ][11903] Starting a new job with PID 11903
2019-06-30 20:44:38,932 [salt.minion      :1711][INFO    ][11903] Returning information for job: 20190630204438860797
2019-06-30 20:45:08,969 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630204508952575
2019-06-30 20:45:08,991 [salt.minion      :1432][INFO    ][11953] Starting a new job with PID 11953
2019-06-30 20:45:09,013 [salt.minion      :1711][INFO    ][11953] Returning information for job: 20190630204508952575
2019-06-30 20:45:09,187 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (829.04381299s left)
2019-06-30 20:45:39,017 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630204538999026
2019-06-30 20:45:39,044 [salt.minion      :1432][INFO    ][11977] Starting a new job with PID 11977
2019-06-30 20:45:39,084 [salt.minion      :1711][INFO    ][11977] Returning information for job: 20190630204538999026
2019-06-30 20:45:42,176 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (796.055171967s left)
2019-06-30 20:46:09,127 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630204609116176
2019-06-30 20:46:09,153 [salt.minion      :1432][INFO    ][12025] Starting a new job with PID 12025
2019-06-30 20:46:09,177 [salt.minion      :1711][INFO    ][12025] Returning information for job: 20190630204609116176
2019-06-30 20:46:15,286 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (762.944656849s left)
2019-06-30 20:46:39,220 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630204639204597
2019-06-30 20:46:39,250 [salt.minion      :1432][INFO    ][12054] Starting a new job with PID 12054
2019-06-30 20:46:39,279 [salt.minion      :1711][INFO    ][12054] Returning information for job: 20190630204639204597
2019-06-30 20:46:48,070 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (730.16118288s left)
2019-06-30 20:47:09,367 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630204709349452
2019-06-30 20:47:09,393 [salt.minion      :1432][INFO    ][12105] Starting a new job with PID 12105
2019-06-30 20:47:09,417 [salt.minion      :1711][INFO    ][12105] Returning information for job: 20190630204709349452
2019-06-30 20:47:20,844 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (697.386899948s left)
2019-06-30 20:47:39,497 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630204739479813
2019-06-30 20:47:39,524 [salt.minion      :1432][INFO    ][12138] Starting a new job with PID 12138
2019-06-30 20:47:39,551 [salt.minion      :1711][INFO    ][12138] Returning information for job: 20190630204739479813
2019-06-30 20:47:53,648 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (664.582980871s left)
2019-06-30 20:48:09,704 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630204809692459
2019-06-30 20:48:09,730 [salt.minion      :1432][INFO    ][12188] Starting a new job with PID 12188
2019-06-30 20:48:09,753 [salt.minion      :1711][INFO    ][12188] Returning information for job: 20190630204809692459
2019-06-30 20:48:26,527 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (631.704289913s left)
2019-06-30 20:48:39,908 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630204839896931
2019-06-30 20:48:39,933 [salt.minion      :1432][INFO    ][12232] Starting a new job with PID 12232
2019-06-30 20:48:39,961 [salt.minion      :1711][INFO    ][12232] Returning information for job: 20190630204839896931
2019-06-30 20:48:59,437 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (598.79418993s left)
2019-06-30 20:49:09,933 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630204909920392
2019-06-30 20:49:09,961 [salt.minion      :1432][INFO    ][12273] Starting a new job with PID 12273
2019-06-30 20:49:09,982 [salt.minion      :1711][INFO    ][12273] Returning information for job: 20190630204909920392
2019-06-30 20:49:32,163 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (566.068048954s left)
2019-06-30 20:49:39,992 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630204939978429
2019-06-30 20:49:40,019 [salt.minion      :1432][INFO    ][12313] Starting a new job with PID 12313
2019-06-30 20:49:40,044 [salt.minion      :1711][INFO    ][12313] Returning information for job: 20190630204939978429
2019-06-30 20:50:05,075 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (533.155926943s left)
2019-06-30 20:50:10,213 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630205010201100
2019-06-30 20:50:10,240 [salt.minion      :1432][INFO    ][12345] Starting a new job with PID 12345
2019-06-30 20:50:10,263 [salt.minion      :1711][INFO    ][12345] Returning information for job: 20190630205010201100
2019-06-30 20:50:37,818 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (500.412691832s left)
2019-06-30 20:50:40,285 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630205040274912
2019-06-30 20:50:40,316 [salt.minion      :1432][INFO    ][12392] Starting a new job with PID 12392
2019-06-30 20:50:40,341 [salt.minion      :1711][INFO    ][12392] Returning information for job: 20190630205040274912
2019-06-30 20:51:10,367 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630205110356938
2019-06-30 20:51:10,387 [salt.minion      :1432][INFO    ][12416] Starting a new job with PID 12416
2019-06-30 20:51:10,411 [salt.minion      :1711][INFO    ][12416] Returning information for job: 20190630205110356938
2019-06-30 20:51:10,785 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (467.445505857s left)
2019-06-30 20:51:40,511 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630205140499159
2019-06-30 20:51:40,534 [salt.minion      :1432][INFO    ][12461] Starting a new job with PID 12461
2019-06-30 20:51:40,557 [salt.minion      :1711][INFO    ][12461] Returning information for job: 20190630205140499159
2019-06-30 20:51:43,722 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (434.508863926s left)
2019-06-30 20:52:10,664 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630205210648401
2019-06-30 20:52:10,690 [salt.minion      :1432][INFO    ][12484] Starting a new job with PID 12484
2019-06-30 20:52:10,714 [salt.minion      :1711][INFO    ][12484] Returning information for job: 20190630205210648401
2019-06-30 20:52:16,463 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (401.76799798s left)
2019-06-30 20:52:40,812 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630205240799476
2019-06-30 20:52:40,838 [salt.minion      :1432][INFO    ][12678] Starting a new job with PID 12678
2019-06-30 20:52:40,863 [salt.minion      :1711][INFO    ][12678] Returning information for job: 20190630205240799476
2019-06-30 20:52:49,312 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (368.91906786s left)
2019-06-30 20:53:10,930 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630205310921490
2019-06-30 20:53:10,956 [salt.minion      :1432][INFO    ][12711] Starting a new job with PID 12711
2019-06-30 20:53:10,978 [salt.minion      :1711][INFO    ][12711] Returning information for job: 20190630205310921490
2019-06-30 20:53:22,422 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (335.808995962s left)
2019-06-30 20:53:41,128 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630205341116485
2019-06-30 20:53:41,146 [salt.minion      :1432][INFO    ][12764] Starting a new job with PID 12764
2019-06-30 20:53:41,172 [salt.minion      :1711][INFO    ][12764] Returning information for job: 20190630205341116485
2019-06-30 20:53:55,207 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (303.023810863s left)
2019-06-30 20:54:11,290 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630205411282720
2019-06-30 20:54:11,313 [salt.minion      :1432][INFO    ][12785] Starting a new job with PID 12785
2019-06-30 20:54:11,336 [salt.minion      :1711][INFO    ][12785] Returning information for job: 20190630205411282720
2019-06-30 20:54:28,063 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (270.168112993s left)
2019-06-30 20:54:41,351 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630205441338395
2019-06-30 20:54:41,376 [salt.minion      :1432][INFO    ][12836] Starting a new job with PID 12836
2019-06-30 20:54:41,398 [salt.minion      :1711][INFO    ][12836] Returning information for job: 20190630205441338395
2019-06-30 20:55:00,864 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (237.367105961s left)
2019-06-30 20:55:11,417 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630205511399724
2019-06-30 20:55:11,444 [salt.minion      :1432][INFO    ][12859] Starting a new job with PID 12859
2019-06-30 20:55:11,468 [salt.minion      :1711][INFO    ][12859] Returning information for job: 20190630205511399724
2019-06-30 20:55:33,676 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (204.554978848s left)
2019-06-30 20:55:41,636 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630205541621609
2019-06-30 20:55:41,665 [salt.minion      :1432][INFO    ][12913] Starting a new job with PID 12913
2019-06-30 20:55:41,688 [salt.minion      :1711][INFO    ][12913] Returning information for job: 20190630205541621609
2019-06-30 20:56:06,580 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (171.65056181s left)
2019-06-30 20:56:11,736 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630205611728487
2019-06-30 20:56:11,759 [salt.minion      :1432][INFO    ][12934] Starting a new job with PID 12934
2019-06-30 20:56:11,783 [salt.minion      :1711][INFO    ][12934] Returning information for job: 20190630205611728487
2019-06-30 20:56:39,703 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (138.527577877s left)
2019-06-30 20:56:41,851 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630205641837503
2019-06-30 20:56:41,876 [salt.minion      :1432][INFO    ][12988] Starting a new job with PID 12988
2019-06-30 20:56:41,897 [salt.minion      :1711][INFO    ][12988] Returning information for job: 20190630205641837503
2019-06-30 20:57:11,971 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630205711957547
2019-06-30 20:57:11,989 [salt.minion      :1432][INFO    ][13014] Starting a new job with PID 13014
2019-06-30 20:57:12,012 [salt.minion      :1711][INFO    ][13014] Returning information for job: 20190630205711957547
2019-06-30 20:57:12,607 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (105.623857975s left)
2019-06-30 20:57:42,052 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630205742036714
2019-06-30 20:57:42,079 [salt.minion      :1432][INFO    ][13067] Starting a new job with PID 13067
2019-06-30 20:57:42,103 [salt.minion      :1711][INFO    ][13067] Returning information for job: 20190630205742036714
2019-06-30 20:57:45,367 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (72.8640739918s left)
2019-06-30 20:58:12,238 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630205812221640
2019-06-30 20:58:12,268 [salt.minion      :1432][INFO    ][13089] Starting a new job with PID 13089
2019-06-30 20:58:12,293 [salt.minion      :1711][INFO    ][13089] Returning information for job: 20190630205812221640
2019-06-30 20:58:18,117 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (40.1138679981s left)
2019-06-30 20:58:42,372 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630205842359663
2019-06-30 20:58:42,393 [salt.minion      :1432][INFO    ][13146] Starting a new job with PID 13146
2019-06-30 20:58:42,417 [salt.minion      :1711][INFO    ][13146] Returning information for job: 20190630205842359663
2019-06-30 20:58:50,929 [salt.loaded.ext.module.maas:1023][INFO    ][7764] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (7.30174589157s left)
2019-06-30 20:59:12,563 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630205912546972
2019-06-30 20:59:12,591 [salt.minion      :1432][INFO    ][13169] Starting a new job with PID 13169
2019-06-30 20:59:12,615 [salt.minion      :1711][INFO    ][13169] Returning information for job: 20190630205912546972
2019-06-30 20:59:23,884 [salt.state       :302 ][ERROR   ][7764] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-06-30 20:59:23,884 [salt.state       :1951][INFO    ][7764] Completed state [maas.wait_for_machine_status] at time 20:59:23.884620 duration_in_ms=2275665.657
2019-06-30 20:59:23,888 [salt.minion      :1711][INFO    ][7764] Returning information for job: 20190630202117668547
2019-06-30 20:59:34,691 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command pillar.get with jid 20190630205934676673
2019-06-30 20:59:34,721 [salt.minion      :1432][INFO    ][13197] Starting a new job with PID 13197
2019-06-30 20:59:34,731 [salt.minion      :1711][INFO    ][13197] Returning information for job: 20190630205934676673
2019-06-30 20:59:35,287 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command service.status with jid 20190630205935276065
2019-06-30 20:59:35,311 [salt.minion      :1432][INFO    ][13202] Starting a new job with PID 13202
2019-06-30 20:59:35,841 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13202] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-06-30 20:59:35,877 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13202] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-06-30 20:59:35,892 [salt.minion      :1711][INFO    ][13202] Returning information for job: 20190630205935276065
2019-06-30 20:59:36,449 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command state.apply with jid 20190630205936435558
2019-06-30 20:59:36,466 [salt.minion      :1432][INFO    ][13213] Starting a new job with PID 13213
2019-06-30 20:59:44,884 [salt.state       :915 ][INFO    ][13213] Loading fresh modules for state activity
2019-06-30 20:59:45,494 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13213] Executing command 'salt-minion --version' in directory '/root'
2019-06-30 20:59:45,779 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13213] Executing command 'salt-minion --version' in directory '/root'
2019-06-30 20:59:46,808 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13213] Executing command 'salt-minion --version' in directory '/root'
2019-06-30 20:59:47,096 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13213] Executing command 'salt-minion --version' in directory '/root'
2019-06-30 20:59:48,932 [salt.state       :1780][INFO    ][13213] Running state [salt-minion] at time 20:59:48.931938
2019-06-30 20:59:48,932 [salt.state       :1813][INFO    ][13213] Executing state pkg.installed for [salt-minion]
2019-06-30 20:59:48,933 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13213] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-30 20:59:49,051 [salt.state       :300 ][INFO    ][13213] All specified packages are already installed
2019-06-30 20:59:49,052 [salt.state       :1951][INFO    ][13213] Completed state [salt-minion] at time 20:59:49.052260 duration_in_ms=120.323
2019-06-30 20:59:49,052 [salt.state       :1780][INFO    ][13213] Running state [salt_minion_dependency_packages] at time 20:59:49.052573
2019-06-30 20:59:49,052 [salt.state       :1813][INFO    ][13213] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-06-30 20:59:49,062 [salt.state       :300 ][INFO    ][13213] All specified packages are already installed
2019-06-30 20:59:49,062 [salt.state       :1951][INFO    ][13213] Completed state [salt_minion_dependency_packages] at time 20:59:49.062701 duration_in_ms=10.127
2019-06-30 20:59:49,065 [salt.state       :1780][INFO    ][13213] Running state [/etc/salt/minion.d/minion.conf] at time 20:59:49.065507
2019-06-30 20:59:49,065 [salt.state       :1813][INFO    ][13213] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-06-30 20:59:49,344 [salt.state       :300 ][INFO    ][13213] File /etc/salt/minion.d/minion.conf is in the correct state
2019-06-30 20:59:49,344 [salt.state       :1951][INFO    ][13213] Completed state [/etc/salt/minion.d/minion.conf] at time 20:59:49.344353 duration_in_ms=278.846
2019-06-30 20:59:49,345 [salt.state       :1780][INFO    ][13213] Running state [python-netaddr] at time 20:59:49.345201
2019-06-30 20:59:49,346 [salt.state       :1813][INFO    ][13213] Executing state pkg.installed for [python-netaddr]
2019-06-30 20:59:49,363 [salt.state       :300 ][INFO    ][13213] All specified packages are already installed
2019-06-30 20:59:49,363 [salt.state       :1951][INFO    ][13213] Completed state [python-netaddr] at time 20:59:49.363660 duration_in_ms=18.46
2019-06-30 20:59:49,366 [salt.state       :1780][INFO    ][13213] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 20:59:49.366835
2019-06-30 20:59:49,367 [salt.state       :1813][INFO    ][13213] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-06-30 20:59:49,383 [salt.state       :300 ][INFO    ][13213] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-06-30 20:59:49,383 [salt.state       :1951][INFO    ][13213] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 20:59:49.383558 duration_in_ms=16.722
2019-06-30 20:59:49,385 [salt.state       :1780][INFO    ][13213] Running state [salt-minion] at time 20:59:49.385739
2019-06-30 20:59:49,386 [salt.state       :1813][INFO    ][13213] Executing state service.running for [salt-minion]
2019-06-30 20:59:49,387 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13213] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-06-30 20:59:49,435 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13213] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-06-30 20:59:49,458 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13213] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-06-30 20:59:49,480 [salt.state       :300 ][INFO    ][13213] The service salt-minion is already running
2019-06-30 20:59:49,481 [salt.state       :1951][INFO    ][13213] Completed state [salt-minion] at time 20:59:49.481332 duration_in_ms=95.593
2019-06-30 20:59:49,484 [salt.state       :1780][INFO    ][13213] Running state [/etc/salt/grains.d] at time 20:59:49.484036
2019-06-30 20:59:49,484 [salt.state       :1813][INFO    ][13213] Executing state file.directory for [/etc/salt/grains.d]
2019-06-30 20:59:49,486 [salt.state       :300 ][INFO    ][13213] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-06-30 20:59:49,486 [salt.state       :1951][INFO    ][13213] Completed state [/etc/salt/grains.d] at time 20:59:49.486489 duration_in_ms=2.453
2019-06-30 20:59:49,487 [salt.state       :1780][INFO    ][13213] Running state [/etc/salt/grains] at time 20:59:49.487660
2019-06-30 20:59:49,488 [salt.state       :1813][INFO    ][13213] Executing state file.managed for [/etc/salt/grains]
2019-06-30 20:59:49,489 [salt.state       :300 ][INFO    ][13213] File /etc/salt/grains exists with proper permissions. No changes made.
2019-06-30 20:59:49,489 [salt.state       :1951][INFO    ][13213] Completed state [/etc/salt/grains] at time 20:59:49.489338 duration_in_ms=1.677
2019-06-30 20:59:49,491 [salt.state       :1780][INFO    ][13213] Running state [/etc/salt/grains.d/placeholder] at time 20:59:49.491762
2019-06-30 20:59:49,492 [salt.state       :1813][INFO    ][13213] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-06-30 20:59:49,492 [salt.state       :300 ][INFO    ][13213] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-06-30 20:59:49,492 [salt.state       :1951][INFO    ][13213] Completed state [/etc/salt/grains.d/placeholder] at time 20:59:49.492814 duration_in_ms=1.052
2019-06-30 20:59:49,493 [salt.state       :1780][INFO    ][13213] Running state [/etc/salt/grains.d/sphinx] at time 20:59:49.493303
2019-06-30 20:59:49,493 [salt.state       :1813][INFO    ][13213] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-06-30 20:59:49,495 [salt.state       :300 ][INFO    ][13213] File /etc/salt/grains.d/sphinx is in the correct state
2019-06-30 20:59:49,495 [salt.state       :1951][INFO    ][13213] Completed state [/etc/salt/grains.d/sphinx] at time 20:59:49.495565 duration_in_ms=2.262
2019-06-30 20:59:49,497 [salt.state       :1780][INFO    ][13213] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 20:59:49.497623
2019-06-30 20:59:49,497 [salt.state       :1813][INFO    ][13213] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-06-30 20:59:49,498 [salt.state       :300 ][INFO    ][13213] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-06-30 20:59:49,498 [salt.state       :1951][INFO    ][13213] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 20:59:49.498455 duration_in_ms=0.832
2019-06-30 20:59:49,499 [salt.state       :1780][INFO    ][13213] Running state [/etc/salt/grains.d/dns_records] at time 20:59:49.498948
2019-06-30 20:59:49,499 [salt.state       :1813][INFO    ][13213] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-06-30 20:59:49,500 [salt.state       :300 ][INFO    ][13213] File /etc/salt/grains.d/dns_records is in the correct state
2019-06-30 20:59:49,500 [salt.state       :1951][INFO    ][13213] Completed state [/etc/salt/grains.d/dns_records] at time 20:59:49.500360 duration_in_ms=1.412
2019-06-30 20:59:49,501 [salt.state       :1780][INFO    ][13213] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 20:59:49.501299
2019-06-30 20:59:49,501 [salt.state       :1813][INFO    ][13213] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-06-30 20:59:49,503 [salt.state       :300 ][INFO    ][13213] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-06-30 20:59:49,503 [salt.state       :1951][INFO    ][13213] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 20:59:49.503826 duration_in_ms=2.527
2019-06-30 20:59:49,504 [salt.state       :1780][INFO    ][13213] Running state [/etc/salt/grains.d/salt] at time 20:59:49.504300
2019-06-30 20:59:49,504 [salt.state       :1813][INFO    ][13213] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-06-30 20:59:49,505 [salt.state       :300 ][INFO    ][13213] File /etc/salt/grains.d/salt is in the correct state
2019-06-30 20:59:49,505 [salt.state       :1951][INFO    ][13213] Completed state [/etc/salt/grains.d/salt] at time 20:59:49.505701 duration_in_ms=1.4
2019-06-30 20:59:49,506 [salt.state       :1780][INFO    ][13213] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 20:59:49.506584
2019-06-30 20:59:49,506 [salt.state       :1813][INFO    ][13213] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-06-30 20:59:49,507 [salt.state       :300 ][INFO    ][13213] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-06-30 20:59:49,507 [salt.state       :1951][INFO    ][13213] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 20:59:49.507334 duration_in_ms=0.75
2019-06-30 20:59:49,509 [salt.state       :1780][INFO    ][13213] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 20:59:49.509221
2019-06-30 20:59:49,509 [salt.state       :1813][INFO    ][13213] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-06-30 20:59:49,509 [salt.state       :300 ][INFO    ][13213] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-06-30 20:59:49,510 [salt.state       :1951][INFO    ][13213] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 20:59:49.510002 duration_in_ms=0.781
2019-06-30 20:59:49,510 [salt.state       :1780][INFO    ][13213] Running state [mine.update] at time 20:59:49.510659
2019-06-30 20:59:49,510 [salt.state       :1813][INFO    ][13213] Executing state module.wait for [mine.update]
2019-06-30 20:59:49,511 [salt.state       :300 ][INFO    ][13213] No changes made for mine.update
2019-06-30 20:59:49,511 [salt.state       :1951][INFO    ][13213] Completed state [mine.update] at time 20:59:49.511367 duration_in_ms=0.708
2019-06-30 20:59:49,511 [salt.state       :1780][INFO    ][13213] Running state [ca-certificates] at time 20:59:49.511609
2019-06-30 20:59:49,511 [salt.state       :1813][INFO    ][13213] Executing state pkg.installed for [ca-certificates]
2019-06-30 20:59:49,521 [salt.state       :300 ][INFO    ][13213] All specified packages are already installed
2019-06-30 20:59:49,521 [salt.state       :1951][INFO    ][13213] Completed state [ca-certificates] at time 20:59:49.521347 duration_in_ms=9.738
2019-06-30 20:59:49,522 [salt.state       :1780][INFO    ][13213] Running state [update-ca-certificates] at time 20:59:49.522352
2019-06-30 20:59:49,522 [salt.state       :1813][INFO    ][13213] Executing state cmd.wait for [update-ca-certificates]
2019-06-30 20:59:49,522 [salt.state       :300 ][INFO    ][13213] No changes made for update-ca-certificates
2019-06-30 20:59:49,523 [salt.state       :1951][INFO    ][13213] Completed state [update-ca-certificates] at time 20:59:49.523075 duration_in_ms=0.723
2019-06-30 20:59:49,523 [salt.state       :1780][INFO    ][13213] Running state [iptables] at time 20:59:49.523309
2019-06-30 20:59:49,523 [salt.state       :1813][INFO    ][13213] Executing state pkg.installed for [iptables]
2019-06-30 20:59:49,533 [salt.state       :300 ][INFO    ][13213] All specified packages are already installed
2019-06-30 20:59:49,533 [salt.state       :1951][INFO    ][13213] Completed state [iptables] at time 20:59:49.533591 duration_in_ms=10.282
2019-06-30 20:59:49,534 [salt.state       :1780][INFO    ][13213] Running state [iptables-persistent] at time 20:59:49.534191
2019-06-30 20:59:49,534 [salt.state       :1813][INFO    ][13213] Executing state pkg.installed for [iptables-persistent]
2019-06-30 20:59:49,543 [salt.state       :300 ][INFO    ][13213] All specified packages are already installed
2019-06-30 20:59:49,543 [salt.state       :1951][INFO    ][13213] Completed state [iptables-persistent] at time 20:59:49.543890 duration_in_ms=9.699
2019-06-30 20:59:49,545 [salt.state       :1780][INFO    ][13213] Running state [iptables_modules_v4_load] at time 20:59:49.545074
2019-06-30 20:59:49,545 [salt.state       :1813][INFO    ][13213] Executing state kmod.present for [iptables_modules_v4_load]
2019-06-30 20:59:49,545 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13213] Executing command 'lsmod' in directory '/root'
2019-06-30 20:59:49,570 [salt.state       :300 ][INFO    ][13213] Kernel modules iptable_filter, ip_tables are already present
2019-06-30 20:59:49,571 [salt.state       :1951][INFO    ][13213] Completed state [iptables_modules_v4_load] at time 20:59:49.571347 duration_in_ms=26.272
2019-06-30 20:59:49,572 [salt.state       :1780][INFO    ][13213] Running state [/etc/iptables/rules.v4] at time 20:59:49.572628
2019-06-30 20:59:49,573 [salt.state       :1813][INFO    ][13213] Executing state file.managed for [/etc/iptables/rules.v4]
2019-06-30 20:59:49,686 [salt.state       :300 ][INFO    ][13213] File /etc/iptables/rules.v4 is in the correct state
2019-06-30 20:59:49,686 [salt.state       :1951][INFO    ][13213] Completed state [/etc/iptables/rules.v4] at time 20:59:49.686658 duration_in_ms=114.031
2019-06-30 20:59:49,687 [salt.state       :1780][INFO    ][13213] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 20:59:49.687727
2019-06-30 20:59:49,688 [salt.state       :1813][INFO    ][13213] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-06-30 20:59:49,688 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13213] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-06-30 20:59:49,711 [salt.state       :300 ][INFO    ][13213] onlyif execution failed
2019-06-30 20:59:49,712 [salt.state       :1951][INFO    ][13213] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 20:59:49.712014 duration_in_ms=24.285
2019-06-30 20:59:49,714 [salt.state       :1780][INFO    ][13213] Running state [netfilter-persistent] at time 20:59:49.714055
2019-06-30 20:59:49,714 [salt.state       :1813][INFO    ][13213] Executing state service.running for [netfilter-persistent]
2019-06-30 20:59:49,716 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13213] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-06-30 20:59:49,740 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13213] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-06-30 20:59:49,763 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13213] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-06-30 20:59:49,788 [salt.state       :300 ][INFO    ][13213] The service netfilter-persistent is already running
2019-06-30 20:59:49,789 [salt.state       :1951][INFO    ][13213] Completed state [netfilter-persistent] at time 20:59:49.789168 duration_in_ms=75.113
2019-06-30 20:59:49,792 [salt.state       :1780][INFO    ][13213] Running state [iptables_extra.remove_stale_tables] at time 20:59:49.792603
2019-06-30 20:59:49,793 [salt.state       :1813][INFO    ][13213] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-06-30 20:59:49,793 [salt.state       :300 ][INFO    ][13213] No changes made for iptables_extra.remove_stale_tables
2019-06-30 20:59:49,793 [salt.state       :1951][INFO    ][13213] Completed state [iptables_extra.remove_stale_tables] at time 20:59:49.793768 duration_in_ms=1.165
2019-06-30 20:59:49,794 [salt.state       :1780][INFO    ][13213] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 20:59:49.794108
2019-06-30 20:59:49,794 [salt.state       :1813][INFO    ][13213] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-06-30 20:59:49,795 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13213] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-06-30 20:59:49,811 [salt.state       :300 ][INFO    ][13213] onlyif execution failed
2019-06-30 20:59:49,812 [salt.state       :1951][INFO    ][13213] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 20:59:49.811991 duration_in_ms=17.883
2019-06-30 20:59:49,813 [salt.state       :1780][INFO    ][13213] Running state [/etc/iptables/rules.v6] at time 20:59:49.813136
2019-06-30 20:59:49,813 [salt.state       :1813][INFO    ][13213] Executing state file.absent for [/etc/iptables/rules.v6]
2019-06-30 20:59:49,814 [salt.state       :300 ][INFO    ][13213] File /etc/iptables/rules.v6 is not present
2019-06-30 20:59:49,814 [salt.state       :1951][INFO    ][13213] Completed state [/etc/iptables/rules.v6] at time 20:59:49.814539 duration_in_ms=1.403
2019-06-30 20:59:49,815 [salt.state       :1780][INFO    ][13213] Running state [iptables_extra.flush_all] at time 20:59:49.815363
2019-06-30 20:59:49,815 [salt.state       :1813][INFO    ][13213] Executing state module.wait for [iptables_extra.flush_all]
2019-06-30 20:59:49,816 [salt.state       :300 ][INFO    ][13213] No changes made for iptables_extra.flush_all
2019-06-30 20:59:49,816 [salt.state       :1951][INFO    ][13213] Completed state [iptables_extra.flush_all] at time 20:59:49.816252 duration_in_ms=0.889
2019-06-30 20:59:49,822 [salt.minion      :1711][INFO    ][13213] Returning information for job: 20190630205936435558
2019-06-30 20:59:50,406 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command state.apply with jid 20190630205950387756
2019-06-30 20:59:50,437 [salt.minion      :1432][INFO    ][13318] Starting a new job with PID 13318
2019-06-30 20:59:51,560 [salt.state       :915 ][INFO    ][13318] Loading fresh modules for state activity
2019-06-30 20:59:52,494 [salt.state       :1780][INFO    ][13318] Running state [maas-rack-controller] at time 20:59:52.494523
2019-06-30 20:59:52,495 [salt.state       :1813][INFO    ][13318] Executing state pkg.installed for [maas-rack-controller]
2019-06-30 20:59:52,495 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13318] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-30 20:59:52,615 [salt.state       :300 ][INFO    ][13318] All specified packages are already installed
2019-06-30 20:59:52,615 [salt.state       :1951][INFO    ][13318] Completed state [maas-rack-controller] at time 20:59:52.615480 duration_in_ms=120.957
2019-06-30 20:59:52,615 [salt.state       :1780][INFO    ][13318] Running state [ipmitool] at time 20:59:52.615809
2019-06-30 20:59:52,616 [salt.state       :1813][INFO    ][13318] Executing state pkg.installed for [ipmitool]
2019-06-30 20:59:52,626 [salt.state       :300 ][INFO    ][13318] All specified packages are already installed
2019-06-30 20:59:52,626 [salt.state       :1951][INFO    ][13318] Completed state [ipmitool] at time 20:59:52.626229 duration_in_ms=10.42
2019-06-30 20:59:52,632 [salt.state       :1780][INFO    ][13318] Running state [/etc/maas/rackd.conf] at time 20:59:52.632739
2019-06-30 20:59:52,633 [salt.state       :1813][INFO    ][13318] Executing state file.line for [/etc/maas/rackd.conf]
2019-06-30 20:59:52,636 [salt.state       :300 ][INFO    ][13318] No changes needed to be made
2019-06-30 20:59:52,636 [salt.state       :1951][INFO    ][13318] Completed state [/etc/maas/rackd.conf] at time 20:59:52.636278 duration_in_ms=3.54
2019-06-30 20:59:52,636 [salt.state       :1780][INFO    ][13318] Running state [/etc/maas/rackd.conf] at time 20:59:52.636565
2019-06-30 20:59:52,636 [salt.state       :1813][INFO    ][13318] Executing state file.managed for [/etc/maas/rackd.conf]
2019-06-30 20:59:52,637 [salt.loaded.int.states.file:2298][WARNING ][13318] 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-06-30 20:59:52,638 [salt.state       :300 ][INFO    ][13318] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-06-30 20:59:52,638 [salt.state       :1951][INFO    ][13318] Completed state [/etc/maas/rackd.conf] at time 20:59:52.638415 duration_in_ms=1.85
2019-06-30 20:59:52,639 [salt.state       :1780][INFO    ][13318] Running state [maas-rackd] at time 20:59:52.639571
2019-06-30 20:59:52,639 [salt.state       :1813][INFO    ][13318] Executing state service.running for [maas-rackd]
2019-06-30 20:59:52,640 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13318] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-06-30 20:59:52,687 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13318] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-06-30 20:59:52,711 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13318] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-06-30 20:59:52,733 [salt.state       :300 ][INFO    ][13318] The service maas-rackd is already running
2019-06-30 20:59:52,734 [salt.state       :1951][INFO    ][13318] Completed state [maas-rackd] at time 20:59:52.734481 duration_in_ms=94.909
2019-06-30 20:59:52,736 [salt.minion      :1711][INFO    ][13318] Returning information for job: 20190630205950387756
2019-06-30 20:59:53,298 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command state.apply with jid 20190630205953281085
2019-06-30 20:59:53,324 [salt.minion      :1432][INFO    ][13343] Starting a new job with PID 13343
2019-06-30 20:59:54,508 [salt.state       :915 ][INFO    ][13343] Loading fresh modules for state activity
2019-06-30 20:59:55,582 [salt.state       :1780][INFO    ][13343] Running state [maas-region-controller] at time 20:59:55.582072
2019-06-30 20:59:55,582 [salt.state       :1813][INFO    ][13343] Executing state pkg.installed for [maas-region-controller]
2019-06-30 20:59:55,584 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13343] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-30 20:59:55,703 [salt.state       :300 ][INFO    ][13343] All specified packages are already installed
2019-06-30 20:59:55,703 [salt.state       :1951][INFO    ][13343] Completed state [maas-region-controller] at time 20:59:55.703490 duration_in_ms=121.419
2019-06-30 20:59:55,703 [salt.state       :1780][INFO    ][13343] Running state [python-oauth] at time 20:59:55.703819
2019-06-30 20:59:55,704 [salt.state       :1813][INFO    ][13343] Executing state pkg.installed for [python-oauth]
2019-06-30 20:59:55,713 [salt.state       :300 ][INFO    ][13343] All specified packages are already installed
2019-06-30 20:59:55,714 [salt.state       :1951][INFO    ][13343] Completed state [python-oauth] at time 20:59:55.714076 duration_in_ms=10.256
2019-06-30 20:59:55,717 [salt.state       :1780][INFO    ][13343] Running state [/etc/maas/regiond.conf] at time 20:59:55.717100
2019-06-30 20:59:55,717 [salt.state       :1813][INFO    ][13343] Executing state file.replace for [/etc/maas/regiond.conf]
2019-06-30 20:59:55,723 [salt.state       :300 ][INFO    ][13343] No changes needed to be made
2019-06-30 20:59:55,723 [salt.state       :1951][INFO    ][13343] Completed state [/etc/maas/regiond.conf] at time 20:59:55.723311 duration_in_ms=6.211
2019-06-30 20:59:55,723 [salt.state       :1780][INFO    ][13343] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 20:59:55.723778
2019-06-30 20:59:55,724 [salt.state       :1813][INFO    ][13343] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-06-30 20:59:55,786 [salt.state       :300 ][INFO    ][13343] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-06-30 20:59:55,786 [salt.state       :1951][INFO    ][13343] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 20:59:55.786851 duration_in_ms=63.073
2019-06-30 20:59:55,787 [salt.state       :1780][INFO    ][13343] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 20:59:55.787434
2019-06-30 20:59:55,787 [salt.state       :1813][INFO    ][13343] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-06-30 20:59:55,792 [salt.state       :300 ][INFO    ][13343] No changes needed to be made
2019-06-30 20:59:55,792 [salt.state       :1951][INFO    ][13343] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 20:59:55.792702 duration_in_ms=5.268
2019-06-30 20:59:55,793 [salt.state       :1780][INFO    ][13343] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 20:59:55.793187
2019-06-30 20:59:55,793 [salt.state       :1813][INFO    ][13343] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-06-30 20:59:55,797 [salt.state       :300 ][INFO    ][13343] No changes needed to be made
2019-06-30 20:59:55,797 [salt.state       :1951][INFO    ][13343] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 20:59:55.797346 duration_in_ms=4.158
2019-06-30 20:59:55,797 [salt.state       :1780][INFO    ][13343] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 20:59:55.797843
2019-06-30 20:59:55,798 [salt.state       :1813][INFO    ][13343] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-06-30 20:59:55,831 [salt.state       :300 ][INFO    ][13343] No changes needed to be made
2019-06-30 20:59:55,831 [salt.state       :1951][INFO    ][13343] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 20:59:55.831621 duration_in_ms=33.778
2019-06-30 20:59:55,832 [salt.state       :1780][INFO    ][13343] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 20:59:55.832136
2019-06-30 20:59:55,832 [salt.state       :1813][INFO    ][13343] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-06-30 20:59:55,848 [salt.state       :300 ][INFO    ][13343] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-06-30 20:59:55,848 [salt.state       :1951][INFO    ][13343] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 20:59:55.848542 duration_in_ms=16.406
2019-06-30 20:59:55,850 [salt.state       :1780][INFO    ][13343] Running state [a2enmod headers] at time 20:59:55.850291
2019-06-30 20:59:55,850 [salt.state       :1813][INFO    ][13343] Executing state cmd.run for [a2enmod headers]
2019-06-30 20:59:55,851 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13343] Executing command 'a2enmod headers' in directory '/root'
2019-06-30 20:59:55,917 [salt.state       :300 ][INFO    ][13343] {'pid': 13362, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-06-30 20:59:55,918 [salt.state       :1951][INFO    ][13343] Completed state [a2enmod headers] at time 20:59:55.918174 duration_in_ms=67.883
2019-06-30 20:59:55,918 [salt.state       :1780][INFO    ][13343] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 20:59:55.918786
2019-06-30 20:59:55,919 [salt.state       :1813][INFO    ][13343] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-06-30 20:59:55,945 [salt.state       :300 ][INFO    ][13343] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-06-30 20:59:55,945 [salt.state       :1951][INFO    ][13343] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 20:59:55.945532 duration_in_ms=26.746
2019-06-30 20:59:55,947 [salt.state       :1780][INFO    ][13343] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 20:59:55.946997
2019-06-30 20:59:55,947 [salt.state       :1813][INFO    ][13343] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-06-30 20:59:56,009 [salt.state       :300 ][INFO    ][13343] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-06-30 20:59:56,009 [salt.state       :1951][INFO    ][13343] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 20:59:56.009696 duration_in_ms=62.7
2019-06-30 20:59:56,010 [salt.state       :1780][INFO    ][13343] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 20:59:56.010243
2019-06-30 20:59:56,010 [salt.state       :1813][INFO    ][13343] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-06-30 20:59:56,075 [salt.state       :300 ][INFO    ][13343] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-06-30 20:59:56,075 [salt.state       :1951][INFO    ][13343] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 20:59:56.075225 duration_in_ms=64.982
2019-06-30 20:59:56,075 [salt.state       :1780][INFO    ][13343] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 20:59:56.075729
2019-06-30 20:59:56,076 [salt.state       :1813][INFO    ][13343] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-06-30 20:59:56,140 [salt.state       :300 ][INFO    ][13343] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-06-30 20:59:56,140 [salt.state       :1951][INFO    ][13343] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 20:59:56.140785 duration_in_ms=65.056
2019-06-30 20:59:56,141 [salt.state       :1780][INFO    ][13343] Running state [/root/.pgpass] at time 20:59:56.141031
2019-06-30 20:59:56,141 [salt.state       :1813][INFO    ][13343] Executing state file.managed for [/root/.pgpass]
2019-06-30 20:59:56,194 [salt.state       :300 ][INFO    ][13343] File /root/.pgpass is in the correct state
2019-06-30 20:59:56,194 [salt.state       :1951][INFO    ][13343] Completed state [/root/.pgpass] at time 20:59:56.194317 duration_in_ms=53.285
2019-06-30 20:59:56,199 [salt.state       :1780][INFO    ][13343] Running state [maas-region syncdb --noinput] at time 20:59:56.199660
2019-06-30 20:59:56,199 [salt.state       :1813][INFO    ][13343] Executing state cmd.run for [maas-region syncdb --noinput]
2019-06-30 20:59:56,200 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13343] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-06-30 20:59:58,810 [salt.state       :300 ][INFO    ][13343] {'pid': 13375, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: maasserver, sessions, sites, contenttypes, auth, piston3, metadataserver\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-06-30 20:59:58,811 [salt.state       :1951][INFO    ][13343] Completed state [maas-region syncdb --noinput] at time 20:59:58.811098 duration_in_ms=2611.436
2019-06-30 20:59:58,811 [salt.state       :2022][WARNING ][13343] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-06-30 20:59:58,815 [salt.state       :1780][INFO    ][13343] Running state [maas-regiond] at time 20:59:58.814993
2019-06-30 20:59:58,815 [salt.state       :1813][INFO    ][13343] Executing state service.running for [maas-regiond]
2019-06-30 20:59:58,816 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13343] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-06-30 20:59:58,858 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13343] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-06-30 20:59:58,881 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13343] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-06-30 20:59:58,906 [salt.state       :300 ][INFO    ][13343] The service maas-regiond is already running
2019-06-30 20:59:58,907 [salt.state       :1951][INFO    ][13343] Completed state [maas-regiond] at time 20:59:58.907712 duration_in_ms=92.717
2019-06-30 20:59:58,912 [salt.state       :1780][INFO    ][13343] Running state [bind9] at time 20:59:58.912571
2019-06-30 20:59:58,913 [salt.state       :1813][INFO    ][13343] Executing state service.running for [bind9]
2019-06-30 20:59:58,913 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13343] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-06-30 20:59:58,938 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13343] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-06-30 20:59:58,957 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13343] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-06-30 20:59:58,981 [salt.state       :300 ][INFO    ][13343] The service bind9 is already running
2019-06-30 20:59:58,982 [salt.state       :1951][INFO    ][13343] Completed state [bind9] at time 20:59:58.982397 duration_in_ms=69.826
2019-06-30 20:59:58,985 [salt.state       :1780][INFO    ][13343] Running state [apache2] at time 20:59:58.985272
2019-06-30 20:59:58,987 [salt.state       :1813][INFO    ][13343] Executing state service.running for [apache2]
2019-06-30 20:59:58,988 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13343] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-06-30 20:59:59,011 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13343] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-06-30 20:59:59,032 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13343] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-06-30 20:59:59,061 [salt.state       :300 ][INFO    ][13343] The service apache2 is already running
2019-06-30 20:59:59,062 [salt.state       :1951][INFO    ][13343] Completed state [apache2] at time 20:59:59.062123 duration_in_ms=76.851
2019-06-30 20:59:59,064 [salt.state       :1780][INFO    ][13343] Running state [maasng.wait_for_http_code] at time 20:59:59.064769
2019-06-30 20:59:59,065 [salt.state       :1813][INFO    ][13343] Executing state module.run for [maasng.wait_for_http_code]
2019-06-30 20:59:59,070 [salt.utils.decorators:613 ][WARNING ][13343] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-30 20:59:59,249 [salt.state       :300 ][INFO    ][13343] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-06-30 20:59:59,250 [salt.state       :1951][INFO    ][13343] Completed state [maasng.wait_for_http_code] at time 20:59:59.249615 duration_in_ms=184.847
2019-06-30 20:59:59,251 [salt.state       :1780][INFO    ][13343] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 20:59:59.251626
2019-06-30 20:59:59,252 [salt.state       :1813][INFO    ][13343] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-06-30 20:59:59,252 [salt.state       :300 ][INFO    ][13343] /var/lib/maas/.setup_admin exists
2019-06-30 20:59:59,253 [salt.state       :1951][INFO    ][13343] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 20:59:59.253024 duration_in_ms=1.399
2019-06-30 20:59:59,254 [salt.state       :1780][INFO    ][13343] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:59:59.254282
2019-06-30 20:59:59,254 [salt.state       :1813][INFO    ][13343] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-30 20:59:59,255 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13343] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-30 21:00:00,949 [salt.state       :300 ][INFO    ][13343] {'pid': 13396, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-30 21:00:00,951 [salt.state       :1951][INFO    ][13343] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:00:00.950855 duration_in_ms=1696.572
2019-06-30 21:00:00,959 [salt.state       :1780][INFO    ][13343] Running state [maas_region_boot_source_resources_mirror] at time 21:00:00.959847
2019-06-30 21:00:00,960 [salt.state       :1813][INFO    ][13343] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-06-30 21:00:01,064 [salt.state       :300 ][INFO    ][13343] {'changes': {}}
2019-06-30 21:00:01,064 [salt.state       :1951][INFO    ][13343] Completed state [maas_region_boot_source_resources_mirror] at time 21:00:01.064820 duration_in_ms=104.972
2019-06-30 21:00:01,067 [salt.state       :1780][INFO    ][13343] Running state [maasng.boot_resources_import] at time 21:00:01.066915
2019-06-30 21:00:01,067 [salt.state       :1813][INFO    ][13343] Executing state module.run for [maasng.boot_resources_import]
2019-06-30 21:00:01,068 [salt.utils.decorators:613 ][WARNING ][13343] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-30 21:00:01,171 [salt.loaded.ext.module.maasng:1600][INFO    ][13343] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-06-30 21:00:06,226 [salt.loaded.ext.module.maasng:1600][INFO    ][13343] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-06-30 21:00:08,338 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630210008327816
2019-06-30 21:00:08,362 [salt.minion      :1432][INFO    ][13428] Starting a new job with PID 13428
2019-06-30 21:00:08,385 [salt.minion      :1711][INFO    ][13428] Returning information for job: 20190630210008327816
2019-06-30 21:00:11,327 [salt.state       :300 ][INFO    ][13343] {'ret': True}
2019-06-30 21:00:11,328 [salt.state       :1951][INFO    ][13343] Completed state [maasng.boot_resources_import] at time 21:00:11.328654 duration_in_ms=10261.737
2019-06-30 21:00:11,331 [salt.state       :1780][INFO    ][13343] Running state [maas_region_boot_sources_selection_xenial] at time 21:00:11.331190
2019-06-30 21:00:11,331 [salt.state       :1813][INFO    ][13343] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-06-30 21:00:11,515 [salt.state       :300 ][INFO    ][13343] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-06-30 21:00:11,516 [salt.state       :1951][INFO    ][13343] Completed state [maas_region_boot_sources_selection_xenial] at time 21:00:11.516343 duration_in_ms=185.153
2019-06-30 21:00:11,518 [salt.state       :1780][INFO    ][13343] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 21:00:11.518763
2019-06-30 21:00:11,519 [salt.state       :1813][INFO    ][13343] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-06-30 21:00:11,519 [salt.utils.decorators:613 ][WARNING ][13343] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-30 21:00:11,520 [salt.loaded.ext.module.maasng:1771][INFO    ][13343] boot-sources sync initiated for ALL Rack's
2019-06-30 21:00:12,663 [salt.state       :300 ][INFO    ][13343] {'ret': True}
2019-06-30 21:00:12,663 [salt.state       :1951][INFO    ][13343] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 21:00:12.663908 duration_in_ms=1145.144
2019-06-30 21:00:12,666 [salt.state       :1780][INFO    ][13343] Running state [maas.process_maas_config] at time 21:00:12.666745
2019-06-30 21:00:12,667 [salt.state       :1813][INFO    ][13343] Executing state module.run for [maas.process_maas_config]
2019-06-30 21:00:12,667 [salt.utils.decorators:613 ][WARNING ][13343] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-30 21:00:12,668 [salt.loaded.ext.module.maas:92  ][INFO    ][13343] maasconfig name=enable_http_proxy value=True
2019-06-30 21:00:12,727 [salt.loaded.ext.module.maas:92  ][INFO    ][13343] maasconfig name=upstream_dns value=8.8.8.8
2019-06-30 21:00:14,204 [salt.loaded.ext.module.maas:92  ][INFO    ][13343] maasconfig name=commissioning_distro_series value=xenial
2019-06-30 21:00:14,266 [salt.loaded.ext.module.maas:92  ][INFO    ][13343] maasconfig name=default_osystem value=ubuntu
2019-06-30 21:00:14,324 [salt.loaded.ext.module.maas:92  ][INFO    ][13343] maasconfig name=active_discovery_interval value=600
2019-06-30 21:00:14,373 [salt.loaded.ext.module.maas:92  ][INFO    ][13343] maasconfig name=dnssec_validation value=no
2019-06-30 21:00:14,431 [salt.loaded.ext.module.maas:92  ][INFO    ][13343] maasconfig name=maas_name value=mas01
2019-06-30 21:00:14,480 [salt.loaded.ext.module.maas:92  ][INFO    ][13343] maasconfig name=network_discovery value=enabled
2019-06-30 21:00:14,579 [salt.loaded.ext.module.maas:92  ][INFO    ][13343] maasconfig name=enable_third_party_drivers value=True
2019-06-30 21:00:14,626 [salt.loaded.ext.module.maas:92  ][INFO    ][13343] maasconfig name=default_storage_layout value=lvm
2019-06-30 21:00:14,674 [salt.loaded.ext.module.maas:92  ][INFO    ][13343] maasconfig name=ntp_external_only value=True
2019-06-30 21:00:14,722 [salt.loaded.ext.module.maas:92  ][INFO    ][13343] maasconfig name=disk_erase_with_secure_erase value=False
2019-06-30 21:00:14,780 [salt.loaded.ext.module.maas:92  ][INFO    ][13343] maasconfig name=default_distro_series value=xenial
2019-06-30 21:00:14,856 [salt.loaded.ext.module.maas:92  ][INFO    ][13343] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-06-30 21:00:14,996 [salt.state       :300 ][INFO    ][13343] {'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-06-30 21:00:14,997 [salt.state       :1951][INFO    ][13343] Completed state [maas.process_maas_config] at time 21:00:14.997158 duration_in_ms=2330.413
2019-06-30 21:00:14,999 [salt.state       :1780][INFO    ][13343] Running state [pxe_admin] at time 21:00:14.998952
2019-06-30 21:00:14,999 [salt.state       :1813][INFO    ][13343] Executing state maasng.fabric_present for [pxe_admin]
2019-06-30 21:00:15,062 [salt.loaded.ext.module.maasng:945 ][INFO    ][13343] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'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'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'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'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'anbk8e', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}]
2019-06-30 21:00:15,129 [salt.loaded.ext.module.maasng:1008][WARNING ][13343] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-06-30 21:00:15,130 [salt.loaded.ext.module.maasng:1011][WARNING ][13343] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-06-30 21:00:15,190 [salt.state       :300 ][INFO    ][13343] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-06-30 21:00:15,191 [salt.state       :1951][INFO    ][13343] Completed state [pxe_admin] at time 21:00:15.191179 duration_in_ms=192.226
2019-06-30 21:00:15,191 [salt.state       :1780][INFO    ][13343] Running state [vlan 0] at time 21:00:15.191502
2019-06-30 21:00:15,191 [salt.state       :1813][INFO    ][13343] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-06-30 21:00:15,248 [salt.loaded.ext.module.maasng:945 ][INFO    ][13343] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'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'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'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'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'anbk8e', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}]
2019-06-30 21:00:15,391 [salt.loaded.ext.module.maasng:945 ][INFO    ][13343] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'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'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'anbk8e', 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'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-06-30 21:00:15,641 [salt.loaded.ext.module.maasng:945 ][INFO    ][13343] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'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'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'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'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'anbk8e', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}]
2019-06-30 21:00:15,723 [salt.state       :300 ][INFO    ][13343] {'new': 'Vlan untagged was updated'}
2019-06-30 21:00:15,723 [salt.state       :1951][INFO    ][13343] Completed state [vlan 0] at time 21:00:15.723833 duration_in_ms=532.329
2019-06-30 21:00:15,725 [salt.state       :1780][INFO    ][13343] Running state [192.168.11.0/24] at time 21:00:15.724982
2019-06-30 21:00:15,725 [salt.state       :1813][INFO    ][13343] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-06-30 21:00:15,925 [salt.loaded.ext.module.maasng:945 ][INFO    ][13343] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'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'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'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'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'anbk8e', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}]
2019-06-30 21:00:15,928 [salt.loaded.ext.module.maasng:1235][WARNING ][13343] Ignoring parameter vlan:0
2019-06-30 21:00:16,005 [salt.state       :300 ][INFO    ][13343] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-06-30 21:00:16,005 [salt.state       :1951][INFO    ][13343] Completed state [192.168.11.0/24] at time 21:00:16.005493 duration_in_ms=280.511
2019-06-30 21:00:16,006 [salt.state       :1780][INFO    ][13343] Running state [maas_create_iprange_1] at time 21:00:16.006333
2019-06-30 21:00:16,006 [salt.state       :1813][INFO    ][13343] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-06-30 21:00:16,059 [salt.state       :300 ][INFO    ][13343] Iprange maas_create_iprange_1 already exist.
2019-06-30 21:00:16,059 [salt.state       :1951][INFO    ][13343] Completed state [maas_create_iprange_1] at time 21:00:16.059913 duration_in_ms=53.58
2019-06-30 21:00:16,060 [salt.state       :1780][INFO    ][13343] Running state [vlan 0] at time 21:00:16.060165
2019-06-30 21:00:16,060 [salt.state       :1813][INFO    ][13343] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-06-30 21:00:16,119 [salt.loaded.ext.module.maasng:945 ][INFO    ][13343] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'name': u'fabric-2', u'id': 2}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'anbk8e', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'name': u'pxe_admin', u'id': 1}]
2019-06-30 21:00:16,227 [salt.loaded.ext.module.maasng:945 ][INFO    ][13343] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'name': u'fabric-2', u'id': 2}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'anbk8e', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'name': u'pxe_admin', u'id': 1}]
2019-06-30 21:00:16,507 [salt.loaded.ext.module.maasng:945 ][INFO    ][13343] [{u'id': 0, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', 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': 2, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-2', 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': 1, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'anbk8e', u'fabric': u'pxe_admin', 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/'}]
2019-06-30 21:00:16,615 [salt.state       :300 ][INFO    ][13343] {'new': 'Vlan untagged was updated'}
2019-06-30 21:00:16,615 [salt.state       :1951][INFO    ][13343] Completed state [vlan 0] at time 21:00:16.615434 duration_in_ms=555.268
2019-06-30 21:00:16,616 [salt.state       :1780][INFO    ][13343] Running state [opnfv] at time 21:00:16.616179
2019-06-30 21:00:16,616 [salt.state       :1813][INFO    ][13343] Executing state maasng.sshkey_present for [opnfv]
2019-06-30 21:00:16,667 [salt.loaded.ext.module.maasng:1903][INFO    ][13343] [{u'keysource': u'', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b', u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/'}]
2019-06-30 21:00:16,667 [salt.state       :300 ][INFO    ][13343] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-06-30 21:00:16,667 [salt.state       :1951][INFO    ][13343] Completed state [opnfv] at time 21:00:16.667517 duration_in_ms=51.338
2019-06-30 21:00:16,671 [salt.minion      :1711][INFO    ][13343] Returning information for job: 20190630205953281085
2019-06-30 21:00:17,286 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command state.apply with jid 20190630210017272110
2019-06-30 21:00:17,313 [salt.minion      :1432][INFO    ][13776] Starting a new job with PID 13776
2019-06-30 21:00:25,686 [salt.state       :915 ][INFO    ][13776] Loading fresh modules for state activity
2019-06-30 21:00:25,797 [salt.state       :1780][INFO    ][13776] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:00:25.797194
2019-06-30 21:00:25,797 [salt.state       :1813][INFO    ][13776] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-30 21:00:25,799 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13776] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-30 21:00:27,505 [salt.state       :300 ][INFO    ][13776] {'pid': 13813, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-30 21:00:27,507 [salt.state       :1951][INFO    ][13776] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:00:27.507010 duration_in_ms=1709.814
2019-06-30 21:00:27,510 [salt.state       :1780][INFO    ][13776] Running state [maas.process_machines] at time 21:00:27.510272
2019-06-30 21:00:27,510 [salt.state       :1813][INFO    ][13776] Executing state module.run for [maas.process_machines]
2019-06-30 21:00:27,511 [salt.utils.decorators:613 ][WARNING ][13776] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-30 21:00:28,098 [salt.loaded.ext.module.maas:412 ][WARNING ][13776] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-30 21:00:28,099 [salt.loaded.ext.module.maas:92  ][INFO    ][13776] 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=8kr3ec architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-30 21:00:29,316 [salt.loaded.ext.module.maas:412 ][WARNING ][13776] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-30 21:00:29,316 [salt.loaded.ext.module.maas:92  ][INFO    ][13776] 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=qycw3y architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-30 21:00:30,659 [salt.loaded.ext.module.maas:412 ][WARNING ][13776] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-30 21:00:30,660 [salt.loaded.ext.module.maas:92  ][INFO    ][13776] 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=twe3hq architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-30 21:00:31,866 [salt.loaded.ext.module.maas:412 ][WARNING ][13776] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-30 21:00:31,867 [salt.loaded.ext.module.maas:92  ][INFO    ][13776] 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=get4hs architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-30 21:00:32,338 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630210032323826
2019-06-30 21:00:32,374 [salt.minion      :1432][INFO    ][13974] Starting a new job with PID 13974
2019-06-30 21:00:32,399 [salt.minion      :1711][INFO    ][13974] Returning information for job: 20190630210032323826
2019-06-30 21:00:33,096 [salt.loaded.ext.module.maas:412 ][WARNING ][13776] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-30 21:00:33,099 [salt.loaded.ext.module.maas:92  ][INFO    ][13776] 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=s44dr4 architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-30 21:00:34,379 [salt.state       :300 ][INFO    ][13776] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-06-30 21:00:34,380 [salt.state       :1951][INFO    ][13776] Completed state [maas.process_machines] at time 21:00:34.380009 duration_in_ms=6869.735
2019-06-30 21:00:34,384 [salt.minion      :1711][INFO    ][13776] Returning information for job: 20190630210017272110
2019-06-30 21:01:07,416 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command state.apply with jid 20190630210107393526
2019-06-30 21:01:07,444 [salt.minion      :1432][INFO    ][14072] Starting a new job with PID 14072
2019-06-30 21:01:15,855 [salt.state       :915 ][INFO    ][14072] Loading fresh modules for state activity
2019-06-30 21:01:15,967 [salt.state       :1780][INFO    ][14072] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:01:15.967258
2019-06-30 21:01:15,967 [salt.state       :1813][INFO    ][14072] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-30 21:01:15,971 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14072] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-30 21:01:17,692 [salt.state       :300 ][INFO    ][14072] {'pid': 14083, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-30 21:01:17,693 [salt.state       :1951][INFO    ][14072] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:01:17.693428 duration_in_ms=1726.171
2019-06-30 21:01:17,696 [salt.state       :1780][INFO    ][14072] Running state [maas.wait_for_machine_status] at time 21:01:17.696751
2019-06-30 21:01:17,697 [salt.state       :1813][INFO    ][14072] Executing state module.run for [maas.wait_for_machine_status]
2019-06-30 21:01:17,698 [salt.utils.decorators:613 ][WARNING ][14072] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-30 21:01:20,497 [salt.state       :300 ][INFO    ][14072] {'ret': True}
2019-06-30 21:01:20,499 [salt.state       :1951][INFO    ][14072] Completed state [maas.wait_for_machine_status] at time 21:01:20.499804 duration_in_ms=2803.051
2019-06-30 21:01:20,505 [salt.minion      :1711][INFO    ][14072] Returning information for job: 20190630210107393526
2019-06-30 21:01:21,077 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command state.apply with jid 20190630210121060752
2019-06-30 21:01:21,106 [salt.minion      :1432][INFO    ][14098] Starting a new job with PID 14098
2019-06-30 21:01:22,220 [salt.state       :915 ][INFO    ][14098] Loading fresh modules for state activity
2019-06-30 21:01:22,419 [salt.state       :1780][INFO    ][14098] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:01:22.419809
2019-06-30 21:01:22,420 [salt.state       :1813][INFO    ][14098] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-30 21:01:22,422 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14098] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-30 21:01:24,176 [salt.state       :300 ][INFO    ][14098] {'pid': 14118, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-30 21:01:24,178 [salt.state       :1951][INFO    ][14098] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:01:24.177539 duration_in_ms=1757.73
2019-06-30 21:01:24,181 [salt.state       :1780][INFO    ][14098] Running state [maas_machines_storage_cmp002_lvm] at time 21:01:24.181598
2019-06-30 21:01:24,184 [salt.state       :1813][INFO    ][14098] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-06-30 21:01:24,737 [salt.state       :300 ][INFO    ][14098] Machine cmp002 is not in Ready state.
2019-06-30 21:01:24,738 [salt.state       :1951][INFO    ][14098] Completed state [maas_machines_storage_cmp002_lvm] at time 21:01:24.738453 duration_in_ms=556.854
2019-06-30 21:01:24,739 [salt.state       :1780][INFO    ][14098] Running state [maas_machines_storage_cmp001_lvm] at time 21:01:24.739047
2019-06-30 21:01:24,739 [salt.state       :1813][INFO    ][14098] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-06-30 21:01:25,504 [salt.state       :300 ][INFO    ][14098] Machine cmp001 is not in Ready state.
2019-06-30 21:01:25,504 [salt.state       :1951][INFO    ][14098] Completed state [maas_machines_storage_cmp001_lvm] at time 21:01:25.504550 duration_in_ms=765.504
2019-06-30 21:01:25,507 [salt.minion      :1711][INFO    ][14098] Returning information for job: 20190630210121060752
2019-06-30 21:01:26,063 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command state.apply with jid 20190630210126049522
2019-06-30 21:01:26,092 [salt.minion      :1432][INFO    ][14129] Starting a new job with PID 14129
2019-06-30 21:01:27,256 [salt.state       :915 ][INFO    ][14129] Loading fresh modules for state activity
2019-06-30 21:01:27,353 [salt.state       :1780][INFO    ][14129] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:01:27.353198
2019-06-30 21:01:27,353 [salt.state       :1813][INFO    ][14129] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-30 21:01:27,355 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14129] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-30 21:01:29,075 [salt.state       :300 ][INFO    ][14129] {'pid': 14136, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-30 21:01:29,076 [salt.state       :1951][INFO    ][14129] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:01:29.076262 duration_in_ms=1723.063
2019-06-30 21:01:29,080 [salt.state       :1780][INFO    ][14129] Running state [maas.deploy_machines] at time 21:01:29.079955
2019-06-30 21:01:29,080 [salt.state       :1813][INFO    ][14129] Executing state module.run for [maas.deploy_machines]
2019-06-30 21:01:29,081 [salt.utils.decorators:613 ][WARNING ][14129] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-30 21:01:29,692 [salt.loaded.ext.module.maas:684 ][INFO    ][14129] deploymachines hwe_kernel=hwe-16.04 system_id=s44dr4 distro_series=xenial
2019-06-30 21:01:32,056 [salt.state       :300 ][INFO    ][14129] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-06-30 21:01:32,056 [salt.state       :1951][INFO    ][14129] Completed state [maas.deploy_machines] at time 21:01:32.056697 duration_in_ms=2976.74
2019-06-30 21:01:32,061 [salt.minion      :1711][INFO    ][14129] Returning information for job: 20190630210126049522
2019-06-30 21:01:32,635 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command state.apply with jid 20190630210132620337
2019-06-30 21:01:32,662 [salt.minion      :1432][INFO    ][14195] Starting a new job with PID 14195
2019-06-30 21:01:40,975 [salt.state       :915 ][INFO    ][14195] Loading fresh modules for state activity
2019-06-30 21:01:41,072 [salt.state       :1780][INFO    ][14195] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:01:41.072149
2019-06-30 21:01:41,072 [salt.state       :1813][INFO    ][14195] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-30 21:01:41,074 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14195] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-30 21:01:42,835 [salt.state       :300 ][INFO    ][14195] {'pid': 14234, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-30 21:01:42,836 [salt.state       :1951][INFO    ][14195] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:01:42.836010 duration_in_ms=1763.859
2019-06-30 21:01:42,839 [salt.state       :1780][INFO    ][14195] Running state [maas.wait_for_machine_status] at time 21:01:42.839316
2019-06-30 21:01:42,839 [salt.state       :1813][INFO    ][14195] Executing state module.run for [maas.wait_for_machine_status]
2019-06-30 21:01:42,840 [salt.utils.decorators:613 ][WARNING ][14195] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-30 21:01:45,587 [salt.loaded.ext.module.maas:1023][INFO    ][14195] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.2650609s left)
2019-06-30 21:01:47,733 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630210147715569
2019-06-30 21:01:47,755 [salt.minion      :1432][INFO    ][14254] Starting a new job with PID 14254
2019-06-30 21:01:47,778 [salt.minion      :1711][INFO    ][14254] Returning information for job: 20190630210147715569
2019-06-30 21:02:17,833 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630210217774890
2019-06-30 21:02:17,866 [salt.minion      :1432][INFO    ][14277] Starting a new job with PID 14277
2019-06-30 21:02:17,893 [salt.minion      :1711][INFO    ][14277] Returning information for job: 20190630210217774890
2019-06-30 21:02:18,659 [salt.loaded.ext.module.maas:1023][INFO    ][14195] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.19333196s left)
2019-06-30 21:02:47,946 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630210247930271
2019-06-30 21:02:47,975 [salt.minion      :1432][INFO    ][14351] Starting a new job with PID 14351
2019-06-30 21:02:48,007 [salt.minion      :1711][INFO    ][14351] Returning information for job: 20190630210247930271
2019-06-30 21:02:51,476 [salt.loaded.ext.module.maas:1023][INFO    ][14195] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2181.37572289s left)
2019-06-30 21:03:18,024 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630210318007798
2019-06-30 21:03:18,052 [salt.minion      :1432][INFO    ][14373] Starting a new job with PID 14373
2019-06-30 21:03:18,080 [salt.minion      :1711][INFO    ][14373] Returning information for job: 20190630210318007798
2019-06-30 21:03:24,383 [salt.loaded.ext.module.maas:1023][INFO    ][14195] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2148.46926403s left)
2019-06-30 21:03:48,108 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630210348092603
2019-06-30 21:03:48,138 [salt.minion      :1432][INFO    ][14433] Starting a new job with PID 14433
2019-06-30 21:03:48,168 [salt.minion      :1711][INFO    ][14433] Returning information for job: 20190630210348092603
2019-06-30 21:03:57,309 [salt.loaded.ext.module.maas:1023][INFO    ][14195] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2115.54310203s left)
2019-06-30 21:04:18,200 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630210418182757
2019-06-30 21:04:18,229 [salt.minion      :1432][INFO    ][14453] Starting a new job with PID 14453
2019-06-30 21:04:18,255 [salt.minion      :1711][INFO    ][14453] Returning information for job: 20190630210418182757
2019-06-30 21:04:30,059 [salt.loaded.ext.module.maas:1023][INFO    ][14195] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2082.7933259s left)
2019-06-30 21:04:48,288 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630210448272928
2019-06-30 21:04:48,319 [salt.minion      :1432][INFO    ][14509] Starting a new job with PID 14509
2019-06-30 21:04:48,343 [salt.minion      :1711][INFO    ][14509] Returning information for job: 20190630210448272928
2019-06-30 21:05:02,936 [salt.loaded.ext.module.maas:1023][INFO    ][14195] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2049.9162991s left)
2019-06-30 21:05:18,372 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630210518354223
2019-06-30 21:05:18,402 [salt.minion      :1432][INFO    ][14528] Starting a new job with PID 14528
2019-06-30 21:05:18,423 [salt.minion      :1711][INFO    ][14528] Returning information for job: 20190630210518354223
2019-06-30 21:05:35,819 [salt.loaded.ext.module.maas:1023][INFO    ][14195] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2017.0325551s left)
2019-06-30 21:05:48,469 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630210548460265
2019-06-30 21:05:48,496 [salt.minion      :1432][INFO    ][14609] Starting a new job with PID 14609
2019-06-30 21:05:48,521 [salt.minion      :1711][INFO    ][14609] Returning information for job: 20190630210548460265
2019-06-30 21:06:08,795 [salt.loaded.ext.module.maas:1023][INFO    ][14195] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1984.05694199s left)
2019-06-30 21:06:18,575 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630210618559297
2019-06-30 21:06:18,598 [salt.minion      :1432][INFO    ][14637] Starting a new job with PID 14637
2019-06-30 21:06:18,622 [salt.minion      :1711][INFO    ][14637] Returning information for job: 20190630210618559297
2019-06-30 21:06:42,126 [salt.loaded.ext.module.maas:1023][INFO    ][14195] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1950.72595406s left)
2019-06-30 21:06:48,703 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630210648688012
2019-06-30 21:06:48,731 [salt.minion      :1432][INFO    ][14797] Starting a new job with PID 14797
2019-06-30 21:06:48,761 [salt.minion      :1711][INFO    ][14797] Returning information for job: 20190630210648688012
2019-06-30 21:07:15,070 [salt.loaded.ext.module.maas:1023][INFO    ][14195] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1917.78260088s left)
2019-06-30 21:07:18,821 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630210718808338
2019-06-30 21:07:18,846 [salt.minion      :1432][INFO    ][14822] Starting a new job with PID 14822
2019-06-30 21:07:18,872 [salt.minion      :1711][INFO    ][14822] Returning information for job: 20190630210718808338
2019-06-30 21:07:48,067 [salt.loaded.ext.module.maas:1023][INFO    ][14195] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1884.78469706s left)
2019-06-30 21:07:48,960 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630210748946732
2019-06-30 21:07:48,987 [salt.minion      :1432][INFO    ][14971] Starting a new job with PID 14971
2019-06-30 21:07:49,011 [salt.minion      :1711][INFO    ][14971] Returning information for job: 20190630210748946732
2019-06-30 21:08:19,099 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630210819085239
2019-06-30 21:08:19,123 [salt.minion      :1432][INFO    ][14989] Starting a new job with PID 14989
2019-06-30 21:08:19,146 [salt.minion      :1711][INFO    ][14989] Returning information for job: 20190630210819085239
2019-06-30 21:08:21,259 [salt.loaded.ext.module.maas:1023][INFO    ][14195] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1851.59251404s left)
2019-06-30 21:08:49,230 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630210849215705
2019-06-30 21:08:49,260 [salt.minion      :1432][INFO    ][15074] Starting a new job with PID 15074
2019-06-30 21:08:49,284 [salt.minion      :1711][INFO    ][15074] Returning information for job: 20190630210849215705
2019-06-30 21:08:54,148 [salt.loaded.ext.module.maas:1023][INFO    ][14195] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1818.70443797s left)
2019-06-30 21:09:19,379 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630210919361308
2019-06-30 21:09:19,404 [salt.minion      :1432][INFO    ][15095] Starting a new job with PID 15095
2019-06-30 21:09:19,438 [salt.minion      :1711][INFO    ][15095] Returning information for job: 20190630210919361308
2019-06-30 21:09:27,083 [salt.loaded.ext.module.maas:1023][INFO    ][14195] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1785.76942611s left)
2019-06-30 21:09:49,572 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630210949554580
2019-06-30 21:09:49,600 [salt.minion      :1432][INFO    ][15299] Starting a new job with PID 15299
2019-06-30 21:09:49,624 [salt.minion      :1711][INFO    ][15299] Returning information for job: 20190630210949554580
2019-06-30 21:09:59,938 [salt.loaded.ext.module.maas:1023][INFO    ][14195] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1752.91377211s left)
2019-06-30 21:10:19,744 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630211019727227
2019-06-30 21:10:19,775 [salt.minion      :1432][INFO    ][15320] Starting a new job with PID 15320
2019-06-30 21:10:19,800 [salt.minion      :1711][INFO    ][15320] Returning information for job: 20190630211019727227
2019-06-30 21:10:32,793 [salt.loaded.ext.module.maas:1023][INFO    ][14195] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1720.0593679s left)
2019-06-30 21:10:49,888 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630211049871030
2019-06-30 21:10:49,916 [salt.minion      :1432][INFO    ][15375] Starting a new job with PID 15375
2019-06-30 21:10:49,939 [salt.minion      :1711][INFO    ][15375] Returning information for job: 20190630211049871030
2019-06-30 21:11:05,826 [salt.loaded.ext.module.maas:1023][INFO    ][14195] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1687.0258801s left)
2019-06-30 21:11:20,081 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630211120064936
2019-06-30 21:11:20,111 [salt.minion      :1432][INFO    ][15399] Starting a new job with PID 15399
2019-06-30 21:11:20,134 [salt.minion      :1711][INFO    ][15399] Returning information for job: 20190630211120064936
2019-06-30 21:11:38,953 [salt.loaded.ext.module.maas:1023][INFO    ][14195] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1653.89940405s left)
2019-06-30 21:11:50,273 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630211150256356
2019-06-30 21:11:50,302 [salt.minion      :1432][INFO    ][15455] Starting a new job with PID 15455
2019-06-30 21:11:50,326 [salt.minion      :1711][INFO    ][15455] Returning information for job: 20190630211150256356
2019-06-30 21:12:11,835 [salt.loaded.ext.module.maas:1023][INFO    ][14195] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1621.01643991s left)
2019-06-30 21:12:20,457 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630211220439642
2019-06-30 21:12:20,485 [salt.minion      :1432][INFO    ][15475] Starting a new job with PID 15475
2019-06-30 21:12:20,507 [salt.minion      :1711][INFO    ][15475] Returning information for job: 20190630211220439642
2019-06-30 21:12:44,968 [salt.loaded.ext.module.maas:1023][INFO    ][14195] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1587.88370895s left)
2019-06-30 21:12:50,632 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630211250623915
2019-06-30 21:12:50,652 [salt.minion      :1432][INFO    ][15696] Starting a new job with PID 15696
2019-06-30 21:12:50,679 [salt.minion      :1711][INFO    ][15696] Returning information for job: 20190630211250623915
2019-06-30 21:13:17,796 [salt.loaded.ext.module.maas:1023][INFO    ][14195] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1555.05604911s left)
2019-06-30 21:13:20,817 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630211320801233
2019-06-30 21:13:20,844 [salt.minion      :1432][INFO    ][15716] Starting a new job with PID 15716
2019-06-30 21:13:20,872 [salt.minion      :1711][INFO    ][15716] Returning information for job: 20190630211320801233
2019-06-30 21:13:31,816 [salt.utils.schedule:1377][INFO    ][5150] Running scheduled job: __mine_interval
2019-06-30 21:13:50,733 [salt.loaded.ext.module.maas:1023][INFO    ][14195] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1522.11894989s left)
2019-06-30 21:13:51,015 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630211351005702
2019-06-30 21:13:51,036 [salt.minion      :1432][INFO    ][15804] Starting a new job with PID 15804
2019-06-30 21:13:51,060 [salt.minion      :1711][INFO    ][15804] Returning information for job: 20190630211351005702
2019-06-30 21:14:21,160 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command saltutil.find_job with jid 20190630211421150437
2019-06-30 21:14:21,184 [salt.minion      :1432][INFO    ][15841] Starting a new job with PID 15841
2019-06-30 21:14:21,207 [salt.minion      :1711][INFO    ][15841] Returning information for job: 20190630211421150437
2019-06-30 21:14:23,867 [salt.state       :300 ][INFO    ][14195] {'ret': True}
2019-06-30 21:14:23,868 [salt.state       :1951][INFO    ][14195] Completed state [maas.wait_for_machine_status] at time 21:14:23.868455 duration_in_ms=761029.132
2019-06-30 21:14:23,876 [salt.minion      :1711][INFO    ][14195] Returning information for job: 20190630210132620337
2019-06-30 22:13:31,816 [salt.utils.schedule:1377][INFO    ][5150] Running scheduled job: __mine_interval
2019-06-30 22:39:24,307 [salt.minion      :1308][INFO    ][5150] User sudo_ubuntu Executing command cp.push_dir with jid 20190630223924291382
2019-06-30 22:39:24,336 [salt.minion      :1432][INFO    ][22005] Starting a new job with PID 22005
