2019-04-06 02:41:05,616 [salt.utils.decorators:613 ][WARNING ][2158] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-06 02:41:06,492 [salt.utils.decorators:613 ][WARNING ][2158] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-06 02:41:09,512 [salt.loaded.int.states.file:2298][WARNING ][2464] 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-04-06 02:41:36,369 [salt.state       :2022][WARNING ][2929] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-04-06 02:41:39,432 [salt.utils.decorators:613 ][WARNING ][2929] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-06 02:41:53,222 [salt.utils.decorators:613 ][WARNING ][2929] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-06 02:42:19,979 [salt.utils.decorators:613 ][WARNING ][2929] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-06 02:42:20,976 [salt.utils.decorators:613 ][WARNING ][2929] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-06 02:42:22,870 [salt.loaded.ext.module.maasng:1008][WARNING ][2929] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-04-06 02:42:22,870 [salt.loaded.ext.module.maasng:1011][WARNING ][2929] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-04-06 02:42:23,586 [salt.loaded.ext.module.maasng:1235][WARNING ][2929] Ignoring parameter vlan:0
2019-04-06 02:42:33,368 [salt.utils.decorators:613 ][WARNING ][5346] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-06 02:42:33,416 [salt.loaded.ext.module.maas:412 ][WARNING ][5346] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-06 02:42:34,740 [salt.loaded.ext.module.maas:412 ][WARNING ][5346] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-06 02:42:35,892 [salt.loaded.ext.module.maas:412 ][WARNING ][5346] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-06 02:42:36,955 [salt.loaded.ext.module.maas:412 ][WARNING ][5346] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-06 02:42:38,027 [salt.loaded.ext.module.maas:412 ][WARNING ][5346] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-06 02:42:42,923 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5967] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-04-06 02:42:42,955 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5967] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-04-06 02:42:42,994 [salt.utils.parsers:1051][WARNING ][383] Minion received a SIGTERM. Exiting.
2019-04-06 02:42:44,012 [salt.cli.daemons :293 ][INFO    ][6026] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-04-06 02:42:44,130 [salt.cli.daemons :82  ][INFO    ][6026] Starting up the Salt Minion
2019-04-06 02:42:44,131 [salt.utils.event :1017][INFO    ][6026] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-04-06 02:42:45,275 [salt.minion      :976 ][INFO    ][6026] Creating minion process manager
2019-04-06 02:42:47,132 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][6026] Executing command ['date', '+%z'] in directory '/root'
2019-04-06 02:42:47,158 [salt.utils.schedule:568 ][INFO    ][6026] Updating job settings for scheduled job: __mine_interval
2019-04-06 02:42:47,160 [salt.minion      :1108][INFO    ][6026] Added mine.update to scheduler
2019-04-06 02:42:47,167 [salt.minion      :1975][INFO    ][6026] Minion is starting as user 'root'
2019-04-06 02:42:47,182 [salt.minion      :2336][INFO    ][6026] Minion is ready to receive requests!
2019-04-06 02:43:10,290 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command state.apply with jid 20190406024310280383
2019-04-06 02:43:10,308 [salt.minion      :1432][INFO    ][6126] Starting a new job with PID 6126
2019-04-06 02:43:16,291 [salt.state       :915 ][INFO    ][6126] Loading fresh modules for state activity
2019-04-06 02:43:16,349 [salt.fileclient  :1219][INFO    ][6126] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-04-06 02:43:16,390 [salt.state       :1780][INFO    ][6126] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:43:16.390796
2019-04-06 02:43:16,391 [salt.state       :1813][INFO    ][6126] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-06 02:43:16,392 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6126] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-06 02:43:18,098 [salt.state       :300 ][INFO    ][6126] {'pid': 6135, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-06 02:43:18,098 [salt.state       :1951][INFO    ][6126] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:43:18.098691 duration_in_ms=1707.896
2019-04-06 02:43:18,100 [salt.state       :1780][INFO    ][6126] Running state [maas.wait_for_machine_status] at time 02:43:18.100398
2019-04-06 02:43:18,100 [salt.state       :1813][INFO    ][6126] Executing state module.run for [maas.wait_for_machine_status]
2019-04-06 02:43:18,101 [salt.utils.decorators:613 ][WARNING ][6126] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-06 02:43:18,833 [salt.loaded.ext.module.maas:1023][INFO    ][6126] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.27460408s left)
2019-04-06 02:43:25,352 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406024325333713
2019-04-06 02:43:25,377 [salt.minion      :1432][INFO    ][6146] Starting a new job with PID 6146
2019-04-06 02:43:25,398 [salt.minion      :1711][INFO    ][6146] Returning information for job: 20190406024325333713
2019-04-06 02:43:49,628 [salt.loaded.ext.module.maas:1023][INFO    ][6126] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.47929692s left)
2019-04-06 02:43:55,546 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406024355538511
2019-04-06 02:43:55,567 [salt.minion      :1432][INFO    ][6219] Starting a new job with PID 6219
2019-04-06 02:43:55,588 [salt.minion      :1711][INFO    ][6219] Returning information for job: 20190406024355538511
2019-04-06 02:44:20,526 [salt.loaded.ext.module.maas:1023][INFO    ][6126] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.58190703s left)
2019-04-06 02:44:25,734 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406024425724238
2019-04-06 02:44:25,757 [salt.minion      :1432][INFO    ][6238] Starting a new job with PID 6238
2019-04-06 02:44:25,774 [salt.minion      :1711][INFO    ][6238] Returning information for job: 20190406024425724238
2019-04-06 02:44:51,338 [salt.loaded.ext.module.maas:1023][INFO    ][6126] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.76977992s left)
2019-04-06 02:44:55,924 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406024455915674
2019-04-06 02:44:55,945 [salt.minion      :1432][INFO    ][6317] Starting a new job with PID 6317
2019-04-06 02:44:55,969 [salt.minion      :1711][INFO    ][6317] Returning information for job: 20190406024455915674
2019-04-06 02:45:22,320 [salt.loaded.ext.module.maas:1023][INFO    ][6126] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.78766894s left)
2019-04-06 02:45:26,121 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406024526114513
2019-04-06 02:45:26,145 [salt.minion      :1432][INFO    ][6362] Starting a new job with PID 6362
2019-04-06 02:45:26,167 [salt.minion      :1711][INFO    ][6362] Returning information for job: 20190406024526114513
2019-04-06 02:45:53,254 [salt.loaded.ext.module.maas:1023][INFO    ][6126] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.85350013s left)
2019-04-06 02:45:56,202 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406024556188104
2019-04-06 02:45:56,220 [salt.minion      :1432][INFO    ][6573] Starting a new job with PID 6573
2019-04-06 02:45:56,245 [salt.minion      :1711][INFO    ][6573] Returning information for job: 20190406024556188104
2019-04-06 02:46:24,235 [salt.loaded.ext.module.maas:1023][INFO    ][6126] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1313.87287307s left)
2019-04-06 02:46:26,288 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406024626275908
2019-04-06 02:46:26,309 [salt.minion      :1432][INFO    ][6630] Starting a new job with PID 6630
2019-04-06 02:46:26,334 [salt.minion      :1711][INFO    ][6630] Returning information for job: 20190406024626275908
2019-04-06 02:46:55,405 [salt.loaded.ext.module.maas:1023][INFO    ][6126] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1282.70306706s left)
2019-04-06 02:46:56,378 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406024656364237
2019-04-06 02:46:56,398 [salt.minion      :1432][INFO    ][6996] Starting a new job with PID 6996
2019-04-06 02:46:56,420 [salt.minion      :1711][INFO    ][6996] Returning information for job: 20190406024656364237
2019-04-06 02:47:26,575 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406024726562252
2019-04-06 02:47:26,593 [salt.minion      :1432][INFO    ][7080] Starting a new job with PID 7080
2019-04-06 02:47:26,626 [salt.minion      :1711][INFO    ][7080] Returning information for job: 20190406024726562252
2019-04-06 02:47:26,864 [salt.loaded.ext.module.maas:1023][INFO    ][6126] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1251.24405909s left)
2019-04-06 02:47:56,645 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406024756635650
2019-04-06 02:47:56,662 [salt.minion      :1432][INFO    ][7516] Starting a new job with PID 7516
2019-04-06 02:47:56,688 [salt.minion      :1711][INFO    ][7516] Returning information for job: 20190406024756635650
2019-04-06 02:47:59,017 [salt.loaded.ext.module.maas:1023][INFO    ][6126] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1219.09112096s left)
2019-04-06 02:48:26,660 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406024826650040
2019-04-06 02:48:26,684 [salt.minion      :1432][INFO    ][7633] Starting a new job with PID 7633
2019-04-06 02:48:26,713 [salt.minion      :1711][INFO    ][7633] Returning information for job: 20190406024826650040
2019-04-06 02:48:31,204 [salt.loaded.ext.module.maas:1023][INFO    ][6126] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1186.90337014s left)
2019-04-06 02:48:56,742 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406024856732328
2019-04-06 02:48:56,760 [salt.minion      :1432][INFO    ][7870] Starting a new job with PID 7870
2019-04-06 02:48:56,786 [salt.minion      :1711][INFO    ][7870] Returning information for job: 20190406024856732328
2019-04-06 02:49:04,056 [salt.loaded.ext.module.maas:1023][INFO    ][6126] Waiting status:Ready|Deployed for machines:['kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1154.05181909s left)
2019-04-06 02:49:26,756 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406024926745125
2019-04-06 02:49:26,780 [salt.minion      :1432][INFO    ][8011] Starting a new job with PID 8011
2019-04-06 02:49:26,809 [salt.minion      :1711][INFO    ][8011] Returning information for job: 20190406024926745125
2019-04-06 02:49:36,857 [salt.state       :300 ][INFO    ][6126] {'ret': True}
2019-04-06 02:49:36,857 [salt.state       :1951][INFO    ][6126] Completed state [maas.wait_for_machine_status] at time 02:49:36.857651 duration_in_ms=378757.251
2019-04-06 02:49:36,861 [salt.minion      :1711][INFO    ][6126] Returning information for job: 20190406024310280383
2019-04-06 02:49:37,668 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command state.apply with jid 20190406024937654821
2019-04-06 02:49:37,689 [salt.minion      :1432][INFO    ][8033] Starting a new job with PID 8033
2019-04-06 02:49:44,068 [salt.state       :915 ][INFO    ][8033] Loading fresh modules for state activity
2019-04-06 02:49:44,141 [salt.fileclient  :1219][INFO    ][8033] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-04-06 02:49:44,237 [salt.state       :1780][INFO    ][8033] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:49:44.237004
2019-04-06 02:49:44,237 [salt.state       :1813][INFO    ][8033] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-06 02:49:44,241 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8033] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-06 02:49:46,360 [salt.state       :300 ][INFO    ][8033] {'pid': 8130, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-06 02:49:46,360 [salt.state       :1951][INFO    ][8033] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:49:46.360605 duration_in_ms=2123.602
2019-04-06 02:49:46,363 [salt.state       :1780][INFO    ][8033] Running state [maas_machines_storage_cmp002_lvm] at time 02:49:46.362804
2019-04-06 02:49:46,363 [salt.state       :1813][INFO    ][8033] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-04-06 02:49:47,478 [salt.loaded.ext.module.maasng:610 ][INFO    ][8033] ra76ks
2019-04-06 02:49:47,478 [salt.loaded.ext.module.maasng:626 ][INFO    ][8033] sda
2019-04-06 02:49:48,053 [salt.loaded.ext.module.maasng:361 ][INFO    ][8033] ra76ks
2019-04-06 02:49:48,139 [salt.loaded.ext.module.maasng:367 ][INFO    ][8033] [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/ra76ks/blockdevices/1/', u'system_id': u'ra76ks', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'dd7f74a3-01a6-4178-9ab9-299158e75e05', u'resource_uri': u'/MAAS/api/2.0/nodes/ra76ks/blockdevices/1/partition/1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ra76ks', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'bdfb5e88-47f0-40aa-939e-bc0c4180e7ad', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 1, u'device_id': 1}], u'type': u'physical', u'id': 1, u'size': 800109715456}, {u'model': None, u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'uuid': u'b79af879-cfcb-4a2e-9c25-9e082b144417', u'used_for': u'ext4 formatted filesystem mounted at /', u'resource_uri': u'/MAAS/api/2.0/nodes/ra76ks/blockdevices/3/', u'system_id': u'ra76ks', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'5e18c8ed-d2a8-4f89-b4e1-f769f7d7944b', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'partitions': [], u'type': u'virtual', u'id': 3, u'size': 800097042432}]
2019-04-06 02:49:48,140 [salt.loaded.ext.module.maasng:632 ][INFO    ][8033] vgroot
2019-04-06 02:49:48,140 [salt.loaded.ext.module.maasng:635 ][INFO    ][8033] lvroot
2019-04-06 02:49:48,141 [salt.loaded.ext.module.maasng:639 ][INFO    ][8033] 107374182400
2019-04-06 02:49:48,724 [salt.loaded.ext.module.maasng:645 ][INFO    ][8033] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'testing_status_name': u'Passed', u'memory_test_status': -1, u'disable_ipv4': False, u'cpu_count': 40, u'power_type': u'ipmi', u'hwe_kernel': u'', u'boot_interface': {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'primary_rack': u'erk6qb', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'name': u'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'primary_rack': u'erk6qb', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'id': 18, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:10:18', u'enabled': True, u'effective_mtu': 1500, 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'primary_rack': u'erk6qb', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'system_id': u'ra76ks', u'params': u'', u'parents': [], u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/ra76ks/interfaces/4/'}, u'fqdn': u'cmp002.maas', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 800109715456, u'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/ra76ks/blockdevices/1/', u'type': u'physical', u'tags': [u'ssd'], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'ra76ks', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'size': 800101236736, u'uuid': u'd72d1a3e-4654-44a4-af23-bb092f6202e2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ra76ks', u'filesystem': {u'label': None, u'uuid': u'2f044e28-4bcd-460d-aa01-fda226d1f93e', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/ra76ks/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'device_id': 1}]}, u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'ra76ks', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'blockdevice_set': [{u'block_size': 4096, u'type': u'physical', u'name': u'sda', u'tags': [u'ssd'], u'resource_uri': u'/MAAS/api/2.0/nodes/ra76ks/blockdevices/1/', u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'ra76ks', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'size': 800109715456, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'size': 800101236736, u'uuid': u'd72d1a3e-4654-44a4-af23-bb092f6202e2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ra76ks', u'filesystem': {u'label': None, u'uuid': u'2f044e28-4bcd-460d-aa01-fda226d1f93e', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/ra76ks/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'device_id': 1}]}, {u'block_size': 4096, u'type': u'virtual', u'name': u'vgroot-lvroot', u'tags': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ra76ks/blockdevices/11/', u'filesystem': {u'label': u'root', u'uuid': u'9d1df2ee-4011-4722-84b9-25ad9fcb9905', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'uuid': u'59b36d64-c767-4030-81bf-88f7fb6b9575', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'ra76ks', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'size': 107374182400, u'used_size': 107374182400, u'id': 11, u'serial': None, u'partitions': []}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'physicalblockdevice_set': [{u'size': 800109715456, u'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/ra76ks/blockdevices/1/', u'type': u'physical', u'tags': [u'ssd'], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'ra76ks', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'size': 800101236736, u'uuid': u'd72d1a3e-4654-44a4-af23-bb092f6202e2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ra76ks', u'filesystem': {u'label': None, u'uuid': u'2f044e28-4bcd-460d-aa01-fda226d1f93e', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/ra76ks/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'device_id': 1}]}], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'ra76ks', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'memory_test_status_name': u'Unknown', u'interface_set': [{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'primary_rack': u'erk6qb', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'name': u'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'primary_rack': u'erk6qb', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'id': 18, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:10:18', u'enabled': True, u'effective_mtu': 1500, 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'primary_rack': u'erk6qb', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'system_id': u'ra76ks', u'params': u'', u'parents': [], u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/ra76ks/interfaces/4/'}, {u'vlan': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:07:50', u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'system_id': u'ra76ks', u'params': u'', u'parents': [], u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/ra76ks/interfaces/13/'}, {u'vlan': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:07:51', u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'system_id': u'ra76ks', u'params': u'', u'parents': [], u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/ra76ks/interfaces/15/'}, {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'primary_rack': u'erk6qb', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'name': u'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'primary_rack': u'erk6qb', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 19, u'mode': u'link_up'}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:10:1c', u'enabled': True, u'effective_mtu': 1500, 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'primary_rack': u'erk6qb', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.40'}], u'system_id': u'ra76ks', u'params': u'', u'parents': [], u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/ra76ks/interfaces/14/'}, {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'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f1', u'links': [{u'id': 20, u'mode': u'link_up'}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:12:49', u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'system_id': u'ra76ks', u'params': u'', u'parents': [], u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/ra76ks/interfaces/11/'}, {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'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f0', u'links': [{u'id': 21, u'mode': u'link_up'}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:12:48', u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'system_id': u'ra76ks', u'params': u'', u'parents': [], u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/ra76ks/interfaces/12/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'storage_test_status': 2, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'node_type': 0, u'virtualblockdevice_set': [{u'size': 107374182400, u'uuid': u'59b36d64-c767-4030-81bf-88f7fb6b9575', u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/ra76ks/blockdevices/11/', u'type': u'virtual', u'tags': [], u'filesystem': {u'label': u'root', u'uuid': u'9d1df2ee-4011-4722-84b9-25ad9fcb9905', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'ra76ks', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 11, u'serial': None, u'partitions': []}], u'commissioning_status': 2, u'min_hwe_kernel': u'ga-16.04', u'commissioning_status_name': u'Passed', u'current_commissioning_result_id': 2, u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/ra76ks/'}
2019-04-06 02:49:48,728 [salt.state       :300 ][INFO    ][8033] {'new': {'storage_layout': 'lvm'}}
2019-04-06 02:49:48,728 [salt.state       :1951][INFO    ][8033] Completed state [maas_machines_storage_cmp002_lvm] at time 02:49:48.728245 duration_in_ms=2365.44
2019-04-06 02:49:48,728 [salt.state       :1780][INFO    ][8033] Running state [maas_machines_storage_cmp001_lvm] at time 02:49:48.728666
2019-04-06 02:49:48,729 [salt.state       :1813][INFO    ][8033] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-04-06 02:49:49,979 [salt.loaded.ext.module.maasng:610 ][INFO    ][8033] nhtt8f
2019-04-06 02:49:49,979 [salt.loaded.ext.module.maasng:626 ][INFO    ][8033] sda
2019-04-06 02:49:50,533 [salt.loaded.ext.module.maasng:361 ][INFO    ][8033] nhtt8f
2019-04-06 02:49:50,605 [salt.loaded.ext.module.maasng:367 ][INFO    ][8033] [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/nhtt8f/blockdevices/2/', u'system_id': u'nhtt8f', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'68d0d679-2abd-4313-ad77-df1aa08fb058', u'resource_uri': u'/MAAS/api/2.0/nodes/nhtt8f/blockdevices/2/partition/2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'nhtt8f', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'6e3bd484-0935-491c-9087-3f0055d2a10e', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 2, u'device_id': 2}], u'type': u'physical', u'id': 2, u'size': 800109715456}, {u'model': None, u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'uuid': u'aabd0b34-5e12-43f4-950c-de4978bdbb2b', u'used_for': u'ext4 formatted filesystem mounted at /', u'resource_uri': u'/MAAS/api/2.0/nodes/nhtt8f/blockdevices/4/', u'system_id': u'nhtt8f', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'81dacd78-7187-443a-ad7d-b6da7930493f', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'partitions': [], u'type': u'virtual', u'id': 4, u'size': 800097042432}]
2019-04-06 02:49:50,606 [salt.loaded.ext.module.maasng:632 ][INFO    ][8033] vgroot
2019-04-06 02:49:50,606 [salt.loaded.ext.module.maasng:635 ][INFO    ][8033] lvroot
2019-04-06 02:49:50,606 [salt.loaded.ext.module.maasng:639 ][INFO    ][8033] 107374182400
2019-04-06 02:49:51,184 [salt.loaded.ext.module.maasng:645 ][INFO    ][8033] {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'swap_size': None, 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'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 800109715456, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'f6c3d8a6-9616-4d79-81f3-0379ead2f8d2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'nhtt8f', u'filesystem': {u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'uuid': u'2b99c0b0-97d7-4f12-9de7-0c4b4aa8bc6c'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/nhtt8f/blockdevices/2/partition/7'}], u'filesystem': None, u'name': u'sda', u'system_id': u'nhtt8f', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/nhtt8f/blockdevices/2/'}, u'fqdn': u'cmp001.maas', u'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default'}, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'nhtt8f', 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'physicalblockdevice_set': [{u'size': 800109715456, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'f6c3d8a6-9616-4d79-81f3-0379ead2f8d2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'nhtt8f', u'filesystem': {u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'uuid': u'2b99c0b0-97d7-4f12-9de7-0c4b4aa8bc6c'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/nhtt8f/blockdevices/2/partition/7'}], u'filesystem': None, u'name': u'sda', u'system_id': u'nhtt8f', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/nhtt8f/blockdevices/2/'}], u'blockdevice_set': [{u'size': 800109715456, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'f6c3d8a6-9616-4d79-81f3-0379ead2f8d2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'nhtt8f', u'filesystem': {u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'uuid': u'2b99c0b0-97d7-4f12-9de7-0c4b4aa8bc6c'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/nhtt8f/blockdevices/2/partition/7'}], u'filesystem': None, u'name': u'sda', u'system_id': u'nhtt8f', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/nhtt8f/blockdevices/2/'}, {u'size': 107374182400, u'uuid': u'378f9e38-3c00-47c8-aa4c-f3b4b28542af', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'uuid': u'eb8a9db3-4167-493b-85b6-13bb2aec3ce7'}, u'name': u'vgroot-lvroot', u'system_id': u'nhtt8f', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 12, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/nhtt8f/blockdevices/12/'}], u'status': 4, u'bcaches': [], u'cpu_count': 40, u'power_state': u'off', u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'nhtt8f', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'commissioning_status_name': u'Passed', u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'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'erk6qb', 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'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'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'erk6qb', 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'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'erk6qb', 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'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'nhtt8f', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/nhtt8f/interfaces/5/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'id': 18, u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d4', u'system_id': u'nhtt8f', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/nhtt8f/interfaces/18/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'id': 19, u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d5', u'system_id': u'nhtt8f', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/nhtt8f/interfaces/19/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'erk6qb', 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'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': 25, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'erk6qb', 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'enabled': True, u'id': 16, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'erk6qb', 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'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'nhtt8f', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/nhtt8f/interfaces/16/'}, {u'name': u'ens2f1', u'links': [{u'id': 26, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'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'enabled': True, u'id': 17, u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cd', u'system_id': u'nhtt8f', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/nhtt8f/interfaces/17/'}, {u'name': u'ens2f0', u'links': [{u'id': 27, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'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'enabled': True, u'id': 20, u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cc', u'system_id': u'nhtt8f', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/nhtt8f/interfaces/20/'}], 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'node_type': 0, u'virtualblockdevice_set': [{u'size': 107374182400, u'uuid': u'378f9e38-3c00-47c8-aa4c-f3b4b28542af', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'uuid': u'eb8a9db3-4167-493b-85b6-13bb2aec3ce7'}, u'name': u'vgroot-lvroot', u'system_id': u'nhtt8f', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 12, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/nhtt8f/blockdevices/12/'}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'erk6qb', 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'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'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'erk6qb', 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'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'erk6qb', 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'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'nhtt8f', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/nhtt8f/interfaces/5/'}, u'ip_addresses': [u'192.168.11.39', u'192.168.11.44'], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/nhtt8f/'}
2019-04-06 02:49:51,187 [salt.state       :300 ][INFO    ][8033] {'new': {'storage_layout': 'lvm'}}
2019-04-06 02:49:51,188 [salt.state       :1951][INFO    ][8033] Completed state [maas_machines_storage_cmp001_lvm] at time 02:49:51.188160 duration_in_ms=2459.493
2019-04-06 02:49:51,191 [salt.minion      :1711][INFO    ][8033] Returning information for job: 20190406024937654821
2019-04-06 02:49:51,873 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command state.apply with jid 20190406024951862702
2019-04-06 02:49:51,890 [salt.minion      :1432][INFO    ][8160] Starting a new job with PID 8160
2019-04-06 02:49:52,825 [salt.state       :915 ][INFO    ][8160] Loading fresh modules for state activity
2019-04-06 02:49:52,880 [salt.fileclient  :1219][INFO    ][8160] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-04-06 02:49:52,929 [salt.state       :1780][INFO    ][8160] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:49:52.929883
2019-04-06 02:49:52,930 [salt.state       :1813][INFO    ][8160] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-06 02:49:52,932 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8160] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-06 02:49:54,822 [salt.state       :300 ][INFO    ][8160] {'pid': 8167, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-06 02:49:54,823 [salt.state       :1951][INFO    ][8160] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:49:54.823547 duration_in_ms=1893.663
2019-04-06 02:49:54,826 [salt.state       :1780][INFO    ][8160] Running state [maas.deploy_machines] at time 02:49:54.826260
2019-04-06 02:49:54,826 [salt.state       :1813][INFO    ][8160] Executing state module.run for [maas.deploy_machines]
2019-04-06 02:49:54,828 [salt.utils.decorators:613 ][WARNING ][8160] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-06 02:49:55,399 [salt.loaded.ext.module.maas:684 ][INFO    ][8160] deploymachines hwe_kernel=ga-16.04 system_id=ra76ks distro_series=xenial
2019-04-06 02:49:57,826 [salt.loaded.ext.module.maas:684 ][INFO    ][8160] deploymachines hwe_kernel=ga-16.04 system_id=nhtt8f distro_series=xenial
2019-04-06 02:50:00,222 [salt.loaded.ext.module.maas:684 ][INFO    ][8160] deploymachines hwe_kernel=ga-16.04 system_id=gnch4x distro_series=xenial
2019-04-06 02:50:02,579 [salt.loaded.ext.module.maas:684 ][INFO    ][8160] deploymachines hwe_kernel=ga-16.04 system_id=xbggya distro_series=xenial
2019-04-06 02:50:04,921 [salt.loaded.ext.module.maas:684 ][INFO    ][8160] deploymachines hwe_kernel=ga-16.04 system_id=mcs773 distro_series=xenial
2019-04-06 02:50:06,956 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406025006949229
2019-04-06 02:50:06,974 [salt.minion      :1432][INFO    ][8429] Starting a new job with PID 8429
2019-04-06 02:50:07,005 [salt.minion      :1711][INFO    ][8429] Returning information for job: 20190406025006949229
2019-04-06 02:50:07,257 [salt.state       :300 ][INFO    ][8160] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-04-06 02:50:07,258 [salt.state       :1951][INFO    ][8160] Completed state [maas.deploy_machines] at time 02:50:07.258049 duration_in_ms=12431.789
2019-04-06 02:50:07,261 [salt.minion      :1711][INFO    ][8160] Returning information for job: 20190406024951862702
2019-04-06 02:50:07,953 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command state.apply with jid 20190406025007938589
2019-04-06 02:50:07,970 [salt.minion      :1432][INFO    ][8446] Starting a new job with PID 8446
2019-04-06 02:50:13,871 [salt.state       :915 ][INFO    ][8446] Loading fresh modules for state activity
2019-04-06 02:50:13,920 [salt.fileclient  :1219][INFO    ][8446] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-04-06 02:50:13,962 [salt.state       :1780][INFO    ][8446] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:50:13.962422
2019-04-06 02:50:13,962 [salt.state       :1813][INFO    ][8446] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-06 02:50:13,964 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8446] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-06 02:50:15,914 [salt.state       :300 ][INFO    ][8446] {'pid': 8460, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-06 02:50:15,915 [salt.state       :1951][INFO    ][8446] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:50:15.915746 duration_in_ms=1953.324
2019-04-06 02:50:15,917 [salt.state       :1780][INFO    ][8446] Running state [maas.wait_for_machine_status] at time 02:50:15.917686
2019-04-06 02:50:15,918 [salt.state       :1813][INFO    ][8446] Executing state module.run for [maas.wait_for_machine_status]
2019-04-06 02:50:15,918 [salt.utils.decorators:613 ][WARNING ][8446] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-06 02:50:18,582 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.34799194s left)
2019-04-06 02:50:23,036 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406025023025655
2019-04-06 02:50:23,058 [salt.minion      :1432][INFO    ][8479] Starting a new job with PID 8479
2019-04-06 02:50:23,085 [salt.minion      :1711][INFO    ][8479] Returning information for job: 20190406025023025655
2019-04-06 02:50:51,629 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.30082893s left)
2019-04-06 02:50:53,238 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406025053220997
2019-04-06 02:50:53,258 [salt.minion      :1432][INFO    ][8558] Starting a new job with PID 8558
2019-04-06 02:50:53,288 [salt.minion      :1711][INFO    ][8558] Returning information for job: 20190406025053220997
2019-04-06 02:51:23,270 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406025123263630
2019-04-06 02:51:23,290 [salt.minion      :1432][INFO    ][8580] Starting a new job with PID 8580
2019-04-06 02:51:23,315 [salt.minion      :1711][INFO    ][8580] Returning information for job: 20190406025123263630
2019-04-06 02:51:24,319 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.61090016s left)
2019-04-06 02:51:53,466 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406025153458161
2019-04-06 02:51:53,479 [salt.minion      :1432][INFO    ][8677] Starting a new job with PID 8677
2019-04-06 02:51:53,507 [salt.minion      :1711][INFO    ][8677] Returning information for job: 20190406025153458161
2019-04-06 02:51:57,245 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2148.68517709s left)
2019-04-06 02:52:23,675 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406025223667784
2019-04-06 02:52:23,687 [salt.minion      :1432][INFO    ][8702] Starting a new job with PID 8702
2019-04-06 02:52:23,712 [salt.minion      :1711][INFO    ][8702] Returning information for job: 20190406025223667784
2019-04-06 02:52:30,148 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2115.78173113s left)
2019-04-06 02:52:53,722 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406025253713370
2019-04-06 02:52:53,743 [salt.minion      :1432][INFO    ][8831] Starting a new job with PID 8831
2019-04-06 02:52:53,766 [salt.minion      :1711][INFO    ][8831] Returning information for job: 20190406025253713370
2019-04-06 02:53:02,921 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2083.00819016s left)
2019-04-06 02:53:23,777 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406025323763805
2019-04-06 02:53:23,794 [salt.minion      :1432][INFO    ][8898] Starting a new job with PID 8898
2019-04-06 02:53:23,821 [salt.minion      :1711][INFO    ][8898] Returning information for job: 20190406025323763805
2019-04-06 02:53:35,801 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2050.12872005s left)
2019-04-06 02:53:53,889 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406025353877994
2019-04-06 02:53:53,913 [salt.minion      :1432][INFO    ][9206] Starting a new job with PID 9206
2019-04-06 02:53:53,940 [salt.minion      :1711][INFO    ][9206] Returning information for job: 20190406025353877994
2019-04-06 02:54:08,706 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2017.22382712s left)
2019-04-06 02:54:23,969 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406025423960612
2019-04-06 02:54:23,984 [salt.minion      :1432][INFO    ][9246] Starting a new job with PID 9246
2019-04-06 02:54:24,012 [salt.minion      :1711][INFO    ][9246] Returning information for job: 20190406025423960612
2019-04-06 02:54:42,005 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1983.92501402s left)
2019-04-06 02:54:54,044 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406025454030606
2019-04-06 02:54:54,066 [salt.minion      :1432][INFO    ][9457] Starting a new job with PID 9457
2019-04-06 02:54:54,091 [salt.minion      :1711][INFO    ][9457] Returning information for job: 20190406025454030606
2019-04-06 02:55:14,945 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1950.98454595s left)
2019-04-06 02:55:24,132 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406025524124116
2019-04-06 02:55:24,154 [salt.minion      :1432][INFO    ][9504] Starting a new job with PID 9504
2019-04-06 02:55:24,181 [salt.minion      :1711][INFO    ][9504] Returning information for job: 20190406025524124116
2019-04-06 02:55:47,860 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1918.06958294s left)
2019-04-06 02:55:54,297 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406025554281694
2019-04-06 02:55:54,318 [salt.minion      :1432][INFO    ][9915] Starting a new job with PID 9915
2019-04-06 02:55:54,346 [salt.minion      :1711][INFO    ][9915] Returning information for job: 20190406025554281694
2019-04-06 02:56:20,803 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1885.12639403s left)
2019-04-06 02:56:24,396 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406025624382176
2019-04-06 02:56:24,413 [salt.minion      :1432][INFO    ][9936] Starting a new job with PID 9936
2019-04-06 02:56:24,443 [salt.minion      :1711][INFO    ][9936] Returning information for job: 20190406025624382176
2019-04-06 02:56:53,644 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1852.28528214s left)
2019-04-06 02:56:54,446 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406025654432590
2019-04-06 02:56:54,466 [salt.minion      :1432][INFO    ][10196] Starting a new job with PID 10196
2019-04-06 02:56:54,495 [salt.minion      :1711][INFO    ][10196] Returning information for job: 20190406025654432590
2019-04-06 02:57:24,574 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406025724565482
2019-04-06 02:57:24,588 [salt.minion      :1432][INFO    ][10227] Starting a new job with PID 10227
2019-04-06 02:57:24,616 [salt.minion      :1711][INFO    ][10227] Returning information for job: 20190406025724565482
2019-04-06 02:57:26,593 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1819.33660698s left)
2019-04-06 02:57:54,693 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406025754683739
2019-04-06 02:57:54,707 [salt.minion      :1432][INFO    ][10437] Starting a new job with PID 10437
2019-04-06 02:57:54,739 [salt.minion      :1711][INFO    ][10437] Returning information for job: 20190406025754683739
2019-04-06 02:57:59,497 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1786.43307614s left)
2019-04-06 02:58:24,824 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406025824809477
2019-04-06 02:58:24,846 [salt.minion      :1432][INFO    ][10486] Starting a new job with PID 10486
2019-04-06 02:58:24,874 [salt.minion      :1711][INFO    ][10486] Returning information for job: 20190406025824809477
2019-04-06 02:58:32,382 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1753.54806709s left)
2019-04-06 02:58:55,032 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406025855013119
2019-04-06 02:58:55,053 [salt.minion      :1432][INFO    ][10853] Starting a new job with PID 10853
2019-04-06 02:58:55,081 [salt.minion      :1711][INFO    ][10853] Returning information for job: 20190406025855013119
2019-04-06 02:59:05,047 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1720.88254905s left)
2019-04-06 02:59:25,172 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406025925156148
2019-04-06 02:59:25,193 [salt.minion      :1432][INFO    ][10885] Starting a new job with PID 10885
2019-04-06 02:59:25,220 [salt.minion      :1711][INFO    ][10885] Returning information for job: 20190406025925156148
2019-04-06 02:59:37,704 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1688.22553515s left)
2019-04-06 02:59:55,350 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406025955333838
2019-04-06 02:59:55,375 [salt.minion      :1432][INFO    ][11121] Starting a new job with PID 11121
2019-04-06 02:59:55,402 [salt.minion      :1711][INFO    ][11121] Returning information for job: 20190406025955333838
2019-04-06 03:00:10,542 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1655.38730502s left)
2019-04-06 03:00:25,489 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406030025481912
2019-04-06 03:00:25,501 [salt.minion      :1432][INFO    ][11141] Starting a new job with PID 11141
2019-04-06 03:00:25,532 [salt.minion      :1711][INFO    ][11141] Returning information for job: 20190406030025481912
2019-04-06 03:00:43,259 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1622.67056108s left)
2019-04-06 03:00:55,644 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406030055636006
2019-04-06 03:00:55,663 [salt.minion      :1432][INFO    ][11222] Starting a new job with PID 11222
2019-04-06 03:00:55,691 [salt.minion      :1711][INFO    ][11222] Returning information for job: 20190406030055636006
2019-04-06 03:01:16,560 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['cmp002', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1589.36967015s left)
2019-04-06 03:01:25,784 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406030125773037
2019-04-06 03:01:25,802 [salt.minion      :1432][INFO    ][11295] Starting a new job with PID 11295
2019-04-06 03:01:25,832 [salt.minion      :1711][INFO    ][11295] Returning information for job: 20190406030125773037
2019-04-06 03:01:49,412 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1556.51807594s left)
2019-04-06 03:01:55,992 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406030155981393
2019-04-06 03:01:56,012 [salt.minion      :1432][INFO    ][11724] Starting a new job with PID 11724
2019-04-06 03:01:56,045 [salt.minion      :1711][INFO    ][11724] Returning information for job: 20190406030155981393
2019-04-06 03:02:22,062 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1523.86758399s left)
2019-04-06 03:02:26,041 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406030226029014
2019-04-06 03:02:26,061 [salt.minion      :1432][INFO    ][11744] Starting a new job with PID 11744
2019-04-06 03:02:26,087 [salt.minion      :1711][INFO    ][11744] Returning information for job: 20190406030226029014
2019-04-06 03:02:54,894 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1491.03606009s left)
2019-04-06 03:02:56,224 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406030256216397
2019-04-06 03:02:56,240 [salt.minion      :1432][INFO    ][11825] Starting a new job with PID 11825
2019-04-06 03:02:56,275 [salt.minion      :1711][INFO    ][11825] Returning information for job: 20190406030256216397
2019-04-06 03:03:26,433 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406030326419978
2019-04-06 03:03:26,455 [salt.minion      :1432][INFO    ][11907] Starting a new job with PID 11907
2019-04-06 03:03:26,490 [salt.minion      :1711][INFO    ][11907] Returning information for job: 20190406030326419978
2019-04-06 03:03:27,721 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1458.208956s left)
2019-04-06 03:03:56,648 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406030356638218
2019-04-06 03:03:56,669 [salt.minion      :1432][INFO    ][12096] Starting a new job with PID 12096
2019-04-06 03:03:56,696 [salt.minion      :1711][INFO    ][12096] Returning information for job: 20190406030356638218
2019-04-06 03:04:00,574 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1425.35571504s left)
2019-04-06 03:04:26,733 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406030426725639
2019-04-06 03:04:26,749 [salt.minion      :1432][INFO    ][12136] Starting a new job with PID 12136
2019-04-06 03:04:26,776 [salt.minion      :1711][INFO    ][12136] Returning information for job: 20190406030426725639
2019-04-06 03:04:33,305 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1392.62484002s left)
2019-04-06 03:04:56,958 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406030456937483
2019-04-06 03:04:56,975 [salt.minion      :1432][INFO    ][12327] Starting a new job with PID 12327
2019-04-06 03:04:57,008 [salt.minion      :1711][INFO    ][12327] Returning information for job: 20190406030456937483
2019-04-06 03:05:06,119 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1359.81059408s left)
2019-04-06 03:05:26,998 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406030526987897
2019-04-06 03:05:27,013 [salt.minion      :1432][INFO    ][12357] Starting a new job with PID 12357
2019-04-06 03:05:27,042 [salt.minion      :1711][INFO    ][12357] Returning information for job: 20190406030526987897
2019-04-06 03:05:38,947 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1326.98276901s left)
2019-04-06 03:05:57,014 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406030557003888
2019-04-06 03:05:57,032 [salt.minion      :1432][INFO    ][12431] Starting a new job with PID 12431
2019-04-06 03:05:57,068 [salt.minion      :1711][INFO    ][12431] Returning information for job: 20190406030557003888
2019-04-06 03:06:11,734 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1294.19595909s left)
2019-04-06 03:06:27,093 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406030627079364
2019-04-06 03:06:27,114 [salt.minion      :1432][INFO    ][12459] Starting a new job with PID 12459
2019-04-06 03:06:27,140 [salt.minion      :1711][INFO    ][12459] Returning information for job: 20190406030627079364
2019-04-06 03:06:44,528 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1261.40175104s left)
2019-04-06 03:06:57,136 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406030657125473
2019-04-06 03:06:57,154 [salt.minion      :1432][INFO    ][12530] Starting a new job with PID 12530
2019-04-06 03:06:57,180 [salt.minion      :1711][INFO    ][12530] Returning information for job: 20190406030657125473
2019-04-06 03:07:17,167 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1228.76244807s left)
2019-04-06 03:07:27,209 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406030727196099
2019-04-06 03:07:27,228 [salt.minion      :1432][INFO    ][12558] Starting a new job with PID 12558
2019-04-06 03:07:27,254 [salt.minion      :1711][INFO    ][12558] Returning information for job: 20190406030727196099
2019-04-06 03:07:49,810 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1196.11985707s left)
2019-04-06 03:07:57,260 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406030757247681
2019-04-06 03:07:57,282 [salt.minion      :1432][INFO    ][12638] Starting a new job with PID 12638
2019-04-06 03:07:57,309 [salt.minion      :1711][INFO    ][12638] Returning information for job: 20190406030757247681
2019-04-06 03:08:22,463 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1163.46659708s left)
2019-04-06 03:08:27,359 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406030827351947
2019-04-06 03:08:27,378 [salt.minion      :1432][INFO    ][12665] Starting a new job with PID 12665
2019-04-06 03:08:27,406 [salt.minion      :1711][INFO    ][12665] Returning information for job: 20190406030827351947
2019-04-06 03:08:55,217 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1130.71245193s left)
2019-04-06 03:08:57,453 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406030857442655
2019-04-06 03:08:57,474 [salt.minion      :1432][INFO    ][12739] Starting a new job with PID 12739
2019-04-06 03:08:57,501 [salt.minion      :1711][INFO    ][12739] Returning information for job: 20190406030857442655
2019-04-06 03:09:27,572 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406030927562796
2019-04-06 03:09:27,588 [salt.minion      :1432][INFO    ][12766] Starting a new job with PID 12766
2019-04-06 03:09:27,614 [salt.minion      :1711][INFO    ][12766] Returning information for job: 20190406030927562796
2019-04-06 03:09:28,149 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1097.78031611s left)
2019-04-06 03:09:57,692 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406030957677827
2019-04-06 03:09:57,714 [salt.minion      :1432][INFO    ][12832] Starting a new job with PID 12832
2019-04-06 03:09:57,743 [salt.minion      :1711][INFO    ][12832] Returning information for job: 20190406030957677827
2019-04-06 03:10:00,796 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1065.13317299s left)
2019-04-06 03:10:27,844 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406031027831523
2019-04-06 03:10:27,861 [salt.minion      :1432][INFO    ][12861] Starting a new job with PID 12861
2019-04-06 03:10:27,890 [salt.minion      :1711][INFO    ][12861] Returning information for job: 20190406031027831523
2019-04-06 03:10:33,548 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1032.38148212s left)
2019-04-06 03:10:57,984 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406031057977210
2019-04-06 03:10:57,997 [salt.minion      :1432][INFO    ][12933] Starting a new job with PID 12933
2019-04-06 03:10:58,022 [salt.minion      :1711][INFO    ][12933] Returning information for job: 20190406031057977210
2019-04-06 03:11:06,310 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (999.620010138s left)
2019-04-06 03:11:28,142 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406031128131573
2019-04-06 03:11:28,163 [salt.minion      :1432][INFO    ][12965] Starting a new job with PID 12965
2019-04-06 03:11:28,192 [salt.minion      :1711][INFO    ][12965] Returning information for job: 20190406031128131573
2019-04-06 03:11:39,080 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (966.850083113s left)
2019-04-06 03:11:58,328 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406031158315803
2019-04-06 03:11:58,344 [salt.minion      :1432][INFO    ][13058] Starting a new job with PID 13058
2019-04-06 03:11:58,372 [salt.minion      :1711][INFO    ][13058] Returning information for job: 20190406031158315803
2019-04-06 03:12:11,791 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (934.13923502s left)
2019-04-06 03:12:28,498 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406031228490308
2019-04-06 03:12:28,519 [salt.minion      :1432][INFO    ][13086] Starting a new job with PID 13086
2019-04-06 03:12:28,545 [salt.minion      :1711][INFO    ][13086] Returning information for job: 20190406031228490308
2019-04-06 03:12:44,420 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (901.509168148s left)
2019-04-06 03:12:58,712 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406031258703090
2019-04-06 03:12:58,734 [salt.minion      :1432][INFO    ][13157] Starting a new job with PID 13157
2019-04-06 03:12:58,760 [salt.minion      :1711][INFO    ][13157] Returning information for job: 20190406031258703090
2019-04-06 03:13:17,167 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (868.763268948s left)
2019-04-06 03:13:28,904 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406031328889671
2019-04-06 03:13:28,925 [salt.minion      :1432][INFO    ][13186] Starting a new job with PID 13186
2019-04-06 03:13:28,954 [salt.minion      :1711][INFO    ][13186] Returning information for job: 20190406031328889671
2019-04-06 03:13:49,914 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (836.016159058s left)
2019-04-06 03:13:58,949 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406031358938480
2019-04-06 03:13:58,970 [salt.minion      :1432][INFO    ][13260] Starting a new job with PID 13260
2019-04-06 03:13:59,001 [salt.minion      :1711][INFO    ][13260] Returning information for job: 20190406031358938480
2019-04-06 03:14:22,532 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (803.397256136s left)
2019-04-06 03:14:29,182 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406031429167771
2019-04-06 03:14:29,202 [salt.minion      :1432][INFO    ][13289] Starting a new job with PID 13289
2019-04-06 03:14:29,229 [salt.minion      :1711][INFO    ][13289] Returning information for job: 20190406031429167771
2019-04-06 03:14:55,317 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (770.612906933s left)
2019-04-06 03:14:59,257 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406031459241831
2019-04-06 03:14:59,277 [salt.minion      :1432][INFO    ][13365] Starting a new job with PID 13365
2019-04-06 03:14:59,304 [salt.minion      :1711][INFO    ][13365] Returning information for job: 20190406031459241831
2019-04-06 03:15:28,039 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (737.891327143s left)
2019-04-06 03:15:29,304 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406031529296999
2019-04-06 03:15:29,325 [salt.minion      :1432][INFO    ][13398] Starting a new job with PID 13398
2019-04-06 03:15:29,352 [salt.minion      :1711][INFO    ][13398] Returning information for job: 20190406031529296999
2019-04-06 03:15:59,413 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406031559406380
2019-04-06 03:15:59,426 [salt.minion      :1432][INFO    ][13468] Starting a new job with PID 13468
2019-04-06 03:15:59,456 [salt.minion      :1711][INFO    ][13468] Returning information for job: 20190406031559406380
2019-04-06 03:16:00,792 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (705.13786006s left)
2019-04-06 03:16:29,504 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406031629492406
2019-04-06 03:16:29,524 [salt.minion      :1432][INFO    ][13494] Starting a new job with PID 13494
2019-04-06 03:16:29,552 [salt.minion      :1711][INFO    ][13494] Returning information for job: 20190406031629492406
2019-04-06 03:16:33,582 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (672.347480059s left)
2019-04-06 03:16:59,657 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406031659642766
2019-04-06 03:16:59,680 [salt.minion      :1432][INFO    ][13570] Starting a new job with PID 13570
2019-04-06 03:16:59,708 [salt.minion      :1711][INFO    ][13570] Returning information for job: 20190406031659642766
2019-04-06 03:17:06,489 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (639.440855026s left)
2019-04-06 03:17:29,807 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406031729800007
2019-04-06 03:17:29,826 [salt.minion      :1432][INFO    ][13602] Starting a new job with PID 13602
2019-04-06 03:17:29,850 [salt.minion      :1711][INFO    ][13602] Returning information for job: 20190406031729800007
2019-04-06 03:17:39,144 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (606.786105156s left)
2019-04-06 03:17:59,946 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406031759935710
2019-04-06 03:17:59,970 [salt.minion      :1432][INFO    ][13676] Starting a new job with PID 13676
2019-04-06 03:17:59,998 [salt.minion      :1711][INFO    ][13676] Returning information for job: 20190406031759935710
2019-04-06 03:18:11,779 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (574.151060104s left)
2019-04-06 03:18:30,157 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406031830149350
2019-04-06 03:18:30,179 [salt.minion      :1432][INFO    ][13705] Starting a new job with PID 13705
2019-04-06 03:18:30,208 [salt.minion      :1711][INFO    ][13705] Returning information for job: 20190406031830149350
2019-04-06 03:18:44,515 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (541.414543152s left)
2019-04-06 03:19:00,334 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406031900327294
2019-04-06 03:19:00,354 [salt.minion      :1432][INFO    ][13777] Starting a new job with PID 13777
2019-04-06 03:19:00,382 [salt.minion      :1711][INFO    ][13777] Returning information for job: 20190406031900327294
2019-04-06 03:19:17,162 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (508.767560959s left)
2019-04-06 03:19:30,367 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406031930358962
2019-04-06 03:19:30,389 [salt.minion      :1432][INFO    ][13808] Starting a new job with PID 13808
2019-04-06 03:19:30,416 [salt.minion      :1711][INFO    ][13808] Returning information for job: 20190406031930358962
2019-04-06 03:19:49,924 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (476.007257938s left)
2019-04-06 03:20:00,600 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406032000585167
2019-04-06 03:20:00,622 [salt.minion      :1432][INFO    ][13882] Starting a new job with PID 13882
2019-04-06 03:20:00,649 [salt.minion      :1711][INFO    ][13882] Returning information for job: 20190406032000585167
2019-04-06 03:20:22,786 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (443.143566132s left)
2019-04-06 03:20:30,678 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406032030663715
2019-04-06 03:20:30,695 [salt.minion      :1432][INFO    ][13913] Starting a new job with PID 13913
2019-04-06 03:20:30,729 [salt.minion      :1711][INFO    ][13913] Returning information for job: 20190406032030663715
2019-04-06 03:20:55,449 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (410.480504036s left)
2019-04-06 03:21:00,727 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406032100713712
2019-04-06 03:21:00,749 [salt.minion      :1432][INFO    ][13989] Starting a new job with PID 13989
2019-04-06 03:21:00,777 [salt.minion      :1711][INFO    ][13989] Returning information for job: 20190406032100713712
2019-04-06 03:21:28,307 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (377.622410059s left)
2019-04-06 03:21:30,877 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406032130857943
2019-04-06 03:21:30,897 [salt.minion      :1432][INFO    ][14151] Starting a new job with PID 14151
2019-04-06 03:21:30,926 [salt.minion      :1711][INFO    ][14151] Returning information for job: 20190406032130857943
2019-04-06 03:22:00,918 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406032200909327
2019-04-06 03:22:00,934 [salt.minion      :1432][INFO    ][14238] Starting a new job with PID 14238
2019-04-06 03:22:00,965 [salt.minion      :1711][INFO    ][14238] Returning information for job: 20190406032200909327
2019-04-06 03:22:01,013 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (344.917062998s left)
2019-04-06 03:22:31,118 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406032231110522
2019-04-06 03:22:31,135 [salt.minion      :1432][INFO    ][14263] Starting a new job with PID 14263
2019-04-06 03:22:31,161 [salt.minion      :1711][INFO    ][14263] Returning information for job: 20190406032231110522
2019-04-06 03:22:33,742 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (312.187325001s left)
2019-04-06 03:23:01,222 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406032301214038
2019-04-06 03:23:01,238 [salt.minion      :1432][INFO    ][14334] Starting a new job with PID 14334
2019-04-06 03:23:01,268 [salt.minion      :1711][INFO    ][14334] Returning information for job: 20190406032301214038
2019-04-06 03:23:06,282 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (279.647886992s left)
2019-04-06 03:23:31,425 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406032331410512
2019-04-06 03:23:31,446 [salt.minion      :1432][INFO    ][14365] Starting a new job with PID 14365
2019-04-06 03:23:31,475 [salt.minion      :1711][INFO    ][14365] Returning information for job: 20190406032331410512
2019-04-06 03:23:38,917 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (247.012571096s left)
2019-04-06 03:24:01,604 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406032401589703
2019-04-06 03:24:01,620 [salt.minion      :1432][INFO    ][14440] Starting a new job with PID 14440
2019-04-06 03:24:01,646 [salt.minion      :1711][INFO    ][14440] Returning information for job: 20190406032401589703
2019-04-06 03:24:11,656 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (214.273748159s left)
2019-04-06 03:24:31,793 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406032431778067
2019-04-06 03:24:31,819 [salt.minion      :1432][INFO    ][14472] Starting a new job with PID 14472
2019-04-06 03:24:31,843 [salt.minion      :1711][INFO    ][14472] Returning information for job: 20190406032431778067
2019-04-06 03:24:44,560 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (181.369251966s left)
2019-04-06 03:25:01,855 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406032501843314
2019-04-06 03:25:01,878 [salt.minion      :1432][INFO    ][14546] Starting a new job with PID 14546
2019-04-06 03:25:01,906 [salt.minion      :1711][INFO    ][14546] Returning information for job: 20190406032501843314
2019-04-06 03:25:17,291 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (148.638266087s left)
2019-04-06 03:25:31,881 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406032531867410
2019-04-06 03:25:31,903 [salt.minion      :1432][INFO    ][14575] Starting a new job with PID 14575
2019-04-06 03:25:31,937 [salt.minion      :1711][INFO    ][14575] Returning information for job: 20190406032531867410
2019-04-06 03:25:49,932 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (115.997332096s left)
2019-04-06 03:26:01,946 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406032601933165
2019-04-06 03:26:01,967 [salt.minion      :1432][INFO    ][14649] Starting a new job with PID 14649
2019-04-06 03:26:01,996 [salt.minion      :1711][INFO    ][14649] Returning information for job: 20190406032601933165
2019-04-06 03:26:22,597 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (83.3326671124s left)
2019-04-06 03:26:31,998 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406032631984413
2019-04-06 03:26:32,018 [salt.minion      :1432][INFO    ][14678] Starting a new job with PID 14678
2019-04-06 03:26:32,050 [salt.minion      :1711][INFO    ][14678] Returning information for job: 20190406032631984413
2019-04-06 03:26:55,365 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (50.5648870468s left)
2019-04-06 03:27:02,122 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406032702108767
2019-04-06 03:27:02,145 [salt.minion      :1432][INFO    ][14753] Starting a new job with PID 14753
2019-04-06 03:27:02,173 [salt.minion      :1711][INFO    ][14753] Returning information for job: 20190406032702108767
2019-04-06 03:27:28,161 [salt.loaded.ext.module.maas:1023][INFO    ][8446] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (17.7687270641s left)
2019-04-06 03:27:32,193 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406032732179535
2019-04-06 03:27:32,216 [salt.minion      :1432][INFO    ][14782] Starting a new job with PID 14782
2019-04-06 03:27:32,245 [salt.minion      :1711][INFO    ][14782] Returning information for job: 20190406032732179535
2019-04-06 03:28:00,921 [salt.state       :302 ][ERROR   ][8446] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-04-06 03:28:00,922 [salt.state       :1951][INFO    ][8446] Completed state [maas.wait_for_machine_status] at time 03:28:00.922059 duration_in_ms=2265004.369
2019-04-06 03:28:00,925 [salt.minion      :1711][INFO    ][8446] Returning information for job: 20190406025007938589
2019-04-06 03:28:11,971 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command pillar.get with jid 20190406032811961246
2019-04-06 03:28:11,994 [salt.minion      :1432][INFO    ][14865] Starting a new job with PID 14865
2019-04-06 03:28:12,001 [salt.minion      :1711][INFO    ][14865] Returning information for job: 20190406032811961246
2019-04-06 03:28:12,681 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command service.status with jid 20190406032812671715
2019-04-06 03:28:12,698 [salt.minion      :1432][INFO    ][14870] Starting a new job with PID 14870
2019-04-06 03:28:13,499 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][14870] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-04-06 03:28:13,542 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][14870] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-04-06 03:28:13,560 [salt.minion      :1711][INFO    ][14870] Returning information for job: 20190406032812671715
2019-04-06 03:28:14,244 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command state.apply with jid 20190406032814235809
2019-04-06 03:28:14,265 [salt.minion      :1432][INFO    ][14881] Starting a new job with PID 14881
2019-04-06 03:28:20,753 [salt.state       :915 ][INFO    ][14881] Loading fresh modules for state activity
2019-04-06 03:28:21,322 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14881] Executing command 'salt-minion --version' in directory '/root'
2019-04-06 03:28:21,766 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14881] Executing command 'salt-minion --version' in directory '/root'
2019-04-06 03:28:22,692 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14881] Executing command 'salt-minion --version' in directory '/root'
2019-04-06 03:28:23,004 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14881] Executing command 'salt-minion --version' in directory '/root'
2019-04-06 03:28:25,077 [salt.state       :1780][INFO    ][14881] Running state [salt-minion] at time 03:28:25.077147
2019-04-06 03:28:25,077 [salt.state       :1813][INFO    ][14881] Executing state pkg.installed for [salt-minion]
2019-04-06 03:28:25,078 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14881] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-06 03:28:25,165 [salt.state       :300 ][INFO    ][14881] All specified packages are already installed
2019-04-06 03:28:25,166 [salt.state       :1951][INFO    ][14881] Completed state [salt-minion] at time 03:28:25.165917 duration_in_ms=88.771
2019-04-06 03:28:25,166 [salt.state       :1780][INFO    ][14881] Running state [salt_minion_dependency_packages] at time 03:28:25.166285
2019-04-06 03:28:25,166 [salt.state       :1813][INFO    ][14881] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-04-06 03:28:25,176 [salt.state       :300 ][INFO    ][14881] All specified packages are already installed
2019-04-06 03:28:25,176 [salt.state       :1951][INFO    ][14881] Completed state [salt_minion_dependency_packages] at time 03:28:25.176722 duration_in_ms=10.437
2019-04-06 03:28:25,181 [salt.state       :1780][INFO    ][14881] Running state [/etc/salt/minion.d/minion.conf] at time 03:28:25.180971
2019-04-06 03:28:25,181 [salt.state       :1813][INFO    ][14881] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-04-06 03:28:25,448 [salt.state       :300 ][INFO    ][14881] File /etc/salt/minion.d/minion.conf is in the correct state
2019-04-06 03:28:25,448 [salt.state       :1951][INFO    ][14881] Completed state [/etc/salt/minion.d/minion.conf] at time 03:28:25.448652 duration_in_ms=267.681
2019-04-06 03:28:25,449 [salt.state       :1780][INFO    ][14881] Running state [python-netaddr] at time 03:28:25.448952
2019-04-06 03:28:25,449 [salt.state       :1813][INFO    ][14881] Executing state pkg.installed for [python-netaddr]
2019-04-06 03:28:25,459 [salt.state       :300 ][INFO    ][14881] All specified packages are already installed
2019-04-06 03:28:25,460 [salt.state       :1951][INFO    ][14881] Completed state [python-netaddr] at time 03:28:25.459968 duration_in_ms=11.016
2019-04-06 03:28:25,463 [salt.state       :1780][INFO    ][14881] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 03:28:25.463138
2019-04-06 03:28:25,463 [salt.state       :1813][INFO    ][14881] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-04-06 03:28:25,474 [salt.state       :300 ][INFO    ][14881] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-04-06 03:28:25,474 [salt.state       :1951][INFO    ][14881] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 03:28:25.474434 duration_in_ms=11.296
2019-04-06 03:28:25,475 [salt.state       :1780][INFO    ][14881] Running state [salt-minion] at time 03:28:25.475640
2019-04-06 03:28:25,475 [salt.state       :1813][INFO    ][14881] Executing state service.running for [salt-minion]
2019-04-06 03:28:25,476 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14881] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-04-06 03:28:25,505 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14881] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-04-06 03:28:25,517 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14881] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-04-06 03:28:25,530 [salt.state       :300 ][INFO    ][14881] The service salt-minion is already running
2019-04-06 03:28:25,530 [salt.state       :1951][INFO    ][14881] Completed state [salt-minion] at time 03:28:25.530388 duration_in_ms=54.748
2019-04-06 03:28:25,532 [salt.state       :1780][INFO    ][14881] Running state [/etc/salt/grains.d] at time 03:28:25.532102
2019-04-06 03:28:25,532 [salt.state       :1813][INFO    ][14881] Executing state file.directory for [/etc/salt/grains.d]
2019-04-06 03:28:25,533 [salt.state       :300 ][INFO    ][14881] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-04-06 03:28:25,533 [salt.state       :1951][INFO    ][14881] Completed state [/etc/salt/grains.d] at time 03:28:25.533735 duration_in_ms=1.633
2019-04-06 03:28:25,534 [salt.state       :1780][INFO    ][14881] Running state [/etc/salt/grains] at time 03:28:25.534436
2019-04-06 03:28:25,534 [salt.state       :1813][INFO    ][14881] Executing state file.managed for [/etc/salt/grains]
2019-04-06 03:28:25,535 [salt.state       :300 ][INFO    ][14881] File /etc/salt/grains exists with proper permissions. No changes made.
2019-04-06 03:28:25,535 [salt.state       :1951][INFO    ][14881] Completed state [/etc/salt/grains] at time 03:28:25.535538 duration_in_ms=1.103
2019-04-06 03:28:25,536 [salt.state       :1780][INFO    ][14881] Running state [/etc/salt/grains.d/placeholder] at time 03:28:25.536039
2019-04-06 03:28:25,536 [salt.state       :1813][INFO    ][14881] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-04-06 03:28:25,536 [salt.state       :300 ][INFO    ][14881] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-04-06 03:28:25,537 [salt.state       :1951][INFO    ][14881] Completed state [/etc/salt/grains.d/placeholder] at time 03:28:25.537111 duration_in_ms=1.073
2019-04-06 03:28:25,537 [salt.state       :1780][INFO    ][14881] Running state [/etc/salt/grains.d/sphinx] at time 03:28:25.537628
2019-04-06 03:28:25,537 [salt.state       :1813][INFO    ][14881] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-04-06 03:28:25,541 [salt.state       :300 ][INFO    ][14881] File /etc/salt/grains.d/sphinx is in the correct state
2019-04-06 03:28:25,541 [salt.state       :1951][INFO    ][14881] Completed state [/etc/salt/grains.d/sphinx] at time 03:28:25.541812 duration_in_ms=4.185
2019-04-06 03:28:25,543 [salt.state       :1780][INFO    ][14881] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 03:28:25.543895
2019-04-06 03:28:25,544 [salt.state       :1813][INFO    ][14881] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-04-06 03:28:25,544 [salt.state       :300 ][INFO    ][14881] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-04-06 03:28:25,544 [salt.state       :1951][INFO    ][14881] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 03:28:25.544740 duration_in_ms=0.845
2019-04-06 03:28:25,545 [salt.state       :1780][INFO    ][14881] Running state [/etc/salt/grains.d/dns_records] at time 03:28:25.545261
2019-04-06 03:28:25,545 [salt.state       :1813][INFO    ][14881] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-04-06 03:28:25,546 [salt.state       :300 ][INFO    ][14881] File /etc/salt/grains.d/dns_records is in the correct state
2019-04-06 03:28:25,546 [salt.state       :1951][INFO    ][14881] Completed state [/etc/salt/grains.d/dns_records] at time 03:28:25.546798 duration_in_ms=1.537
2019-04-06 03:28:25,547 [salt.state       :1780][INFO    ][14881] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 03:28:25.547827
2019-04-06 03:28:25,548 [salt.state       :1813][INFO    ][14881] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-04-06 03:28:25,548 [salt.state       :300 ][INFO    ][14881] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-04-06 03:28:25,548 [salt.state       :1951][INFO    ][14881] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 03:28:25.548668 duration_in_ms=0.841
2019-04-06 03:28:25,549 [salt.state       :1780][INFO    ][14881] Running state [/etc/salt/grains.d/salt] at time 03:28:25.549181
2019-04-06 03:28:25,549 [salt.state       :1813][INFO    ][14881] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-04-06 03:28:25,550 [salt.state       :300 ][INFO    ][14881] File /etc/salt/grains.d/salt is in the correct state
2019-04-06 03:28:25,550 [salt.state       :1951][INFO    ][14881] Completed state [/etc/salt/grains.d/salt] at time 03:28:25.550730 duration_in_ms=1.549
2019-04-06 03:28:25,553 [salt.state       :1780][INFO    ][14881] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 03:28:25.553302
2019-04-06 03:28:25,553 [salt.state       :1813][INFO    ][14881] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-04-06 03:28:25,553 [salt.state       :300 ][INFO    ][14881] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-04-06 03:28:25,554 [salt.state       :1951][INFO    ][14881] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 03:28:25.554133 duration_in_ms=0.831
2019-04-06 03:28:25,556 [salt.state       :1780][INFO    ][14881] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 03:28:25.556402
2019-04-06 03:28:25,556 [salt.state       :1813][INFO    ][14881] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-04-06 03:28:25,557 [salt.state       :300 ][INFO    ][14881] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-04-06 03:28:25,557 [salt.state       :1951][INFO    ][14881] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 03:28:25.557254 duration_in_ms=0.852
2019-04-06 03:28:25,558 [salt.state       :1780][INFO    ][14881] Running state [mine.update] at time 03:28:25.557953
2019-04-06 03:28:25,558 [salt.state       :1813][INFO    ][14881] Executing state module.wait for [mine.update]
2019-04-06 03:28:25,558 [salt.state       :300 ][INFO    ][14881] No changes made for mine.update
2019-04-06 03:28:25,559 [salt.state       :1951][INFO    ][14881] Completed state [mine.update] at time 03:28:25.559315 duration_in_ms=1.362
2019-04-06 03:28:25,559 [salt.state       :1780][INFO    ][14881] Running state [ca-certificates] at time 03:28:25.559587
2019-04-06 03:28:25,559 [salt.state       :1813][INFO    ][14881] Executing state pkg.installed for [ca-certificates]
2019-04-06 03:28:25,569 [salt.state       :300 ][INFO    ][14881] All specified packages are already installed
2019-04-06 03:28:25,570 [salt.state       :1951][INFO    ][14881] Completed state [ca-certificates] at time 03:28:25.570059 duration_in_ms=10.471
2019-04-06 03:28:25,571 [salt.state       :1780][INFO    ][14881] Running state [update-ca-certificates] at time 03:28:25.570790
2019-04-06 03:28:25,571 [salt.state       :1813][INFO    ][14881] Executing state cmd.wait for [update-ca-certificates]
2019-04-06 03:28:25,572 [salt.state       :300 ][INFO    ][14881] No changes made for update-ca-certificates
2019-04-06 03:28:25,572 [salt.state       :1951][INFO    ][14881] Completed state [update-ca-certificates] at time 03:28:25.572297 duration_in_ms=1.508
2019-04-06 03:28:25,572 [salt.state       :1780][INFO    ][14881] Running state [iptables] at time 03:28:25.572568
2019-04-06 03:28:25,572 [salt.state       :1813][INFO    ][14881] Executing state pkg.installed for [iptables]
2019-04-06 03:28:25,583 [salt.state       :300 ][INFO    ][14881] All specified packages are already installed
2019-04-06 03:28:25,583 [salt.state       :1951][INFO    ][14881] Completed state [iptables] at time 03:28:25.583351 duration_in_ms=10.783
2019-04-06 03:28:25,583 [salt.state       :1780][INFO    ][14881] Running state [iptables-persistent] at time 03:28:25.583632
2019-04-06 03:28:25,583 [salt.state       :1813][INFO    ][14881] Executing state pkg.installed for [iptables-persistent]
2019-04-06 03:28:25,594 [salt.state       :300 ][INFO    ][14881] All specified packages are already installed
2019-04-06 03:28:25,594 [salt.state       :1951][INFO    ][14881] Completed state [iptables-persistent] at time 03:28:25.594446 duration_in_ms=10.814
2019-04-06 03:28:25,595 [salt.state       :1780][INFO    ][14881] Running state [iptables_modules_v4_load] at time 03:28:25.595502
2019-04-06 03:28:25,595 [salt.state       :1813][INFO    ][14881] Executing state kmod.present for [iptables_modules_v4_load]
2019-04-06 03:28:25,596 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14881] Executing command 'lsmod' in directory '/root'
2019-04-06 03:28:25,613 [salt.state       :300 ][INFO    ][14881] Kernel modules iptable_filter, ip_tables are already present
2019-04-06 03:28:25,614 [salt.state       :1951][INFO    ][14881] Completed state [iptables_modules_v4_load] at time 03:28:25.614519 duration_in_ms=19.015
2019-04-06 03:28:25,616 [salt.state       :1780][INFO    ][14881] Running state [/etc/iptables/rules.v4] at time 03:28:25.616395
2019-04-06 03:28:25,617 [salt.state       :1813][INFO    ][14881] Executing state file.managed for [/etc/iptables/rules.v4]
2019-04-06 03:28:25,713 [salt.state       :300 ][INFO    ][14881] File /etc/iptables/rules.v4 is in the correct state
2019-04-06 03:28:25,713 [salt.state       :1951][INFO    ][14881] Completed state [/etc/iptables/rules.v4] at time 03:28:25.713811 duration_in_ms=97.418
2019-04-06 03:28:25,715 [salt.state       :1780][INFO    ][14881] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 03:28:25.715083
2019-04-06 03:28:25,715 [salt.state       :1813][INFO    ][14881] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-04-06 03:28:25,716 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14881] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-04-06 03:28:25,733 [salt.state       :300 ][INFO    ][14881] onlyif execution failed
2019-04-06 03:28:25,734 [salt.state       :1951][INFO    ][14881] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 03:28:25.733940 duration_in_ms=18.857
2019-04-06 03:28:25,735 [salt.state       :1780][INFO    ][14881] Running state [netfilter-persistent] at time 03:28:25.735074
2019-04-06 03:28:25,735 [salt.state       :1813][INFO    ][14881] Executing state service.running for [netfilter-persistent]
2019-04-06 03:28:25,736 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14881] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-04-06 03:28:25,751 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14881] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-04-06 03:28:25,763 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14881] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-04-06 03:28:25,781 [salt.state       :300 ][INFO    ][14881] The service netfilter-persistent is already running
2019-04-06 03:28:25,781 [salt.state       :1951][INFO    ][14881] Completed state [netfilter-persistent] at time 03:28:25.781694 duration_in_ms=46.619
2019-04-06 03:28:25,782 [salt.state       :1780][INFO    ][14881] Running state [iptables_extra.remove_stale_tables] at time 03:28:25.782618
2019-04-06 03:28:25,783 [salt.state       :1813][INFO    ][14881] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-04-06 03:28:25,783 [salt.state       :300 ][INFO    ][14881] No changes made for iptables_extra.remove_stale_tables
2019-04-06 03:28:25,783 [salt.state       :1951][INFO    ][14881] Completed state [iptables_extra.remove_stale_tables] at time 03:28:25.783816 duration_in_ms=1.198
2019-04-06 03:28:25,784 [salt.state       :1780][INFO    ][14881] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 03:28:25.784097
2019-04-06 03:28:25,784 [salt.state       :1813][INFO    ][14881] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-04-06 03:28:25,785 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14881] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-04-06 03:28:25,795 [salt.state       :300 ][INFO    ][14881] onlyif execution failed
2019-04-06 03:28:25,796 [salt.state       :1951][INFO    ][14881] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 03:28:25.796209 duration_in_ms=12.113
2019-04-06 03:28:25,797 [salt.state       :1780][INFO    ][14881] Running state [/etc/iptables/rules.v6] at time 03:28:25.797158
2019-04-06 03:28:25,797 [salt.state       :1813][INFO    ][14881] Executing state file.absent for [/etc/iptables/rules.v6]
2019-04-06 03:28:25,798 [salt.state       :300 ][INFO    ][14881] File /etc/iptables/rules.v6 is not present
2019-04-06 03:28:25,798 [salt.state       :1951][INFO    ][14881] Completed state [/etc/iptables/rules.v6] at time 03:28:25.798415 duration_in_ms=1.257
2019-04-06 03:28:25,799 [salt.state       :1780][INFO    ][14881] Running state [iptables_extra.flush_all] at time 03:28:25.799198
2019-04-06 03:28:25,799 [salt.state       :1813][INFO    ][14881] Executing state module.wait for [iptables_extra.flush_all]
2019-04-06 03:28:25,799 [salt.state       :300 ][INFO    ][14881] No changes made for iptables_extra.flush_all
2019-04-06 03:28:25,800 [salt.state       :1951][INFO    ][14881] Completed state [iptables_extra.flush_all] at time 03:28:25.800074 duration_in_ms=0.877
2019-04-06 03:28:25,805 [salt.minion      :1711][INFO    ][14881] Returning information for job: 20190406032814235809
2019-04-06 03:28:26,514 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command state.apply with jid 20190406032826505455
2019-04-06 03:28:26,530 [salt.minion      :1432][INFO    ][14970] Starting a new job with PID 14970
2019-04-06 03:28:27,497 [salt.state       :915 ][INFO    ][14970] Loading fresh modules for state activity
2019-04-06 03:28:28,719 [salt.state       :1780][INFO    ][14970] Running state [maas-rack-controller] at time 03:28:28.719688
2019-04-06 03:28:28,720 [salt.state       :1813][INFO    ][14970] Executing state pkg.installed for [maas-rack-controller]
2019-04-06 03:28:28,721 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14970] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-06 03:28:28,838 [salt.state       :300 ][INFO    ][14970] All specified packages are already installed
2019-04-06 03:28:28,839 [salt.state       :1951][INFO    ][14970] Completed state [maas-rack-controller] at time 03:28:28.839297 duration_in_ms=119.61
2019-04-06 03:28:28,840 [salt.state       :1780][INFO    ][14970] Running state [ipmitool] at time 03:28:28.839940
2019-04-06 03:28:28,840 [salt.state       :1813][INFO    ][14970] Executing state pkg.installed for [ipmitool]
2019-04-06 03:28:28,849 [salt.state       :300 ][INFO    ][14970] All specified packages are already installed
2019-04-06 03:28:28,849 [salt.state       :1951][INFO    ][14970] Completed state [ipmitool] at time 03:28:28.849659 duration_in_ms=9.718
2019-04-06 03:28:28,853 [salt.state       :1780][INFO    ][14970] Running state [/etc/maas/rackd.conf] at time 03:28:28.853372
2019-04-06 03:28:28,853 [salt.state       :1813][INFO    ][14970] Executing state file.line for [/etc/maas/rackd.conf]
2019-04-06 03:28:28,854 [salt.state       :300 ][INFO    ][14970] No changes needed to be made
2019-04-06 03:28:28,854 [salt.state       :1951][INFO    ][14970] Completed state [/etc/maas/rackd.conf] at time 03:28:28.854909 duration_in_ms=1.537
2019-04-06 03:28:28,855 [salt.state       :1780][INFO    ][14970] Running state [/etc/maas/rackd.conf] at time 03:28:28.855199
2019-04-06 03:28:28,855 [salt.state       :1813][INFO    ][14970] Executing state file.managed for [/etc/maas/rackd.conf]
2019-04-06 03:28:28,855 [salt.loaded.int.states.file:2298][WARNING ][14970] 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-04-06 03:28:28,856 [salt.state       :300 ][INFO    ][14970] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-04-06 03:28:28,856 [salt.state       :1951][INFO    ][14970] Completed state [/etc/maas/rackd.conf] at time 03:28:28.856849 duration_in_ms=1.65
2019-04-06 03:28:28,857 [salt.state       :1780][INFO    ][14970] Running state [maas-rackd] at time 03:28:28.857899
2019-04-06 03:28:28,858 [salt.state       :1813][INFO    ][14970] Executing state service.running for [maas-rackd]
2019-04-06 03:28:28,859 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14970] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-04-06 03:28:28,895 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14970] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-04-06 03:28:28,912 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14970] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-04-06 03:28:28,930 [salt.state       :300 ][INFO    ][14970] The service maas-rackd is already running
2019-04-06 03:28:28,930 [salt.state       :1951][INFO    ][14970] Completed state [maas-rackd] at time 03:28:28.930556 duration_in_ms=72.657
2019-04-06 03:28:28,933 [salt.minion      :1711][INFO    ][14970] Returning information for job: 20190406032826505455
2019-04-06 03:28:29,606 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command state.apply with jid 20190406032829595839
2019-04-06 03:28:29,627 [salt.minion      :1432][INFO    ][14993] Starting a new job with PID 14993
2019-04-06 03:28:30,561 [salt.state       :915 ][INFO    ][14993] Loading fresh modules for state activity
2019-04-06 03:28:31,914 [salt.state       :1780][INFO    ][14993] Running state [maas-region-controller] at time 03:28:31.914614
2019-04-06 03:28:31,915 [salt.state       :1813][INFO    ][14993] Executing state pkg.installed for [maas-region-controller]
2019-04-06 03:28:31,916 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14993] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-06 03:28:32,037 [salt.state       :300 ][INFO    ][14993] All specified packages are already installed
2019-04-06 03:28:32,037 [salt.state       :1951][INFO    ][14993] Completed state [maas-region-controller] at time 03:28:32.037476 duration_in_ms=122.862
2019-04-06 03:28:32,037 [salt.state       :1780][INFO    ][14993] Running state [python-oauth] at time 03:28:32.037843
2019-04-06 03:28:32,038 [salt.state       :1813][INFO    ][14993] Executing state pkg.installed for [python-oauth]
2019-04-06 03:28:32,048 [salt.state       :300 ][INFO    ][14993] All specified packages are already installed
2019-04-06 03:28:32,048 [salt.state       :1951][INFO    ][14993] Completed state [python-oauth] at time 03:28:32.048491 duration_in_ms=10.648
2019-04-06 03:28:32,051 [salt.state       :1780][INFO    ][14993] Running state [/etc/maas/regiond.conf] at time 03:28:32.051614
2019-04-06 03:28:32,051 [salt.state       :1813][INFO    ][14993] Executing state file.replace for [/etc/maas/regiond.conf]
2019-04-06 03:28:32,057 [salt.state       :300 ][INFO    ][14993] No changes needed to be made
2019-04-06 03:28:32,057 [salt.state       :1951][INFO    ][14993] Completed state [/etc/maas/regiond.conf] at time 03:28:32.057310 duration_in_ms=5.696
2019-04-06 03:28:32,057 [salt.state       :1780][INFO    ][14993] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 03:28:32.057835
2019-04-06 03:28:32,058 [salt.state       :1813][INFO    ][14993] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-04-06 03:28:32,124 [salt.state       :300 ][INFO    ][14993] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-04-06 03:28:32,124 [salt.state       :1951][INFO    ][14993] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 03:28:32.124890 duration_in_ms=67.054
2019-04-06 03:28:32,125 [salt.state       :1780][INFO    ][14993] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 03:28:32.125583
2019-04-06 03:28:32,126 [salt.state       :1813][INFO    ][14993] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-04-06 03:28:32,131 [salt.state       :300 ][INFO    ][14993] No changes needed to be made
2019-04-06 03:28:32,131 [salt.state       :1951][INFO    ][14993] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 03:28:32.131800 duration_in_ms=6.218
2019-04-06 03:28:32,132 [salt.state       :1780][INFO    ][14993] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 03:28:32.132325
2019-04-06 03:28:32,132 [salt.state       :1813][INFO    ][14993] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-04-06 03:28:32,137 [salt.state       :300 ][INFO    ][14993] No changes needed to be made
2019-04-06 03:28:32,137 [salt.state       :1951][INFO    ][14993] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 03:28:32.137365 duration_in_ms=5.04
2019-04-06 03:28:32,137 [salt.state       :1780][INFO    ][14993] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 03:28:32.137888
2019-04-06 03:28:32,138 [salt.state       :1813][INFO    ][14993] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-04-06 03:28:32,162 [salt.state       :300 ][INFO    ][14993] No changes needed to be made
2019-04-06 03:28:32,162 [salt.state       :1951][INFO    ][14993] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 03:28:32.162623 duration_in_ms=24.735
2019-04-06 03:28:32,164 [salt.state       :1780][INFO    ][14993] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 03:28:32.164417
2019-04-06 03:28:32,164 [salt.state       :1813][INFO    ][14993] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-04-06 03:28:32,178 [salt.state       :300 ][INFO    ][14993] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-04-06 03:28:32,178 [salt.state       :1951][INFO    ][14993] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 03:28:32.178352 duration_in_ms=13.933
2019-04-06 03:28:32,181 [salt.state       :1780][INFO    ][14993] Running state [a2enmod headers] at time 03:28:32.180904
2019-04-06 03:28:32,181 [salt.state       :1813][INFO    ][14993] Executing state cmd.run for [a2enmod headers]
2019-04-06 03:28:32,182 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14993] Executing command 'a2enmod headers' in directory '/root'
2019-04-06 03:28:32,255 [salt.state       :300 ][INFO    ][14993] {'pid': 15014, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-04-06 03:28:32,255 [salt.state       :1951][INFO    ][14993] Completed state [a2enmod headers] at time 03:28:32.255773 duration_in_ms=74.869
2019-04-06 03:28:32,256 [salt.state       :1780][INFO    ][14993] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 03:28:32.256538
2019-04-06 03:28:32,257 [salt.state       :1813][INFO    ][14993] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-04-06 03:28:32,277 [salt.state       :300 ][INFO    ][14993] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-04-06 03:28:32,277 [salt.state       :1951][INFO    ][14993] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 03:28:32.277894 duration_in_ms=21.357
2019-04-06 03:28:32,278 [salt.state       :1780][INFO    ][14993] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 03:28:32.278565
2019-04-06 03:28:32,279 [salt.state       :1813][INFO    ][14993] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-04-06 03:28:32,344 [salt.state       :300 ][INFO    ][14993] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-04-06 03:28:32,344 [salt.state       :1951][INFO    ][14993] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 03:28:32.344726 duration_in_ms=66.161
2019-04-06 03:28:32,345 [salt.state       :1780][INFO    ][14993] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 03:28:32.345368
2019-04-06 03:28:32,345 [salt.state       :1813][INFO    ][14993] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-04-06 03:28:32,408 [salt.state       :300 ][INFO    ][14993] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-04-06 03:28:32,408 [salt.state       :1951][INFO    ][14993] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 03:28:32.408542 duration_in_ms=63.174
2019-04-06 03:28:32,409 [salt.state       :1780][INFO    ][14993] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 03:28:32.409100
2019-04-06 03:28:32,409 [salt.state       :1813][INFO    ][14993] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-04-06 03:28:32,474 [salt.state       :300 ][INFO    ][14993] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-04-06 03:28:32,474 [salt.state       :1951][INFO    ][14993] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 03:28:32.474491 duration_in_ms=65.391
2019-04-06 03:28:32,475 [salt.state       :1780][INFO    ][14993] Running state [/root/.pgpass] at time 03:28:32.474790
2019-04-06 03:28:32,475 [salt.state       :1813][INFO    ][14993] Executing state file.managed for [/root/.pgpass]
2019-04-06 03:28:32,519 [salt.state       :300 ][INFO    ][14993] File /root/.pgpass is in the correct state
2019-04-06 03:28:32,519 [salt.state       :1951][INFO    ][14993] Completed state [/root/.pgpass] at time 03:28:32.519469 duration_in_ms=44.679
2019-04-06 03:28:32,524 [salt.state       :1780][INFO    ][14993] Running state [maas-region syncdb --noinput] at time 03:28:32.524856
2019-04-06 03:28:32,525 [salt.state       :1813][INFO    ][14993] Executing state cmd.run for [maas-region syncdb --noinput]
2019-04-06 03:28:32,525 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14993] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-04-06 03:28:35,116 [salt.state       :300 ][INFO    ][14993] {'pid': 15027, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: sites, piston3, auth, contenttypes, metadataserver, maasserver, sessions\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-04-06 03:28:35,117 [salt.state       :1951][INFO    ][14993] Completed state [maas-region syncdb --noinput] at time 03:28:35.117427 duration_in_ms=2592.571
2019-04-06 03:28:35,117 [salt.state       :2022][WARNING ][14993] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-04-06 03:28:35,120 [salt.state       :1780][INFO    ][14993] Running state [maas-regiond] at time 03:28:35.120848
2019-04-06 03:28:35,121 [salt.state       :1813][INFO    ][14993] Executing state service.running for [maas-regiond]
2019-04-06 03:28:35,122 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14993] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-04-06 03:28:35,169 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14993] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-04-06 03:28:35,186 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14993] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-04-06 03:28:35,207 [salt.state       :300 ][INFO    ][14993] The service maas-regiond is already running
2019-04-06 03:28:35,207 [salt.state       :1951][INFO    ][14993] Completed state [maas-regiond] at time 03:28:35.207744 duration_in_ms=86.897
2019-04-06 03:28:35,210 [salt.state       :1780][INFO    ][14993] Running state [bind9] at time 03:28:35.210343
2019-04-06 03:28:35,212 [salt.state       :1813][INFO    ][14993] Executing state service.running for [bind9]
2019-04-06 03:28:35,213 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14993] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-04-06 03:28:35,233 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14993] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-04-06 03:28:35,252 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14993] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-04-06 03:28:35,269 [salt.state       :300 ][INFO    ][14993] The service bind9 is already running
2019-04-06 03:28:35,270 [salt.state       :1951][INFO    ][14993] Completed state [bind9] at time 03:28:35.269960 duration_in_ms=59.617
2019-04-06 03:28:35,274 [salt.state       :1780][INFO    ][14993] Running state [apache2] at time 03:28:35.274512
2019-04-06 03:28:35,275 [salt.state       :1813][INFO    ][14993] Executing state service.running for [apache2]
2019-04-06 03:28:35,276 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14993] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-04-06 03:28:35,293 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14993] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-04-06 03:28:35,313 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14993] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-04-06 03:28:35,341 [salt.state       :300 ][INFO    ][14993] The service apache2 is already running
2019-04-06 03:28:35,342 [salt.state       :1951][INFO    ][14993] Completed state [apache2] at time 03:28:35.341866 duration_in_ms=67.353
2019-04-06 03:28:35,346 [salt.state       :1780][INFO    ][14993] Running state [maasng.wait_for_http_code] at time 03:28:35.346727
2019-04-06 03:28:35,347 [salt.state       :1813][INFO    ][14993] Executing state module.run for [maasng.wait_for_http_code]
2019-04-06 03:28:35,347 [salt.utils.decorators:613 ][WARNING ][14993] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-06 03:28:35,479 [salt.state       :300 ][INFO    ][14993] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-04-06 03:28:35,480 [salt.state       :1951][INFO    ][14993] Completed state [maasng.wait_for_http_code] at time 03:28:35.480327 duration_in_ms=133.599
2019-04-06 03:28:35,481 [salt.state       :1780][INFO    ][14993] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 03:28:35.481604
2019-04-06 03:28:35,482 [salt.state       :1813][INFO    ][14993] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-04-06 03:28:35,483 [salt.state       :300 ][INFO    ][14993] /var/lib/maas/.setup_admin exists
2019-04-06 03:28:35,483 [salt.state       :1951][INFO    ][14993] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 03:28:35.483795 duration_in_ms=2.19
2019-04-06 03:28:35,485 [salt.state       :1780][INFO    ][14993] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:28:35.485009
2019-04-06 03:28:35,485 [salt.state       :1813][INFO    ][14993] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-06 03:28:35,486 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14993] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-06 03:28:37,304 [salt.state       :300 ][INFO    ][14993] {'pid': 15049, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-06 03:28:37,306 [salt.state       :1951][INFO    ][14993] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:28:37.305931 duration_in_ms=1820.922
2019-04-06 03:28:37,315 [salt.state       :1780][INFO    ][14993] Running state [maas_region_boot_source_resources_mirror] at time 03:28:37.315442
2019-04-06 03:28:37,315 [salt.state       :1813][INFO    ][14993] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-04-06 03:28:37,402 [salt.state       :300 ][INFO    ][14993] {'changes': {}}
2019-04-06 03:28:37,403 [salt.state       :1951][INFO    ][14993] Completed state [maas_region_boot_source_resources_mirror] at time 03:28:37.403328 duration_in_ms=87.886
2019-04-06 03:28:37,404 [salt.state       :1780][INFO    ][14993] Running state [maasng.boot_resources_import] at time 03:28:37.404494
2019-04-06 03:28:37,405 [salt.state       :1813][INFO    ][14993] Executing state module.run for [maasng.boot_resources_import]
2019-04-06 03:28:37,405 [salt.utils.decorators:613 ][WARNING ][14993] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-06 03:28:37,480 [salt.loaded.ext.module.maasng:1600][INFO    ][14993] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-04-06 03:28:42,521 [salt.loaded.ext.module.maasng:1600][INFO    ][14993] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-04-06 03:28:44,718 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406032844700278
2019-04-06 03:28:44,743 [salt.minion      :1432][INFO    ][15247] Starting a new job with PID 15247
2019-04-06 03:28:44,773 [salt.minion      :1711][INFO    ][15247] Returning information for job: 20190406032844700278
2019-04-06 03:28:47,616 [salt.state       :300 ][INFO    ][14993] {'ret': True}
2019-04-06 03:28:47,617 [salt.state       :1951][INFO    ][14993] Completed state [maasng.boot_resources_import] at time 03:28:47.617087 duration_in_ms=10212.593
2019-04-06 03:28:47,617 [salt.state       :1780][INFO    ][14993] Running state [maas_region_boot_sources_selection_xenial] at time 03:28:47.617922
2019-04-06 03:28:47,618 [salt.state       :1813][INFO    ][14993] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-04-06 03:28:47,764 [salt.state       :300 ][INFO    ][14993] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-04-06 03:28:47,764 [salt.state       :1951][INFO    ][14993] Completed state [maas_region_boot_sources_selection_xenial] at time 03:28:47.764485 duration_in_ms=146.561
2019-04-06 03:28:47,765 [salt.state       :1780][INFO    ][14993] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 03:28:47.765684
2019-04-06 03:28:47,766 [salt.state       :1813][INFO    ][14993] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-04-06 03:28:47,766 [salt.utils.decorators:613 ][WARNING ][14993] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-06 03:28:47,766 [salt.loaded.ext.module.maasng:1771][INFO    ][14993] boot-sources sync initiated for ALL Rack's
2019-04-06 03:28:48,688 [salt.state       :300 ][INFO    ][14993] {'ret': True}
2019-04-06 03:28:48,688 [salt.state       :1951][INFO    ][14993] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 03:28:48.688436 duration_in_ms=922.75
2019-04-06 03:28:48,689 [salt.state       :1780][INFO    ][14993] Running state [maas.process_maas_config] at time 03:28:48.689815
2019-04-06 03:28:48,690 [salt.state       :1813][INFO    ][14993] Executing state module.run for [maas.process_maas_config]
2019-04-06 03:28:48,690 [salt.utils.decorators:613 ][WARNING ][14993] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-06 03:28:48,692 [salt.loaded.ext.module.maas:92  ][INFO    ][14993] maasconfig name=enable_http_proxy value=True
2019-04-06 03:28:48,740 [salt.loaded.ext.module.maas:92  ][INFO    ][14993] maasconfig name=upstream_dns value=8.8.8.8
2019-04-06 03:28:48,786 [salt.loaded.ext.module.maas:92  ][INFO    ][14993] maasconfig name=commissioning_distro_series value=xenial
2019-04-06 03:28:48,837 [salt.loaded.ext.module.maas:92  ][INFO    ][14993] maasconfig name=default_osystem value=ubuntu
2019-04-06 03:28:50,017 [salt.loaded.ext.module.maas:92  ][INFO    ][14993] maasconfig name=active_discovery_interval value=600
2019-04-06 03:28:50,063 [salt.loaded.ext.module.maas:92  ][INFO    ][14993] maasconfig name=dnssec_validation value=no
2019-04-06 03:28:50,104 [salt.loaded.ext.module.maas:92  ][INFO    ][14993] maasconfig name=maas_name value=mas01
2019-04-06 03:28:50,141 [salt.loaded.ext.module.maas:92  ][INFO    ][14993] maasconfig name=network_discovery value=enabled
2019-04-06 03:28:50,217 [salt.loaded.ext.module.maas:92  ][INFO    ][14993] maasconfig name=enable_third_party_drivers value=True
2019-04-06 03:28:50,276 [salt.loaded.ext.module.maas:92  ][INFO    ][14993] maasconfig name=default_storage_layout value=lvm
2019-04-06 03:28:50,313 [salt.loaded.ext.module.maas:92  ][INFO    ][14993] maasconfig name=ntp_external_only value=True
2019-04-06 03:28:50,358 [salt.loaded.ext.module.maas:92  ][INFO    ][14993] maasconfig name=disk_erase_with_secure_erase value=False
2019-04-06 03:28:50,405 [salt.loaded.ext.module.maas:92  ][INFO    ][14993] maasconfig name=default_distro_series value=xenial
2019-04-06 03:28:50,468 [salt.loaded.ext.module.maas:92  ][INFO    ][14993] maasconfig name=default_min_hwe_kernel value=ga-16.04
2019-04-06 03:28:50,568 [salt.state       :300 ][INFO    ][14993] {'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-04-06 03:28:50,568 [salt.state       :1951][INFO    ][14993] Completed state [maas.process_maas_config] at time 03:28:50.568372 duration_in_ms=1878.557
2019-04-06 03:28:50,569 [salt.state       :1780][INFO    ][14993] Running state [pxe_admin] at time 03:28:50.569067
2019-04-06 03:28:50,569 [salt.state       :1813][INFO    ][14993] Executing state maasng.fabric_present for [pxe_admin]
2019-04-06 03:28:50,624 [salt.loaded.ext.module.maasng:945 ][INFO    ][14993] [{u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'id': 0}, {u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, 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'name': u'fabric-2', u'id': 2}, {u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'erk6qb', 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'name': u'pxe_admin', u'id': 1}]
2019-04-06 03:28:50,687 [salt.loaded.ext.module.maasng:1008][WARNING ][14993] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-04-06 03:28:50,687 [salt.loaded.ext.module.maasng:1011][WARNING ][14993] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-04-06 03:28:50,749 [salt.state       :300 ][INFO    ][14993] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-04-06 03:28:50,749 [salt.state       :1951][INFO    ][14993] Completed state [pxe_admin] at time 03:28:50.749473 duration_in_ms=180.403
2019-04-06 03:28:50,750 [salt.state       :1780][INFO    ][14993] Running state [vlan 0] at time 03:28:50.750161
2019-04-06 03:28:50,750 [salt.state       :1813][INFO    ][14993] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-04-06 03:28:50,797 [salt.loaded.ext.module.maasng:945 ][INFO    ][14993] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'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'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'erk6qb', 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'class_type': u''}]
2019-04-06 03:28:50,898 [salt.loaded.ext.module.maasng:945 ][INFO    ][14993] [{u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'id': 0}, {u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, 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'name': u'fabric-2', u'id': 2}, {u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'erk6qb', 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'name': u'pxe_admin', u'id': 1}]
2019-04-06 03:28:51,111 [salt.loaded.ext.module.maasng:945 ][INFO    ][14993] [{u'id': 0, u'class_type': None, 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'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'erk6qb', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-06 03:28:51,197 [salt.state       :300 ][INFO    ][14993] {'new': 'Vlan untagged was updated'}
2019-04-06 03:28:51,198 [salt.state       :1951][INFO    ][14993] Completed state [vlan 0] at time 03:28:51.197995 duration_in_ms=447.833
2019-04-06 03:28:51,200 [salt.state       :1780][INFO    ][14993] Running state [192.168.11.0/24] at time 03:28:51.199957
2019-04-06 03:28:51,200 [salt.state       :1813][INFO    ][14993] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-04-06 03:28:51,347 [salt.loaded.ext.module.maasng:945 ][INFO    ][14993] [{u'name': u'fabric-0', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'name': u'fabric-2', u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}, {u'name': u'pxe_admin', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'erk6qb', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}]
2019-04-06 03:28:51,348 [salt.loaded.ext.module.maasng:1235][WARNING ][14993] Ignoring parameter vlan:0
2019-04-06 03:28:51,419 [salt.state       :300 ][INFO    ][14993] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-04-06 03:28:51,420 [salt.state       :1951][INFO    ][14993] Completed state [192.168.11.0/24] at time 03:28:51.420164 duration_in_ms=220.206
2019-04-06 03:28:51,421 [salt.state       :1780][INFO    ][14993] Running state [maas_create_iprange_1] at time 03:28:51.421341
2019-04-06 03:28:51,421 [salt.state       :1813][INFO    ][14993] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-04-06 03:28:51,465 [salt.state       :300 ][INFO    ][14993] Iprange maas_create_iprange_1 already exist.
2019-04-06 03:28:51,466 [salt.state       :1951][INFO    ][14993] Completed state [maas_create_iprange_1] at time 03:28:51.465938 duration_in_ms=44.598
2019-04-06 03:28:51,466 [salt.state       :1780][INFO    ][14993] Running state [vlan 0] at time 03:28:51.466415
2019-04-06 03:28:51,467 [salt.state       :1813][INFO    ][14993] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-04-06 03:28:51,523 [salt.loaded.ext.module.maasng:945 ][INFO    ][14993] [{u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'id': 0}, {u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, 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'name': u'fabric-2', u'id': 2}, {u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'erk6qb', 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'name': u'pxe_admin', u'id': 1}]
2019-04-06 03:28:51,610 [salt.loaded.ext.module.maasng:945 ][INFO    ][14993] [{u'id': 0, u'class_type': None, 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'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'erk6qb', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-06 03:28:51,815 [salt.loaded.ext.module.maasng:945 ][INFO    ][14993] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'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'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'erk6qb', 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'class_type': u''}]
2019-04-06 03:28:51,896 [salt.state       :300 ][INFO    ][14993] {'new': 'Vlan untagged was updated'}
2019-04-06 03:28:51,896 [salt.state       :1951][INFO    ][14993] Completed state [vlan 0] at time 03:28:51.896782 duration_in_ms=430.367
2019-04-06 03:28:51,897 [salt.state       :1780][INFO    ][14993] Running state [opnfv] at time 03:28:51.897421
2019-04-06 03:28:51,897 [salt.state       :1813][INFO    ][14993] Executing state maasng.sshkey_present for [opnfv]
2019-04-06 03:28:51,937 [salt.loaded.ext.module.maasng:1903][INFO    ][14993] [{u'keysource': u'', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCwbsLV1UY13y2+2b7H9h6zOFMrcnumZvKOz6oqAXgttZjoKci01cqGRU8OMjob/9xeAOP2Cmu7HOBpRxda00X57yYx4UidSgEtlbOi7LLj2DuvJScix+rgPAd19aukdCiTuMmyLZQPMzVrdBxqzupJ5zqS3djSCllnQ2myEwswcSBn0Dpusgt3bnwdsCgI6B6ZgL9aR9DLnyMPMVroI6ZZH7aP7syIy1sPEsiJd3smWEULgeRElsuK+4I3YKG1Z1J2n80lmtR2gVD5tuKLFRRxnbjAqzyCEXQBTUVRc13SAFrH0b5bzFaqfxY1wdQPzP+dBSA+t25KcjnpTAAtL86H', u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/'}]
2019-04-06 03:28:51,938 [salt.state       :300 ][INFO    ][14993] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCwbsLV1UY13y2+2b7H9h6zOFMrcnumZvKOz6oqAXgttZjoKci01cqGRU8OMjob/9xeAOP2Cmu7HOBpRxda00X57yYx4UidSgEtlbOi7LLj2DuvJScix+rgPAd19aukdCiTuMmyLZQPMzVrdBxqzupJ5zqS3djSCllnQ2myEwswcSBn0Dpusgt3bnwdsCgI6B6ZgL9aR9DLnyMPMVroI6ZZH7aP7syIy1sPEsiJd3smWEULgeRElsuK+4I3YKG1Z1J2n80lmtR2gVD5tuKLFRRxnbjAqzyCEXQBTUVRc13SAFrH0b5bzFaqfxY1wdQPzP+dBSA+t25KcjnpTAAtL86H already exist for user opnfv.
2019-04-06 03:28:51,938 [salt.state       :1951][INFO    ][14993] Completed state [opnfv] at time 03:28:51.938303 duration_in_ms=40.882
2019-04-06 03:28:51,944 [salt.minion      :1711][INFO    ][14993] Returning information for job: 20190406032829595839
2019-04-06 03:28:52,705 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command state.apply with jid 20190406032852698698
2019-04-06 03:28:52,724 [salt.minion      :1432][INFO    ][15484] Starting a new job with PID 15484
2019-04-06 03:28:58,684 [salt.state       :915 ][INFO    ][15484] Loading fresh modules for state activity
2019-04-06 03:28:58,778 [salt.state       :1780][INFO    ][15484] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:28:58.778346
2019-04-06 03:28:58,778 [salt.state       :1813][INFO    ][15484] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-06 03:28:58,781 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15484] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-06 03:29:00,604 [salt.state       :300 ][INFO    ][15484] {'pid': 15512, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-06 03:29:00,605 [salt.state       :1951][INFO    ][15484] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:29:00.605105 duration_in_ms=1826.757
2019-04-06 03:29:00,608 [salt.state       :1780][INFO    ][15484] Running state [maas.process_machines] at time 03:29:00.608295
2019-04-06 03:29:00,609 [salt.state       :1813][INFO    ][15484] Executing state module.run for [maas.process_machines]
2019-04-06 03:29:00,610 [salt.utils.decorators:613 ][WARNING ][15484] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-06 03:29:01,088 [salt.loaded.ext.module.maas:412 ][WARNING ][15484] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-06 03:29:01,089 [salt.loaded.ext.module.maas:92  ][INFO    ][15484] 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=ra76ks architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-06 03:29:02,106 [salt.loaded.ext.module.maas:412 ][WARNING ][15484] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-06 03:29:02,107 [salt.loaded.ext.module.maas:92  ][INFO    ][15484] 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=nhtt8f architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-06 03:29:03,103 [salt.loaded.ext.module.maas:412 ][WARNING ][15484] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-06 03:29:03,104 [salt.loaded.ext.module.maas:92  ][INFO    ][15484] 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=gnch4x architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-06 03:29:04,108 [salt.loaded.ext.module.maas:412 ][WARNING ][15484] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-06 03:29:04,109 [salt.loaded.ext.module.maas:92  ][INFO    ][15484] 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=xbggya architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-06 03:29:05,096 [salt.loaded.ext.module.maas:412 ][WARNING ][15484] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-06 03:29:05,097 [salt.loaded.ext.module.maas:92  ][INFO    ][15484] 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=mcs773 architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-06 03:29:06,058 [salt.state       :300 ][INFO    ][15484] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-04-06 03:29:06,058 [salt.state       :1951][INFO    ][15484] Completed state [maas.process_machines] at time 03:29:06.058591 duration_in_ms=5450.296
2019-04-06 03:29:06,066 [salt.minion      :1711][INFO    ][15484] Returning information for job: 20190406032852698698
2019-04-06 03:29:39,990 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command state.apply with jid 20190406032939980214
2019-04-06 03:29:40,006 [salt.minion      :1432][INFO    ][15784] Starting a new job with PID 15784
2019-04-06 03:29:45,911 [salt.state       :915 ][INFO    ][15784] Loading fresh modules for state activity
2019-04-06 03:29:45,996 [salt.state       :1780][INFO    ][15784] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:29:45.996528
2019-04-06 03:29:45,997 [salt.state       :1813][INFO    ][15784] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-06 03:29:45,999 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15784] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-06 03:29:47,877 [salt.state       :300 ][INFO    ][15784] {'pid': 15805, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-06 03:29:47,880 [salt.state       :1951][INFO    ][15784] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:29:47.880061 duration_in_ms=1883.533
2019-04-06 03:29:47,884 [salt.state       :1780][INFO    ][15784] Running state [maas.wait_for_machine_status] at time 03:29:47.884271
2019-04-06 03:29:47,884 [salt.state       :1813][INFO    ][15784] Executing state module.run for [maas.wait_for_machine_status]
2019-04-06 03:29:47,885 [salt.utils.decorators:613 ][WARNING ][15784] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-06 03:29:50,587 [salt.state       :300 ][INFO    ][15784] {'ret': True}
2019-04-06 03:29:50,588 [salt.state       :1951][INFO    ][15784] Completed state [maas.wait_for_machine_status] at time 03:29:50.588304 duration_in_ms=2704.03
2019-04-06 03:29:50,592 [salt.minion      :1711][INFO    ][15784] Returning information for job: 20190406032939980214
2019-04-06 03:29:51,282 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command state.apply with jid 20190406032951265744
2019-04-06 03:29:51,305 [salt.minion      :1432][INFO    ][15827] Starting a new job with PID 15827
2019-04-06 03:29:52,292 [salt.state       :915 ][INFO    ][15827] Loading fresh modules for state activity
2019-04-06 03:29:52,439 [salt.state       :1780][INFO    ][15827] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:29:52.439161
2019-04-06 03:29:52,439 [salt.state       :1813][INFO    ][15827] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-06 03:29:52,442 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15827] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-06 03:29:54,274 [salt.state       :300 ][INFO    ][15827] {'pid': 15834, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-06 03:29:54,276 [salt.state       :1951][INFO    ][15827] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:29:54.275824 duration_in_ms=1836.663
2019-04-06 03:29:54,280 [salt.state       :1780][INFO    ][15827] Running state [maas_machines_storage_cmp002_lvm] at time 03:29:54.280335
2019-04-06 03:29:54,281 [salt.state       :1813][INFO    ][15827] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-04-06 03:29:54,935 [salt.state       :300 ][INFO    ][15827] Machine cmp002 is not in Ready state.
2019-04-06 03:29:54,936 [salt.state       :1951][INFO    ][15827] Completed state [maas_machines_storage_cmp002_lvm] at time 03:29:54.936037 duration_in_ms=655.701
2019-04-06 03:29:54,936 [salt.state       :1780][INFO    ][15827] Running state [maas_machines_storage_cmp001_lvm] at time 03:29:54.936515
2019-04-06 03:29:54,936 [salt.state       :1813][INFO    ][15827] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-04-06 03:29:55,559 [salt.state       :300 ][INFO    ][15827] Machine cmp001 is not in Ready state.
2019-04-06 03:29:55,559 [salt.state       :1951][INFO    ][15827] Completed state [maas_machines_storage_cmp001_lvm] at time 03:29:55.559700 duration_in_ms=623.184
2019-04-06 03:29:55,562 [salt.minion      :1711][INFO    ][15827] Returning information for job: 20190406032951265744
2019-04-06 03:29:56,242 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command state.apply with jid 20190406032956230603
2019-04-06 03:29:56,264 [salt.minion      :1432][INFO    ][15845] Starting a new job with PID 15845
2019-04-06 03:29:57,235 [salt.state       :915 ][INFO    ][15845] Loading fresh modules for state activity
2019-04-06 03:29:57,329 [salt.state       :1780][INFO    ][15845] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:29:57.329004
2019-04-06 03:29:57,329 [salt.state       :1813][INFO    ][15845] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-06 03:29:57,331 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15845] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-06 03:29:59,181 [salt.state       :300 ][INFO    ][15845] {'pid': 15856, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-06 03:29:59,182 [salt.state       :1951][INFO    ][15845] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:29:59.182136 duration_in_ms=1853.131
2019-04-06 03:29:59,185 [salt.state       :1780][INFO    ][15845] Running state [maas.deploy_machines] at time 03:29:59.185592
2019-04-06 03:29:59,186 [salt.state       :1813][INFO    ][15845] Executing state module.run for [maas.deploy_machines]
2019-04-06 03:29:59,188 [salt.utils.decorators:613 ][WARNING ][15845] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-06 03:29:59,733 [salt.loaded.ext.module.maas:684 ][INFO    ][15845] deploymachines hwe_kernel=ga-16.04 system_id=mcs773 distro_series=xenial
2019-04-06 03:30:02,049 [salt.state       :300 ][INFO    ][15845] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-04-06 03:30:02,049 [salt.state       :1951][INFO    ][15845] Completed state [maas.deploy_machines] at time 03:30:02.049350 duration_in_ms=2863.757
2019-04-06 03:30:02,052 [salt.minion      :1711][INFO    ][15845] Returning information for job: 20190406032956230603
2019-04-06 03:30:02,734 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command state.apply with jid 20190406033002723211
2019-04-06 03:30:02,754 [salt.minion      :1432][INFO    ][15915] Starting a new job with PID 15915
2019-04-06 03:30:08,644 [salt.state       :915 ][INFO    ][15915] Loading fresh modules for state activity
2019-04-06 03:30:08,736 [salt.state       :1780][INFO    ][15915] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:30:08.736832
2019-04-06 03:30:08,737 [salt.state       :1813][INFO    ][15915] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-06 03:30:08,739 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15915] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-06 03:30:10,640 [salt.state       :300 ][INFO    ][15915] {'pid': 15928, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-06 03:30:10,641 [salt.state       :1951][INFO    ][15915] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:30:10.641184 duration_in_ms=1904.351
2019-04-06 03:30:10,644 [salt.state       :1780][INFO    ][15915] Running state [maas.wait_for_machine_status] at time 03:30:10.644660
2019-04-06 03:30:10,645 [salt.state       :1813][INFO    ][15915] Executing state module.run for [maas.wait_for_machine_status]
2019-04-06 03:30:10,646 [salt.utils.decorators:613 ][WARNING ][15915] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-06 03:30:13,293 [salt.loaded.ext.module.maas:1023][INFO    ][15915] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.36886001s left)
2019-04-06 03:30:17,803 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406033017793495
2019-04-06 03:30:17,828 [salt.minion      :1432][INFO    ][15945] Starting a new job with PID 15945
2019-04-06 03:30:17,856 [salt.minion      :1711][INFO    ][15945] Returning information for job: 20190406033017793495
2019-04-06 03:30:45,890 [salt.loaded.ext.module.maas:1023][INFO    ][15915] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.77174807s left)
2019-04-06 03:30:47,976 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406033047917116
2019-04-06 03:30:47,998 [salt.minion      :1432][INFO    ][16026] Starting a new job with PID 16026
2019-04-06 03:30:48,028 [salt.minion      :1711][INFO    ][16026] Returning information for job: 20190406033047917116
2019-04-06 03:31:17,956 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406033117948424
2019-04-06 03:31:17,971 [salt.minion      :1432][INFO    ][16046] Starting a new job with PID 16046
2019-04-06 03:31:17,999 [salt.minion      :1711][INFO    ][16046] Returning information for job: 20190406033117948424
2019-04-06 03:31:18,552 [salt.loaded.ext.module.maas:1023][INFO    ][15915] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2182.10933805s left)
2019-04-06 03:31:47,997 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406033147987490
2019-04-06 03:31:48,020 [salt.minion      :1432][INFO    ][16139] Starting a new job with PID 16139
2019-04-06 03:31:48,048 [salt.minion      :1711][INFO    ][16139] Returning information for job: 20190406033147987490
2019-04-06 03:31:51,490 [salt.loaded.ext.module.maas:1023][INFO    ][15915] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2149.17143607s left)
2019-04-06 03:32:18,212 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406033218195358
2019-04-06 03:32:18,228 [salt.minion      :1432][INFO    ][16164] Starting a new job with PID 16164
2019-04-06 03:32:18,254 [salt.minion      :1711][INFO    ][16164] Returning information for job: 20190406033218195358
2019-04-06 03:32:24,189 [salt.loaded.ext.module.maas:1023][INFO    ][15915] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2116.4726882s left)
2019-04-06 03:32:48,413 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406033248404143
2019-04-06 03:32:48,429 [salt.minion      :1432][INFO    ][16248] Starting a new job with PID 16248
2019-04-06 03:32:48,456 [salt.minion      :1711][INFO    ][16248] Returning information for job: 20190406033248404143
2019-04-06 03:32:56,838 [salt.loaded.ext.module.maas:1023][INFO    ][15915] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2083.8241601s left)
2019-04-06 03:33:18,598 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406033318591094
2019-04-06 03:33:18,620 [salt.minion      :1432][INFO    ][16269] Starting a new job with PID 16269
2019-04-06 03:33:18,648 [salt.minion      :1711][INFO    ][16269] Returning information for job: 20190406033318591094
2019-04-06 03:33:29,565 [salt.loaded.ext.module.maas:1023][INFO    ][15915] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2051.09690619s left)
2019-04-06 03:33:48,643 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406033348635693
2019-04-06 03:33:48,667 [salt.minion      :1432][INFO    ][16352] Starting a new job with PID 16352
2019-04-06 03:33:48,696 [salt.minion      :1711][INFO    ][16352] Returning information for job: 20190406033348635693
2019-04-06 03:34:02,257 [salt.loaded.ext.module.maas:1023][INFO    ][15915] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2018.40444708s left)
2019-04-06 03:34:18,853 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406033418844146
2019-04-06 03:34:18,870 [salt.minion      :1432][INFO    ][16398] Starting a new job with PID 16398
2019-04-06 03:34:18,895 [salt.minion      :1711][INFO    ][16398] Returning information for job: 20190406033418844146
2019-04-06 03:34:35,075 [salt.loaded.ext.module.maas:1023][INFO    ][15915] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1985.58645701s left)
2019-04-06 03:34:49,046 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406033449038268
2019-04-06 03:34:49,070 [salt.minion      :1432][INFO    ][16507] Starting a new job with PID 16507
2019-04-06 03:34:49,102 [salt.minion      :1711][INFO    ][16507] Returning information for job: 20190406033449038268
2019-04-06 03:35:07,820 [salt.loaded.ext.module.maas:1023][INFO    ][15915] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1952.84155512s left)
2019-04-06 03:35:19,255 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406033519247619
2019-04-06 03:35:19,279 [salt.minion      :1432][INFO    ][16536] Starting a new job with PID 16536
2019-04-06 03:35:19,308 [salt.minion      :1711][INFO    ][16536] Returning information for job: 20190406033519247619
2019-04-06 03:35:41,534 [salt.loaded.ext.module.maas:1023][INFO    ][15915] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1919.12810421s left)
2019-04-06 03:35:49,366 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406033549358752
2019-04-06 03:35:49,390 [salt.minion      :1432][INFO    ][16773] Starting a new job with PID 16773
2019-04-06 03:35:49,421 [salt.minion      :1711][INFO    ][16773] Returning information for job: 20190406033549358752
2019-04-06 03:36:14,404 [salt.loaded.ext.module.maas:1023][INFO    ][15915] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1886.25767112s left)
2019-04-06 03:36:19,449 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406033619441902
2019-04-06 03:36:19,472 [salt.minion      :1432][INFO    ][16794] Starting a new job with PID 16794
2019-04-06 03:36:19,500 [salt.minion      :1711][INFO    ][16794] Returning information for job: 20190406033619441902
2019-04-06 03:36:47,173 [salt.loaded.ext.module.maas:1023][INFO    ][15915] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1853.48841619s left)
2019-04-06 03:36:49,474 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406033649458979
2019-04-06 03:36:49,502 [salt.minion      :1432][INFO    ][16898] Starting a new job with PID 16898
2019-04-06 03:36:49,537 [salt.minion      :1711][INFO    ][16898] Returning information for job: 20190406033649458979
2019-04-06 03:37:19,560 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406033719551806
2019-04-06 03:37:19,579 [salt.minion      :1432][INFO    ][16921] Starting a new job with PID 16921
2019-04-06 03:37:19,607 [salt.minion      :1711][INFO    ][16921] Returning information for job: 20190406033719551806
2019-04-06 03:37:20,056 [salt.loaded.ext.module.maas:1023][INFO    ][15915] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1820.60535121s left)
2019-04-06 03:37:49,781 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406033749770608
2019-04-06 03:37:49,799 [salt.minion      :1432][INFO    ][17075] Starting a new job with PID 17075
2019-04-06 03:37:49,829 [salt.minion      :1711][INFO    ][17075] Returning information for job: 20190406033749770608
2019-04-06 03:37:52,975 [salt.loaded.ext.module.maas:1023][INFO    ][15915] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1787.6865921s left)
2019-04-06 03:38:19,795 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406033819787787
2019-04-06 03:38:19,820 [salt.minion      :1432][INFO    ][17102] Starting a new job with PID 17102
2019-04-06 03:38:19,850 [salt.minion      :1711][INFO    ][17102] Returning information for job: 20190406033819787787
2019-04-06 03:38:25,818 [salt.loaded.ext.module.maas:1023][INFO    ][15915] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1754.84351516s left)
2019-04-06 03:38:49,834 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406033849825938
2019-04-06 03:38:49,856 [salt.minion      :1432][INFO    ][17274] Starting a new job with PID 17274
2019-04-06 03:38:49,893 [salt.minion      :1711][INFO    ][17274] Returning information for job: 20190406033849825938
2019-04-06 03:38:58,701 [salt.loaded.ext.module.maas:1023][INFO    ][15915] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1721.96064806s left)
2019-04-06 03:39:19,866 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406033919856189
2019-04-06 03:39:19,881 [salt.minion      :1432][INFO    ][17299] Starting a new job with PID 17299
2019-04-06 03:39:19,908 [salt.minion      :1711][INFO    ][17299] Returning information for job: 20190406033919856189
2019-04-06 03:39:31,436 [salt.loaded.ext.module.maas:1023][INFO    ][15915] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1689.22620702s left)
2019-04-06 03:39:49,893 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406033949885600
2019-04-06 03:39:49,917 [salt.minion      :1432][INFO    ][17380] Starting a new job with PID 17380
2019-04-06 03:39:49,946 [salt.minion      :1711][INFO    ][17380] Returning information for job: 20190406033949885600
2019-04-06 03:40:04,177 [salt.loaded.ext.module.maas:1023][INFO    ][15915] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1656.48508406s left)
2019-04-06 03:40:19,929 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406034019912860
2019-04-06 03:40:19,953 [salt.minion      :1432][INFO    ][17401] Starting a new job with PID 17401
2019-04-06 03:40:19,980 [salt.minion      :1711][INFO    ][17401] Returning information for job: 20190406034019912860
2019-04-06 03:40:37,043 [salt.loaded.ext.module.maas:1023][INFO    ][15915] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1623.61849117s left)
2019-04-06 03:40:49,973 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406034049960515
2019-04-06 03:40:49,997 [salt.minion      :1432][INFO    ][17481] Starting a new job with PID 17481
2019-04-06 03:40:50,024 [salt.minion      :1711][INFO    ][17481] Returning information for job: 20190406034049960515
2019-04-06 03:41:09,929 [salt.loaded.ext.module.maas:1023][INFO    ][15915] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1590.73254204s left)
2019-04-06 03:41:20,012 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406034119997647
2019-04-06 03:41:20,034 [salt.minion      :1432][INFO    ][17502] Starting a new job with PID 17502
2019-04-06 03:41:20,063 [salt.minion      :1711][INFO    ][17502] Returning information for job: 20190406034119997647
2019-04-06 03:41:44,479 [salt.loaded.ext.module.maas:1023][INFO    ][15915] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1556.18281507s left)
2019-04-06 03:41:50,065 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406034150058110
2019-04-06 03:41:50,089 [salt.minion      :1432][INFO    ][17750] Starting a new job with PID 17750
2019-04-06 03:41:50,121 [salt.minion      :1711][INFO    ][17750] Returning information for job: 20190406034150058110
2019-04-06 03:42:17,360 [salt.loaded.ext.module.maas:1023][INFO    ][15915] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1523.3021872s left)
2019-04-06 03:42:20,229 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command saltutil.find_job with jid 20190406034220215906
2019-04-06 03:42:20,252 [salt.minion      :1432][INFO    ][17794] Starting a new job with PID 17794
2019-04-06 03:42:20,280 [salt.minion      :1711][INFO    ][17794] Returning information for job: 20190406034220215906
2019-04-06 03:42:48,185 [salt.utils.schedule:1377][INFO    ][6026] Running scheduled job: __mine_interval
2019-04-06 03:42:50,216 [salt.state       :300 ][INFO    ][15915] {'ret': True}
2019-04-06 03:42:50,217 [salt.state       :1951][INFO    ][15915] Completed state [maas.wait_for_machine_status] at time 03:42:50.217276 duration_in_ms=759572.614
2019-04-06 03:42:50,224 [salt.minion      :1711][INFO    ][15915] Returning information for job: 20190406033002723211
2019-04-06 04:42:48,185 [salt.utils.schedule:1377][INFO    ][6026] Running scheduled job: __mine_interval
2019-04-06 05:27:22,180 [salt.minion      :1308][INFO    ][6026] User sudo_ubuntu Executing command cp.push_dir with jid 20190406052722168260
2019-04-06 05:27:22,202 [salt.minion      :1432][INFO    ][28480] Starting a new job with PID 28480
