2019-05-30 02:06:34,116 [salt.utils.decorators:613 ][WARNING ][2056] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-30 02:06:35,255 [salt.utils.decorators:613 ][WARNING ][2056] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-30 02:06:39,188 [salt.loaded.int.states.file:2298][WARNING ][2603] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-05-30 02:07:08,804 [salt.state       :2022][WARNING ][2999] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-30 02:07:12,096 [salt.utils.decorators:613 ][WARNING ][2999] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-30 02:07:25,777 [salt.utils.decorators:613 ][WARNING ][2999] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-30 02:07:59,272 [salt.utils.decorators:613 ][WARNING ][2999] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-30 02:08:00,207 [salt.utils.decorators:613 ][WARNING ][2999] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-30 02:08:02,262 [salt.loaded.ext.module.maasng:1008][WARNING ][2999] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-05-30 02:08:02,263 [salt.loaded.ext.module.maasng:1011][WARNING ][2999] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-05-30 02:08:02,940 [salt.loaded.ext.module.maasng:1235][WARNING ][2999] Ignoring parameter vlan:0
2019-05-30 02:08:15,255 [salt.utils.decorators:613 ][WARNING ][5579] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-30 02:08:15,324 [salt.loaded.ext.module.maas:412 ][WARNING ][5579] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-30 02:08:16,906 [salt.loaded.ext.module.maas:412 ][WARNING ][5579] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-30 02:08:18,297 [salt.loaded.ext.module.maas:412 ][WARNING ][5579] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-30 02:08:19,630 [salt.loaded.ext.module.maas:412 ][WARNING ][5579] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-30 02:08:20,878 [salt.loaded.ext.module.maas:412 ][WARNING ][5579] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-30 02:08:26,319 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6229] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-30 02:08:26,363 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6229] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-05-30 02:08:26,407 [salt.utils.parsers:1051][WARNING ][387] Minion received a SIGTERM. Exiting.
2019-05-30 02:08:27,584 [salt.cli.daemons :293 ][INFO    ][6288] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-05-30 02:08:27,716 [salt.cli.daemons :82  ][INFO    ][6288] Starting up the Salt Minion
2019-05-30 02:08:27,717 [salt.utils.event :1017][INFO    ][6288] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-05-30 02:08:28,947 [salt.minion      :976 ][INFO    ][6288] Creating minion process manager
2019-05-30 02:08:31,069 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][6288] Executing command ['date', '+%z'] in directory '/root'
2019-05-30 02:08:31,098 [salt.utils.schedule:568 ][INFO    ][6288] Updating job settings for scheduled job: __mine_interval
2019-05-30 02:08:31,100 [salt.minion      :1108][INFO    ][6288] Added mine.update to scheduler
2019-05-30 02:08:31,107 [salt.minion      :1975][INFO    ][6288] Minion is starting as user 'root'
2019-05-30 02:08:31,134 [salt.minion      :2336][INFO    ][6288] Minion is ready to receive requests!
2019-05-30 02:08:53,349 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command state.apply with jid 20190530020853333022
2019-05-30 02:08:53,376 [salt.minion      :1432][INFO    ][6374] Starting a new job with PID 6374
2019-05-30 02:09:01,030 [salt.state       :915 ][INFO    ][6374] Loading fresh modules for state activity
2019-05-30 02:09:01,095 [salt.fileclient  :1219][INFO    ][6374] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-05-30 02:09:01,144 [salt.state       :1780][INFO    ][6374] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:09:01.144432
2019-05-30 02:09:01,144 [salt.state       :1813][INFO    ][6374] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-30 02:09:01,146 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6374] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-30 02:09:02,877 [salt.state       :300 ][INFO    ][6374] {'pid': 6394, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-30 02:09:02,878 [salt.state       :1951][INFO    ][6374] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:09:02.878504 duration_in_ms=1734.071
2019-05-30 02:09:02,881 [salt.state       :1780][INFO    ][6374] Running state [maas.wait_for_machine_status] at time 02:09:02.881346
2019-05-30 02:09:02,883 [salt.state       :1813][INFO    ][6374] Executing state module.run for [maas.wait_for_machine_status]
2019-05-30 02:09:02,883 [salt.utils.decorators:613 ][WARNING ][6374] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-30 02:09:03,720 [salt.loaded.ext.module.maas:1023][INFO    ][6374] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.17103696s left)
2019-05-30 02:09:08,450 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530020908432540
2019-05-30 02:09:08,480 [salt.minion      :1432][INFO    ][6405] Starting a new job with PID 6405
2019-05-30 02:09:08,502 [salt.minion      :1711][INFO    ][6405] Returning information for job: 20190530020908432540
2019-05-30 02:09:34,560 [salt.loaded.ext.module.maas:1023][INFO    ][6374] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.33033204s left)
2019-05-30 02:09:38,515 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530020938500146
2019-05-30 02:09:38,544 [salt.minion      :1432][INFO    ][6449] Starting a new job with PID 6449
2019-05-30 02:09:38,568 [salt.minion      :1711][INFO    ][6449] Returning information for job: 20190530020938500146
2019-05-30 02:10:05,377 [salt.loaded.ext.module.maas:1023][INFO    ][6374] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.51385498s left)
2019-05-30 02:10:08,620 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530021008605478
2019-05-30 02:10:08,646 [salt.minion      :1432][INFO    ][6475] Starting a new job with PID 6475
2019-05-30 02:10:08,668 [salt.minion      :1711][INFO    ][6475] Returning information for job: 20190530021008605478
2019-05-30 02:10:36,251 [salt.loaded.ext.module.maas:1023][INFO    ][6374] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.63928199s left)
2019-05-30 02:10:38,694 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530021038680787
2019-05-30 02:10:38,722 [salt.minion      :1432][INFO    ][6523] Starting a new job with PID 6523
2019-05-30 02:10:38,750 [salt.minion      :1711][INFO    ][6523] Returning information for job: 20190530021038680787
2019-05-30 02:11:07,426 [salt.loaded.ext.module.maas:1023][INFO    ][6374] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.46438909s left)
2019-05-30 02:11:08,800 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530021108781176
2019-05-30 02:11:08,831 [salt.minion      :1432][INFO    ][6597] Starting a new job with PID 6597
2019-05-30 02:11:08,852 [salt.minion      :1711][INFO    ][6597] Returning information for job: 20190530021108781176
2019-05-30 02:11:38,575 [salt.loaded.ext.module.maas:1023][INFO    ][6374] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.31528902s left)
2019-05-30 02:11:38,908 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530021138881658
2019-05-30 02:11:38,937 [salt.minion      :1432][INFO    ][6751] Starting a new job with PID 6751
2019-05-30 02:11:38,959 [salt.minion      :1711][INFO    ][6751] Returning information for job: 20190530021138881658
2019-05-30 02:12:09,007 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530021208989566
2019-05-30 02:12:09,033 [salt.minion      :1432][INFO    ][6800] Starting a new job with PID 6800
2019-05-30 02:12:09,057 [salt.minion      :1711][INFO    ][6800] Returning information for job: 20190530021208989566
2019-05-30 02:12:09,874 [salt.loaded.ext.module.maas:1023][INFO    ][6374] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1313.01665807s left)
2019-05-30 02:12:39,127 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530021239113684
2019-05-30 02:12:39,145 [salt.minion      :1432][INFO    ][7106] Starting a new job with PID 7106
2019-05-30 02:12:39,168 [salt.minion      :1711][INFO    ][7106] Returning information for job: 20190530021239113684
2019-05-30 02:12:41,259 [salt.loaded.ext.module.maas:1023][INFO    ][6374] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1281.63128304s left)
2019-05-30 02:13:09,248 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530021309231856
2019-05-30 02:13:09,281 [salt.minion      :1432][INFO    ][7178] Starting a new job with PID 7178
2019-05-30 02:13:09,307 [salt.minion      :1711][INFO    ][7178] Returning information for job: 20190530021309231856
2019-05-30 02:13:13,391 [salt.loaded.ext.module.maas:1023][INFO    ][6374] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1249.49976993s left)
2019-05-30 02:13:39,441 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530021339431023
2019-05-30 02:13:39,469 [salt.minion      :1432][INFO    ][7655] Starting a new job with PID 7655
2019-05-30 02:13:39,493 [salt.minion      :1711][INFO    ][7655] Returning information for job: 20190530021339431023
2019-05-30 02:13:45,850 [salt.loaded.ext.module.maas:1023][INFO    ][6374] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1217.04078197s left)
2019-05-30 02:14:09,555 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530021409539007
2019-05-30 02:14:09,580 [salt.minion      :1432][INFO    ][7719] Starting a new job with PID 7719
2019-05-30 02:14:09,598 [salt.minion      :1711][INFO    ][7719] Returning information for job: 20190530021409539007
2019-05-30 02:14:18,085 [salt.loaded.ext.module.maas:1023][INFO    ][6374] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1184.80606008s left)
2019-05-30 02:14:39,697 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530021439686794
2019-05-30 02:14:39,717 [salt.minion      :1432][INFO    ][7977] Starting a new job with PID 7977
2019-05-30 02:14:39,741 [salt.minion      :1711][INFO    ][7977] Returning information for job: 20190530021439686794
2019-05-30 02:14:51,040 [salt.state       :300 ][INFO    ][6374] {'ret': True}
2019-05-30 02:14:51,041 [salt.state       :1951][INFO    ][6374] Completed state [maas.wait_for_machine_status] at time 02:14:51.041014 duration_in_ms=348159.667
2019-05-30 02:14:51,046 [salt.minion      :1711][INFO    ][6374] Returning information for job: 20190530020853333022
2019-05-30 02:14:51,612 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command state.apply with jid 20190530021451603696
2019-05-30 02:14:51,636 [salt.minion      :1432][INFO    ][7994] Starting a new job with PID 7994
2019-05-30 02:14:59,490 [salt.state       :915 ][INFO    ][7994] Loading fresh modules for state activity
2019-05-30 02:14:59,552 [salt.fileclient  :1219][INFO    ][7994] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-05-30 02:14:59,662 [salt.state       :1780][INFO    ][7994] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:14:59.662393
2019-05-30 02:14:59,662 [salt.state       :1813][INFO    ][7994] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-30 02:14:59,664 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7994] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-30 02:15:01,466 [salt.state       :300 ][INFO    ][7994] {'pid': 8040, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-30 02:15:01,467 [salt.state       :1951][INFO    ][7994] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:15:01.467120 duration_in_ms=1804.726
2019-05-30 02:15:01,472 [salt.state       :1780][INFO    ][7994] Running state [maas_machines_storage_cmp002_lvm] at time 02:15:01.472765
2019-05-30 02:15:01,473 [salt.state       :1813][INFO    ][7994] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-30 02:15:02,688 [salt.loaded.ext.module.maasng:610 ][INFO    ][7994] ccdpbr
2019-05-30 02:15:02,688 [salt.loaded.ext.module.maasng:626 ][INFO    ][7994] sda
2019-05-30 02:15:03,250 [salt.loaded.ext.module.maasng:361 ][INFO    ][7994] ccdpbr
2019-05-30 02:15:03,345 [salt.loaded.ext.module.maasng:367 ][INFO    ][7994] [{u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/ccdpbr/blockdevices/1/', u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'87fc9552-3dc0-4a66-8a2a-018c0f6be19d', u'resource_uri': u'/MAAS/api/2.0/nodes/ccdpbr/blockdevices/1/partition/1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ccdpbr', u'filesystem': {u'uuid': u'85e553bd-0a39-4de1-b92f-e2a52c6960dc', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 1, u'size': 800101236736}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'ccdpbr', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'size': 800109715456}, {u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/ccdpbr/blockdevices/3/', u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'0060ecb1-fee6-47c6-b041-caef977ffd49', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'ccdpbr', u'partition_table_type': None, u'filesystem': {u'uuid': u'58d6ddb9-a287-44e0-a732-0ef8d1683882', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'used_size': 800097042432, u'id': 3, u'size': 800097042432}]
2019-05-30 02:15:03,346 [salt.loaded.ext.module.maasng:632 ][INFO    ][7994] vgroot
2019-05-30 02:15:03,346 [salt.loaded.ext.module.maasng:635 ][INFO    ][7994] lvroot
2019-05-30 02:15:03,347 [salt.loaded.ext.module.maasng:639 ][INFO    ][7994] 107374182400
2019-05-30 02:15:03,957 [salt.loaded.ext.module.maasng:645 ][INFO    ][7994] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'status_action': u'', u'testing_status_name': u'Passed', u'memory_test_status': -1, u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'min_hwe_kernel': u'ga-16.04', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/ccdpbr/blockdevices/1/', u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'249ba877-f2f6-4164-be86-91f1c9341891', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ccdpbr', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'90e9b7cf-250e-4a7c-a5b9-77e1c43cbc62', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/ccdpbr/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'device_id': 1}], 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'ccdpbr', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'uuid': None}, u'zone': {u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default', u'description': u''}, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'ccdpbr', 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'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/ccdpbr/blockdevices/11/', u'type': u'virtual', u'partitions': [], u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'ccdpbr', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'12b6f168-680a-498f-86af-74c5ddc8e4d7', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 11, u'uuid': u'0c48138c-3e6d-46b3-8289-a12a40bf77dd'}], u'blockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/ccdpbr/blockdevices/1/', u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'249ba877-f2f6-4164-be86-91f1c9341891', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ccdpbr', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'90e9b7cf-250e-4a7c-a5b9-77e1c43cbc62', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/ccdpbr/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'device_id': 1}], u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'ccdpbr', 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'path': u'/dev/disk/by-dname/sda', u'used_size': 800106479616, u'id': 1, u'uuid': None}, {u'size': 107374182400, u'model': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/ccdpbr/blockdevices/11/', u'type': u'virtual', u'partitions': [], u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'ccdpbr', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'12b6f168-680a-498f-86af-74c5ddc8e4d7', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'serial': None, u'path': u'/dev/disk/by-dname/lvroot', u'used_size': 107374182400, u'id': 11, u'uuid': u'0c48138c-3e6d-46b3-8289-a12a40bf77dd'}], u'status': 4, u'bcaches': [], u'cpu_count': 40, u'power_state': u'off', u'physicalblockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/ccdpbr/blockdevices/1/', u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'249ba877-f2f6-4164-be86-91f1c9341891', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ccdpbr', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'90e9b7cf-250e-4a7c-a5b9-77e1c43cbc62', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/ccdpbr/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'device_id': 1}], 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'ccdpbr', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'uuid': None}], u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'ccdpbr', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'commissioning_status_name': u'Passed', u'current_testing_result_id': 3, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'commissioning_status': 2, u'architecture': u'amd64/generic', u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'ftepry', 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'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'ftepry', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'ftepry', 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'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'ccdpbr', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/ccdpbr/interfaces/4/'}, u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'ftepry', 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'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'ftepry', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'ftepry', 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'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'ccdpbr', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/ccdpbr/interfaces/4/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:50', u'system_id': u'ccdpbr', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/ccdpbr/interfaces/15/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:51', u'system_id': u'ccdpbr', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/ccdpbr/interfaces/13/'}, {u'name': u'ens2f0', u'links': [{u'id': 19, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:48', u'system_id': u'ccdpbr', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/ccdpbr/interfaces/14/'}, {u'name': u'ens2f1', u'links': [{u'id': 20, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:49', u'system_id': u'ccdpbr', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/ccdpbr/interfaces/11/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'ftepry', 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': 21, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'ftepry', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'ftepry', 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'mac_address': u'9c:b6:54:8a:10:1c', u'system_id': u'ccdpbr', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/ccdpbr/interfaces/12/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/ccdpbr/'}
2019-05-30 02:15:03,959 [salt.state       :300 ][INFO    ][7994] {'new': {'storage_layout': 'lvm'}}
2019-05-30 02:15:03,960 [salt.state       :1951][INFO    ][7994] Completed state [maas_machines_storage_cmp002_lvm] at time 02:15:03.959990 duration_in_ms=2487.224
2019-05-30 02:15:03,960 [salt.state       :1780][INFO    ][7994] Running state [maas_machines_storage_cmp001_lvm] at time 02:15:03.960584
2019-05-30 02:15:03,961 [salt.state       :1813][INFO    ][7994] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-30 02:15:05,105 [salt.loaded.ext.module.maasng:610 ][INFO    ][7994] 844twf
2019-05-30 02:15:05,105 [salt.loaded.ext.module.maasng:626 ][INFO    ][7994] sda
2019-05-30 02:15:05,664 [salt.loaded.ext.module.maasng:361 ][INFO    ][7994] 844twf
2019-05-30 02:15:05,759 [salt.loaded.ext.module.maasng:367 ][INFO    ][7994] [{u'size': 800109715456, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/844twf/blockdevices/2/', u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'uuid': u'd7ec0cfc-e388-4ab5-a12f-ffb717118fca', u'resource_uri': u'/MAAS/api/2.0/nodes/844twf/blockdevices/2/partition/2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'844twf', u'filesystem': {u'uuid': u'130dbd1c-82e1-41ec-b6c6-ad548d666d69', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 2, u'size': 800101236736}], u'tags': [u'ssd'], u'filesystem': None, u'id': 2, u'system_id': u'844twf', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'uuid': None}, {u'size': 800097042432, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/844twf/blockdevices/4/', u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'partitions': [], u'tags': [], u'filesystem': {u'uuid': u'48734ccb-a975-41a6-97fd-ec61973f45cb', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id': 4, u'system_id': u'844twf', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'used_size': 800097042432, u'serial': None, u'uuid': u'0adc282b-7976-48f9-bca6-4ab05764bc73'}]
2019-05-30 02:15:05,760 [salt.loaded.ext.module.maasng:632 ][INFO    ][7994] vgroot
2019-05-30 02:15:05,760 [salt.loaded.ext.module.maasng:635 ][INFO    ][7994] lvroot
2019-05-30 02:15:05,761 [salt.loaded.ext.module.maasng:639 ][INFO    ][7994] 107374182400
2019-05-30 02:15:06,369 [salt.loaded.ext.module.maasng:645 ][INFO    ][7994] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'status_action': u'', u'testing_status_name': u'Passed', u'memory_test_status': -1, u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'min_hwe_kernel': u'ga-16.04', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/844twf/blockdevices/2/', u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'eaa68bbc-1de8-4b2f-b37a-400a3f01d46d', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'844twf', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'58c7c866-52e7-425c-aec0-0256e20288ba', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/844twf/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'device_id': 2}], 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'844twf', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'uuid': None}, u'zone': {u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default', u'description': u''}, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'844twf', 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'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/844twf/blockdevices/12/', u'type': u'virtual', u'partitions': [], u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'844twf', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'06ea8666-c8e6-418d-afd8-40c195a94091', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 12, u'uuid': u'4a459e51-9414-4e3d-9d02-56ec30a68ff3'}], u'blockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/844twf/blockdevices/2/', u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'eaa68bbc-1de8-4b2f-b37a-400a3f01d46d', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'844twf', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'58c7c866-52e7-425c-aec0-0256e20288ba', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/844twf/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'device_id': 2}], u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'844twf', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'used_size': 800106479616, u'id': 2, u'uuid': None}, {u'size': 107374182400, u'model': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/844twf/blockdevices/12/', u'type': u'virtual', u'partitions': [], u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'844twf', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'06ea8666-c8e6-418d-afd8-40c195a94091', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'serial': None, u'path': u'/dev/disk/by-dname/lvroot', u'used_size': 107374182400, u'id': 12, u'uuid': u'4a459e51-9414-4e3d-9d02-56ec30a68ff3'}], u'status': 4, u'bcaches': [], u'cpu_count': 40, u'power_state': u'off', u'physicalblockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/844twf/blockdevices/2/', u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'eaa68bbc-1de8-4b2f-b37a-400a3f01d46d', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'844twf', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'58c7c866-52e7-425c-aec0-0256e20288ba', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/844twf/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'device_id': 2}], 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'844twf', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'uuid': None}], u'ip_addresses': [u'192.168.11.39', u'192.168.11.44'], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'844twf', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'commissioning_status_name': u'Passed', u'current_testing_result_id': 5, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'commissioning_status': 2, u'architecture': u'amd64/generic', u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'ftepry', 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'id': 24, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'ftepry', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'ftepry', 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'system_id': u'844twf', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/844twf/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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'ftepry', 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'id': 24, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'ftepry', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'ftepry', 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'system_id': u'844twf', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/844twf/interfaces/5/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d5', u'system_id': u'844twf', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/844twf/interfaces/18/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d4', u'system_id': u'844twf', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/844twf/interfaces/20/'}, {u'name': u'ens2f1', u'links': [{u'id': 25, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cd', u'system_id': u'844twf', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/844twf/interfaces/16/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'ftepry', 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': 26, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'ftepry', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'ftepry', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.44'}], u'mac_address': u'9c:b6:54:8a:95:a4', u'system_id': u'844twf', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/844twf/interfaces/17/'}, {u'name': u'ens2f0', u'links': [{u'id': 27, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cc', u'system_id': u'844twf', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/844twf/interfaces/19/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/844twf/'}
2019-05-30 02:15:06,373 [salt.state       :300 ][INFO    ][7994] {'new': {'storage_layout': 'lvm'}}
2019-05-30 02:15:06,375 [salt.state       :1951][INFO    ][7994] Completed state [maas_machines_storage_cmp001_lvm] at time 02:15:06.373650 duration_in_ms=2413.066
2019-05-30 02:15:06,380 [salt.minion      :1711][INFO    ][7994] Returning information for job: 20190530021451603696
2019-05-30 02:15:06,948 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command state.apply with jid 20190530021506933239
2019-05-30 02:15:06,976 [salt.minion      :1432][INFO    ][8058] Starting a new job with PID 8058
2019-05-30 02:15:08,116 [salt.state       :915 ][INFO    ][8058] Loading fresh modules for state activity
2019-05-30 02:15:08,179 [salt.fileclient  :1219][INFO    ][8058] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-05-30 02:15:08,239 [salt.state       :1780][INFO    ][8058] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:15:08.239856
2019-05-30 02:15:08,240 [salt.state       :1813][INFO    ][8058] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-30 02:15:08,241 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8058] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-30 02:15:10,044 [salt.state       :300 ][INFO    ][8058] {'pid': 8065, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-30 02:15:10,046 [salt.state       :1951][INFO    ][8058] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:15:10.046459 duration_in_ms=1806.604
2019-05-30 02:15:10,048 [salt.state       :1780][INFO    ][8058] Running state [maas.deploy_machines] at time 02:15:10.048863
2019-05-30 02:15:10,049 [salt.state       :1813][INFO    ][8058] Executing state module.run for [maas.deploy_machines]
2019-05-30 02:15:10,050 [salt.utils.decorators:613 ][WARNING ][8058] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-30 02:15:10,747 [salt.loaded.ext.module.maas:684 ][INFO    ][8058] deploymachines hwe_kernel=ga-16.04 system_id=ccdpbr distro_series=xenial
2019-05-30 02:15:13,429 [salt.loaded.ext.module.maas:684 ][INFO    ][8058] deploymachines hwe_kernel=ga-16.04 system_id=844twf distro_series=xenial
2019-05-30 02:15:15,923 [salt.loaded.ext.module.maas:684 ][INFO    ][8058] deploymachines hwe_kernel=ga-16.04 system_id=8c66ya distro_series=xenial
2019-05-30 02:15:18,478 [salt.loaded.ext.module.maas:684 ][INFO    ][8058] deploymachines hwe_kernel=ga-16.04 system_id=yp8csd distro_series=xenial
2019-05-30 02:15:21,047 [salt.loaded.ext.module.maas:684 ][INFO    ][8058] deploymachines hwe_kernel=ga-16.04 system_id=4qah6q distro_series=xenial
2019-05-30 02:15:22,071 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530021522057799
2019-05-30 02:15:22,092 [salt.minion      :1432][INFO    ][8337] Starting a new job with PID 8337
2019-05-30 02:15:22,113 [salt.minion      :1711][INFO    ][8337] Returning information for job: 20190530021522057799
2019-05-30 02:15:23,756 [salt.state       :300 ][INFO    ][8058] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-05-30 02:15:23,757 [salt.state       :1951][INFO    ][8058] Completed state [maas.deploy_machines] at time 02:15:23.757459 duration_in_ms=13708.594
2019-05-30 02:15:23,764 [salt.minion      :1711][INFO    ][8058] Returning information for job: 20190530021506933239
2019-05-30 02:15:24,340 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command state.apply with jid 20190530021524325421
2019-05-30 02:15:24,367 [salt.minion      :1432][INFO    ][8374] Starting a new job with PID 8374
2019-05-30 02:15:32,152 [salt.state       :915 ][INFO    ][8374] Loading fresh modules for state activity
2019-05-30 02:15:32,216 [salt.fileclient  :1219][INFO    ][8374] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-05-30 02:15:32,266 [salt.state       :1780][INFO    ][8374] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:15:32.266482
2019-05-30 02:15:32,266 [salt.state       :1813][INFO    ][8374] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-30 02:15:32,268 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8374] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-30 02:15:34,057 [salt.state       :300 ][INFO    ][8374] {'pid': 8388, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-30 02:15:34,058 [salt.state       :1951][INFO    ][8374] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:15:34.058685 duration_in_ms=1792.203
2019-05-30 02:15:34,061 [salt.state       :1780][INFO    ][8374] Running state [maas.wait_for_machine_status] at time 02:15:34.061503
2019-05-30 02:15:34,062 [salt.state       :1813][INFO    ][8374] Executing state module.run for [maas.wait_for_machine_status]
2019-05-30 02:15:34,062 [salt.utils.decorators:613 ][WARNING ][8374] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-30 02:15:36,861 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.21527982s left)
2019-05-30 02:15:39,456 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530021539444801
2019-05-30 02:15:39,486 [salt.minion      :1432][INFO    ][8404] Starting a new job with PID 8404
2019-05-30 02:15:39,508 [salt.minion      :1711][INFO    ][8404] Returning information for job: 20190530021539444801
2019-05-30 02:16:09,525 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530021609514309
2019-05-30 02:16:09,546 [salt.minion      :1432][INFO    ][8432] Starting a new job with PID 8432
2019-05-30 02:16:09,569 [salt.minion      :1711][INFO    ][8432] Returning information for job: 20190530021609514309
2019-05-30 02:16:09,768 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.30858588s left)
2019-05-30 02:16:39,633 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530021639618520
2019-05-30 02:16:39,660 [salt.minion      :1432][INFO    ][8476] Starting a new job with PID 8476
2019-05-30 02:16:39,679 [salt.minion      :1711][INFO    ][8476] Returning information for job: 20190530021639618520
2019-05-30 02:16:42,736 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.33998084s left)
2019-05-30 02:17:09,712 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530021709696092
2019-05-30 02:17:09,742 [salt.minion      :1432][INFO    ][8524] Starting a new job with PID 8524
2019-05-30 02:17:09,766 [salt.minion      :1711][INFO    ][8524] Returning information for job: 20190530021709696092
2019-05-30 02:17:15,780 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2148.29690099s left)
2019-05-30 02:17:39,821 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530021739807531
2019-05-30 02:17:39,852 [salt.minion      :1432][INFO    ][8586] Starting a new job with PID 8586
2019-05-30 02:17:39,874 [salt.minion      :1711][INFO    ][8586] Returning information for job: 20190530021739807531
2019-05-30 02:17:48,533 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2115.5436039s left)
2019-05-30 02:18:09,908 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530021809889382
2019-05-30 02:18:09,935 [salt.minion      :1432][INFO    ][8642] Starting a new job with PID 8642
2019-05-30 02:18:09,959 [salt.minion      :1711][INFO    ][8642] Returning information for job: 20190530021809889382
2019-05-30 02:18:21,701 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2082.37559581s left)
2019-05-30 02:18:40,040 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530021840024517
2019-05-30 02:18:40,069 [salt.minion      :1432][INFO    ][8816] Starting a new job with PID 8816
2019-05-30 02:18:40,102 [salt.minion      :1711][INFO    ][8816] Returning information for job: 20190530021840024517
2019-05-30 02:18:54,575 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2049.50159502s left)
2019-05-30 02:19:10,157 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530021910141144
2019-05-30 02:19:10,180 [salt.minion      :1432][INFO    ][8854] Starting a new job with PID 8854
2019-05-30 02:19:10,206 [salt.minion      :1711][INFO    ][8854] Returning information for job: 20190530021910141144
2019-05-30 02:19:27,558 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2016.51835084s left)
2019-05-30 02:19:40,312 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530021940295818
2019-05-30 02:19:40,333 [salt.minion      :1432][INFO    ][9190] Starting a new job with PID 9190
2019-05-30 02:19:40,356 [salt.minion      :1711][INFO    ][9190] Returning information for job: 20190530021940295818
2019-05-30 02:20:01,012 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1983.06446099s left)
2019-05-30 02:20:10,423 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530022010411509
2019-05-30 02:20:10,452 [salt.minion      :1432][INFO    ][9275] Starting a new job with PID 9275
2019-05-30 02:20:10,479 [salt.minion      :1711][INFO    ][9275] Returning information for job: 20190530022010411509
2019-05-30 02:20:34,186 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1949.89062095s left)
2019-05-30 02:20:40,579 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530022040563168
2019-05-30 02:20:40,608 [salt.minion      :1432][INFO    ][9494] Starting a new job with PID 9494
2019-05-30 02:20:40,637 [salt.minion      :1711][INFO    ][9494] Returning information for job: 20190530022040563168
2019-05-30 02:21:07,288 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1916.78833389s left)
2019-05-30 02:21:10,736 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530022110719985
2019-05-30 02:21:10,760 [salt.minion      :1432][INFO    ][9523] Starting a new job with PID 9523
2019-05-30 02:21:10,784 [salt.minion      :1711][INFO    ][9523] Returning information for job: 20190530022110719985
2019-05-30 02:21:40,194 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1883.88242793s left)
2019-05-30 02:21:40,908 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530022140897146
2019-05-30 02:21:40,928 [salt.minion      :1432][INFO    ][9900] Starting a new job with PID 9900
2019-05-30 02:21:40,956 [salt.minion      :1711][INFO    ][9900] Returning information for job: 20190530022140897146
2019-05-30 02:22:11,058 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530022211044986
2019-05-30 02:22:11,082 [salt.minion      :1432][INFO    ][9944] Starting a new job with PID 9944
2019-05-30 02:22:11,104 [salt.minion      :1711][INFO    ][9944] Returning information for job: 20190530022211044986
2019-05-30 02:22:13,942 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1850.13425279s left)
2019-05-30 02:22:41,233 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530022241214280
2019-05-30 02:22:41,260 [salt.minion      :1432][INFO    ][10201] Starting a new job with PID 10201
2019-05-30 02:22:41,281 [salt.minion      :1711][INFO    ][10201] Returning information for job: 20190530022241214280
2019-05-30 02:22:47,207 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1816.86977887s left)
2019-05-30 02:23:11,397 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530022311376320
2019-05-30 02:23:11,430 [salt.minion      :1432][INFO    ][10242] Starting a new job with PID 10242
2019-05-30 02:23:11,456 [salt.minion      :1711][INFO    ][10242] Returning information for job: 20190530022311376320
2019-05-30 02:23:20,168 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1783.90803885s left)
2019-05-30 02:23:41,596 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530022341586280
2019-05-30 02:23:41,621 [salt.minion      :1432][INFO    ][10609] Starting a new job with PID 10609
2019-05-30 02:23:41,646 [salt.minion      :1711][INFO    ][10609] Returning information for job: 20190530022341586280
2019-05-30 02:23:53,151 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1750.92527699s left)
2019-05-30 02:24:11,784 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530022411763851
2019-05-30 02:24:11,816 [salt.minion      :1432][INFO    ][10656] Starting a new job with PID 10656
2019-05-30 02:24:11,840 [salt.minion      :1711][INFO    ][10656] Returning information for job: 20190530022411763851
2019-05-30 02:24:26,324 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1717.75245786s left)
2019-05-30 02:24:42,008 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530022441987468
2019-05-30 02:24:42,036 [salt.minion      :1432][INFO    ][10900] Starting a new job with PID 10900
2019-05-30 02:24:42,060 [salt.minion      :1711][INFO    ][10900] Returning information for job: 20190530022441987468
2019-05-30 02:24:59,431 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1684.6449759s left)
2019-05-30 02:25:12,196 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530022512178506
2019-05-30 02:25:12,227 [salt.minion      :1432][INFO    ][10932] Starting a new job with PID 10932
2019-05-30 02:25:12,250 [salt.minion      :1711][INFO    ][10932] Returning information for job: 20190530022512178506
2019-05-30 02:25:32,346 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1651.73056197s left)
2019-05-30 02:25:42,363 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530022542353178
2019-05-30 02:25:42,388 [salt.minion      :1432][INFO    ][11003] Starting a new job with PID 11003
2019-05-30 02:25:42,414 [salt.minion      :1711][INFO    ][11003] Returning information for job: 20190530022542353178
2019-05-30 02:26:05,467 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1618.60915685s left)
2019-05-30 02:26:12,385 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530022612371143
2019-05-30 02:26:12,420 [salt.minion      :1432][INFO    ][11102] Starting a new job with PID 11102
2019-05-30 02:26:12,467 [salt.minion      :1711][INFO    ][11102] Returning information for job: 20190530022612371143
2019-05-30 02:26:38,279 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1585.79700589s left)
2019-05-30 02:26:42,433 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530022642421469
2019-05-30 02:26:42,462 [salt.minion      :1432][INFO    ][11328] Starting a new job with PID 11328
2019-05-30 02:26:42,484 [salt.minion      :1711][INFO    ][11328] Returning information for job: 20190530022642421469
2019-05-30 02:27:11,124 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1552.95263481s left)
2019-05-30 02:27:12,622 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530022712608405
2019-05-30 02:27:12,647 [salt.minion      :1432][INFO    ][11538] Starting a new job with PID 11538
2019-05-30 02:27:12,683 [salt.minion      :1711][INFO    ][11538] Returning information for job: 20190530022712608405
2019-05-30 02:27:42,849 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530022742837679
2019-05-30 02:27:42,873 [salt.minion      :1432][INFO    ][11605] Starting a new job with PID 11605
2019-05-30 02:27:42,898 [salt.minion      :1711][INFO    ][11605] Returning information for job: 20190530022742837679
2019-05-30 02:27:44,193 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1519.883214s left)
2019-05-30 02:28:13,073 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530022813060776
2019-05-30 02:28:13,097 [salt.minion      :1432][INFO    ][11676] Starting a new job with PID 11676
2019-05-30 02:28:13,119 [salt.minion      :1711][INFO    ][11676] Returning information for job: 20190530022813060776
2019-05-30 02:28:17,494 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1486.58201599s left)
2019-05-30 02:28:43,098 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530022843081578
2019-05-30 02:28:43,128 [salt.minion      :1432][INFO    ][11772] Starting a new job with PID 11772
2019-05-30 02:28:43,149 [salt.minion      :1711][INFO    ][11772] Returning information for job: 20190530022843081578
2019-05-30 02:28:50,492 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1453.58473182s left)
2019-05-30 02:29:13,162 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530022913149852
2019-05-30 02:29:13,197 [salt.minion      :1432][INFO    ][11913] Starting a new job with PID 11913
2019-05-30 02:29:13,235 [salt.minion      :1711][INFO    ][11913] Returning information for job: 20190530022913149852
2019-05-30 02:29:23,599 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1420.47772288s left)
2019-05-30 02:29:43,239 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530022943223304
2019-05-30 02:29:43,271 [salt.minion      :1432][INFO    ][12028] Starting a new job with PID 12028
2019-05-30 02:29:43,292 [salt.minion      :1711][INFO    ][12028] Returning information for job: 20190530022943223304
2019-05-30 02:29:56,563 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1387.51334s left)
2019-05-30 02:30:13,304 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530023013292384
2019-05-30 02:30:13,331 [salt.minion      :1432][INFO    ][12079] Starting a new job with PID 12079
2019-05-30 02:30:13,363 [salt.minion      :1711][INFO    ][12079] Returning information for job: 20190530023013292384
2019-05-30 02:30:29,623 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1354.45377088s left)
2019-05-30 02:30:43,381 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530023043364734
2019-05-30 02:30:43,410 [salt.minion      :1432][INFO    ][12104] Starting a new job with PID 12104
2019-05-30 02:30:43,433 [salt.minion      :1711][INFO    ][12104] Returning information for job: 20190530023043364734
2019-05-30 02:31:02,648 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1321.42790389s left)
2019-05-30 02:31:13,435 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530023113425546
2019-05-30 02:31:13,458 [salt.minion      :1432][INFO    ][12156] Starting a new job with PID 12156
2019-05-30 02:31:13,479 [salt.minion      :1711][INFO    ][12156] Returning information for job: 20190530023113425546
2019-05-30 02:31:35,571 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1288.5054729s left)
2019-05-30 02:31:43,524 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530023143512228
2019-05-30 02:31:43,548 [salt.minion      :1432][INFO    ][12187] Starting a new job with PID 12187
2019-05-30 02:31:43,581 [salt.minion      :1711][INFO    ][12187] Returning information for job: 20190530023143512228
2019-05-30 02:32:08,544 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1255.53263783s left)
2019-05-30 02:32:13,652 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530023213637706
2019-05-30 02:32:13,679 [salt.minion      :1432][INFO    ][12242] Starting a new job with PID 12242
2019-05-30 02:32:13,701 [salt.minion      :1711][INFO    ][12242] Returning information for job: 20190530023213637706
2019-05-30 02:32:41,355 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1222.72121596s left)
2019-05-30 02:32:43,736 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530023243724755
2019-05-30 02:32:43,767 [salt.minion      :1432][INFO    ][12267] Starting a new job with PID 12267
2019-05-30 02:32:43,786 [salt.minion      :1711][INFO    ][12267] Returning information for job: 20190530023243724755
2019-05-30 02:33:13,890 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530023313877282
2019-05-30 02:33:13,914 [salt.minion      :1432][INFO    ][12322] Starting a new job with PID 12322
2019-05-30 02:33:13,939 [salt.minion      :1711][INFO    ][12322] Returning information for job: 20190530023313877282
2019-05-30 02:33:14,272 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1189.80446696s left)
2019-05-30 02:33:44,049 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530023344031612
2019-05-30 02:33:44,079 [salt.minion      :1432][INFO    ][12342] Starting a new job with PID 12342
2019-05-30 02:33:44,106 [salt.minion      :1711][INFO    ][12342] Returning information for job: 20190530023344031612
2019-05-30 02:33:47,023 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1157.05325985s left)
2019-05-30 02:34:14,218 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530023414208461
2019-05-30 02:34:14,244 [salt.minion      :1432][INFO    ][12396] Starting a new job with PID 12396
2019-05-30 02:34:14,268 [salt.minion      :1711][INFO    ][12396] Returning information for job: 20190530023414208461
2019-05-30 02:34:19,936 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1124.1400609s left)
2019-05-30 02:34:44,360 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530023444347842
2019-05-30 02:34:44,389 [salt.minion      :1432][INFO    ][12417] Starting a new job with PID 12417
2019-05-30 02:34:44,417 [salt.minion      :1711][INFO    ][12417] Returning information for job: 20190530023444347842
2019-05-30 02:34:52,973 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1091.10350585s left)
2019-05-30 02:35:14,542 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530023514530461
2019-05-30 02:35:14,573 [salt.minion      :1432][INFO    ][12477] Starting a new job with PID 12477
2019-05-30 02:35:14,599 [salt.minion      :1711][INFO    ][12477] Returning information for job: 20190530023514530461
2019-05-30 02:35:25,786 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1058.29000282s left)
2019-05-30 02:35:44,747 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530023544731086
2019-05-30 02:35:44,776 [salt.minion      :1432][INFO    ][12497] Starting a new job with PID 12497
2019-05-30 02:35:44,800 [salt.minion      :1711][INFO    ][12497] Returning information for job: 20190530023544731086
2019-05-30 02:35:58,649 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1025.42771983s left)
2019-05-30 02:36:14,777 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530023614762702
2019-05-30 02:36:14,807 [salt.minion      :1432][INFO    ][12554] Starting a new job with PID 12554
2019-05-30 02:36:14,829 [salt.minion      :1711][INFO    ][12554] Returning information for job: 20190530023614762702
2019-05-30 02:36:31,399 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (992.676926851s left)
2019-05-30 02:36:44,807 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530023644792885
2019-05-30 02:36:44,836 [salt.minion      :1432][INFO    ][12575] Starting a new job with PID 12575
2019-05-30 02:36:44,864 [salt.minion      :1711][INFO    ][12575] Returning information for job: 20190530023644792885
2019-05-30 02:37:04,144 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (959.932760954s left)
2019-05-30 02:37:14,876 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530023714863117
2019-05-30 02:37:14,903 [salt.minion      :1432][INFO    ][12649] Starting a new job with PID 12649
2019-05-30 02:37:14,927 [salt.minion      :1711][INFO    ][12649] Returning information for job: 20190530023714863117
2019-05-30 02:37:36,957 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (927.119369984s left)
2019-05-30 02:37:44,924 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530023744913677
2019-05-30 02:37:44,947 [salt.minion      :1432][INFO    ][12679] Starting a new job with PID 12679
2019-05-30 02:37:44,971 [salt.minion      :1711][INFO    ][12679] Returning information for job: 20190530023744913677
2019-05-30 02:38:10,146 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (893.930467844s left)
2019-05-30 02:38:14,997 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530023814987006
2019-05-30 02:38:15,023 [salt.minion      :1432][INFO    ][12733] Starting a new job with PID 12733
2019-05-30 02:38:15,052 [salt.minion      :1711][INFO    ][12733] Returning information for job: 20190530023814987006
2019-05-30 02:38:42,963 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (861.113017797s left)
2019-05-30 02:38:45,107 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530023845092281
2019-05-30 02:38:45,133 [salt.minion      :1432][INFO    ][12762] Starting a new job with PID 12762
2019-05-30 02:38:45,155 [salt.minion      :1711][INFO    ][12762] Returning information for job: 20190530023845092281
2019-05-30 02:39:15,187 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530023915173127
2019-05-30 02:39:15,208 [salt.minion      :1432][INFO    ][12812] Starting a new job with PID 12812
2019-05-30 02:39:15,231 [salt.minion      :1711][INFO    ][12812] Returning information for job: 20190530023915173127
2019-05-30 02:39:15,906 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (828.170571804s left)
2019-05-30 02:39:45,328 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530023945313550
2019-05-30 02:39:45,359 [salt.minion      :1432][INFO    ][12828] Starting a new job with PID 12828
2019-05-30 02:39:45,381 [salt.minion      :1711][INFO    ][12828] Returning information for job: 20190530023945313550
2019-05-30 02:39:48,648 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (795.428536892s left)
2019-05-30 02:40:15,452 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530024015437930
2019-05-30 02:40:15,480 [salt.minion      :1432][INFO    ][12884] Starting a new job with PID 12884
2019-05-30 02:40:15,504 [salt.minion      :1711][INFO    ][12884] Returning information for job: 20190530024015437930
2019-05-30 02:40:21,508 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (762.568561792s left)
2019-05-30 02:40:45,629 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530024045612840
2019-05-30 02:40:45,661 [salt.minion      :1432][INFO    ][12904] Starting a new job with PID 12904
2019-05-30 02:40:45,689 [salt.minion      :1711][INFO    ][12904] Returning information for job: 20190530024045612840
2019-05-30 02:40:54,235 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (729.840993881s left)
2019-05-30 02:41:15,793 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530024115776860
2019-05-30 02:41:15,826 [salt.minion      :1432][INFO    ][12958] Starting a new job with PID 12958
2019-05-30 02:41:15,850 [salt.minion      :1711][INFO    ][12958] Returning information for job: 20190530024115776860
2019-05-30 02:41:27,153 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (696.923429966s left)
2019-05-30 02:41:45,834 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530024145816169
2019-05-30 02:41:45,858 [salt.minion      :1432][INFO    ][12978] Starting a new job with PID 12978
2019-05-30 02:41:45,888 [salt.minion      :1711][INFO    ][12978] Returning information for job: 20190530024145816169
2019-05-30 02:41:59,856 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (664.220088959s left)
2019-05-30 02:42:16,013 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530024215998239
2019-05-30 02:42:16,044 [salt.minion      :1432][INFO    ][13038] Starting a new job with PID 13038
2019-05-30 02:42:16,075 [salt.minion      :1711][INFO    ][13038] Returning information for job: 20190530024215998239
2019-05-30 02:42:32,799 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (631.277397871s left)
2019-05-30 02:42:46,074 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530024246061026
2019-05-30 02:42:46,099 [salt.minion      :1432][INFO    ][13061] Starting a new job with PID 13061
2019-05-30 02:42:46,124 [salt.minion      :1711][INFO    ][13061] Returning information for job: 20190530024246061026
2019-05-30 02:43:05,695 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (598.381546974s left)
2019-05-30 02:43:16,290 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530024316281609
2019-05-30 02:43:16,315 [salt.minion      :1432][INFO    ][13116] Starting a new job with PID 13116
2019-05-30 02:43:16,343 [salt.minion      :1711][INFO    ][13116] Returning information for job: 20190530024316281609
2019-05-30 02:43:38,455 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (565.621397972s left)
2019-05-30 02:43:46,385 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530024346369000
2019-05-30 02:43:46,415 [salt.minion      :1432][INFO    ][13136] Starting a new job with PID 13136
2019-05-30 02:43:46,438 [salt.minion      :1711][INFO    ][13136] Returning information for job: 20190530024346369000
2019-05-30 02:44:11,348 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (532.728562832s left)
2019-05-30 02:44:16,519 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530024416504557
2019-05-30 02:44:16,541 [salt.minion      :1432][INFO    ][13195] Starting a new job with PID 13195
2019-05-30 02:44:16,565 [salt.minion      :1711][INFO    ][13195] Returning information for job: 20190530024416504557
2019-05-30 02:44:44,312 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (499.763936996s left)
2019-05-30 02:44:46,558 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530024446546953
2019-05-30 02:44:46,590 [salt.minion      :1432][INFO    ][13216] Starting a new job with PID 13216
2019-05-30 02:44:46,614 [salt.minion      :1711][INFO    ][13216] Returning information for job: 20190530024446546953
2019-05-30 02:45:16,745 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530024516730906
2019-05-30 02:45:16,768 [salt.minion      :1432][INFO    ][13269] Starting a new job with PID 13269
2019-05-30 02:45:16,796 [salt.minion      :1711][INFO    ][13269] Returning information for job: 20190530024516730906
2019-05-30 02:45:17,195 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (466.881633997s left)
2019-05-30 02:45:46,860 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530024546845363
2019-05-30 02:45:46,882 [salt.minion      :1432][INFO    ][13285] Starting a new job with PID 13285
2019-05-30 02:45:46,907 [salt.minion      :1711][INFO    ][13285] Returning information for job: 20190530024546845363
2019-05-30 02:45:50,080 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (433.996349812s left)
2019-05-30 02:46:17,061 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530024617049176
2019-05-30 02:46:17,089 [salt.minion      :1432][INFO    ][13342] Starting a new job with PID 13342
2019-05-30 02:46:17,112 [salt.minion      :1711][INFO    ][13342] Returning information for job: 20190530024617049176
2019-05-30 02:46:22,905 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (401.171257973s left)
2019-05-30 02:46:47,242 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530024647226994
2019-05-30 02:46:47,269 [salt.minion      :1432][INFO    ][13362] Starting a new job with PID 13362
2019-05-30 02:46:47,294 [salt.minion      :1711][INFO    ][13362] Returning information for job: 20190530024647226994
2019-05-30 02:46:55,956 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (368.120090961s left)
2019-05-30 02:47:17,315 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530024717299706
2019-05-30 02:47:17,340 [salt.minion      :1432][INFO    ][13563] Starting a new job with PID 13563
2019-05-30 02:47:17,368 [salt.minion      :1711][INFO    ][13563] Returning information for job: 20190530024717299706
2019-05-30 02:47:28,852 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (335.224678993s left)
2019-05-30 02:47:47,412 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530024747397163
2019-05-30 02:47:47,442 [salt.minion      :1432][INFO    ][13589] Starting a new job with PID 13589
2019-05-30 02:47:47,466 [salt.minion      :1711][INFO    ][13589] Returning information for job: 20190530024747397163
2019-05-30 02:48:01,633 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (302.443470955s left)
2019-05-30 02:48:17,477 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530024817460420
2019-05-30 02:48:17,501 [salt.minion      :1432][INFO    ][13646] Starting a new job with PID 13646
2019-05-30 02:48:17,523 [salt.minion      :1711][INFO    ][13646] Returning information for job: 20190530024817460420
2019-05-30 02:48:34,308 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (269.768859863s left)
2019-05-30 02:48:47,574 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530024847561302
2019-05-30 02:48:47,604 [salt.minion      :1432][INFO    ][13670] Starting a new job with PID 13670
2019-05-30 02:48:47,628 [salt.minion      :1711][INFO    ][13670] Returning information for job: 20190530024847561302
2019-05-30 02:49:07,220 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (236.856681824s left)
2019-05-30 02:49:17,709 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530024917697253
2019-05-30 02:49:17,736 [salt.minion      :1432][INFO    ][13732] Starting a new job with PID 13732
2019-05-30 02:49:17,761 [salt.minion      :1711][INFO    ][13732] Returning information for job: 20190530024917697253
2019-05-30 02:49:40,103 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (203.97289896s left)
2019-05-30 02:49:47,833 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530024947825330
2019-05-30 02:49:47,855 [salt.minion      :1432][INFO    ][13753] Starting a new job with PID 13753
2019-05-30 02:49:47,879 [salt.minion      :1711][INFO    ][13753] Returning information for job: 20190530024947825330
2019-05-30 02:50:13,230 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (170.846586943s left)
2019-05-30 02:50:17,926 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530025017912813
2019-05-30 02:50:17,957 [salt.minion      :1432][INFO    ][13812] Starting a new job with PID 13812
2019-05-30 02:50:17,980 [salt.minion      :1711][INFO    ][13812] Returning information for job: 20190530025017912813
2019-05-30 02:50:45,967 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (138.109453917s left)
2019-05-30 02:50:48,121 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530025048104825
2019-05-30 02:50:48,149 [salt.minion      :1432][INFO    ][13832] Starting a new job with PID 13832
2019-05-30 02:50:48,171 [salt.minion      :1711][INFO    ][13832] Returning information for job: 20190530025048104825
2019-05-30 02:51:18,260 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530025118247962
2019-05-30 02:51:18,283 [salt.minion      :1432][INFO    ][13890] Starting a new job with PID 13890
2019-05-30 02:51:18,305 [salt.minion      :1711][INFO    ][13890] Returning information for job: 20190530025118247962
2019-05-30 02:51:18,720 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (105.356444836s left)
2019-05-30 02:51:48,452 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530025148443737
2019-05-30 02:51:48,480 [salt.minion      :1432][INFO    ][13907] Starting a new job with PID 13907
2019-05-30 02:51:48,502 [salt.minion      :1711][INFO    ][13907] Returning information for job: 20190530025148443737
2019-05-30 02:51:51,776 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (72.3003418446s left)
2019-05-30 02:52:18,657 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530025218640696
2019-05-30 02:52:18,687 [salt.minion      :1432][INFO    ][13973] Starting a new job with PID 13973
2019-05-30 02:52:18,714 [salt.minion      :1711][INFO    ][13973] Returning information for job: 20190530025218640696
2019-05-30 02:52:24,544 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (39.5327348709s left)
2019-05-30 02:52:48,726 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530025248709484
2019-05-30 02:52:48,753 [salt.minion      :1432][INFO    ][13992] Starting a new job with PID 13992
2019-05-30 02:52:48,775 [salt.minion      :1711][INFO    ][13992] Returning information for job: 20190530025248709484
2019-05-30 02:52:57,442 [salt.loaded.ext.module.maas:1023][INFO    ][8374] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (6.6346809864s left)
2019-05-30 02:53:18,771 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530025318756469
2019-05-30 02:53:18,792 [salt.minion      :1432][INFO    ][14046] Starting a new job with PID 14046
2019-05-30 02:53:18,819 [salt.minion      :1711][INFO    ][14046] Returning information for job: 20190530025318756469
2019-05-30 02:53:30,320 [salt.state       :302 ][ERROR   ][8374] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-05-30 02:53:30,321 [salt.state       :1951][INFO    ][8374] Completed state [maas.wait_for_machine_status] at time 02:53:30.320930 duration_in_ms=2276259.42
2019-05-30 02:53:30,330 [salt.minion      :1711][INFO    ][8374] Returning information for job: 20190530021524325421
2019-05-30 02:53:41,153 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command pillar.get with jid 20190530025341141612
2019-05-30 02:53:41,179 [salt.minion      :1432][INFO    ][14064] Starting a new job with PID 14064
2019-05-30 02:53:41,190 [salt.minion      :1711][INFO    ][14064] Returning information for job: 20190530025341141612
2019-05-30 02:53:41,756 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command service.status with jid 20190530025341741430
2019-05-30 02:53:41,785 [salt.minion      :1432][INFO    ][14069] Starting a new job with PID 14069
2019-05-30 02:53:42,395 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][14069] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-05-30 02:53:42,441 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][14069] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-05-30 02:53:42,463 [salt.minion      :1711][INFO    ][14069] Returning information for job: 20190530025341741430
2019-05-30 02:53:43,030 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command state.apply with jid 20190530025343019145
2019-05-30 02:53:43,057 [salt.minion      :1432][INFO    ][14084] Starting a new job with PID 14084
2019-05-30 02:53:50,657 [salt.state       :915 ][INFO    ][14084] Loading fresh modules for state activity
2019-05-30 02:53:51,272 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14084] Executing command 'salt-minion --version' in directory '/root'
2019-05-30 02:53:51,595 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14084] Executing command 'salt-minion --version' in directory '/root'
2019-05-30 02:53:52,632 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14084] Executing command 'salt-minion --version' in directory '/root'
2019-05-30 02:53:52,926 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14084] Executing command 'salt-minion --version' in directory '/root'
2019-05-30 02:53:54,808 [salt.state       :1780][INFO    ][14084] Running state [salt-minion] at time 02:53:54.808167
2019-05-30 02:53:54,808 [salt.state       :1813][INFO    ][14084] Executing state pkg.installed for [salt-minion]
2019-05-30 02:53:54,810 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14084] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-30 02:53:54,939 [salt.state       :300 ][INFO    ][14084] All specified packages are already installed
2019-05-30 02:53:54,940 [salt.state       :1951][INFO    ][14084] Completed state [salt-minion] at time 02:53:54.940183 duration_in_ms=132.017
2019-05-30 02:53:54,940 [salt.state       :1780][INFO    ][14084] Running state [salt_minion_dependency_packages] at time 02:53:54.940521
2019-05-30 02:53:54,940 [salt.state       :1813][INFO    ][14084] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-05-30 02:53:54,949 [salt.state       :300 ][INFO    ][14084] All specified packages are already installed
2019-05-30 02:53:54,949 [salt.state       :1951][INFO    ][14084] Completed state [salt_minion_dependency_packages] at time 02:53:54.949624 duration_in_ms=9.103
2019-05-30 02:53:54,953 [salt.state       :1780][INFO    ][14084] Running state [/etc/salt/minion.d/minion.conf] at time 02:53:54.953874
2019-05-30 02:53:54,954 [salt.state       :1813][INFO    ][14084] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-05-30 02:53:55,289 [salt.state       :300 ][INFO    ][14084] File /etc/salt/minion.d/minion.conf is in the correct state
2019-05-30 02:53:55,290 [salt.state       :1951][INFO    ][14084] Completed state [/etc/salt/minion.d/minion.conf] at time 02:53:55.290122 duration_in_ms=336.248
2019-05-30 02:53:55,290 [salt.state       :1780][INFO    ][14084] Running state [python-netaddr] at time 02:53:55.290406
2019-05-30 02:53:55,290 [salt.state       :1813][INFO    ][14084] Executing state pkg.installed for [python-netaddr]
2019-05-30 02:53:55,300 [salt.state       :300 ][INFO    ][14084] All specified packages are already installed
2019-05-30 02:53:55,301 [salt.state       :1951][INFO    ][14084] Completed state [python-netaddr] at time 02:53:55.301101 duration_in_ms=10.695
2019-05-30 02:53:55,304 [salt.state       :1780][INFO    ][14084] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:53:55.304229
2019-05-30 02:53:55,304 [salt.state       :1813][INFO    ][14084] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-05-30 02:53:55,318 [salt.state       :300 ][INFO    ][14084] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-05-30 02:53:55,318 [salt.state       :1951][INFO    ][14084] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:53:55.318369 duration_in_ms=14.139
2019-05-30 02:53:55,319 [salt.state       :1780][INFO    ][14084] Running state [salt-minion] at time 02:53:55.319623
2019-05-30 02:53:55,319 [salt.state       :1813][INFO    ][14084] Executing state service.running for [salt-minion]
2019-05-30 02:53:55,320 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14084] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-30 02:53:55,360 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14084] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-05-30 02:53:55,378 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14084] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-05-30 02:53:55,399 [salt.state       :300 ][INFO    ][14084] The service salt-minion is already running
2019-05-30 02:53:55,399 [salt.state       :1951][INFO    ][14084] Completed state [salt-minion] at time 02:53:55.399825 duration_in_ms=80.202
2019-05-30 02:53:55,402 [salt.state       :1780][INFO    ][14084] Running state [/etc/salt/grains.d] at time 02:53:55.402764
2019-05-30 02:53:55,403 [salt.state       :1813][INFO    ][14084] Executing state file.directory for [/etc/salt/grains.d]
2019-05-30 02:53:55,405 [salt.state       :300 ][INFO    ][14084] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-05-30 02:53:55,405 [salt.state       :1951][INFO    ][14084] Completed state [/etc/salt/grains.d] at time 02:53:55.405538 duration_in_ms=2.773
2019-05-30 02:53:55,408 [salt.state       :1780][INFO    ][14084] Running state [/etc/salt/grains] at time 02:53:55.408942
2019-05-30 02:53:55,409 [salt.state       :1813][INFO    ][14084] Executing state file.managed for [/etc/salt/grains]
2019-05-30 02:53:55,409 [salt.state       :300 ][INFO    ][14084] File /etc/salt/grains exists with proper permissions. No changes made.
2019-05-30 02:53:55,410 [salt.state       :1951][INFO    ][14084] Completed state [/etc/salt/grains] at time 02:53:55.410068 duration_in_ms=1.126
2019-05-30 02:53:55,410 [salt.state       :1780][INFO    ][14084] Running state [/etc/salt/grains.d/placeholder] at time 02:53:55.410602
2019-05-30 02:53:55,410 [salt.state       :1813][INFO    ][14084] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-05-30 02:53:55,411 [salt.state       :300 ][INFO    ][14084] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-05-30 02:53:55,411 [salt.state       :1951][INFO    ][14084] Completed state [/etc/salt/grains.d/placeholder] at time 02:53:55.411659 duration_in_ms=1.058
2019-05-30 02:53:55,412 [salt.state       :1780][INFO    ][14084] Running state [/etc/salt/grains.d/sphinx] at time 02:53:55.412186
2019-05-30 02:53:55,412 [salt.state       :1813][INFO    ][14084] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-05-30 02:53:55,413 [salt.state       :300 ][INFO    ][14084] File /etc/salt/grains.d/sphinx is in the correct state
2019-05-30 02:53:55,413 [salt.state       :1951][INFO    ][14084] Completed state [/etc/salt/grains.d/sphinx] at time 02:53:55.413940 duration_in_ms=1.754
2019-05-30 02:53:55,416 [salt.state       :1780][INFO    ][14084] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:53:55.416282
2019-05-30 02:53:55,416 [salt.state       :1813][INFO    ][14084] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-05-30 02:53:55,416 [salt.state       :300 ][INFO    ][14084] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-05-30 02:53:55,417 [salt.state       :1951][INFO    ][14084] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:53:55.417142 duration_in_ms=0.861
2019-05-30 02:53:55,419 [salt.state       :1780][INFO    ][14084] Running state [/etc/salt/grains.d/dns_records] at time 02:53:55.417695
2019-05-30 02:53:55,419 [salt.state       :1813][INFO    ][14084] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-05-30 02:53:55,420 [salt.state       :300 ][INFO    ][14084] File /etc/salt/grains.d/dns_records is in the correct state
2019-05-30 02:53:55,420 [salt.state       :1951][INFO    ][14084] Completed state [/etc/salt/grains.d/dns_records] at time 02:53:55.420779 duration_in_ms=3.084
2019-05-30 02:53:55,421 [salt.state       :1780][INFO    ][14084] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:53:55.421692
2019-05-30 02:53:55,421 [salt.state       :1813][INFO    ][14084] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-05-30 02:53:55,422 [salt.state       :300 ][INFO    ][14084] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-05-30 02:53:55,422 [salt.state       :1951][INFO    ][14084] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:53:55.422446 duration_in_ms=0.754
2019-05-30 02:53:55,422 [salt.state       :1780][INFO    ][14084] Running state [/etc/salt/grains.d/salt] at time 02:53:55.422915
2019-05-30 02:53:55,423 [salt.state       :1813][INFO    ][14084] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-05-30 02:53:55,424 [salt.state       :300 ][INFO    ][14084] File /etc/salt/grains.d/salt is in the correct state
2019-05-30 02:53:55,424 [salt.state       :1951][INFO    ][14084] Completed state [/etc/salt/grains.d/salt] at time 02:53:55.424244 duration_in_ms=1.329
2019-05-30 02:53:55,425 [salt.state       :1780][INFO    ][14084] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:53:55.425106
2019-05-30 02:53:55,425 [salt.state       :1813][INFO    ][14084] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-05-30 02:53:55,425 [salt.state       :300 ][INFO    ][14084] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-05-30 02:53:55,425 [salt.state       :1951][INFO    ][14084] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:53:55.425852 duration_in_ms=0.746
2019-05-30 02:53:55,427 [salt.state       :1780][INFO    ][14084] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:53:55.427754
2019-05-30 02:53:55,428 [salt.state       :1813][INFO    ][14084] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-05-30 02:53:55,428 [salt.state       :300 ][INFO    ][14084] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-05-30 02:53:55,428 [salt.state       :1951][INFO    ][14084] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:53:55.428514 duration_in_ms=0.76
2019-05-30 02:53:55,429 [salt.state       :1780][INFO    ][14084] Running state [mine.update] at time 02:53:55.429165
2019-05-30 02:53:55,429 [salt.state       :1813][INFO    ][14084] Executing state module.wait for [mine.update]
2019-05-30 02:53:55,429 [salt.state       :300 ][INFO    ][14084] No changes made for mine.update
2019-05-30 02:53:55,430 [salt.state       :1951][INFO    ][14084] Completed state [mine.update] at time 02:53:55.430751 duration_in_ms=1.586
2019-05-30 02:53:55,431 [salt.state       :1780][INFO    ][14084] Running state [ca-certificates] at time 02:53:55.430980
2019-05-30 02:53:55,431 [salt.state       :1813][INFO    ][14084] Executing state pkg.installed for [ca-certificates]
2019-05-30 02:53:55,440 [salt.state       :300 ][INFO    ][14084] All specified packages are already installed
2019-05-30 02:53:55,440 [salt.state       :1951][INFO    ][14084] Completed state [ca-certificates] at time 02:53:55.440435 duration_in_ms=9.454
2019-05-30 02:53:55,441 [salt.state       :1780][INFO    ][14084] Running state [update-ca-certificates] at time 02:53:55.441109
2019-05-30 02:53:55,441 [salt.state       :1813][INFO    ][14084] Executing state cmd.wait for [update-ca-certificates]
2019-05-30 02:53:55,441 [salt.state       :300 ][INFO    ][14084] No changes made for update-ca-certificates
2019-05-30 02:53:55,443 [salt.state       :1951][INFO    ][14084] Completed state [update-ca-certificates] at time 02:53:55.443000 duration_in_ms=1.89
2019-05-30 02:53:55,443 [salt.state       :1780][INFO    ][14084] Running state [iptables] at time 02:53:55.443504
2019-05-30 02:53:55,443 [salt.state       :1813][INFO    ][14084] Executing state pkg.installed for [iptables]
2019-05-30 02:53:55,452 [salt.state       :300 ][INFO    ][14084] All specified packages are already installed
2019-05-30 02:53:55,452 [salt.state       :1951][INFO    ][14084] Completed state [iptables] at time 02:53:55.452338 duration_in_ms=8.834
2019-05-30 02:53:55,452 [salt.state       :1780][INFO    ][14084] Running state [iptables-persistent] at time 02:53:55.452568
2019-05-30 02:53:55,452 [salt.state       :1813][INFO    ][14084] Executing state pkg.installed for [iptables-persistent]
2019-05-30 02:53:55,463 [salt.state       :300 ][INFO    ][14084] All specified packages are already installed
2019-05-30 02:53:55,463 [salt.state       :1951][INFO    ][14084] Completed state [iptables-persistent] at time 02:53:55.463459 duration_in_ms=10.89
2019-05-30 02:53:55,464 [salt.state       :1780][INFO    ][14084] Running state [iptables_modules_v4_load] at time 02:53:55.464409
2019-05-30 02:53:55,464 [salt.state       :1813][INFO    ][14084] Executing state kmod.present for [iptables_modules_v4_load]
2019-05-30 02:53:55,465 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14084] Executing command 'lsmod' in directory '/root'
2019-05-30 02:53:55,490 [salt.state       :300 ][INFO    ][14084] Kernel modules iptable_filter, ip_tables are already present
2019-05-30 02:53:55,491 [salt.state       :1951][INFO    ][14084] Completed state [iptables_modules_v4_load] at time 02:53:55.491099 duration_in_ms=26.689
2019-05-30 02:53:55,492 [salt.state       :1780][INFO    ][14084] Running state [/etc/iptables/rules.v4] at time 02:53:55.492213
2019-05-30 02:53:55,492 [salt.state       :1813][INFO    ][14084] Executing state file.managed for [/etc/iptables/rules.v4]
2019-05-30 02:53:55,600 [salt.state       :300 ][INFO    ][14084] File /etc/iptables/rules.v4 is in the correct state
2019-05-30 02:53:55,601 [salt.state       :1951][INFO    ][14084] Completed state [/etc/iptables/rules.v4] at time 02:53:55.600964 duration_in_ms=108.751
2019-05-30 02:53:55,602 [salt.state       :1780][INFO    ][14084] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:53:55.602151
2019-05-30 02:53:55,602 [salt.state       :1813][INFO    ][14084] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-05-30 02:53:55,603 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14084] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-05-30 02:53:55,626 [salt.state       :300 ][INFO    ][14084] onlyif execution failed
2019-05-30 02:53:55,627 [salt.state       :1951][INFO    ][14084] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:53:55.627181 duration_in_ms=25.029
2019-05-30 02:53:55,629 [salt.state       :1780][INFO    ][14084] Running state [netfilter-persistent] at time 02:53:55.629046
2019-05-30 02:53:55,629 [salt.state       :1813][INFO    ][14084] Executing state service.running for [netfilter-persistent]
2019-05-30 02:53:55,630 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14084] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-05-30 02:53:55,656 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14084] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-05-30 02:53:55,679 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14084] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-05-30 02:53:55,700 [salt.state       :300 ][INFO    ][14084] The service netfilter-persistent is already running
2019-05-30 02:53:55,700 [salt.state       :1951][INFO    ][14084] Completed state [netfilter-persistent] at time 02:53:55.700881 duration_in_ms=71.836
2019-05-30 02:53:55,702 [salt.state       :1780][INFO    ][14084] Running state [iptables_extra.remove_stale_tables] at time 02:53:55.702567
2019-05-30 02:53:55,703 [salt.state       :1813][INFO    ][14084] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-05-30 02:53:55,703 [salt.state       :300 ][INFO    ][14084] No changes made for iptables_extra.remove_stale_tables
2019-05-30 02:53:55,703 [salt.state       :1951][INFO    ][14084] Completed state [iptables_extra.remove_stale_tables] at time 02:53:55.703830 duration_in_ms=1.264
2019-05-30 02:53:55,704 [salt.state       :1780][INFO    ][14084] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:53:55.704188
2019-05-30 02:53:55,704 [salt.state       :1813][INFO    ][14084] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-05-30 02:53:55,705 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14084] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-05-30 02:53:55,724 [salt.state       :300 ][INFO    ][14084] onlyif execution failed
2019-05-30 02:53:55,724 [salt.state       :1951][INFO    ][14084] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:53:55.724692 duration_in_ms=20.504
2019-05-30 02:53:55,726 [salt.state       :1780][INFO    ][14084] Running state [/etc/iptables/rules.v6] at time 02:53:55.726666
2019-05-30 02:53:55,727 [salt.state       :1813][INFO    ][14084] Executing state file.absent for [/etc/iptables/rules.v6]
2019-05-30 02:53:55,727 [salt.state       :300 ][INFO    ][14084] File /etc/iptables/rules.v6 is not present
2019-05-30 02:53:55,728 [salt.state       :1951][INFO    ][14084] Completed state [/etc/iptables/rules.v6] at time 02:53:55.728260 duration_in_ms=1.595
2019-05-30 02:53:55,729 [salt.state       :1780][INFO    ][14084] Running state [iptables_extra.flush_all] at time 02:53:55.729448
2019-05-30 02:53:55,731 [salt.state       :1813][INFO    ][14084] Executing state module.wait for [iptables_extra.flush_all]
2019-05-30 02:53:55,731 [salt.state       :300 ][INFO    ][14084] No changes made for iptables_extra.flush_all
2019-05-30 02:53:55,732 [salt.state       :1951][INFO    ][14084] Completed state [iptables_extra.flush_all] at time 02:53:55.731974 duration_in_ms=2.526
2019-05-30 02:53:55,737 [salt.minion      :1711][INFO    ][14084] Returning information for job: 20190530025343019145
2019-05-30 02:53:56,300 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command state.apply with jid 20190530025356290365
2019-05-30 02:53:56,329 [salt.minion      :1432][INFO    ][14173] Starting a new job with PID 14173
2019-05-30 02:53:57,483 [salt.state       :915 ][INFO    ][14173] Loading fresh modules for state activity
2019-05-30 02:53:58,398 [salt.state       :1780][INFO    ][14173] Running state [maas-rack-controller] at time 02:53:58.398234
2019-05-30 02:53:58,398 [salt.state       :1813][INFO    ][14173] Executing state pkg.installed for [maas-rack-controller]
2019-05-30 02:53:58,399 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14173] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-30 02:53:58,516 [salt.state       :300 ][INFO    ][14173] All specified packages are already installed
2019-05-30 02:53:58,516 [salt.state       :1951][INFO    ][14173] Completed state [maas-rack-controller] at time 02:53:58.516274 duration_in_ms=118.042
2019-05-30 02:53:58,516 [salt.state       :1780][INFO    ][14173] Running state [ipmitool] at time 02:53:58.516620
2019-05-30 02:53:58,516 [salt.state       :1813][INFO    ][14173] Executing state pkg.installed for [ipmitool]
2019-05-30 02:53:58,527 [salt.state       :300 ][INFO    ][14173] All specified packages are already installed
2019-05-30 02:53:58,528 [salt.state       :1951][INFO    ][14173] Completed state [ipmitool] at time 02:53:58.527907 duration_in_ms=11.287
2019-05-30 02:53:58,537 [salt.state       :1780][INFO    ][14173] Running state [/etc/maas/rackd.conf] at time 02:53:58.537137
2019-05-30 02:53:58,537 [salt.state       :1813][INFO    ][14173] Executing state file.line for [/etc/maas/rackd.conf]
2019-05-30 02:53:58,539 [salt.state       :300 ][INFO    ][14173] No changes needed to be made
2019-05-30 02:53:58,540 [salt.state       :1951][INFO    ][14173] Completed state [/etc/maas/rackd.conf] at time 02:53:58.540302 duration_in_ms=3.165
2019-05-30 02:53:58,540 [salt.state       :1780][INFO    ][14173] Running state [/etc/maas/rackd.conf] at time 02:53:58.540783
2019-05-30 02:53:58,541 [salt.state       :1813][INFO    ][14173] Executing state file.managed for [/etc/maas/rackd.conf]
2019-05-30 02:53:58,543 [salt.loaded.int.states.file:2298][WARNING ][14173] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-05-30 02:53:58,543 [salt.state       :300 ][INFO    ][14173] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-05-30 02:53:58,544 [salt.state       :1951][INFO    ][14173] Completed state [/etc/maas/rackd.conf] at time 02:53:58.544144 duration_in_ms=3.361
2019-05-30 02:53:58,545 [salt.state       :1780][INFO    ][14173] Running state [maas-rackd] at time 02:53:58.545178
2019-05-30 02:53:58,545 [salt.state       :1813][INFO    ][14173] Executing state service.running for [maas-rackd]
2019-05-30 02:53:58,547 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14173] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-05-30 02:53:58,596 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14173] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-05-30 02:53:58,618 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14173] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-05-30 02:53:58,640 [salt.state       :300 ][INFO    ][14173] The service maas-rackd is already running
2019-05-30 02:53:58,641 [salt.state       :1951][INFO    ][14173] Completed state [maas-rackd] at time 02:53:58.641218 duration_in_ms=96.039
2019-05-30 02:53:58,643 [salt.minion      :1711][INFO    ][14173] Returning information for job: 20190530025356290365
2019-05-30 02:53:59,231 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command state.apply with jid 20190530025359208205
2019-05-30 02:53:59,253 [salt.minion      :1432][INFO    ][14197] Starting a new job with PID 14197
2019-05-30 02:54:00,394 [salt.state       :915 ][INFO    ][14197] Loading fresh modules for state activity
2019-05-30 02:54:01,473 [salt.state       :1780][INFO    ][14197] Running state [maas-region-controller] at time 02:54:01.473015
2019-05-30 02:54:01,473 [salt.state       :1813][INFO    ][14197] Executing state pkg.installed for [maas-region-controller]
2019-05-30 02:54:01,474 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14197] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-30 02:54:01,591 [salt.state       :300 ][INFO    ][14197] All specified packages are already installed
2019-05-30 02:54:01,591 [salt.state       :1951][INFO    ][14197] Completed state [maas-region-controller] at time 02:54:01.591416 duration_in_ms=118.402
2019-05-30 02:54:01,591 [salt.state       :1780][INFO    ][14197] Running state [python-oauth] at time 02:54:01.591753
2019-05-30 02:54:01,592 [salt.state       :1813][INFO    ][14197] Executing state pkg.installed for [python-oauth]
2019-05-30 02:54:01,602 [salt.state       :300 ][INFO    ][14197] All specified packages are already installed
2019-05-30 02:54:01,603 [salt.state       :1951][INFO    ][14197] Completed state [python-oauth] at time 02:54:01.603071 duration_in_ms=11.318
2019-05-30 02:54:01,606 [salt.state       :1780][INFO    ][14197] Running state [/etc/maas/regiond.conf] at time 02:54:01.606473
2019-05-30 02:54:01,606 [salt.state       :1813][INFO    ][14197] Executing state file.replace for [/etc/maas/regiond.conf]
2019-05-30 02:54:01,612 [salt.state       :300 ][INFO    ][14197] No changes needed to be made
2019-05-30 02:54:01,612 [salt.state       :1951][INFO    ][14197] Completed state [/etc/maas/regiond.conf] at time 02:54:01.612544 duration_in_ms=6.071
2019-05-30 02:54:01,613 [salt.state       :1780][INFO    ][14197] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:54:01.613022
2019-05-30 02:54:01,613 [salt.state       :1813][INFO    ][14197] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-05-30 02:54:01,682 [salt.state       :300 ][INFO    ][14197] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-05-30 02:54:01,682 [salt.state       :1951][INFO    ][14197] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:54:01.682858 duration_in_ms=69.836
2019-05-30 02:54:01,683 [salt.state       :1780][INFO    ][14197] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:54:01.683344
2019-05-30 02:54:01,683 [salt.state       :1813][INFO    ][14197] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-30 02:54:01,689 [salt.state       :300 ][INFO    ][14197] No changes needed to be made
2019-05-30 02:54:01,690 [salt.state       :1951][INFO    ][14197] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:54:01.690075 duration_in_ms=6.73
2019-05-30 02:54:01,690 [salt.state       :1780][INFO    ][14197] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:54:01.690732
2019-05-30 02:54:01,691 [salt.state       :1813][INFO    ][14197] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-30 02:54:01,695 [salt.state       :300 ][INFO    ][14197] No changes needed to be made
2019-05-30 02:54:01,696 [salt.state       :1951][INFO    ][14197] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:54:01.695942 duration_in_ms=5.21
2019-05-30 02:54:01,696 [salt.state       :1780][INFO    ][14197] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:54:01.696551
2019-05-30 02:54:01,696 [salt.state       :1813][INFO    ][14197] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-05-30 02:54:01,734 [salt.state       :300 ][INFO    ][14197] No changes needed to be made
2019-05-30 02:54:01,734 [salt.state       :1951][INFO    ][14197] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:54:01.734832 duration_in_ms=38.281
2019-05-30 02:54:01,735 [salt.state       :1780][INFO    ][14197] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:54:01.735322
2019-05-30 02:54:01,735 [salt.state       :1813][INFO    ][14197] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-05-30 02:54:01,757 [salt.state       :300 ][INFO    ][14197] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-05-30 02:54:01,757 [salt.state       :1951][INFO    ][14197] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:54:01.757564 duration_in_ms=22.241
2019-05-30 02:54:01,759 [salt.state       :1780][INFO    ][14197] Running state [a2enmod headers] at time 02:54:01.759381
2019-05-30 02:54:01,759 [salt.state       :1813][INFO    ][14197] Executing state cmd.run for [a2enmod headers]
2019-05-30 02:54:01,760 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14197] Executing command 'a2enmod headers' in directory '/root'
2019-05-30 02:54:01,840 [salt.state       :300 ][INFO    ][14197] {'pid': 14216, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-05-30 02:54:01,840 [salt.state       :1951][INFO    ][14197] Completed state [a2enmod headers] at time 02:54:01.840684 duration_in_ms=81.302
2019-05-30 02:54:01,841 [salt.state       :1780][INFO    ][14197] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:54:01.841254
2019-05-30 02:54:01,841 [salt.state       :1813][INFO    ][14197] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-05-30 02:54:01,871 [salt.state       :300 ][INFO    ][14197] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-05-30 02:54:01,872 [salt.state       :1951][INFO    ][14197] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:54:01.871978 duration_in_ms=30.724
2019-05-30 02:54:01,872 [salt.state       :1780][INFO    ][14197] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:54:01.872868
2019-05-30 02:54:01,873 [salt.state       :1813][INFO    ][14197] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-05-30 02:54:01,941 [salt.state       :300 ][INFO    ][14197] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-05-30 02:54:01,942 [salt.state       :1951][INFO    ][14197] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:54:01.942150 duration_in_ms=69.283
2019-05-30 02:54:01,942 [salt.state       :1780][INFO    ][14197] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:54:01.942632
2019-05-30 02:54:01,942 [salt.state       :1813][INFO    ][14197] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-05-30 02:54:02,008 [salt.state       :300 ][INFO    ][14197] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-05-30 02:54:02,009 [salt.state       :1951][INFO    ][14197] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:54:02.009014 duration_in_ms=66.382
2019-05-30 02:54:02,009 [salt.state       :1780][INFO    ][14197] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:54:02.009539
2019-05-30 02:54:02,009 [salt.state       :1813][INFO    ][14197] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-05-30 02:54:02,081 [salt.state       :300 ][INFO    ][14197] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-05-30 02:54:02,081 [salt.state       :1951][INFO    ][14197] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:54:02.081859 duration_in_ms=72.32
2019-05-30 02:54:02,082 [salt.state       :1780][INFO    ][14197] Running state [/root/.pgpass] at time 02:54:02.082124
2019-05-30 02:54:02,082 [salt.state       :1813][INFO    ][14197] Executing state file.managed for [/root/.pgpass]
2019-05-30 02:54:02,130 [salt.state       :300 ][INFO    ][14197] File /root/.pgpass is in the correct state
2019-05-30 02:54:02,130 [salt.state       :1951][INFO    ][14197] Completed state [/root/.pgpass] at time 02:54:02.130569 duration_in_ms=48.445
2019-05-30 02:54:02,135 [salt.state       :1780][INFO    ][14197] Running state [maas-region syncdb --noinput] at time 02:54:02.135418
2019-05-30 02:54:02,135 [salt.state       :1813][INFO    ][14197] Executing state cmd.run for [maas-region syncdb --noinput]
2019-05-30 02:54:02,136 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14197] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-05-30 02:54:04,764 [salt.state       :300 ][INFO    ][14197] {'pid': 14229, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: sessions, metadataserver, maasserver, contenttypes, auth, sites, piston3\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-05-30 02:54:04,764 [salt.state       :1951][INFO    ][14197] Completed state [maas-region syncdb --noinput] at time 02:54:04.764805 duration_in_ms=2629.385
2019-05-30 02:54:04,765 [salt.state       :2022][WARNING ][14197] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-30 02:54:04,770 [salt.state       :1780][INFO    ][14197] Running state [maas-regiond] at time 02:54:04.770288
2019-05-30 02:54:04,770 [salt.state       :1813][INFO    ][14197] Executing state service.running for [maas-regiond]
2019-05-30 02:54:04,772 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14197] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-05-30 02:54:04,822 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14197] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-05-30 02:54:04,844 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14197] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-05-30 02:54:04,870 [salt.state       :300 ][INFO    ][14197] The service maas-regiond is already running
2019-05-30 02:54:04,871 [salt.state       :1951][INFO    ][14197] Completed state [maas-regiond] at time 02:54:04.871446 duration_in_ms=101.157
2019-05-30 02:54:04,876 [salt.state       :1780][INFO    ][14197] Running state [bind9] at time 02:54:04.876832
2019-05-30 02:54:04,877 [salt.state       :1813][INFO    ][14197] Executing state service.running for [bind9]
2019-05-30 02:54:04,878 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14197] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-05-30 02:54:04,900 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14197] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-05-30 02:54:04,920 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14197] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-05-30 02:54:04,945 [salt.state       :300 ][INFO    ][14197] The service bind9 is already running
2019-05-30 02:54:04,948 [salt.state       :1951][INFO    ][14197] Completed state [bind9] at time 02:54:04.948180 duration_in_ms=71.347
2019-05-30 02:54:04,951 [salt.state       :1780][INFO    ][14197] Running state [apache2] at time 02:54:04.951133
2019-05-30 02:54:04,951 [salt.state       :1813][INFO    ][14197] Executing state service.running for [apache2]
2019-05-30 02:54:04,952 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14197] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-05-30 02:54:04,978 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14197] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-05-30 02:54:05,003 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14197] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-05-30 02:54:05,036 [salt.state       :300 ][INFO    ][14197] The service apache2 is already running
2019-05-30 02:54:05,036 [salt.state       :1951][INFO    ][14197] Completed state [apache2] at time 02:54:05.036633 duration_in_ms=85.5
2019-05-30 02:54:05,039 [salt.state       :1780][INFO    ][14197] Running state [maasng.wait_for_http_code] at time 02:54:05.039288
2019-05-30 02:54:05,040 [salt.state       :1813][INFO    ][14197] Executing state module.run for [maasng.wait_for_http_code]
2019-05-30 02:54:05,040 [salt.utils.decorators:613 ][WARNING ][14197] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-30 02:54:05,198 [salt.state       :300 ][INFO    ][14197] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-05-30 02:54:05,198 [salt.state       :1951][INFO    ][14197] Completed state [maasng.wait_for_http_code] at time 02:54:05.198864 duration_in_ms=159.577
2019-05-30 02:54:05,200 [salt.state       :1780][INFO    ][14197] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:54:05.199945
2019-05-30 02:54:05,200 [salt.state       :1813][INFO    ][14197] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-05-30 02:54:05,201 [salt.state       :300 ][INFO    ][14197] /var/lib/maas/.setup_admin exists
2019-05-30 02:54:05,201 [salt.state       :1951][INFO    ][14197] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:54:05.201520 duration_in_ms=1.575
2019-05-30 02:54:05,203 [salt.state       :1780][INFO    ][14197] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:05.203020
2019-05-30 02:54:05,203 [salt.state       :1813][INFO    ][14197] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-30 02:54:05,204 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14197] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-30 02:54:06,966 [salt.state       :300 ][INFO    ][14197] {'pid': 14250, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-30 02:54:06,967 [salt.state       :1951][INFO    ][14197] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:06.967810 duration_in_ms=1764.788
2019-05-30 02:54:06,976 [salt.state       :1780][INFO    ][14197] Running state [maas_region_boot_source_resources_mirror] at time 02:54:06.976873
2019-05-30 02:54:06,977 [salt.state       :1813][INFO    ][14197] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-05-30 02:54:07,064 [salt.state       :300 ][INFO    ][14197] {'changes': {}}
2019-05-30 02:54:07,065 [salt.state       :1951][INFO    ][14197] Completed state [maas_region_boot_source_resources_mirror] at time 02:54:07.065177 duration_in_ms=88.303
2019-05-30 02:54:07,067 [salt.state       :1780][INFO    ][14197] Running state [maasng.boot_resources_import] at time 02:54:07.067446
2019-05-30 02:54:07,067 [salt.state       :1813][INFO    ][14197] Executing state module.run for [maasng.boot_resources_import]
2019-05-30 02:54:07,068 [salt.utils.decorators:613 ][WARNING ][14197] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-30 02:54:07,160 [salt.loaded.ext.module.maasng:1600][INFO    ][14197] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-05-30 02:54:12,202 [salt.loaded.ext.module.maasng:1600][INFO    ][14197] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-05-30 02:54:14,344 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530025414332492
2019-05-30 02:54:14,372 [salt.minion      :1432][INFO    ][14314] Starting a new job with PID 14314
2019-05-30 02:54:14,400 [salt.minion      :1711][INFO    ][14314] Returning information for job: 20190530025414332492
2019-05-30 02:54:17,310 [salt.state       :300 ][INFO    ][14197] {'ret': True}
2019-05-30 02:54:17,311 [salt.state       :1951][INFO    ][14197] Completed state [maasng.boot_resources_import] at time 02:54:17.311485 duration_in_ms=10244.038
2019-05-30 02:54:17,312 [salt.state       :1780][INFO    ][14197] Running state [maas_region_boot_sources_selection_xenial] at time 02:54:17.312726
2019-05-30 02:54:17,313 [salt.state       :1813][INFO    ][14197] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-05-30 02:54:17,506 [salt.state       :300 ][INFO    ][14197] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-05-30 02:54:17,506 [salt.state       :1951][INFO    ][14197] Completed state [maas_region_boot_sources_selection_xenial] at time 02:54:17.506739 duration_in_ms=194.013
2019-05-30 02:54:17,508 [salt.state       :1780][INFO    ][14197] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 02:54:17.508252
2019-05-30 02:54:17,508 [salt.state       :1813][INFO    ][14197] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-05-30 02:54:17,509 [salt.utils.decorators:613 ][WARNING ][14197] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-30 02:54:17,511 [salt.loaded.ext.module.maasng:1771][INFO    ][14197] boot-sources sync initiated for ALL Rack's
2019-05-30 02:54:18,540 [salt.state       :300 ][INFO    ][14197] {'ret': True}
2019-05-30 02:54:18,541 [salt.state       :1951][INFO    ][14197] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 02:54:18.541390 duration_in_ms=1033.138
2019-05-30 02:54:18,544 [salt.state       :1780][INFO    ][14197] Running state [maas.process_maas_config] at time 02:54:18.544590
2019-05-30 02:54:18,545 [salt.state       :1813][INFO    ][14197] Executing state module.run for [maas.process_maas_config]
2019-05-30 02:54:18,545 [salt.utils.decorators:613 ][WARNING ][14197] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-30 02:54:18,546 [salt.loaded.ext.module.maas:92  ][INFO    ][14197] maasconfig name=enable_http_proxy value=True
2019-05-30 02:54:18,609 [salt.loaded.ext.module.maas:92  ][INFO    ][14197] maasconfig name=upstream_dns value=8.8.8.8
2019-05-30 02:54:18,659 [salt.loaded.ext.module.maas:92  ][INFO    ][14197] maasconfig name=commissioning_distro_series value=xenial
2019-05-30 02:54:18,723 [salt.loaded.ext.module.maas:92  ][INFO    ][14197] maasconfig name=default_osystem value=ubuntu
2019-05-30 02:54:18,784 [salt.loaded.ext.module.maas:92  ][INFO    ][14197] maasconfig name=active_discovery_interval value=600
2019-05-30 02:54:18,836 [salt.loaded.ext.module.maas:92  ][INFO    ][14197] maasconfig name=dnssec_validation value=no
2019-05-30 02:54:18,886 [salt.loaded.ext.module.maas:92  ][INFO    ][14197] maasconfig name=maas_name value=mas01
2019-05-30 02:54:20,180 [salt.loaded.ext.module.maas:92  ][INFO    ][14197] maasconfig name=network_discovery value=enabled
2019-05-30 02:54:20,323 [salt.loaded.ext.module.maas:92  ][INFO    ][14197] maasconfig name=enable_third_party_drivers value=True
2019-05-30 02:54:20,378 [salt.loaded.ext.module.maas:92  ][INFO    ][14197] maasconfig name=default_storage_layout value=lvm
2019-05-30 02:54:20,423 [salt.loaded.ext.module.maas:92  ][INFO    ][14197] maasconfig name=ntp_external_only value=True
2019-05-30 02:54:20,472 [salt.loaded.ext.module.maas:92  ][INFO    ][14197] maasconfig name=disk_erase_with_secure_erase value=False
2019-05-30 02:54:20,516 [salt.loaded.ext.module.maas:92  ][INFO    ][14197] maasconfig name=default_distro_series value=xenial
2019-05-30 02:54:20,580 [salt.loaded.ext.module.maas:92  ][INFO    ][14197] maasconfig name=default_min_hwe_kernel value=ga-16.04
2019-05-30 02:54:20,699 [salt.state       :300 ][INFO    ][14197] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-05-30 02:54:20,700 [salt.state       :1951][INFO    ][14197] Completed state [maas.process_maas_config] at time 02:54:20.700037 duration_in_ms=2155.448
2019-05-30 02:54:20,700 [salt.state       :1780][INFO    ][14197] Running state [pxe_admin] at time 02:54:20.700783
2019-05-30 02:54:20,701 [salt.state       :1813][INFO    ][14197] Executing state maasng.fabric_present for [pxe_admin]
2019-05-30 02:54:20,763 [salt.loaded.ext.module.maasng:945 ][INFO    ][14197] [{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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ftepry', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-30 02:54:20,820 [salt.loaded.ext.module.maasng:1008][WARNING ][14197] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-05-30 02:54:20,820 [salt.loaded.ext.module.maasng:1011][WARNING ][14197] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-05-30 02:54:20,878 [salt.state       :300 ][INFO    ][14197] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-05-30 02:54:20,879 [salt.state       :1951][INFO    ][14197] Completed state [pxe_admin] at time 02:54:20.879052 duration_in_ms=178.267
2019-05-30 02:54:20,879 [salt.state       :1780][INFO    ][14197] Running state [vlan 0] at time 02:54:20.879431
2019-05-30 02:54:20,879 [salt.state       :1813][INFO    ][14197] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-30 02:54:20,953 [salt.loaded.ext.module.maasng:945 ][INFO    ][14197] [{u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0'}, {u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'fabric-2'}, {u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'ftepry', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'pxe_admin'}]
2019-05-30 02:54:21,068 [salt.loaded.ext.module.maasng:945 ][INFO    ][14197] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ftepry', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-30 02:54:21,331 [salt.loaded.ext.module.maasng:945 ][INFO    ][14197] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ftepry', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-30 02:54:21,415 [salt.state       :300 ][INFO    ][14197] {'new': 'Vlan untagged was updated'}
2019-05-30 02:54:21,415 [salt.state       :1951][INFO    ][14197] Completed state [vlan 0] at time 02:54:21.415882 duration_in_ms=536.45
2019-05-30 02:54:21,416 [salt.state       :1780][INFO    ][14197] Running state [192.168.11.0/24] at time 02:54:21.416805
2019-05-30 02:54:21,417 [salt.state       :1813][INFO    ][14197] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-05-30 02:54:21,604 [salt.loaded.ext.module.maasng:945 ][INFO    ][14197] [{u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2, u'name': u'fabric-2', u'class_type': None}, {u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ftepry', u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1, u'name': u'pxe_admin', u'class_type': u''}]
2019-05-30 02:54:21,605 [salt.loaded.ext.module.maasng:1235][WARNING ][14197] Ignoring parameter vlan:0
2019-05-30 02:54:21,691 [salt.state       :300 ][INFO    ][14197] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-05-30 02:54:21,692 [salt.state       :1951][INFO    ][14197] Completed state [192.168.11.0/24] at time 02:54:21.692246 duration_in_ms=275.44
2019-05-30 02:54:21,693 [salt.state       :1780][INFO    ][14197] Running state [maas_create_iprange_1] at time 02:54:21.693361
2019-05-30 02:54:21,694 [salt.state       :1813][INFO    ][14197] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-05-30 02:54:21,743 [salt.state       :300 ][INFO    ][14197] Iprange maas_create_iprange_1 already exist.
2019-05-30 02:54:21,744 [salt.state       :1951][INFO    ][14197] Completed state [maas_create_iprange_1] at time 02:54:21.743959 duration_in_ms=50.597
2019-05-30 02:54:21,744 [salt.state       :1780][INFO    ][14197] Running state [vlan 0] at time 02:54:21.744474
2019-05-30 02:54:21,744 [salt.state       :1813][INFO    ][14197] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-30 02:54:21,798 [salt.loaded.ext.module.maasng:945 ][INFO    ][14197] [{u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0'}, {u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'fabric-2'}, {u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'ftepry', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'pxe_admin'}]
2019-05-30 02:54:21,893 [salt.loaded.ext.module.maasng:945 ][INFO    ][14197] [{u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0'}, {u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'fabric-2'}, {u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'ftepry', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'pxe_admin'}]
2019-05-30 02:54:22,149 [salt.loaded.ext.module.maasng:945 ][INFO    ][14197] [{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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'ftepry', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-30 02:54:22,258 [salt.state       :300 ][INFO    ][14197] {'new': 'Vlan untagged was updated'}
2019-05-30 02:54:22,258 [salt.state       :1951][INFO    ][14197] Completed state [vlan 0] at time 02:54:22.258763 duration_in_ms=514.289
2019-05-30 02:54:22,259 [salt.state       :1780][INFO    ][14197] Running state [opnfv] at time 02:54:22.259513
2019-05-30 02:54:22,259 [salt.state       :1813][INFO    ][14197] Executing state maasng.sshkey_present for [opnfv]
2019-05-30 02:54:22,317 [salt.loaded.ext.module.maasng:1903][INFO    ][14197] [{u'keysource': u'', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b', u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/'}]
2019-05-30 02:54:22,317 [salt.state       :300 ][INFO    ][14197] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-05-30 02:54:22,318 [salt.state       :1951][INFO    ][14197] Completed state [opnfv] at time 02:54:22.318539 duration_in_ms=59.025
2019-05-30 02:54:22,321 [salt.minion      :1711][INFO    ][14197] Returning information for job: 20190530025359208205
2019-05-30 02:54:22,930 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command state.apply with jid 20190530025422919140
2019-05-30 02:54:22,952 [salt.minion      :1432][INFO    ][14660] Starting a new job with PID 14660
2019-05-30 02:54:30,611 [salt.state       :915 ][INFO    ][14660] Loading fresh modules for state activity
2019-05-30 02:54:30,727 [salt.state       :1780][INFO    ][14660] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:30.727024
2019-05-30 02:54:30,727 [salt.state       :1813][INFO    ][14660] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-30 02:54:30,729 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14660] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-30 02:54:32,506 [salt.state       :300 ][INFO    ][14660] {'pid': 14689, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-30 02:54:32,507 [salt.state       :1951][INFO    ][14660] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:32.506872 duration_in_ms=1779.847
2019-05-30 02:54:32,511 [salt.state       :1780][INFO    ][14660] Running state [maas.process_machines] at time 02:54:32.511499
2019-05-30 02:54:32,512 [salt.state       :1813][INFO    ][14660] Executing state module.run for [maas.process_machines]
2019-05-30 02:54:32,513 [salt.utils.decorators:613 ][WARNING ][14660] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-30 02:54:33,072 [salt.loaded.ext.module.maas:412 ][WARNING ][14660] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-30 02:54:33,073 [salt.loaded.ext.module.maas:92  ][INFO    ][14660] 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=ccdpbr architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-30 02:54:34,424 [salt.loaded.ext.module.maas:412 ][WARNING ][14660] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-30 02:54:34,425 [salt.loaded.ext.module.maas:92  ][INFO    ][14660] 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=844twf architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-30 02:54:35,624 [salt.loaded.ext.module.maas:412 ][WARNING ][14660] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-30 02:54:35,624 [salt.loaded.ext.module.maas:92  ][INFO    ][14660] 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=8c66ya architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-30 02:54:36,862 [salt.loaded.ext.module.maas:412 ][WARNING ][14660] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-30 02:54:36,863 [salt.loaded.ext.module.maas:92  ][INFO    ][14660] 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=yp8csd architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-30 02:54:38,034 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530025438017194
2019-05-30 02:54:38,058 [salt.minion      :1432][INFO    ][14861] Starting a new job with PID 14861
2019-05-30 02:54:38,082 [salt.minion      :1711][INFO    ][14861] Returning information for job: 20190530025438017194
2019-05-30 02:54:38,092 [salt.loaded.ext.module.maas:412 ][WARNING ][14660] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-30 02:54:38,093 [salt.loaded.ext.module.maas:92  ][INFO    ][14660] 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=4qah6q architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-30 02:54:39,252 [salt.state       :300 ][INFO    ][14660] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-05-30 02:54:39,252 [salt.state       :1951][INFO    ][14660] Completed state [maas.process_machines] at time 02:54:39.252793 duration_in_ms=6741.292
2019-05-30 02:54:39,257 [salt.minion      :1711][INFO    ][14660] Returning information for job: 20190530025422919140
2019-05-30 02:55:12,309 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command state.apply with jid 20190530025512295377
2019-05-30 02:55:12,337 [salt.minion      :1432][INFO    ][14935] Starting a new job with PID 14935
2019-05-30 02:55:20,490 [salt.state       :915 ][INFO    ][14935] Loading fresh modules for state activity
2019-05-30 02:55:20,583 [salt.state       :1780][INFO    ][14935] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:55:20.583757
2019-05-30 02:55:20,584 [salt.state       :1813][INFO    ][14935] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-30 02:55:20,586 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14935] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-30 02:55:22,298 [salt.state       :300 ][INFO    ][14935] {'pid': 14970, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-30 02:55:22,299 [salt.state       :1951][INFO    ][14935] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:55:22.299623 duration_in_ms=1715.865
2019-05-30 02:55:22,304 [salt.state       :1780][INFO    ][14935] Running state [maas.wait_for_machine_status] at time 02:55:22.304200
2019-05-30 02:55:22,304 [salt.state       :1813][INFO    ][14935] Executing state module.run for [maas.wait_for_machine_status]
2019-05-30 02:55:22,305 [salt.utils.decorators:613 ][WARNING ][14935] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-30 02:55:25,102 [salt.state       :300 ][INFO    ][14935] {'ret': True}
2019-05-30 02:55:25,103 [salt.state       :1951][INFO    ][14935] Completed state [maas.wait_for_machine_status] at time 02:55:25.103056 duration_in_ms=2798.856
2019-05-30 02:55:25,110 [salt.minion      :1711][INFO    ][14935] Returning information for job: 20190530025512295377
2019-05-30 02:55:25,704 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command state.apply with jid 20190530025525650512
2019-05-30 02:55:25,733 [salt.minion      :1432][INFO    ][14989] Starting a new job with PID 14989
2019-05-30 02:55:26,876 [salt.state       :915 ][INFO    ][14989] Loading fresh modules for state activity
2019-05-30 02:55:27,031 [salt.state       :1780][INFO    ][14989] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:55:27.031559
2019-05-30 02:55:27,031 [salt.state       :1813][INFO    ][14989] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-30 02:55:27,033 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14989] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-30 02:55:28,750 [salt.state       :300 ][INFO    ][14989] {'pid': 14996, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-30 02:55:28,751 [salt.state       :1951][INFO    ][14989] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:55:28.751336 duration_in_ms=1719.775
2019-05-30 02:55:28,756 [salt.state       :1780][INFO    ][14989] Running state [maas_machines_storage_cmp002_lvm] at time 02:55:28.756743
2019-05-30 02:55:28,757 [salt.state       :1813][INFO    ][14989] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-30 02:55:29,285 [salt.state       :300 ][INFO    ][14989] Machine cmp002 is not in Ready state.
2019-05-30 02:55:29,285 [salt.state       :1951][INFO    ][14989] Completed state [maas_machines_storage_cmp002_lvm] at time 02:55:29.285859 duration_in_ms=529.117
2019-05-30 02:55:29,286 [salt.state       :1780][INFO    ][14989] Running state [maas_machines_storage_cmp001_lvm] at time 02:55:29.286195
2019-05-30 02:55:29,286 [salt.state       :1813][INFO    ][14989] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-30 02:55:29,801 [salt.state       :300 ][INFO    ][14989] Machine cmp001 is not in Ready state.
2019-05-30 02:55:29,802 [salt.state       :1951][INFO    ][14989] Completed state [maas_machines_storage_cmp001_lvm] at time 02:55:29.802415 duration_in_ms=516.219
2019-05-30 02:55:29,807 [salt.minion      :1711][INFO    ][14989] Returning information for job: 20190530025525650512
2019-05-30 02:55:30,369 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command state.apply with jid 20190530025530354493
2019-05-30 02:55:30,397 [salt.minion      :1432][INFO    ][15006] Starting a new job with PID 15006
2019-05-30 02:55:31,517 [salt.state       :915 ][INFO    ][15006] Loading fresh modules for state activity
2019-05-30 02:55:31,618 [salt.state       :1780][INFO    ][15006] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:55:31.617940
2019-05-30 02:55:31,618 [salt.state       :1813][INFO    ][15006] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-30 02:55:31,620 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15006] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-30 02:55:33,369 [salt.state       :300 ][INFO    ][15006] {'pid': 15013, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-30 02:55:33,371 [salt.state       :1951][INFO    ][15006] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:55:33.371007 duration_in_ms=1753.066
2019-05-30 02:55:33,375 [salt.state       :1780][INFO    ][15006] Running state [maas.deploy_machines] at time 02:55:33.375788
2019-05-30 02:55:33,376 [salt.state       :1813][INFO    ][15006] Executing state module.run for [maas.deploy_machines]
2019-05-30 02:55:33,377 [salt.utils.decorators:613 ][WARNING ][15006] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-30 02:55:33,946 [salt.loaded.ext.module.maas:684 ][INFO    ][15006] deploymachines hwe_kernel=ga-16.04 system_id=4qah6q distro_series=xenial
2019-05-30 02:55:36,453 [salt.state       :300 ][INFO    ][15006] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-05-30 02:55:36,455 [salt.state       :1951][INFO    ][15006] Completed state [maas.deploy_machines] at time 02:55:36.455060 duration_in_ms=3079.271
2019-05-30 02:55:36,462 [salt.minion      :1711][INFO    ][15006] Returning information for job: 20190530025530354493
2019-05-30 02:55:37,028 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command state.apply with jid 20190530025537015092
2019-05-30 02:55:37,055 [salt.minion      :1432][INFO    ][15072] Starting a new job with PID 15072
2019-05-30 02:55:44,920 [salt.state       :915 ][INFO    ][15072] Loading fresh modules for state activity
2019-05-30 02:55:45,017 [salt.state       :1780][INFO    ][15072] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:55:45.016991
2019-05-30 02:55:45,017 [salt.state       :1813][INFO    ][15072] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-30 02:55:45,019 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15072] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-30 02:55:46,773 [salt.state       :300 ][INFO    ][15072] {'pid': 15089, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-30 02:55:46,774 [salt.state       :1951][INFO    ][15072] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:55:46.774784 duration_in_ms=1757.792
2019-05-30 02:55:46,778 [salt.state       :1780][INFO    ][15072] Running state [maas.wait_for_machine_status] at time 02:55:46.778327
2019-05-30 02:55:46,779 [salt.state       :1813][INFO    ][15072] Executing state module.run for [maas.wait_for_machine_status]
2019-05-30 02:55:46,780 [salt.utils.decorators:613 ][WARNING ][15072] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-30 02:55:49,470 [salt.loaded.ext.module.maas:1023][INFO    ][15072] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.33066797s left)
2019-05-30 02:55:52,117 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530025552102033
2019-05-30 02:55:52,147 [salt.minion      :1432][INFO    ][15100] Starting a new job with PID 15100
2019-05-30 02:55:52,173 [salt.minion      :1711][INFO    ][15100] Returning information for job: 20190530025552102033
2019-05-30 02:56:22,200 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530025622193035
2019-05-30 02:56:22,218 [salt.minion      :1432][INFO    ][15160] Starting a new job with PID 15160
2019-05-30 02:56:22,240 [salt.minion      :1711][INFO    ][15160] Returning information for job: 20190530025622193035
2019-05-30 02:56:22,605 [salt.loaded.ext.module.maas:1023][INFO    ][15072] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.19591713s left)
2019-05-30 02:56:52,285 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530025652268946
2019-05-30 02:56:52,324 [salt.minion      :1432][INFO    ][15175] Starting a new job with PID 15175
2019-05-30 02:56:52,355 [salt.minion      :1711][INFO    ][15175] Returning information for job: 20190530025652268946
2019-05-30 02:56:55,381 [salt.loaded.ext.module.maas:1023][INFO    ][15072] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2181.41981816s left)
2019-05-30 02:57:22,392 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530025722376333
2019-05-30 02:57:22,426 [salt.minion      :1432][INFO    ][15255] Starting a new job with PID 15255
2019-05-30 02:57:22,455 [salt.minion      :1711][INFO    ][15255] Returning information for job: 20190530025722376333
2019-05-30 02:57:28,050 [salt.loaded.ext.module.maas:1023][INFO    ][15072] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2148.75024295s left)
2019-05-30 02:57:52,495 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530025752477658
2019-05-30 02:57:52,524 [salt.minion      :1432][INFO    ][15277] Starting a new job with PID 15277
2019-05-30 02:57:52,548 [salt.minion      :1711][INFO    ][15277] Returning information for job: 20190530025752477658
2019-05-30 02:58:00,825 [salt.loaded.ext.module.maas:1023][INFO    ][15072] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2115.97553706s left)
2019-05-30 02:58:22,588 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530025822567656
2019-05-30 02:58:22,619 [salt.minion      :1432][INFO    ][15337] Starting a new job with PID 15337
2019-05-30 02:58:22,644 [salt.minion      :1711][INFO    ][15337] Returning information for job: 20190530025822567656
2019-05-30 02:58:33,786 [salt.loaded.ext.module.maas:1023][INFO    ][15072] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2083.01499104s left)
2019-05-30 02:58:52,672 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530025852657513
2019-05-30 02:58:52,703 [salt.minion      :1432][INFO    ][15361] Starting a new job with PID 15361
2019-05-30 02:58:52,732 [salt.minion      :1711][INFO    ][15361] Returning information for job: 20190530025852657513
2019-05-30 02:59:06,620 [salt.loaded.ext.module.maas:1023][INFO    ][15072] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2050.18089104s left)
2019-05-30 02:59:22,785 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530025922767542
2019-05-30 02:59:22,817 [salt.minion      :1432][INFO    ][15421] Starting a new job with PID 15421
2019-05-30 02:59:22,844 [salt.minion      :1711][INFO    ][15421] Returning information for job: 20190530025922767542
2019-05-30 02:59:39,315 [salt.loaded.ext.module.maas:1023][INFO    ][15072] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2017.48564816s left)
2019-05-30 02:59:52,864 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530025952853221
2019-05-30 02:59:52,884 [salt.minion      :1432][INFO    ][15462] Starting a new job with PID 15462
2019-05-30 02:59:52,915 [salt.minion      :1711][INFO    ][15462] Returning information for job: 20190530025952853221
2019-05-30 03:00:12,647 [salt.loaded.ext.module.maas:1023][INFO    ][15072] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1984.15310597s left)
2019-05-30 03:00:22,969 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530030022952744
2019-05-30 03:00:23,000 [salt.minion      :1432][INFO    ][15568] Starting a new job with PID 15568
2019-05-30 03:00:23,025 [salt.minion      :1711][INFO    ][15568] Returning information for job: 20190530030022952744
2019-05-30 03:00:45,568 [salt.loaded.ext.module.maas:1023][INFO    ][15072] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1951.23209906s left)
2019-05-30 03:00:53,077 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530030053066299
2019-05-30 03:00:53,104 [salt.minion      :1432][INFO    ][15593] Starting a new job with PID 15593
2019-05-30 03:00:53,136 [salt.minion      :1711][INFO    ][15593] Returning information for job: 20190530030053066299
2019-05-30 03:01:18,551 [salt.loaded.ext.module.maas:1023][INFO    ][15072] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1918.24949813s left)
2019-05-30 03:01:23,239 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530030123221669
2019-05-30 03:01:23,273 [salt.minion      :1432][INFO    ][15793] Starting a new job with PID 15793
2019-05-30 03:01:23,300 [salt.minion      :1711][INFO    ][15793] Returning information for job: 20190530030123221669
2019-05-30 03:01:51,388 [salt.loaded.ext.module.maas:1023][INFO    ][15072] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1885.41296411s left)
2019-05-30 03:01:53,357 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530030153347703
2019-05-30 03:01:53,375 [salt.minion      :1432][INFO    ][15812] Starting a new job with PID 15812
2019-05-30 03:01:53,397 [salt.minion      :1711][INFO    ][15812] Returning information for job: 20190530030153347703
2019-05-30 03:02:23,466 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530030223452597
2019-05-30 03:02:23,492 [salt.minion      :1432][INFO    ][15892] Starting a new job with PID 15892
2019-05-30 03:02:23,517 [salt.minion      :1711][INFO    ][15892] Returning information for job: 20190530030223452597
2019-05-30 03:02:24,433 [salt.loaded.ext.module.maas:1023][INFO    ][15072] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1852.36769795s left)
2019-05-30 03:02:53,614 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530030253598543
2019-05-30 03:02:53,644 [salt.minion      :1432][INFO    ][15916] Starting a new job with PID 15916
2019-05-30 03:02:53,668 [salt.minion      :1711][INFO    ][15916] Returning information for job: 20190530030253598543
2019-05-30 03:02:57,521 [salt.loaded.ext.module.maas:1023][INFO    ][15072] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1819.28000307s left)
2019-05-30 03:03:23,767 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530030323751264
2019-05-30 03:03:23,799 [salt.minion      :1432][INFO    ][16037] Starting a new job with PID 16037
2019-05-30 03:03:23,828 [salt.minion      :1711][INFO    ][16037] Returning information for job: 20190530030323751264
2019-05-30 03:03:30,477 [salt.loaded.ext.module.maas:1023][INFO    ][15072] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1786.32390213s left)
2019-05-30 03:03:53,925 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530030353909849
2019-05-30 03:03:53,956 [salt.minion      :1432][INFO    ][16063] Starting a new job with PID 16063
2019-05-30 03:03:53,982 [salt.minion      :1711][INFO    ][16063] Returning information for job: 20190530030353909849
2019-05-30 03:04:03,252 [salt.loaded.ext.module.maas:1023][INFO    ][15072] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1753.54818296s left)
2019-05-30 03:04:24,096 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530030424087011
2019-05-30 03:04:24,125 [salt.minion      :1432][INFO    ][16211] Starting a new job with PID 16211
2019-05-30 03:04:24,151 [salt.minion      :1711][INFO    ][16211] Returning information for job: 20190530030424087011
2019-05-30 03:04:36,077 [salt.loaded.ext.module.maas:1023][INFO    ][15072] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1720.72390199s left)
2019-05-30 03:04:54,272 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530030454259739
2019-05-30 03:04:54,306 [salt.minion      :1432][INFO    ][16233] Starting a new job with PID 16233
2019-05-30 03:04:54,331 [salt.minion      :1711][INFO    ][16233] Returning information for job: 20190530030454259739
2019-05-30 03:05:08,888 [salt.loaded.ext.module.maas:1023][INFO    ][15072] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1687.91248012s left)
2019-05-30 03:05:24,433 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530030524418138
2019-05-30 03:05:24,463 [salt.minion      :1432][INFO    ][16291] Starting a new job with PID 16291
2019-05-30 03:05:24,497 [salt.minion      :1711][INFO    ][16291] Returning information for job: 20190530030524418138
2019-05-30 03:05:41,886 [salt.loaded.ext.module.maas:1023][INFO    ][15072] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1654.91508698s left)
2019-05-30 03:05:54,601 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530030554592338
2019-05-30 03:05:54,623 [salt.minion      :1432][INFO    ][16311] Starting a new job with PID 16311
2019-05-30 03:05:54,648 [salt.minion      :1711][INFO    ][16311] Returning information for job: 20190530030554592338
2019-05-30 03:06:14,996 [salt.loaded.ext.module.maas:1023][INFO    ][15072] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1621.80422497s left)
2019-05-30 03:06:24,780 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530030624766666
2019-05-30 03:06:24,810 [salt.minion      :1432][INFO    ][16368] Starting a new job with PID 16368
2019-05-30 03:06:24,835 [salt.minion      :1711][INFO    ][16368] Returning information for job: 20190530030624766666
2019-05-30 03:06:47,872 [salt.loaded.ext.module.maas:1023][INFO    ][15072] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1588.928303s left)
2019-05-30 03:06:54,965 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530030654947000
2019-05-30 03:06:54,992 [salt.minion      :1432][INFO    ][16390] Starting a new job with PID 16390
2019-05-30 03:06:55,018 [salt.minion      :1711][INFO    ][16390] Returning information for job: 20190530030654947000
2019-05-30 03:07:21,168 [salt.loaded.ext.module.maas:1023][INFO    ][15072] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1555.63295412s left)
2019-05-30 03:07:25,157 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530030725146664
2019-05-30 03:07:25,182 [salt.minion      :1432][INFO    ][16619] Starting a new job with PID 16619
2019-05-30 03:07:25,207 [salt.minion      :1711][INFO    ][16619] Returning information for job: 20190530030725146664
2019-05-30 03:07:53,993 [salt.loaded.ext.module.maas:1023][INFO    ][15072] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1522.80793095s left)
2019-05-30 03:07:55,355 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530030755343980
2019-05-30 03:07:55,384 [salt.minion      :1432][INFO    ][16674] Starting a new job with PID 16674
2019-05-30 03:07:55,408 [salt.minion      :1711][INFO    ][16674] Returning information for job: 20190530030755343980
2019-05-30 03:08:25,472 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530030825463552
2019-05-30 03:08:25,500 [salt.minion      :1432][INFO    ][16737] Starting a new job with PID 16737
2019-05-30 03:08:25,530 [salt.minion      :1711][INFO    ][16737] Returning information for job: 20190530030825463552
2019-05-30 03:08:26,961 [salt.state       :300 ][INFO    ][15072] {'ret': True}
2019-05-30 03:08:26,962 [salt.state       :1951][INFO    ][15072] Completed state [maas.wait_for_machine_status] at time 03:08:26.962329 duration_in_ms=760183.999
2019-05-30 03:08:26,969 [salt.minion      :1711][INFO    ][15072] Returning information for job: 20190530025537015092
2019-05-30 03:08:32,137 [salt.utils.schedule:1377][INFO    ][6288] Running scheduled job: __mine_interval
2019-05-30 04:08:32,137 [salt.utils.schedule:1377][INFO    ][6288] Running scheduled job: __mine_interval
2019-05-30 04:44:40,269 [salt.minion      :1308][INFO    ][6288] User sudo_ubuntu Executing command cp.push_dir with jid 20190530044440252446
2019-05-30 04:44:40,294 [salt.minion      :1432][INFO    ][23343] Starting a new job with PID 23343
