2019-03-20 08:14:22,157 [salt.utils.decorators:613 ][WARNING ][2358] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-20 08:14:23,218 [salt.utils.decorators:613 ][WARNING ][2358] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-20 08:14:26,669 [salt.loaded.int.states.file:2298][WARNING ][2622] 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-03-20 08:14:50,674 [salt.state       :2022][WARNING ][2965] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-03-20 08:14:53,770 [salt.utils.decorators:613 ][WARNING ][2965] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-20 08:15:04,247 [salt.utils.decorators:613 ][WARNING ][2965] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-20 08:15:32,492 [salt.utils.decorators:613 ][WARNING ][2965] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-20 08:15:33,441 [salt.utils.decorators:613 ][WARNING ][2965] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-20 08:15:35,519 [salt.loaded.ext.module.maasng:1008][WARNING ][2965] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-03-20 08:15:35,519 [salt.loaded.ext.module.maasng:1011][WARNING ][2965] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-03-20 08:15:36,192 [salt.loaded.ext.module.maasng:1235][WARNING ][2965] Ignoring parameter vlan:0
2019-03-20 08:15:39,886 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5532] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-03-20 08:15:39,911 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5532] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-03-20 08:15:39,950 [salt.utils.parsers:1051][WARNING ][387] Minion received a SIGTERM. Exiting.
2019-03-20 08:15:41,063 [salt.cli.daemons :293 ][INFO    ][5608] Setting up the Salt Minion "mas01.mcp-ovs-ha.local"
2019-03-20 08:15:41,182 [salt.cli.daemons :82  ][INFO    ][5608] Starting up the Salt Minion
2019-03-20 08:15:41,182 [salt.utils.event :1017][INFO    ][5608] Starting pull socket on /var/run/salt/minion/minion_event_501f9ec045_pull.ipc
2019-03-20 08:15:42,252 [salt.minion      :976 ][INFO    ][5608] Creating minion process manager
2019-03-20 08:15:44,044 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][5608] Executing command ['date', '+%z'] in directory '/root'
2019-03-20 08:15:44,065 [salt.utils.schedule:568 ][INFO    ][5608] Updating job settings for scheduled job: __mine_interval
2019-03-20 08:15:44,069 [salt.minion      :1108][INFO    ][5608] Added mine.update to scheduler
2019-03-20 08:15:44,074 [salt.minion      :1975][INFO    ][5608] Minion is starting as user 'root'
2019-03-20 08:15:44,089 [salt.minion      :2336][INFO    ][5608] Minion is ready to receive requests!
2019-03-20 08:15:48,233 [salt.utils.decorators:613 ][WARNING ][5541] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-20 08:15:48,280 [salt.loaded.ext.module.maas:412 ][WARNING ][5541] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-20 08:15:49,629 [salt.loaded.ext.module.maas:412 ][WARNING ][5541] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-20 08:15:50,952 [salt.loaded.ext.module.maas:412 ][WARNING ][5541] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-20 08:15:52,303 [salt.loaded.ext.module.maas:412 ][WARNING ][5541] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-20 08:15:52,940 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320081552927400
2019-03-20 08:15:52,965 [salt.minion      :1432][INFO    ][5899] Starting a new job with PID 5899
2019-03-20 08:15:52,998 [salt.minion      :1711][INFO    ][5899] Returning information for job: 20190320081552927400
2019-03-20 08:15:53,622 [salt.loaded.ext.module.maas:412 ][WARNING ][5541] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-20 08:16:26,131 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command state.apply with jid 20190320081626116074
2019-03-20 08:16:26,149 [salt.minion      :1432][INFO    ][6062] Starting a new job with PID 6062
2019-03-20 08:16:33,760 [salt.state       :915 ][INFO    ][6062] Loading fresh modules for state activity
2019-03-20 08:16:33,813 [salt.fileclient  :1219][INFO    ][6062] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-03-20 08:16:33,861 [salt.state       :1780][INFO    ][6062] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:16:33.861121
2019-03-20 08:16:33,861 [salt.state       :1813][INFO    ][6062] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-20 08:16:33,863 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6062] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-20 08:16:35,556 [salt.state       :300 ][INFO    ][6062] {'pid': 6069, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-20 08:16:35,557 [salt.state       :1951][INFO    ][6062] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:16:35.557199 duration_in_ms=1696.077
2019-03-20 08:16:35,561 [salt.state       :1780][INFO    ][6062] Running state [maas.wait_for_machine_status] at time 08:16:35.560974
2019-03-20 08:16:35,561 [salt.state       :1813][INFO    ][6062] Executing state module.run for [maas.wait_for_machine_status]
2019-03-20 08:16:35,562 [salt.utils.decorators:613 ][WARNING ][6062] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-20 08:16:36,312 [salt.loaded.ext.module.maas:1023][INFO    ][6062] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.26299405s left)
2019-03-20 08:16:41,156 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320081641147588
2019-03-20 08:16:41,179 [salt.minion      :1432][INFO    ][6095] Starting a new job with PID 6095
2019-03-20 08:16:41,201 [salt.minion      :1711][INFO    ][6095] Returning information for job: 20190320081641147588
2019-03-20 08:17:07,189 [salt.loaded.ext.module.maas:1023][INFO    ][6062] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.38588715s left)
2019-03-20 08:17:11,204 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320081711196434
2019-03-20 08:17:11,227 [salt.minion      :1432][INFO    ][6166] Starting a new job with PID 6166
2019-03-20 08:17:11,248 [salt.minion      :1711][INFO    ][6166] Returning information for job: 20190320081711196434
2019-03-20 08:17:38,038 [salt.loaded.ext.module.maas:1023][INFO    ][6062] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.53739119s left)
2019-03-20 08:17:41,232 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320081741219146
2019-03-20 08:17:41,255 [salt.minion      :1432][INFO    ][6195] Starting a new job with PID 6195
2019-03-20 08:17:41,277 [salt.minion      :1711][INFO    ][6195] Returning information for job: 20190320081741219146
2019-03-20 08:18:08,816 [salt.loaded.ext.module.maas:1023][INFO    ][6062] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.75882912s left)
2019-03-20 08:18:11,431 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320081811422521
2019-03-20 08:18:11,455 [salt.minion      :1432][INFO    ][6267] Starting a new job with PID 6267
2019-03-20 08:18:11,478 [salt.minion      :1711][INFO    ][6267] Returning information for job: 20190320081811422521
2019-03-20 08:18:39,797 [salt.loaded.ext.module.maas:1023][INFO    ][6062] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.77814102s left)
2019-03-20 08:18:41,501 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320081841485479
2019-03-20 08:18:41,518 [salt.minion      :1432][INFO    ][6324] Starting a new job with PID 6324
2019-03-20 08:18:41,541 [salt.minion      :1711][INFO    ][6324] Returning information for job: 20190320081841485479
2019-03-20 08:19:10,828 [salt.loaded.ext.module.maas:1023][INFO    ][6062] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.74704504s left)
2019-03-20 08:19:11,586 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320081911574305
2019-03-20 08:19:11,610 [salt.minion      :1432][INFO    ][6505] Starting a new job with PID 6505
2019-03-20 08:19:11,630 [salt.minion      :1711][INFO    ][6505] Returning information for job: 20190320081911574305
2019-03-20 08:19:41,788 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320081941777028
2019-03-20 08:19:41,804 [salt.minion      :1432][INFO    ][6587] Starting a new job with PID 6587
2019-03-20 08:19:41,827 [salt.minion      :1711][INFO    ][6587] Returning information for job: 20190320081941777028
2019-03-20 08:19:41,920 [salt.loaded.ext.module.maas:1023][INFO    ][6062] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1313.6549561s left)
2019-03-20 08:20:11,914 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320082011903933
2019-03-20 08:20:11,929 [salt.minion      :1432][INFO    ][6926] Starting a new job with PID 6926
2019-03-20 08:20:11,952 [salt.minion      :1711][INFO    ][6926] Returning information for job: 20190320082011903933
2019-03-20 08:20:13,086 [salt.loaded.ext.module.maas:1023][INFO    ][6062] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1282.48911619s left)
2019-03-20 08:20:42,118 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320082042108634
2019-03-20 08:20:42,140 [salt.minion      :1432][INFO    ][7024] Starting a new job with PID 7024
2019-03-20 08:20:42,162 [salt.minion      :1711][INFO    ][7024] Returning information for job: 20190320082042108634
2019-03-20 08:20:44,841 [salt.loaded.ext.module.maas:1023][INFO    ][6062] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1250.73431802s left)
2019-03-20 08:21:12,187 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320082112170671
2019-03-20 08:21:12,203 [salt.minion      :1432][INFO    ][7375] Starting a new job with PID 7375
2019-03-20 08:21:12,227 [salt.minion      :1711][INFO    ][7375] Returning information for job: 20190320082112170671
2019-03-20 08:21:16,554 [salt.loaded.ext.module.maas:1023][INFO    ][6062] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1219.02118516s left)
2019-03-20 08:21:42,201 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320082142191505
2019-03-20 08:21:42,226 [salt.minion      :1432][INFO    ][7561] Starting a new job with PID 7561
2019-03-20 08:21:42,248 [salt.minion      :1711][INFO    ][7561] Returning information for job: 20190320082142191505
2019-03-20 08:21:48,763 [salt.loaded.ext.module.maas:1023][INFO    ][6062] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1186.81164002s left)
2019-03-20 08:22:12,225 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320082212216599
2019-03-20 08:22:12,244 [salt.minion      :1432][INFO    ][7799] Starting a new job with PID 7799
2019-03-20 08:22:12,266 [salt.minion      :1711][INFO    ][7799] Returning information for job: 20190320082212216599
2019-03-20 08:22:20,557 [salt.loaded.ext.module.maas:1023][INFO    ][6062] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1155.01751518s left)
2019-03-20 08:22:42,249 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320082242241305
2019-03-20 08:22:42,274 [salt.minion      :1432][INFO    ][7958] Starting a new job with PID 7958
2019-03-20 08:22:42,296 [salt.minion      :1711][INFO    ][7958] Returning information for job: 20190320082242241305
2019-03-20 08:22:53,514 [salt.state       :300 ][INFO    ][6062] {'ret': True}
2019-03-20 08:22:53,514 [salt.state       :1951][INFO    ][6062] Completed state [maas.wait_for_machine_status] at time 08:22:53.514614 duration_in_ms=377953.64
2019-03-20 08:22:53,517 [salt.minion      :1711][INFO    ][6062] Returning information for job: 20190320081626116074
2019-03-20 08:22:54,154 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command state.apply with jid 20190320082254140122
2019-03-20 08:22:54,179 [salt.minion      :1432][INFO    ][8006] Starting a new job with PID 8006
2019-03-20 08:23:02,380 [salt.state       :915 ][INFO    ][8006] Loading fresh modules for state activity
2019-03-20 08:23:02,434 [salt.fileclient  :1219][INFO    ][8006] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-03-20 08:23:02,527 [salt.state       :1780][INFO    ][8006] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:23:02.527638
2019-03-20 08:23:02,527 [salt.state       :1813][INFO    ][8006] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-20 08:23:02,529 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8006] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-20 08:23:04,446 [salt.state       :300 ][INFO    ][8006] {'pid': 8098, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-20 08:23:04,447 [salt.state       :1951][INFO    ][8006] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:23:04.447463 duration_in_ms=1919.826
2019-03-20 08:23:04,449 [salt.state       :1780][INFO    ][8006] Running state [maas_machines_storage_cmp002_lvm] at time 08:23:04.449176
2019-03-20 08:23:04,449 [salt.state       :1813][INFO    ][8006] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-03-20 08:23:05,628 [salt.loaded.ext.module.maasng:610 ][INFO    ][8006] q8rgkr
2019-03-20 08:23:05,629 [salt.loaded.ext.module.maasng:626 ][INFO    ][8006] sda
2019-03-20 08:23:06,198 [salt.loaded.ext.module.maasng:361 ][INFO    ][8006] q8rgkr
2019-03-20 08:23:06,283 [salt.loaded.ext.module.maasng:367 ][INFO    ][8006] [{u'size': 800109715456, u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'q8rgkr', 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'resource_uri': u'/MAAS/api/2.0/nodes/q8rgkr/blockdevices/1/', u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'edace4d3-c64a-4e71-b54f-9222fda6ec31', u'resource_uri': u'/MAAS/api/2.0/nodes/q8rgkr/blockdevices/1/partition/1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'q8rgkr', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'c392a6a8-4071-417a-ac4a-f11c7e2eac16', 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'size': 800097042432, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'60af7cbe-e515-4455-8812-73d8ebf1d044', u'fstype': u'ext4'}, u'uuid': u'0586ff25-29d3-4da0-96cc-415940b4a4c9', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'q8rgkr', 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'resource_uri': u'/MAAS/api/2.0/nodes/q8rgkr/blockdevices/3/', u'used_size': 800097042432, u'id': 3, u'serial': None, u'partitions': []}]
2019-03-20 08:23:06,283 [salt.loaded.ext.module.maasng:632 ][INFO    ][8006] vgroot
2019-03-20 08:23:06,283 [salt.loaded.ext.module.maasng:635 ][INFO    ][8006] lvroot
2019-03-20 08:23:06,284 [salt.loaded.ext.module.maasng:639 ][INFO    ][8006] 107374182400
2019-03-20 08:23:06,873 [salt.loaded.ext.module.maasng:645 ][INFO    ][8006] {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'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', 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'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/q8rgkr/blockdevices/1/', u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'q8rgkr', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'partitions': [{u'size': 800101236736, u'uuid': u'0e6ade28-feaf-47f3-b577-e42f934639f2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'q8rgkr', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'faf9f1d9-bc93-4c43-8255-fb77d5f0d8a1', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/q8rgkr/blockdevices/1/partition/6'}]}, u'blockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/q8rgkr/blockdevices/1/', u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'q8rgkr', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'partitions': [{u'size': 800101236736, u'uuid': u'0e6ade28-feaf-47f3-b577-e42f934639f2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'q8rgkr', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'faf9f1d9-bc93-4c43-8255-fb77d5f0d8a1', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/q8rgkr/blockdevices/1/partition/6'}]}, {u'size': 107374182400, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/q8rgkr/blockdevices/11/', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'uuid': u'865ee77f-6541-452f-9866-c0345f63889b', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'q8rgkr', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'4d3db6d2-91ae-45b4-9cb3-3b447e724fb9', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 11, u'partitions': []}], u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'description': u''}, u'current_commissioning_result_id': 2, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'q8rgkr', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/q8rgkr/blockdevices/11/', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'uuid': u'865ee77f-6541-452f-9866-c0345f63889b', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'q8rgkr', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'4d3db6d2-91ae-45b4-9cb3-3b447e724fb9', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 11, u'partitions': []}], u'min_hwe_kernel': u'hwe-16.04', u'status': 4, u'bcaches': [], u'cpu_count': 40, u'power_state': u'off', u'physicalblockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/q8rgkr/blockdevices/1/', u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'q8rgkr', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'partitions': [{u'size': 800101236736, u'uuid': u'0e6ade28-feaf-47f3-b577-e42f934639f2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'q8rgkr', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'faf9f1d9-bc93-4c43-8255-fb77d5f0d8a1', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/q8rgkr/blockdevices/1/partition/6'}]}], u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'q8rgkr', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'commissioning_status_name': u'Passed', u'current_testing_result_id': 3, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'node_type': 0, 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'hswkeq', 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.38', u'id': 18, 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'hswkeq', 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'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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'hswkeq', 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.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'q8rgkr', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/q8rgkr/interfaces/4/'}, u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'hswkeq', 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.38', u'id': 18, 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'hswkeq', 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'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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'hswkeq', 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.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'q8rgkr', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/q8rgkr/interfaces/4/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:51', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'q8rgkr', u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/q8rgkr/interfaces/14/'}, {u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 19}], 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:48', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'q8rgkr', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/q8rgkr/interfaces/13/'}, {u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 20}], 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:49', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'q8rgkr', u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/q8rgkr/interfaces/15/'}, {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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'hswkeq', 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': 21, 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'hswkeq', 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'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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'hswkeq', 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.40'}], u'mac_address': u'9c:b6:54:8a:10:1c', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'q8rgkr', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/q8rgkr/interfaces/12/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:50', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'q8rgkr', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/q8rgkr/interfaces/11/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/q8rgkr/'}
2019-03-20 08:23:06,875 [salt.state       :300 ][INFO    ][8006] {'new': {'storage_layout': 'lvm'}}
2019-03-20 08:23:06,875 [salt.state       :1951][INFO    ][8006] Completed state [maas_machines_storage_cmp002_lvm] at time 08:23:06.875641 duration_in_ms=2426.464
2019-03-20 08:23:06,876 [salt.state       :1780][INFO    ][8006] Running state [maas_machines_storage_cmp001_lvm] at time 08:23:06.876024
2019-03-20 08:23:06,876 [salt.state       :1813][INFO    ][8006] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-03-20 08:23:07,998 [salt.loaded.ext.module.maasng:610 ][INFO    ][8006] 8teekt
2019-03-20 08:23:07,998 [salt.loaded.ext.module.maasng:626 ][INFO    ][8006] sda
2019-03-20 08:23:08,544 [salt.loaded.ext.module.maasng:361 ][INFO    ][8006] 8teekt
2019-03-20 08:23:08,637 [salt.loaded.ext.module.maasng:367 ][INFO    ][8006] [{u'size': 800109715456, 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'system_id': u'8teekt', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/8teekt/blockdevices/2/', u'type': u'physical', u'id': 2, u'partitions': [{u'size': 800101236736, u'uuid': u'0acf50e2-ea70-44e3-8199-8600e76ef65f', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'8teekt', u'filesystem': {u'label': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'e5532a21-f256-43fb-a725-f6cbec9d700b', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/8teekt/blockdevices/2/partition/2'}]}, {u'size': 800097042432, 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'9e4d4c79-bbf1-49f8-8278-ffd53564917a', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'8teekt', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'8c842d0a-ad24-41c6-97cd-e8e54c369ff9', u'mount_options': None}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/8teekt/blockdevices/4/', u'type': u'virtual', u'id': 4, u'partitions': []}]
2019-03-20 08:23:08,638 [salt.loaded.ext.module.maasng:632 ][INFO    ][8006] vgroot
2019-03-20 08:23:08,638 [salt.loaded.ext.module.maasng:635 ][INFO    ][8006] lvroot
2019-03-20 08:23:08,639 [salt.loaded.ext.module.maasng:639 ][INFO    ][8006] 107374182400
2019-03-20 08:23:09,224 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320082309215382
2019-03-20 08:23:09,242 [salt.minion      :1432][INFO    ][8118] Starting a new job with PID 8118
2019-03-20 08:23:09,261 [salt.loaded.ext.module.maasng:645 ][INFO    ][8006] {u'hwe_kernel': u'', u'swap_size': None, u'ip_addresses': [u'192.168.11.39', u'192.168.11.44'], u'cpu_count': 40, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'commissioning_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'status_action': u'', u'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'id': 1}, u'resource_uri': u'/MAAS/api/2.0/machines/8teekt/', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'8teekt', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'uuid': u'eaa02ff8-cfc2-4984-b473-752dfabd7c8e', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'8teekt', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'47b0e130-c904-4c26-be3e-3bdb57c0fd06', u'mount_options': None}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/8teekt/blockdevices/12/', u'type': u'virtual', u'id': 12, u'partitions': []}], u'blockdevice_set': [{u'size': 800109715456, 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'system_id': u'8teekt', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/8teekt/blockdevices/2/', u'type': u'physical', u'id': 2, u'partitions': [{u'size': 800101236736, u'uuid': u'b06298ef-bc12-4f8a-83ad-ffe45981d2c4', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'8teekt', u'filesystem': {u'label': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'4a4ae945-c504-48a3-be43-31c90883acd8', u'mount_options': None}, 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/8teekt/blockdevices/2/partition/7'}]}, {u'size': 107374182400, u'model': None, u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'uuid': u'eaa02ff8-cfc2-4984-b473-752dfabd7c8e', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'8teekt', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'47b0e130-c904-4c26-be3e-3bdb57c0fd06', u'mount_options': None}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/8teekt/blockdevices/12/', u'type': u'virtual', u'id': 12, u'partitions': []}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'8teekt', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'size': 800109715456, 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'system_id': u'8teekt', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/8teekt/blockdevices/2/', u'type': u'physical', u'id': 2, u'partitions': [{u'size': 800101236736, u'uuid': u'b06298ef-bc12-4f8a-83ad-ffe45981d2c4', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'8teekt', u'filesystem': {u'label': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'4a4ae945-c504-48a3-be43-31c90883acd8', u'mount_options': None}, 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/8teekt/blockdevices/2/partition/7'}]}, u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'hswkeq', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 25}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'hswkeq', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'hswkeq', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'system_id': u'8teekt', u'mac_address': u'9c:b6:54:8a:95:a0', u'id': 5, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/8teekt/interfaces/5/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'system_id': u'8teekt', u'mac_address': u'38:ea:a7:8f:1f:d5', u'id': 17, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/8teekt/interfaces/17/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'system_id': u'8teekt', u'mac_address': u'38:ea:a7:8f:1f:d4', u'id': 19, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/8teekt/interfaces/19/'}, {u'name': u'ens2f0', u'links': [{u'id': 26, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'system_id': u'8teekt', u'mac_address': u'38:ea:a7:8f:52:cc', u'id': 16, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/8teekt/interfaces/16/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'hswkeq', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'mode': u'link_up', u'id': 27}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'hswkeq', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'hswkeq', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.44'}], u'system_id': u'8teekt', u'mac_address': u'9c:b6:54:8a:95:a4', u'id': 18, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/8teekt/interfaces/18/'}, {u'name': u'ens2f1', u'links': [{u'id': 28, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'system_id': u'8teekt', u'mac_address': u'38:ea:a7:8f:52:cd', u'id': 20, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/8teekt/interfaces/20/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 800109715456, 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'system_id': u'8teekt', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/8teekt/blockdevices/2/', u'type': u'physical', u'id': 2, u'partitions': [{u'size': 800101236736, u'uuid': u'b06298ef-bc12-4f8a-83ad-ffe45981d2c4', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'8teekt', u'filesystem': {u'label': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'4a4ae945-c504-48a3-be43-31c90883acd8', u'mount_options': None}, 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/8teekt/blockdevices/2/partition/7'}]}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'disable_ipv4': False, u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'hswkeq', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 25}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'hswkeq', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'hswkeq', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'system_id': u'8teekt', u'mac_address': u'9c:b6:54:8a:95:a0', u'id': 5, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/8teekt/interfaces/5/'}, u'current_commissioning_result_id': 4, u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'node_type_name': u'Machine'}
2019-03-20 08:23:09,264 [salt.state       :300 ][INFO    ][8006] {'new': {'storage_layout': 'lvm'}}
2019-03-20 08:23:09,265 [salt.state       :1951][INFO    ][8006] Completed state [maas_machines_storage_cmp001_lvm] at time 08:23:09.265009 duration_in_ms=2388.983
2019-03-20 08:23:09,266 [salt.minion      :1711][INFO    ][8118] Returning information for job: 20190320082309215382
2019-03-20 08:23:09,271 [salt.minion      :1711][INFO    ][8006] Returning information for job: 20190320082254140122
2019-03-20 08:23:09,824 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command state.apply with jid 20190320082309811210
2019-03-20 08:23:09,846 [salt.minion      :1432][INFO    ][8125] Starting a new job with PID 8125
2019-03-20 08:23:10,900 [salt.state       :915 ][INFO    ][8125] Loading fresh modules for state activity
2019-03-20 08:23:10,955 [salt.fileclient  :1219][INFO    ][8125] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-03-20 08:23:11,002 [salt.state       :1780][INFO    ][8125] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:23:11.002006
2019-03-20 08:23:11,002 [salt.state       :1813][INFO    ][8125] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-20 08:23:11,005 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8125] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-20 08:23:12,821 [salt.state       :300 ][INFO    ][8125] {'pid': 8132, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-20 08:23:12,823 [salt.state       :1951][INFO    ][8125] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:23:12.822776 duration_in_ms=1820.768
2019-03-20 08:23:12,826 [salt.state       :1780][INFO    ][8125] Running state [maas.deploy_machines] at time 08:23:12.825968
2019-03-20 08:23:12,826 [salt.state       :1813][INFO    ][8125] Executing state module.run for [maas.deploy_machines]
2019-03-20 08:23:12,828 [salt.utils.decorators:613 ][WARNING ][8125] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-20 08:23:13,418 [salt.loaded.ext.module.maas:684 ][INFO    ][8125] deploymachines hwe_kernel=hwe-16.04 system_id=q8rgkr distro_series=xenial
2019-03-20 08:23:15,757 [salt.loaded.ext.module.maas:684 ][INFO    ][8125] deploymachines hwe_kernel=hwe-16.04 system_id=8teekt distro_series=xenial
2019-03-20 08:23:18,080 [salt.loaded.ext.module.maas:684 ][INFO    ][8125] deploymachines hwe_kernel=hwe-16.04 system_id=sskgpg distro_series=xenial
2019-03-20 08:23:20,436 [salt.loaded.ext.module.maas:684 ][INFO    ][8125] deploymachines hwe_kernel=hwe-16.04 system_id=4hcxey distro_series=xenial
2019-03-20 08:23:22,802 [salt.loaded.ext.module.maas:684 ][INFO    ][8125] deploymachines hwe_kernel=hwe-16.04 system_id=a6ggf4 distro_series=xenial
2019-03-20 08:23:24,706 [salt.state       :300 ][INFO    ][8125] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-03-20 08:23:24,706 [salt.state       :1951][INFO    ][8125] Completed state [maas.deploy_machines] at time 08:23:24.706400 duration_in_ms=11880.432
2019-03-20 08:23:24,709 [salt.minion      :1711][INFO    ][8125] Returning information for job: 20190320082309811210
2019-03-20 08:23:25,262 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command state.apply with jid 20190320082325254417
2019-03-20 08:23:25,280 [salt.minion      :1432][INFO    ][8403] Starting a new job with PID 8403
2019-03-20 08:23:33,125 [salt.state       :915 ][INFO    ][8403] Loading fresh modules for state activity
2019-03-20 08:23:33,179 [salt.fileclient  :1219][INFO    ][8403] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-03-20 08:23:33,236 [salt.state       :1780][INFO    ][8403] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:23:33.235992
2019-03-20 08:23:33,236 [salt.state       :1813][INFO    ][8403] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-20 08:23:33,237 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8403] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-20 08:23:34,941 [salt.state       :300 ][INFO    ][8403] {'pid': 8414, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-20 08:23:34,943 [salt.state       :1951][INFO    ][8403] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:23:34.942729 duration_in_ms=1706.735
2019-03-20 08:23:34,946 [salt.state       :1780][INFO    ][8403] Running state [maas.wait_for_machine_status] at time 08:23:34.945962
2019-03-20 08:23:34,946 [salt.state       :1813][INFO    ][8403] Executing state module.run for [maas.wait_for_machine_status]
2019-03-20 08:23:34,948 [salt.utils.decorators:613 ][WARNING ][8403] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-20 08:23:37,450 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.50470114s left)
2019-03-20 08:23:40,337 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320082340318587
2019-03-20 08:23:40,357 [salt.minion      :1432][INFO    ][8439] Starting a new job with PID 8439
2019-03-20 08:23:40,380 [salt.minion      :1711][INFO    ][8439] Returning information for job: 20190320082340318587
2019-03-20 08:24:10,362 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320082410353730
2019-03-20 08:24:10,380 [salt.minion      :1432][INFO    ][8512] Starting a new job with PID 8512
2019-03-20 08:24:10,401 [salt.minion      :1711][INFO    ][8512] Returning information for job: 20190320082410353730
2019-03-20 08:24:10,470 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.48558617s left)
2019-03-20 08:24:40,425 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320082440410797
2019-03-20 08:24:40,448 [salt.minion      :1432][INFO    ][8555] Starting a new job with PID 8555
2019-03-20 08:24:40,471 [salt.minion      :1711][INFO    ][8555] Returning information for job: 20190320082440410797
2019-03-20 08:24:43,229 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.72622299s left)
2019-03-20 08:25:10,490 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320082510477752
2019-03-20 08:25:10,510 [salt.minion      :1432][INFO    ][8643] Starting a new job with PID 8643
2019-03-20 08:25:10,532 [salt.minion      :1711][INFO    ][8643] Returning information for job: 20190320082510477752
2019-03-20 08:25:16,129 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2148.82595515s left)
2019-03-20 08:25:40,537 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320082540521190
2019-03-20 08:25:40,556 [salt.minion      :1432][INFO    ][8672] Starting a new job with PID 8672
2019-03-20 08:25:40,580 [salt.minion      :1711][INFO    ][8672] Returning information for job: 20190320082540521190
2019-03-20 08:25:48,881 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2116.07385111s left)
2019-03-20 08:26:10,594 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320082610585167
2019-03-20 08:26:10,610 [salt.minion      :1432][INFO    ][8767] Starting a new job with PID 8767
2019-03-20 08:26:10,634 [salt.minion      :1711][INFO    ][8767] Returning information for job: 20190320082610585167
2019-03-20 08:26:21,852 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2083.10311818s left)
2019-03-20 08:26:40,789 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320082640782512
2019-03-20 08:26:40,801 [salt.minion      :1432][INFO    ][8845] Starting a new job with PID 8845
2019-03-20 08:26:40,823 [salt.minion      :1711][INFO    ][8845] Returning information for job: 20190320082640782512
2019-03-20 08:26:56,059 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2048.89583421s left)
2019-03-20 08:27:10,888 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320082710874383
2019-03-20 08:27:10,907 [salt.minion      :1432][INFO    ][9155] Starting a new job with PID 9155
2019-03-20 08:27:10,929 [salt.minion      :1711][INFO    ][9155] Returning information for job: 20190320082710874383
2019-03-20 08:27:28,794 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2016.16142011s left)
2019-03-20 08:27:40,941 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320082740932696
2019-03-20 08:27:40,955 [salt.minion      :1432][INFO    ][9226] Starting a new job with PID 9226
2019-03-20 08:27:40,977 [salt.minion      :1711][INFO    ][9226] Returning information for job: 20190320082740932696
2019-03-20 08:28:01,669 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1983.28647614s left)
2019-03-20 08:28:11,014 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320082811000580
2019-03-20 08:28:11,036 [salt.minion      :1432][INFO    ][9465] Starting a new job with PID 9465
2019-03-20 08:28:11,065 [salt.minion      :1711][INFO    ][9465] Returning information for job: 20190320082811000580
2019-03-20 08:28:34,464 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1950.49071407s left)
2019-03-20 08:28:41,134 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320082841119293
2019-03-20 08:28:41,156 [salt.minion      :1432][INFO    ][9505] Starting a new job with PID 9505
2019-03-20 08:28:41,180 [salt.minion      :1711][INFO    ][9505] Returning information for job: 20190320082841119293
2019-03-20 08:29:07,605 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1917.34968209s left)
2019-03-20 08:29:11,273 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320082911262499
2019-03-20 08:29:11,288 [salt.minion      :1432][INFO    ][9946] Starting a new job with PID 9946
2019-03-20 08:29:11,312 [salt.minion      :1711][INFO    ][9946] Returning information for job: 20190320082911262499
2019-03-20 08:29:40,682 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1884.27336502s left)
2019-03-20 08:29:41,374 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320082941363728
2019-03-20 08:29:41,397 [salt.minion      :1432][INFO    ][10001] Starting a new job with PID 10001
2019-03-20 08:29:41,416 [salt.minion      :1711][INFO    ][10001] Returning information for job: 20190320082941363728
2019-03-20 08:30:11,482 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320083011474408
2019-03-20 08:30:11,498 [salt.minion      :1432][INFO    ][10391] Starting a new job with PID 10391
2019-03-20 08:30:11,520 [salt.minion      :1711][INFO    ][10391] Returning information for job: 20190320083011474408
2019-03-20 08:30:13,472 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1851.48326015s left)
2019-03-20 08:30:41,617 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320083041599101
2019-03-20 08:30:41,637 [salt.minion      :1432][INFO    ][10432] Starting a new job with PID 10432
2019-03-20 08:30:41,659 [salt.minion      :1711][INFO    ][10432] Returning information for job: 20190320083041599101
2019-03-20 08:30:46,423 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1818.53211808s left)
2019-03-20 08:31:11,745 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320083111725418
2019-03-20 08:31:11,768 [salt.minion      :1432][INFO    ][10605] Starting a new job with PID 10605
2019-03-20 08:31:11,789 [salt.minion      :1711][INFO    ][10605] Returning information for job: 20190320083111725418
2019-03-20 08:31:19,408 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1785.54692602s left)
2019-03-20 08:31:41,812 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320083141803132
2019-03-20 08:31:41,833 [salt.minion      :1432][INFO    ][10650] Starting a new job with PID 10650
2019-03-20 08:31:41,856 [salt.minion      :1711][INFO    ][10650] Returning information for job: 20190320083141803132
2019-03-20 08:31:52,151 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1752.80425906s left)
2019-03-20 08:32:11,861 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320083211850398
2019-03-20 08:32:11,876 [salt.minion      :1432][INFO    ][10893] Starting a new job with PID 10893
2019-03-20 08:32:11,896 [salt.minion      :1711][INFO    ][10893] Returning information for job: 20190320083211850398
2019-03-20 08:32:25,102 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1719.85284901s left)
2019-03-20 08:32:41,984 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320083241972483
2019-03-20 08:32:42,007 [salt.minion      :1432][INFO    ][10949] Starting a new job with PID 10949
2019-03-20 08:32:42,029 [salt.minion      :1711][INFO    ][10949] Returning information for job: 20190320083241972483
2019-03-20 08:32:58,461 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1686.4941411s left)
2019-03-20 08:33:12,031 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320083312015923
2019-03-20 08:33:12,053 [salt.minion      :1432][INFO    ][11227] Starting a new job with PID 11227
2019-03-20 08:33:12,072 [salt.minion      :1711][INFO    ][11227] Returning information for job: 20190320083312015923
2019-03-20 08:33:31,414 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1653.54085112s left)
2019-03-20 08:33:42,155 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320083342141909
2019-03-20 08:33:42,175 [salt.minion      :1432][INFO    ][11257] Starting a new job with PID 11257
2019-03-20 08:33:42,196 [salt.minion      :1711][INFO    ][11257] Returning information for job: 20190320083342141909
2019-03-20 08:34:04,218 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1620.73763609s left)
2019-03-20 08:34:12,302 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320083412287331
2019-03-20 08:34:12,324 [salt.minion      :1432][INFO    ][11385] Starting a new job with PID 11385
2019-03-20 08:34:12,346 [salt.minion      :1711][INFO    ][11385] Returning information for job: 20190320083412287331
2019-03-20 08:34:37,223 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1587.73225307s left)
2019-03-20 08:34:42,441 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320083442431769
2019-03-20 08:34:42,463 [salt.minion      :1432][INFO    ][11548] Starting a new job with PID 11548
2019-03-20 08:34:42,484 [salt.minion      :1711][INFO    ][11548] Returning information for job: 20190320083442431769
2019-03-20 08:35:10,033 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1554.92233515s left)
2019-03-20 08:35:12,619 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320083512604561
2019-03-20 08:35:12,637 [salt.minion      :1432][INFO    ][11639] Starting a new job with PID 11639
2019-03-20 08:35:12,666 [salt.minion      :1711][INFO    ][11639] Returning information for job: 20190320083512604561
2019-03-20 08:35:42,700 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320083542690609
2019-03-20 08:35:42,716 [salt.minion      :1432][INFO    ][11739] Starting a new job with PID 11739
2019-03-20 08:35:42,740 [salt.minion      :1711][INFO    ][11739] Returning information for job: 20190320083542690609
2019-03-20 08:35:42,821 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1522.13392401s left)
2019-03-20 08:36:12,792 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320083612784637
2019-03-20 08:36:12,811 [salt.minion      :1432][INFO    ][11997] Starting a new job with PID 11997
2019-03-20 08:36:12,830 [salt.minion      :1711][INFO    ][11997] Returning information for job: 20190320083612784637
2019-03-20 08:36:15,749 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1489.20637822s left)
2019-03-20 08:36:42,953 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320083642943867
2019-03-20 08:36:42,973 [salt.minion      :1432][INFO    ][12027] Starting a new job with PID 12027
2019-03-20 08:36:42,992 [salt.minion      :1711][INFO    ][12027] Returning information for job: 20190320083642943867
2019-03-20 08:36:48,569 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1456.38590503s left)
2019-03-20 08:37:13,170 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320083713158671
2019-03-20 08:37:13,193 [salt.minion      :1432][INFO    ][12155] Starting a new job with PID 12155
2019-03-20 08:37:13,212 [salt.minion      :1711][INFO    ][12155] Returning information for job: 20190320083713158671
2019-03-20 08:37:21,362 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1423.59296513s left)
2019-03-20 08:37:43,370 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320083743360710
2019-03-20 08:37:43,389 [salt.minion      :1432][INFO    ][12185] Starting a new job with PID 12185
2019-03-20 08:37:43,412 [salt.minion      :1711][INFO    ][12185] Returning information for job: 20190320083743360710
2019-03-20 08:37:54,338 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1390.61725616s left)
2019-03-20 08:38:13,385 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320083813372439
2019-03-20 08:38:13,405 [salt.minion      :1432][INFO    ][12256] Starting a new job with PID 12256
2019-03-20 08:38:13,429 [salt.minion      :1711][INFO    ][12256] Returning information for job: 20190320083813372439
2019-03-20 08:38:27,422 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1357.53292608s left)
2019-03-20 08:38:43,409 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320083843401153
2019-03-20 08:38:43,424 [salt.minion      :1432][INFO    ][12286] Starting a new job with PID 12286
2019-03-20 08:38:43,445 [salt.minion      :1711][INFO    ][12286] Returning information for job: 20190320083843401153
2019-03-20 08:39:00,104 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1324.85093307s left)
2019-03-20 08:39:13,437 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320083913425816
2019-03-20 08:39:13,458 [salt.minion      :1432][INFO    ][12367] Starting a new job with PID 12367
2019-03-20 08:39:13,477 [salt.minion      :1711][INFO    ][12367] Returning information for job: 20190320083913425816
2019-03-20 08:39:32,953 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1292.00193s left)
2019-03-20 08:39:43,493 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320083943480593
2019-03-20 08:39:43,509 [salt.minion      :1432][INFO    ][12400] Starting a new job with PID 12400
2019-03-20 08:39:43,534 [salt.minion      :1711][INFO    ][12400] Returning information for job: 20190320083943480593
2019-03-20 08:40:05,654 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1259.30150008s left)
2019-03-20 08:40:13,540 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320084013528869
2019-03-20 08:40:13,558 [salt.minion      :1432][INFO    ][12479] Starting a new job with PID 12479
2019-03-20 08:40:13,580 [salt.minion      :1711][INFO    ][12479] Returning information for job: 20190320084013528869
2019-03-20 08:40:38,445 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1226.50970817s left)
2019-03-20 08:40:43,605 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320084043594193
2019-03-20 08:40:43,626 [salt.minion      :1432][INFO    ][12511] Starting a new job with PID 12511
2019-03-20 08:40:43,650 [salt.minion      :1711][INFO    ][12511] Returning information for job: 20190320084043594193
2019-03-20 08:41:11,158 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1193.79690719s left)
2019-03-20 08:41:13,669 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320084113655615
2019-03-20 08:41:13,689 [salt.minion      :1432][INFO    ][12585] Starting a new job with PID 12585
2019-03-20 08:41:13,712 [salt.minion      :1711][INFO    ][12585] Returning information for job: 20190320084113655615
2019-03-20 08:41:43,770 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320084143761121
2019-03-20 08:41:43,784 [salt.minion      :1432][INFO    ][12615] Starting a new job with PID 12615
2019-03-20 08:41:43,808 [salt.minion      :1711][INFO    ][12615] Returning information for job: 20190320084143761121
2019-03-20 08:41:43,966 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1160.98958421s left)
2019-03-20 08:42:13,881 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320084213869437
2019-03-20 08:42:13,904 [salt.minion      :1432][INFO    ][12684] Starting a new job with PID 12684
2019-03-20 08:42:13,929 [salt.minion      :1711][INFO    ][12684] Returning information for job: 20190320084213869437
2019-03-20 08:42:16,788 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1128.16715908s left)
2019-03-20 08:42:44,005 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320084243989391
2019-03-20 08:42:44,028 [salt.minion      :1432][INFO    ][12714] Starting a new job with PID 12714
2019-03-20 08:42:44,051 [salt.minion      :1711][INFO    ][12714] Returning information for job: 20190320084243989391
2019-03-20 08:42:49,469 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1095.48609614s left)
2019-03-20 08:43:14,100 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320084314086244
2019-03-20 08:43:14,120 [salt.minion      :1432][INFO    ][12785] Starting a new job with PID 12785
2019-03-20 08:43:14,141 [salt.minion      :1711][INFO    ][12785] Returning information for job: 20190320084314086244
2019-03-20 08:43:22,092 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1062.86343122s left)
2019-03-20 08:43:44,263 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320084344249253
2019-03-20 08:43:44,286 [salt.minion      :1432][INFO    ][12815] Starting a new job with PID 12815
2019-03-20 08:43:44,306 [salt.minion      :1711][INFO    ][12815] Returning information for job: 20190320084344249253
2019-03-20 08:43:55,296 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1029.65901399s left)
2019-03-20 08:44:14,397 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320084414389768
2019-03-20 08:44:14,410 [salt.minion      :1432][INFO    ][12889] Starting a new job with PID 12889
2019-03-20 08:44:14,430 [salt.minion      :1711][INFO    ][12889] Returning information for job: 20190320084414389768
2019-03-20 08:44:28,053 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (996.902328014s left)
2019-03-20 08:44:44,561 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320084444548207
2019-03-20 08:44:44,579 [salt.minion      :1432][INFO    ][12927] Starting a new job with PID 12927
2019-03-20 08:44:44,602 [salt.minion      :1711][INFO    ][12927] Returning information for job: 20190320084444548207
2019-03-20 08:45:00,721 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (964.233673096s left)
2019-03-20 08:45:14,754 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320084514745371
2019-03-20 08:45:14,777 [salt.minion      :1432][INFO    ][13017] Starting a new job with PID 13017
2019-03-20 08:45:14,797 [salt.minion      :1711][INFO    ][13017] Returning information for job: 20190320084514745371
2019-03-20 08:45:33,410 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (931.545530081s left)
2019-03-20 08:45:44,924 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320084544913955
2019-03-20 08:45:44,945 [salt.minion      :1432][INFO    ][13049] Starting a new job with PID 13049
2019-03-20 08:45:44,964 [salt.minion      :1711][INFO    ][13049] Returning information for job: 20190320084544913955
2019-03-20 08:46:06,124 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (898.830760002s left)
2019-03-20 08:46:15,151 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320084615136169
2019-03-20 08:46:15,174 [salt.minion      :1432][INFO    ][13120] Starting a new job with PID 13120
2019-03-20 08:46:15,196 [salt.minion      :1711][INFO    ][13120] Returning information for job: 20190320084615136169
2019-03-20 08:46:38,953 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (866.002351999s left)
2019-03-20 08:46:45,347 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320084645334596
2019-03-20 08:46:45,369 [salt.minion      :1432][INFO    ][13150] Starting a new job with PID 13150
2019-03-20 08:46:45,393 [salt.minion      :1711][INFO    ][13150] Returning information for job: 20190320084645334596
2019-03-20 08:47:11,766 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (833.188962221s left)
2019-03-20 08:47:15,400 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320084715387468
2019-03-20 08:47:15,419 [salt.minion      :1432][INFO    ][13221] Starting a new job with PID 13221
2019-03-20 08:47:15,444 [salt.minion      :1711][INFO    ][13221] Returning information for job: 20190320084715387468
2019-03-20 08:47:44,457 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (800.497808218s left)
2019-03-20 08:47:45,426 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320084745413683
2019-03-20 08:47:45,446 [salt.minion      :1432][INFO    ][13251] Starting a new job with PID 13251
2019-03-20 08:47:45,468 [salt.minion      :1711][INFO    ][13251] Returning information for job: 20190320084745413683
2019-03-20 08:48:15,514 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320084815506556
2019-03-20 08:48:15,537 [salt.minion      :1432][INFO    ][13322] Starting a new job with PID 13322
2019-03-20 08:48:15,563 [salt.minion      :1711][INFO    ][13322] Returning information for job: 20190320084815506556
2019-03-20 08:48:17,125 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (767.83032608s left)
2019-03-20 08:48:45,582 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320084845569326
2019-03-20 08:48:45,604 [salt.minion      :1432][INFO    ][13353] Starting a new job with PID 13353
2019-03-20 08:48:45,632 [salt.minion      :1711][INFO    ][13353] Returning information for job: 20190320084845569326
2019-03-20 08:48:49,904 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (735.051402092s left)
2019-03-20 08:49:15,691 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320084915681159
2019-03-20 08:49:15,709 [salt.minion      :1432][INFO    ][13425] Starting a new job with PID 13425
2019-03-20 08:49:15,734 [salt.minion      :1711][INFO    ][13425] Returning information for job: 20190320084915681159
2019-03-20 08:49:22,730 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (702.225711107s left)
2019-03-20 08:49:45,774 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320084945760921
2019-03-20 08:49:45,800 [salt.minion      :1432][INFO    ][13457] Starting a new job with PID 13457
2019-03-20 08:49:45,823 [salt.minion      :1711][INFO    ][13457] Returning information for job: 20190320084945760921
2019-03-20 08:49:55,593 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (669.36177206s left)
2019-03-20 08:50:15,945 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320085015929497
2019-03-20 08:50:15,967 [salt.minion      :1432][INFO    ][13530] Starting a new job with PID 13530
2019-03-20 08:50:15,990 [salt.minion      :1711][INFO    ][13530] Returning information for job: 20190320085015929497
2019-03-20 08:50:28,197 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (636.758171082s left)
2019-03-20 08:50:46,094 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320085046085863
2019-03-20 08:50:46,113 [salt.minion      :1432][INFO    ][13562] Starting a new job with PID 13562
2019-03-20 08:50:46,138 [salt.minion      :1711][INFO    ][13562] Returning information for job: 20190320085046085863
2019-03-20 08:51:00,959 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (603.995907068s left)
2019-03-20 08:51:16,269 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320085116254304
2019-03-20 08:51:16,291 [salt.minion      :1432][INFO    ][13636] Starting a new job with PID 13636
2019-03-20 08:51:16,316 [salt.minion      :1711][INFO    ][13636] Returning information for job: 20190320085116254304
2019-03-20 08:51:33,679 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (571.27592206s left)
2019-03-20 08:51:46,490 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320085146474385
2019-03-20 08:51:46,509 [salt.minion      :1432][INFO    ][13666] Starting a new job with PID 13666
2019-03-20 08:51:46,534 [salt.minion      :1711][INFO    ][13666] Returning information for job: 20190320085146474385
2019-03-20 08:52:06,371 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (538.583893061s left)
2019-03-20 08:52:16,657 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320085216641398
2019-03-20 08:52:16,676 [salt.minion      :1432][INFO    ][13738] Starting a new job with PID 13738
2019-03-20 08:52:16,705 [salt.minion      :1711][INFO    ][13738] Returning information for job: 20190320085216641398
2019-03-20 08:52:39,086 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (505.869571209s left)
2019-03-20 08:52:46,713 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320085246700365
2019-03-20 08:52:46,733 [salt.minion      :1432][INFO    ][13770] Starting a new job with PID 13770
2019-03-20 08:52:46,760 [salt.minion      :1711][INFO    ][13770] Returning information for job: 20190320085246700365
2019-03-20 08:53:11,735 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (473.220777035s left)
2019-03-20 08:53:16,918 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320085316909332
2019-03-20 08:53:16,934 [salt.minion      :1432][INFO    ][13844] Starting a new job with PID 13844
2019-03-20 08:53:16,967 [salt.minion      :1711][INFO    ][13844] Returning information for job: 20190320085316909332
2019-03-20 08:53:44,725 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (440.230022192s left)
2019-03-20 08:53:47,005 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320085346990099
2019-03-20 08:53:47,025 [salt.minion      :1432][INFO    ][13874] Starting a new job with PID 13874
2019-03-20 08:53:47,050 [salt.minion      :1711][INFO    ][13874] Returning information for job: 20190320085346990099
2019-03-20 08:54:17,140 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320085417130326
2019-03-20 08:54:17,159 [salt.minion      :1432][INFO    ][13950] Starting a new job with PID 13950
2019-03-20 08:54:17,183 [salt.minion      :1711][INFO    ][13950] Returning information for job: 20190320085417130326
2019-03-20 08:54:17,313 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (407.642531157s left)
2019-03-20 08:54:47,301 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320085447286696
2019-03-20 08:54:47,324 [salt.minion      :1432][INFO    ][14111] Starting a new job with PID 14111
2019-03-20 08:54:47,348 [salt.minion      :1711][INFO    ][14111] Returning information for job: 20190320085447286696
2019-03-20 08:54:50,026 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (374.928850174s left)
2019-03-20 08:55:17,369 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320085517351786
2019-03-20 08:55:17,388 [salt.minion      :1432][INFO    ][14197] Starting a new job with PID 14197
2019-03-20 08:55:17,416 [salt.minion      :1711][INFO    ][14197] Returning information for job: 20190320085517351786
2019-03-20 08:55:22,761 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (342.194525003s left)
2019-03-20 08:55:47,549 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320085547536352
2019-03-20 08:55:47,568 [salt.minion      :1432][INFO    ][14227] Starting a new job with PID 14227
2019-03-20 08:55:47,593 [salt.minion      :1711][INFO    ][14227] Returning information for job: 20190320085547536352
2019-03-20 08:55:55,459 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (309.496428013s left)
2019-03-20 08:56:17,647 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320085617634830
2019-03-20 08:56:17,670 [salt.minion      :1432][INFO    ][14299] Starting a new job with PID 14299
2019-03-20 08:56:17,693 [salt.minion      :1711][INFO    ][14299] Returning information for job: 20190320085617634830
2019-03-20 08:56:28,190 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (276.765097141s left)
2019-03-20 08:56:47,861 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320085647843784
2019-03-20 08:56:47,881 [salt.minion      :1432][INFO    ][14329] Starting a new job with PID 14329
2019-03-20 08:56:47,908 [salt.minion      :1711][INFO    ][14329] Returning information for job: 20190320085647843784
2019-03-20 08:57:00,956 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (243.999258041s left)
2019-03-20 08:57:18,071 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320085718061854
2019-03-20 08:57:18,084 [salt.minion      :1432][INFO    ][14405] Starting a new job with PID 14405
2019-03-20 08:57:18,107 [salt.minion      :1711][INFO    ][14405] Returning information for job: 20190320085718061854
2019-03-20 08:57:33,621 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (211.334549189s left)
2019-03-20 08:57:48,287 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320085748275474
2019-03-20 08:57:48,309 [salt.minion      :1432][INFO    ][14435] Starting a new job with PID 14435
2019-03-20 08:57:48,333 [salt.minion      :1711][INFO    ][14435] Returning information for job: 20190320085748275474
2019-03-20 08:58:06,561 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (178.394349098s left)
2019-03-20 08:58:18,358 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320085818346026
2019-03-20 08:58:18,373 [salt.minion      :1432][INFO    ][14509] Starting a new job with PID 14509
2019-03-20 08:58:18,401 [salt.minion      :1711][INFO    ][14509] Returning information for job: 20190320085818346026
2019-03-20 08:58:39,418 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (145.537295103s left)
2019-03-20 08:58:48,534 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320085848520180
2019-03-20 08:58:48,554 [salt.minion      :1432][INFO    ][14543] Starting a new job with PID 14543
2019-03-20 08:58:48,580 [salt.minion      :1711][INFO    ][14543] Returning information for job: 20190320085848520180
2019-03-20 08:59:12,121 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (112.834560156s left)
2019-03-20 08:59:18,598 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320085918586743
2019-03-20 08:59:18,617 [salt.minion      :1432][INFO    ][14614] Starting a new job with PID 14614
2019-03-20 08:59:18,642 [salt.minion      :1711][INFO    ][14614] Returning information for job: 20190320085918586743
2019-03-20 08:59:44,862 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (80.0933921337s left)
2019-03-20 08:59:48,629 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320085948614408
2019-03-20 08:59:48,646 [salt.minion      :1432][INFO    ][14644] Starting a new job with PID 14644
2019-03-20 08:59:48,668 [salt.minion      :1711][INFO    ][14644] Returning information for job: 20190320085948614408
2019-03-20 09:00:17,554 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (47.4016740322s left)
2019-03-20 09:00:18,739 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320090018728581
2019-03-20 09:00:18,762 [salt.minion      :1432][INFO    ][14718] Starting a new job with PID 14718
2019-03-20 09:00:18,786 [salt.minion      :1711][INFO    ][14718] Returning information for job: 20190320090018728581
2019-03-20 09:00:48,838 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320090048828218
2019-03-20 09:00:48,853 [salt.minion      :1432][INFO    ][14746] Starting a new job with PID 14746
2019-03-20 09:00:48,877 [salt.minion      :1711][INFO    ][14746] Returning information for job: 20190320090048828218
2019-03-20 09:00:50,286 [salt.loaded.ext.module.maas:1023][INFO    ][8403] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (14.6689360142s left)
2019-03-20 09:01:19,001 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320090118984356
2019-03-20 09:01:19,021 [salt.minion      :1432][INFO    ][14817] Starting a new job with PID 14817
2019-03-20 09:01:19,048 [salt.minion      :1711][INFO    ][14817] Returning information for job: 20190320090118984356
2019-03-20 09:01:23,040 [salt.state       :302 ][ERROR   ][8403] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-03-20 09:01:23,041 [salt.state       :1951][INFO    ][8403] Completed state [maas.wait_for_machine_status] at time 09:01:23.040694 duration_in_ms=2268094.729
2019-03-20 09:01:23,049 [salt.minion      :1711][INFO    ][8403] Returning information for job: 20190320082325254417
2019-03-20 09:01:33,876 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command pillar.get with jid 20190320090133864652
2019-03-20 09:01:33,895 [salt.minion      :1432][INFO    ][14835] Starting a new job with PID 14835
2019-03-20 09:01:33,905 [salt.minion      :1711][INFO    ][14835] Returning information for job: 20190320090133864652
2019-03-20 09:01:34,458 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command service.status with jid 20190320090134441775
2019-03-20 09:01:34,475 [salt.minion      :1432][INFO    ][14840] Starting a new job with PID 14840
2019-03-20 09:01:35,045 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][14840] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-03-20 09:01:35,086 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][14840] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-03-20 09:01:35,102 [salt.minion      :1711][INFO    ][14840] Returning information for job: 20190320090134441775
2019-03-20 09:01:35,648 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command state.apply with jid 20190320090135637534
2019-03-20 09:01:35,665 [salt.minion      :1432][INFO    ][14851] Starting a new job with PID 14851
2019-03-20 09:01:43,764 [salt.state       :915 ][INFO    ][14851] Loading fresh modules for state activity
2019-03-20 09:01:44,339 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14851] Executing command 'salt-minion --version' in directory '/root'
2019-03-20 09:01:44,728 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14851] Executing command 'salt-minion --version' in directory '/root'
2019-03-20 09:01:45,699 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14851] Executing command 'salt-minion --version' in directory '/root'
2019-03-20 09:01:45,971 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14851] Executing command 'salt-minion --version' in directory '/root'
2019-03-20 09:01:47,893 [salt.state       :1780][INFO    ][14851] Running state [salt-minion] at time 09:01:47.893125
2019-03-20 09:01:47,893 [salt.state       :1813][INFO    ][14851] Executing state pkg.installed for [salt-minion]
2019-03-20 09:01:47,894 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14851] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-03-20 09:01:48,006 [salt.state       :300 ][INFO    ][14851] All specified packages are already installed
2019-03-20 09:01:48,006 [salt.state       :1951][INFO    ][14851] Completed state [salt-minion] at time 09:01:48.006748 duration_in_ms=113.624
2019-03-20 09:01:48,007 [salt.state       :1780][INFO    ][14851] Running state [salt_minion_dependency_packages] at time 09:01:48.007362
2019-03-20 09:01:48,007 [salt.state       :1813][INFO    ][14851] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-03-20 09:01:48,017 [salt.state       :300 ][INFO    ][14851] All specified packages are already installed
2019-03-20 09:01:48,017 [salt.state       :1951][INFO    ][14851] Completed state [salt_minion_dependency_packages] at time 09:01:48.017635 duration_in_ms=10.273
2019-03-20 09:01:48,023 [salt.state       :1780][INFO    ][14851] Running state [/etc/salt/minion.d/minion.conf] at time 09:01:48.023875
2019-03-20 09:01:48,024 [salt.state       :1813][INFO    ][14851] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-03-20 09:01:48,295 [salt.state       :300 ][INFO    ][14851] File /etc/salt/minion.d/minion.conf is in the correct state
2019-03-20 09:01:48,295 [salt.state       :1951][INFO    ][14851] Completed state [/etc/salt/minion.d/minion.conf] at time 09:01:48.295763 duration_in_ms=271.888
2019-03-20 09:01:48,296 [salt.state       :1780][INFO    ][14851] Running state [python-netaddr] at time 09:01:48.296025
2019-03-20 09:01:48,296 [salt.state       :1813][INFO    ][14851] Executing state pkg.installed for [python-netaddr]
2019-03-20 09:01:48,305 [salt.state       :300 ][INFO    ][14851] All specified packages are already installed
2019-03-20 09:01:48,306 [salt.state       :1951][INFO    ][14851] Completed state [python-netaddr] at time 09:01:48.306058 duration_in_ms=10.033
2019-03-20 09:01:48,313 [salt.state       :1780][INFO    ][14851] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 09:01:48.313010
2019-03-20 09:01:48,313 [salt.state       :1813][INFO    ][14851] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-03-20 09:01:48,329 [salt.state       :300 ][INFO    ][14851] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-03-20 09:01:48,330 [salt.state       :1951][INFO    ][14851] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 09:01:48.330206 duration_in_ms=17.197
2019-03-20 09:01:48,332 [salt.state       :1780][INFO    ][14851] Running state [salt-minion] at time 09:01:48.332168
2019-03-20 09:01:48,332 [salt.state       :1813][INFO    ][14851] Executing state service.running for [salt-minion]
2019-03-20 09:01:48,333 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14851] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-03-20 09:01:48,368 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14851] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-03-20 09:01:48,384 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14851] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-03-20 09:01:48,403 [salt.state       :300 ][INFO    ][14851] The service salt-minion is already running
2019-03-20 09:01:48,403 [salt.state       :1951][INFO    ][14851] Completed state [salt-minion] at time 09:01:48.403744 duration_in_ms=71.575
2019-03-20 09:01:48,406 [salt.state       :1780][INFO    ][14851] Running state [/etc/salt/grains.d] at time 09:01:48.406591
2019-03-20 09:01:48,407 [salt.state       :1813][INFO    ][14851] Executing state file.directory for [/etc/salt/grains.d]
2019-03-20 09:01:48,409 [salt.state       :300 ][INFO    ][14851] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-03-20 09:01:48,409 [salt.state       :1951][INFO    ][14851] Completed state [/etc/salt/grains.d] at time 09:01:48.409436 duration_in_ms=2.845
2019-03-20 09:01:48,410 [salt.state       :1780][INFO    ][14851] Running state [/etc/salt/grains] at time 09:01:48.410621
2019-03-20 09:01:48,413 [salt.state       :1813][INFO    ][14851] Executing state file.managed for [/etc/salt/grains]
2019-03-20 09:01:48,414 [salt.state       :300 ][INFO    ][14851] File /etc/salt/grains exists with proper permissions. No changes made.
2019-03-20 09:01:48,414 [salt.state       :1951][INFO    ][14851] Completed state [/etc/salt/grains] at time 09:01:48.414222 duration_in_ms=3.602
2019-03-20 09:01:48,414 [salt.state       :1780][INFO    ][14851] Running state [/etc/salt/grains.d/placeholder] at time 09:01:48.414715
2019-03-20 09:01:48,415 [salt.state       :1813][INFO    ][14851] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-03-20 09:01:48,415 [salt.state       :300 ][INFO    ][14851] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-03-20 09:01:48,415 [salt.state       :1951][INFO    ][14851] Completed state [/etc/salt/grains.d/placeholder] at time 09:01:48.415717 duration_in_ms=1.002
2019-03-20 09:01:48,416 [salt.state       :1780][INFO    ][14851] Running state [/etc/salt/grains.d/sphinx] at time 09:01:48.416199
2019-03-20 09:01:48,416 [salt.state       :1813][INFO    ][14851] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-03-20 09:01:48,417 [salt.state       :300 ][INFO    ][14851] File /etc/salt/grains.d/sphinx is in the correct state
2019-03-20 09:01:48,417 [salt.state       :1951][INFO    ][14851] Completed state [/etc/salt/grains.d/sphinx] at time 09:01:48.417761 duration_in_ms=1.562
2019-03-20 09:01:48,419 [salt.state       :1780][INFO    ][14851] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 09:01:48.419814
2019-03-20 09:01:48,420 [salt.state       :1813][INFO    ][14851] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-03-20 09:01:48,420 [salt.state       :300 ][INFO    ][14851] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-03-20 09:01:48,420 [salt.state       :1951][INFO    ][14851] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 09:01:48.420601 duration_in_ms=0.787
2019-03-20 09:01:48,421 [salt.state       :1780][INFO    ][14851] Running state [/etc/salt/grains.d/dns_records] at time 09:01:48.421101
2019-03-20 09:01:48,421 [salt.state       :1813][INFO    ][14851] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-03-20 09:01:48,422 [salt.state       :300 ][INFO    ][14851] File /etc/salt/grains.d/dns_records is in the correct state
2019-03-20 09:01:48,422 [salt.state       :1951][INFO    ][14851] Completed state [/etc/salt/grains.d/dns_records] at time 09:01:48.422485 duration_in_ms=1.384
2019-03-20 09:01:48,425 [salt.state       :1780][INFO    ][14851] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 09:01:48.425348
2019-03-20 09:01:48,425 [salt.state       :1813][INFO    ][14851] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-03-20 09:01:48,425 [salt.state       :300 ][INFO    ][14851] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-03-20 09:01:48,426 [salt.state       :1951][INFO    ][14851] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 09:01:48.426109 duration_in_ms=0.761
2019-03-20 09:01:48,426 [salt.state       :1780][INFO    ][14851] Running state [/etc/salt/grains.d/salt] at time 09:01:48.426595
2019-03-20 09:01:48,426 [salt.state       :1813][INFO    ][14851] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-03-20 09:01:48,427 [salt.state       :300 ][INFO    ][14851] File /etc/salt/grains.d/salt is in the correct state
2019-03-20 09:01:48,428 [salt.state       :1951][INFO    ][14851] Completed state [/etc/salt/grains.d/salt] at time 09:01:48.427974 duration_in_ms=1.379
2019-03-20 09:01:48,428 [salt.state       :1780][INFO    ][14851] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 09:01:48.428848
2019-03-20 09:01:48,429 [salt.state       :1813][INFO    ][14851] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-03-20 09:01:48,429 [salt.state       :300 ][INFO    ][14851] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-03-20 09:01:48,429 [salt.state       :1951][INFO    ][14851] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 09:01:48.429619 duration_in_ms=0.771
2019-03-20 09:01:48,431 [salt.state       :1780][INFO    ][14851] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 09:01:48.431570
2019-03-20 09:01:48,431 [salt.state       :1813][INFO    ][14851] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-03-20 09:01:48,432 [salt.state       :300 ][INFO    ][14851] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-03-20 09:01:48,432 [salt.state       :1951][INFO    ][14851] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 09:01:48.432342 duration_in_ms=0.772
2019-03-20 09:01:48,433 [salt.state       :1780][INFO    ][14851] Running state [mine.update] at time 09:01:48.433004
2019-03-20 09:01:48,433 [salt.state       :1813][INFO    ][14851] Executing state module.wait for [mine.update]
2019-03-20 09:01:48,433 [salt.state       :300 ][INFO    ][14851] No changes made for mine.update
2019-03-20 09:01:48,433 [salt.state       :1951][INFO    ][14851] Completed state [mine.update] at time 09:01:48.433707 duration_in_ms=0.703
2019-03-20 09:01:48,433 [salt.state       :1780][INFO    ][14851] Running state [ca-certificates] at time 09:01:48.433947
2019-03-20 09:01:48,434 [salt.state       :1813][INFO    ][14851] Executing state pkg.installed for [ca-certificates]
2019-03-20 09:01:48,444 [salt.state       :300 ][INFO    ][14851] All specified packages are already installed
2019-03-20 09:01:48,444 [salt.state       :1951][INFO    ][14851] Completed state [ca-certificates] at time 09:01:48.444317 duration_in_ms=10.369
2019-03-20 09:01:48,445 [salt.state       :1780][INFO    ][14851] Running state [update-ca-certificates] at time 09:01:48.445004
2019-03-20 09:01:48,445 [salt.state       :1813][INFO    ][14851] Executing state cmd.wait for [update-ca-certificates]
2019-03-20 09:01:48,445 [salt.state       :300 ][INFO    ][14851] No changes made for update-ca-certificates
2019-03-20 09:01:48,445 [salt.state       :1951][INFO    ][14851] Completed state [update-ca-certificates] at time 09:01:48.445733 duration_in_ms=0.728
2019-03-20 09:01:48,446 [salt.state       :1780][INFO    ][14851] Running state [iptables] at time 09:01:48.445972
2019-03-20 09:01:48,446 [salt.state       :1813][INFO    ][14851] Executing state pkg.installed for [iptables]
2019-03-20 09:01:48,455 [salt.state       :300 ][INFO    ][14851] All specified packages are already installed
2019-03-20 09:01:48,456 [salt.state       :1951][INFO    ][14851] Completed state [iptables] at time 09:01:48.456014 duration_in_ms=10.041
2019-03-20 09:01:48,456 [salt.state       :1780][INFO    ][14851] Running state [iptables-persistent] at time 09:01:48.456251
2019-03-20 09:01:48,456 [salt.state       :1813][INFO    ][14851] Executing state pkg.installed for [iptables-persistent]
2019-03-20 09:01:48,465 [salt.state       :300 ][INFO    ][14851] All specified packages are already installed
2019-03-20 09:01:48,466 [salt.state       :1951][INFO    ][14851] Completed state [iptables-persistent] at time 09:01:48.466101 duration_in_ms=9.85
2019-03-20 09:01:48,467 [salt.state       :1780][INFO    ][14851] Running state [iptables_modules_v4_load] at time 09:01:48.467412
2019-03-20 09:01:48,467 [salt.state       :1813][INFO    ][14851] Executing state kmod.present for [iptables_modules_v4_load]
2019-03-20 09:01:48,468 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14851] Executing command 'lsmod' in directory '/root'
2019-03-20 09:01:48,488 [salt.state       :300 ][INFO    ][14851] Kernel modules iptable_filter, ip_tables are already present
2019-03-20 09:01:48,489 [salt.state       :1951][INFO    ][14851] Completed state [iptables_modules_v4_load] at time 09:01:48.489436 duration_in_ms=22.024
2019-03-20 09:01:48,490 [salt.state       :1780][INFO    ][14851] Running state [/etc/iptables/rules.v4] at time 09:01:48.490544
2019-03-20 09:01:48,491 [salt.state       :1813][INFO    ][14851] Executing state file.managed for [/etc/iptables/rules.v4]
2019-03-20 09:01:48,594 [salt.state       :300 ][INFO    ][14851] File /etc/iptables/rules.v4 is in the correct state
2019-03-20 09:01:48,595 [salt.state       :1951][INFO    ][14851] Completed state [/etc/iptables/rules.v4] at time 09:01:48.595001 duration_in_ms=104.447
2019-03-20 09:01:48,596 [salt.state       :1780][INFO    ][14851] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 09:01:48.596340
2019-03-20 09:01:48,596 [salt.state       :1813][INFO    ][14851] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-03-20 09:01:48,597 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14851] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-03-20 09:01:48,614 [salt.state       :300 ][INFO    ][14851] onlyif execution failed
2019-03-20 09:01:48,617 [salt.state       :1951][INFO    ][14851] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 09:01:48.617404 duration_in_ms=21.063
2019-03-20 09:01:48,619 [salt.state       :1780][INFO    ][14851] Running state [netfilter-persistent] at time 09:01:48.619175
2019-03-20 09:01:48,619 [salt.state       :1813][INFO    ][14851] Executing state service.running for [netfilter-persistent]
2019-03-20 09:01:48,620 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14851] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-03-20 09:01:48,644 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14851] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-03-20 09:01:48,662 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14851] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-03-20 09:01:48,684 [salt.state       :300 ][INFO    ][14851] The service netfilter-persistent is already running
2019-03-20 09:01:48,684 [salt.state       :1951][INFO    ][14851] Completed state [netfilter-persistent] at time 09:01:48.684722 duration_in_ms=65.547
2019-03-20 09:01:48,686 [salt.state       :1780][INFO    ][14851] Running state [iptables_extra.remove_stale_tables] at time 09:01:48.686085
2019-03-20 09:01:48,686 [salt.state       :1813][INFO    ][14851] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-03-20 09:01:48,689 [salt.state       :300 ][INFO    ][14851] No changes made for iptables_extra.remove_stale_tables
2019-03-20 09:01:48,690 [salt.state       :1951][INFO    ][14851] Completed state [iptables_extra.remove_stale_tables] at time 09:01:48.689948 duration_in_ms=3.864
2019-03-20 09:01:48,690 [salt.state       :1780][INFO    ][14851] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 09:01:48.690293
2019-03-20 09:01:48,690 [salt.state       :1813][INFO    ][14851] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-03-20 09:01:48,691 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14851] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-03-20 09:01:48,704 [salt.state       :300 ][INFO    ][14851] onlyif execution failed
2019-03-20 09:01:48,705 [salt.state       :1951][INFO    ][14851] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 09:01:48.705178 duration_in_ms=14.884
2019-03-20 09:01:48,706 [salt.state       :1780][INFO    ][14851] Running state [/etc/iptables/rules.v6] at time 09:01:48.706400
2019-03-20 09:01:48,706 [salt.state       :1813][INFO    ][14851] Executing state file.absent for [/etc/iptables/rules.v6]
2019-03-20 09:01:48,707 [salt.state       :300 ][INFO    ][14851] File /etc/iptables/rules.v6 is not present
2019-03-20 09:01:48,707 [salt.state       :1951][INFO    ][14851] Completed state [/etc/iptables/rules.v6] at time 09:01:48.707724 duration_in_ms=1.325
2019-03-20 09:01:48,708 [salt.state       :1780][INFO    ][14851] Running state [iptables_extra.flush_all] at time 09:01:48.708471
2019-03-20 09:01:48,708 [salt.state       :1813][INFO    ][14851] Executing state module.wait for [iptables_extra.flush_all]
2019-03-20 09:01:48,709 [salt.state       :300 ][INFO    ][14851] No changes made for iptables_extra.flush_all
2019-03-20 09:01:48,709 [salt.state       :1951][INFO    ][14851] Completed state [iptables_extra.flush_all] at time 09:01:48.709424 duration_in_ms=0.953
2019-03-20 09:01:48,715 [salt.minion      :1711][INFO    ][14851] Returning information for job: 20190320090135637534
2019-03-20 09:01:49,250 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command state.apply with jid 20190320090149237331
2019-03-20 09:01:49,269 [salt.minion      :1432][INFO    ][14940] Starting a new job with PID 14940
2019-03-20 09:01:50,126 [salt.state       :915 ][INFO    ][14940] Loading fresh modules for state activity
2019-03-20 09:01:50,973 [salt.state       :1780][INFO    ][14940] Running state [maas-rack-controller] at time 09:01:50.973174
2019-03-20 09:01:50,973 [salt.state       :1813][INFO    ][14940] Executing state pkg.installed for [maas-rack-controller]
2019-03-20 09:01:50,974 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14940] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-03-20 09:01:51,081 [salt.state       :300 ][INFO    ][14940] All specified packages are already installed
2019-03-20 09:01:51,081 [salt.state       :1951][INFO    ][14940] Completed state [maas-rack-controller] at time 09:01:51.081641 duration_in_ms=108.467
2019-03-20 09:01:51,082 [salt.state       :1780][INFO    ][14940] Running state [ipmitool] at time 09:01:51.082019
2019-03-20 09:01:51,082 [salt.state       :1813][INFO    ][14940] Executing state pkg.installed for [ipmitool]
2019-03-20 09:01:51,092 [salt.state       :300 ][INFO    ][14940] All specified packages are already installed
2019-03-20 09:01:51,092 [salt.state       :1951][INFO    ][14940] Completed state [ipmitool] at time 09:01:51.092510 duration_in_ms=10.49
2019-03-20 09:01:51,096 [salt.state       :1780][INFO    ][14940] Running state [/etc/maas/rackd.conf] at time 09:01:51.096265
2019-03-20 09:01:51,096 [salt.state       :1813][INFO    ][14940] Executing state file.line for [/etc/maas/rackd.conf]
2019-03-20 09:01:51,097 [salt.state       :300 ][INFO    ][14940] No changes needed to be made
2019-03-20 09:01:51,097 [salt.state       :1951][INFO    ][14940] Completed state [/etc/maas/rackd.conf] at time 09:01:51.097889 duration_in_ms=1.624
2019-03-20 09:01:51,098 [salt.state       :1780][INFO    ][14940] Running state [/etc/maas/rackd.conf] at time 09:01:51.098137
2019-03-20 09:01:51,098 [salt.state       :1813][INFO    ][14940] Executing state file.managed for [/etc/maas/rackd.conf]
2019-03-20 09:01:51,098 [salt.loaded.int.states.file:2298][WARNING ][14940] 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-03-20 09:01:51,100 [salt.state       :300 ][INFO    ][14940] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-03-20 09:01:51,100 [salt.state       :1951][INFO    ][14940] Completed state [/etc/maas/rackd.conf] at time 09:01:51.100569 duration_in_ms=2.431
2019-03-20 09:01:51,101 [salt.state       :1780][INFO    ][14940] Running state [maas-rackd] at time 09:01:51.101879
2019-03-20 09:01:51,102 [salt.state       :1813][INFO    ][14940] Executing state service.running for [maas-rackd]
2019-03-20 09:01:51,102 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14940] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-03-20 09:01:51,137 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14940] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-03-20 09:01:51,155 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14940] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-03-20 09:01:51,175 [salt.state       :300 ][INFO    ][14940] The service maas-rackd is already running
2019-03-20 09:01:51,176 [salt.state       :1951][INFO    ][14940] Completed state [maas-rackd] at time 09:01:51.175971 duration_in_ms=74.091
2019-03-20 09:01:51,178 [salt.minion      :1711][INFO    ][14940] Returning information for job: 20190320090149237331
2019-03-20 09:01:51,721 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command state.apply with jid 20190320090151709911
2019-03-20 09:01:51,741 [salt.minion      :1432][INFO    ][14963] Starting a new job with PID 14963
2019-03-20 09:01:52,602 [salt.state       :915 ][INFO    ][14963] Loading fresh modules for state activity
2019-03-20 09:01:53,581 [salt.state       :1780][INFO    ][14963] Running state [maas-region-controller] at time 09:01:53.581727
2019-03-20 09:01:53,582 [salt.state       :1813][INFO    ][14963] Executing state pkg.installed for [maas-region-controller]
2019-03-20 09:01:53,584 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14963] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-03-20 09:01:53,689 [salt.state       :300 ][INFO    ][14963] All specified packages are already installed
2019-03-20 09:01:53,690 [salt.state       :1951][INFO    ][14963] Completed state [maas-region-controller] at time 09:01:53.689972 duration_in_ms=108.246
2019-03-20 09:01:53,690 [salt.state       :1780][INFO    ][14963] Running state [python-oauth] at time 09:01:53.690293
2019-03-20 09:01:53,690 [salt.state       :1813][INFO    ][14963] Executing state pkg.installed for [python-oauth]
2019-03-20 09:01:53,699 [salt.state       :300 ][INFO    ][14963] All specified packages are already installed
2019-03-20 09:01:53,700 [salt.state       :1951][INFO    ][14963] Completed state [python-oauth] at time 09:01:53.700073 duration_in_ms=9.78
2019-03-20 09:01:53,704 [salt.state       :1780][INFO    ][14963] Running state [/etc/maas/regiond.conf] at time 09:01:53.703987
2019-03-20 09:01:53,704 [salt.state       :1813][INFO    ][14963] Executing state file.replace for [/etc/maas/regiond.conf]
2019-03-20 09:01:53,709 [salt.state       :300 ][INFO    ][14963] No changes needed to be made
2019-03-20 09:01:53,710 [salt.state       :1951][INFO    ][14963] Completed state [/etc/maas/regiond.conf] at time 09:01:53.710051 duration_in_ms=6.063
2019-03-20 09:01:53,711 [salt.state       :1780][INFO    ][14963] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 09:01:53.710997
2019-03-20 09:01:53,711 [salt.state       :1813][INFO    ][14963] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-03-20 09:01:53,776 [salt.state       :300 ][INFO    ][14963] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-03-20 09:01:53,777 [salt.state       :1951][INFO    ][14963] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 09:01:53.777117 duration_in_ms=66.12
2019-03-20 09:01:53,777 [salt.state       :1780][INFO    ][14963] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 09:01:53.777603
2019-03-20 09:01:53,777 [salt.state       :1813][INFO    ][14963] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-03-20 09:01:53,781 [salt.state       :300 ][INFO    ][14963] No changes needed to be made
2019-03-20 09:01:53,781 [salt.state       :1951][INFO    ][14963] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 09:01:53.781819 duration_in_ms=4.217
2019-03-20 09:01:53,782 [salt.state       :1780][INFO    ][14963] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 09:01:53.782289
2019-03-20 09:01:53,782 [salt.state       :1813][INFO    ][14963] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-03-20 09:01:53,785 [salt.state       :300 ][INFO    ][14963] No changes needed to be made
2019-03-20 09:01:53,786 [salt.state       :1951][INFO    ][14963] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 09:01:53.786085 duration_in_ms=3.796
2019-03-20 09:01:53,786 [salt.state       :1780][INFO    ][14963] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 09:01:53.786552
2019-03-20 09:01:53,786 [salt.state       :1813][INFO    ][14963] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-03-20 09:01:53,809 [salt.state       :300 ][INFO    ][14963] No changes needed to be made
2019-03-20 09:01:53,810 [salt.state       :1951][INFO    ][14963] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 09:01:53.810025 duration_in_ms=23.473
2019-03-20 09:01:53,810 [salt.state       :1780][INFO    ][14963] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 09:01:53.810491
2019-03-20 09:01:53,810 [salt.state       :1813][INFO    ][14963] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-03-20 09:01:53,825 [salt.state       :300 ][INFO    ][14963] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-03-20 09:01:53,825 [salt.state       :1951][INFO    ][14963] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 09:01:53.825797 duration_in_ms=15.305
2019-03-20 09:01:53,827 [salt.state       :1780][INFO    ][14963] Running state [a2enmod headers] at time 09:01:53.827376
2019-03-20 09:01:53,827 [salt.state       :1813][INFO    ][14963] Executing state cmd.run for [a2enmod headers]
2019-03-20 09:01:53,828 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14963] Executing command 'a2enmod headers' in directory '/root'
2019-03-20 09:01:53,902 [salt.state       :300 ][INFO    ][14963] {'pid': 14983, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-03-20 09:01:53,903 [salt.state       :1951][INFO    ][14963] Completed state [a2enmod headers] at time 09:01:53.902932 duration_in_ms=75.556
2019-03-20 09:01:53,903 [salt.state       :1780][INFO    ][14963] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 09:01:53.903367
2019-03-20 09:01:53,903 [salt.state       :1813][INFO    ][14963] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-03-20 09:01:53,925 [salt.state       :300 ][INFO    ][14963] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-03-20 09:01:53,925 [salt.state       :1951][INFO    ][14963] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 09:01:53.925695 duration_in_ms=22.328
2019-03-20 09:01:53,926 [salt.state       :1780][INFO    ][14963] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 09:01:53.926500
2019-03-20 09:01:53,926 [salt.state       :1813][INFO    ][14963] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-03-20 09:01:53,992 [salt.state       :300 ][INFO    ][14963] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-03-20 09:01:53,993 [salt.state       :1951][INFO    ][14963] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 09:01:53.993179 duration_in_ms=66.678
2019-03-20 09:01:53,993 [salt.state       :1780][INFO    ][14963] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 09:01:53.993856
2019-03-20 09:01:53,994 [salt.state       :1813][INFO    ][14963] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-03-20 09:01:54,072 [salt.state       :300 ][INFO    ][14963] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-03-20 09:01:54,072 [salt.state       :1951][INFO    ][14963] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 09:01:54.072432 duration_in_ms=78.574
2019-03-20 09:01:54,073 [salt.state       :1780][INFO    ][14963] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 09:01:54.072970
2019-03-20 09:01:54,073 [salt.state       :1813][INFO    ][14963] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-03-20 09:01:54,162 [salt.state       :300 ][INFO    ][14963] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-03-20 09:01:54,163 [salt.state       :1951][INFO    ][14963] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 09:01:54.162813 duration_in_ms=89.843
2019-03-20 09:01:54,163 [salt.state       :1780][INFO    ][14963] Running state [/root/.pgpass] at time 09:01:54.163657
2019-03-20 09:01:54,163 [salt.state       :1813][INFO    ][14963] Executing state file.managed for [/root/.pgpass]
2019-03-20 09:01:54,209 [salt.state       :300 ][INFO    ][14963] File /root/.pgpass is in the correct state
2019-03-20 09:01:54,209 [salt.state       :1951][INFO    ][14963] Completed state [/root/.pgpass] at time 09:01:54.209657 duration_in_ms=45.999
2019-03-20 09:01:54,214 [salt.state       :1780][INFO    ][14963] Running state [maas-region syncdb --noinput] at time 09:01:54.214793
2019-03-20 09:01:54,215 [salt.state       :1813][INFO    ][14963] Executing state cmd.run for [maas-region syncdb --noinput]
2019-03-20 09:01:54,215 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14963] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-03-20 09:01:57,068 [salt.state       :300 ][INFO    ][14963] {'pid': 15015, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: maasserver, piston3, sessions, contenttypes, auth, metadataserver, sites\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-03-20 09:01:57,069 [salt.state       :1951][INFO    ][14963] Completed state [maas-region syncdb --noinput] at time 09:01:57.069320 duration_in_ms=2854.526
2019-03-20 09:01:57,069 [salt.state       :2022][WARNING ][14963] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-03-20 09:01:57,073 [salt.state       :1780][INFO    ][14963] Running state [maas-regiond] at time 09:01:57.073404
2019-03-20 09:01:57,074 [salt.state       :1813][INFO    ][14963] Executing state service.running for [maas-regiond]
2019-03-20 09:01:57,076 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14963] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-03-20 09:01:57,113 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14963] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-03-20 09:01:57,130 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14963] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-03-20 09:01:57,146 [salt.state       :300 ][INFO    ][14963] The service maas-regiond is already running
2019-03-20 09:01:57,146 [salt.state       :1951][INFO    ][14963] Completed state [maas-regiond] at time 09:01:57.146439 duration_in_ms=73.036
2019-03-20 09:01:57,150 [salt.state       :1780][INFO    ][14963] Running state [bind9] at time 09:01:57.150641
2019-03-20 09:01:57,151 [salt.state       :1813][INFO    ][14963] Executing state service.running for [bind9]
2019-03-20 09:01:57,151 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14963] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-03-20 09:01:57,168 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14963] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-03-20 09:01:57,187 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14963] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-03-20 09:01:57,204 [salt.state       :300 ][INFO    ][14963] The service bind9 is already running
2019-03-20 09:01:57,204 [salt.state       :1951][INFO    ][14963] Completed state [bind9] at time 09:01:57.204583 duration_in_ms=53.942
2019-03-20 09:01:57,208 [salt.state       :1780][INFO    ][14963] Running state [apache2] at time 09:01:57.208865
2019-03-20 09:01:57,209 [salt.state       :1813][INFO    ][14963] Executing state service.running for [apache2]
2019-03-20 09:01:57,210 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14963] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-03-20 09:01:57,229 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14963] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-03-20 09:01:57,251 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14963] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-03-20 09:01:57,277 [salt.state       :300 ][INFO    ][14963] The service apache2 is already running
2019-03-20 09:01:57,277 [salt.state       :1951][INFO    ][14963] Completed state [apache2] at time 09:01:57.277579 duration_in_ms=68.714
2019-03-20 09:01:57,282 [salt.state       :1780][INFO    ][14963] Running state [maasng.wait_for_http_code] at time 09:01:57.282188
2019-03-20 09:01:57,282 [salt.state       :1813][INFO    ][14963] Executing state module.run for [maasng.wait_for_http_code]
2019-03-20 09:01:57,283 [salt.utils.decorators:613 ][WARNING ][14963] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-20 09:01:57,460 [salt.state       :300 ][INFO    ][14963] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-03-20 09:01:57,460 [salt.state       :1951][INFO    ][14963] Completed state [maasng.wait_for_http_code] at time 09:01:57.460439 duration_in_ms=178.25
2019-03-20 09:01:57,461 [salt.state       :1780][INFO    ][14963] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 09:01:57.461692
2019-03-20 09:01:57,462 [salt.state       :1813][INFO    ][14963] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-03-20 09:01:57,462 [salt.state       :300 ][INFO    ][14963] /var/lib/maas/.setup_admin exists
2019-03-20 09:01:57,462 [salt.state       :1951][INFO    ][14963] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 09:01:57.462725 duration_in_ms=1.033
2019-03-20 09:01:57,463 [salt.state       :1780][INFO    ][14963] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:01:57.463750
2019-03-20 09:01:57,464 [salt.state       :1813][INFO    ][14963] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-20 09:01:57,464 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14963] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-20 09:01:59,204 [salt.state       :300 ][INFO    ][14963] {'pid': 15061, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-20 09:01:59,205 [salt.state       :1951][INFO    ][14963] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:01:59.205254 duration_in_ms=1741.503
2019-03-20 09:01:59,214 [salt.state       :1780][INFO    ][14963] Running state [maas_region_boot_source_resources_mirror] at time 09:01:59.214030
2019-03-20 09:01:59,214 [salt.state       :1813][INFO    ][14963] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-03-20 09:01:59,304 [salt.state       :300 ][INFO    ][14963] {'changes': {}}
2019-03-20 09:01:59,304 [salt.state       :1951][INFO    ][14963] Completed state [maas_region_boot_source_resources_mirror] at time 09:01:59.304485 duration_in_ms=90.453
2019-03-20 09:01:59,305 [salt.state       :1780][INFO    ][14963] Running state [maasng.boot_resources_import] at time 09:01:59.305478
2019-03-20 09:01:59,305 [salt.state       :1813][INFO    ][14963] Executing state module.run for [maasng.boot_resources_import]
2019-03-20 09:01:59,306 [salt.utils.decorators:613 ][WARNING ][14963] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-20 09:01:59,402 [salt.loaded.ext.module.maasng:1600][INFO    ][14963] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-03-20 09:02:04,458 [salt.loaded.ext.module.maasng:1600][INFO    ][14963] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-03-20 09:02:06,774 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320090206758005
2019-03-20 09:02:06,790 [salt.minion      :1432][INFO    ][15181] Starting a new job with PID 15181
2019-03-20 09:02:06,828 [salt.minion      :1711][INFO    ][15181] Returning information for job: 20190320090206758005
2019-03-20 09:02:09,550 [salt.state       :300 ][INFO    ][14963] {'ret': True}
2019-03-20 09:02:09,550 [salt.state       :1951][INFO    ][14963] Completed state [maasng.boot_resources_import] at time 09:02:09.550603 duration_in_ms=10245.125
2019-03-20 09:02:09,552 [salt.state       :1780][INFO    ][14963] Running state [maas_region_boot_sources_selection_xenial] at time 09:02:09.551951
2019-03-20 09:02:09,552 [salt.state       :1813][INFO    ][14963] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-03-20 09:02:09,707 [salt.state       :300 ][INFO    ][14963] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-03-20 09:02:09,707 [salt.state       :1951][INFO    ][14963] Completed state [maas_region_boot_sources_selection_xenial] at time 09:02:09.707595 duration_in_ms=155.643
2019-03-20 09:02:09,708 [salt.state       :1780][INFO    ][14963] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 09:02:09.708888
2019-03-20 09:02:09,709 [salt.state       :1813][INFO    ][14963] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-03-20 09:02:09,709 [salt.utils.decorators:613 ][WARNING ][14963] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-20 09:02:09,710 [salt.loaded.ext.module.maasng:1771][INFO    ][14963] boot-sources sync initiated for ALL Rack's
2019-03-20 09:02:10,822 [salt.state       :300 ][INFO    ][14963] {'ret': True}
2019-03-20 09:02:10,823 [salt.state       :1951][INFO    ][14963] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 09:02:10.823247 duration_in_ms=1114.359
2019-03-20 09:02:10,824 [salt.state       :1780][INFO    ][14963] Running state [maas.process_maas_config] at time 09:02:10.824799
2019-03-20 09:02:10,825 [salt.state       :1813][INFO    ][14963] Executing state module.run for [maas.process_maas_config]
2019-03-20 09:02:10,825 [salt.utils.decorators:613 ][WARNING ][14963] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-20 09:02:10,826 [salt.loaded.ext.module.maas:92  ][INFO    ][14963] maasconfig name=enable_http_proxy value=True
2019-03-20 09:02:10,876 [salt.loaded.ext.module.maas:92  ][INFO    ][14963] maasconfig name=upstream_dns value=8.8.8.8
2019-03-20 09:02:10,928 [salt.loaded.ext.module.maas:92  ][INFO    ][14963] maasconfig name=commissioning_distro_series value=xenial
2019-03-20 09:02:10,988 [salt.loaded.ext.module.maas:92  ][INFO    ][14963] maasconfig name=default_osystem value=ubuntu
2019-03-20 09:02:11,047 [salt.loaded.ext.module.maas:92  ][INFO    ][14963] maasconfig name=active_discovery_interval value=600
2019-03-20 09:02:11,099 [salt.loaded.ext.module.maas:92  ][INFO    ][14963] maasconfig name=dnssec_validation value=no
2019-03-20 09:02:11,144 [salt.loaded.ext.module.maas:92  ][INFO    ][14963] maasconfig name=maas_name value=mas01
2019-03-20 09:02:12,625 [salt.loaded.ext.module.maas:92  ][INFO    ][14963] maasconfig name=network_discovery value=enabled
2019-03-20 09:02:12,757 [salt.loaded.ext.module.maas:92  ][INFO    ][14963] maasconfig name=enable_third_party_drivers value=True
2019-03-20 09:02:12,802 [salt.loaded.ext.module.maas:92  ][INFO    ][14963] maasconfig name=default_storage_layout value=lvm
2019-03-20 09:02:12,846 [salt.loaded.ext.module.maas:92  ][INFO    ][14963] maasconfig name=ntp_external_only value=True
2019-03-20 09:02:12,898 [salt.loaded.ext.module.maas:92  ][INFO    ][14963] maasconfig name=disk_erase_with_secure_erase value=False
2019-03-20 09:02:12,944 [salt.loaded.ext.module.maas:92  ][INFO    ][14963] maasconfig name=default_distro_series value=xenial
2019-03-20 09:02:13,022 [salt.loaded.ext.module.maas:92  ][INFO    ][14963] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-03-20 09:02:13,136 [salt.state       :300 ][INFO    ][14963] {'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-03-20 09:02:13,136 [salt.state       :1951][INFO    ][14963] Completed state [maas.process_maas_config] at time 09:02:13.136830 duration_in_ms=2312.031
2019-03-20 09:02:13,137 [salt.state       :1780][INFO    ][14963] Running state [pxe_admin] at time 09:02:13.137577
2019-03-20 09:02:13,137 [salt.state       :1813][INFO    ][14963] Executing state maasng.fabric_present for [pxe_admin]
2019-03-20 09:02:13,185 [salt.loaded.ext.module.maasng:945 ][INFO    ][14963] [{u'name': u'fabric-0', u'vlans': [{u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', 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'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'vid': 0, u'fabric': u'fabric-2', u'name': u'untagged', 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'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'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'hswkeq', 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'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}]
2019-03-20 09:02:13,240 [salt.loaded.ext.module.maasng:1008][WARNING ][14963] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-03-20 09:02:13,240 [salt.loaded.ext.module.maasng:1011][WARNING ][14963] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-03-20 09:02:13,298 [salt.state       :300 ][INFO    ][14963] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-03-20 09:02:13,298 [salt.state       :1951][INFO    ][14963] Completed state [pxe_admin] at time 09:02:13.298581 duration_in_ms=161.002
2019-03-20 09:02:13,302 [salt.state       :1780][INFO    ][14963] Running state [vlan 0] at time 09:02:13.301690
2019-03-20 09:02:13,302 [salt.state       :1813][INFO    ][14963] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-03-20 09:02:13,353 [salt.loaded.ext.module.maasng:945 ][INFO    ][14963] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/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'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'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'hswkeq', 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-03-20 09:02:13,441 [salt.loaded.ext.module.maasng:945 ][INFO    ][14963] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'hswkeq', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}]
2019-03-20 09:02:13,663 [salt.loaded.ext.module.maasng:945 ][INFO    ][14963] [{u'name': u'fabric-0', u'vlans': [{u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', 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'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'vid': 0, u'fabric': u'fabric-2', u'name': u'untagged', 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'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'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'hswkeq', 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'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}]
2019-03-20 09:02:13,753 [salt.state       :300 ][INFO    ][14963] {'new': 'Vlan untagged was updated'}
2019-03-20 09:02:13,754 [salt.state       :1951][INFO    ][14963] Completed state [vlan 0] at time 09:02:13.754242 duration_in_ms=452.551
2019-03-20 09:02:13,757 [salt.state       :1780][INFO    ][14963] Running state [192.168.11.0/24] at time 09:02:13.757153
2019-03-20 09:02:13,757 [salt.state       :1813][INFO    ][14963] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-03-20 09:02:13,928 [salt.loaded.ext.module.maasng:945 ][INFO    ][14963] [{u'name': u'fabric-0', u'vlans': [{u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', 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'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'vid': 0, u'fabric': u'fabric-2', u'name': u'untagged', 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'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'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'hswkeq', 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'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}]
2019-03-20 09:02:13,929 [salt.loaded.ext.module.maasng:1235][WARNING ][14963] Ignoring parameter vlan:0
2019-03-20 09:02:13,993 [salt.state       :300 ][INFO    ][14963] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-03-20 09:02:13,993 [salt.state       :1951][INFO    ][14963] Completed state [192.168.11.0/24] at time 09:02:13.993229 duration_in_ms=236.075
2019-03-20 09:02:13,994 [salt.state       :1780][INFO    ][14963] Running state [maas_create_iprange_1] at time 09:02:13.994009
2019-03-20 09:02:13,994 [salt.state       :1813][INFO    ][14963] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-03-20 09:02:14,040 [salt.state       :300 ][INFO    ][14963] Iprange maas_create_iprange_1 already exist.
2019-03-20 09:02:14,041 [salt.state       :1951][INFO    ][14963] Completed state [maas_create_iprange_1] at time 09:02:14.041159 duration_in_ms=47.15
2019-03-20 09:02:14,041 [salt.state       :1780][INFO    ][14963] Running state [vlan 0] at time 09:02:14.041427
2019-03-20 09:02:14,041 [salt.state       :1813][INFO    ][14963] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-03-20 09:02:14,083 [salt.loaded.ext.module.maasng:945 ][INFO    ][14963] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/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'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'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'hswkeq', 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-03-20 09:02:14,169 [salt.loaded.ext.module.maasng:945 ][INFO    ][14963] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'hswkeq', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u''}]
2019-03-20 09:02:14,371 [salt.loaded.ext.module.maasng:945 ][INFO    ][14963] [{u'name': u'fabric-0', u'vlans': [{u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', 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'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'vid': 0, u'fabric': u'fabric-2', u'name': u'untagged', 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'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'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'hswkeq', 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'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}]
2019-03-20 09:02:14,497 [salt.state       :300 ][INFO    ][14963] {'new': 'Vlan untagged was updated'}
2019-03-20 09:02:14,497 [salt.state       :1951][INFO    ][14963] Completed state [vlan 0] at time 09:02:14.497913 duration_in_ms=456.485
2019-03-20 09:02:14,498 [salt.state       :1780][INFO    ][14963] Running state [opnfv] at time 09:02:14.498545
2019-03-20 09:02:14,502 [salt.state       :1813][INFO    ][14963] Executing state maasng.sshkey_present for [opnfv]
2019-03-20 09:02:14,546 [salt.loaded.ext.module.maasng:1903][INFO    ][14963] [{u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCwbsLV1UY13y2+2b7H9h6zOFMrcnumZvKOz6oqAXgttZjoKci01cqGRU8OMjob/9xeAOP2Cmu7HOBpRxda00X57yYx4UidSgEtlbOi7LLj2DuvJScix+rgPAd19aukdCiTuMmyLZQPMzVrdBxqzupJ5zqS3djSCllnQ2myEwswcSBn0Dpusgt3bnwdsCgI6B6ZgL9aR9DLnyMPMVroI6ZZH7aP7syIy1sPEsiJd3smWEULgeRElsuK+4I3YKG1Z1J2n80lmtR2gVD5tuKLFRRxnbjAqzyCEXQBTUVRc13SAFrH0b5bzFaqfxY1wdQPzP+dBSA+t25KcjnpTAAtL86H', u'keysource': u''}]
2019-03-20 09:02:14,549 [salt.state       :300 ][INFO    ][14963] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCwbsLV1UY13y2+2b7H9h6zOFMrcnumZvKOz6oqAXgttZjoKci01cqGRU8OMjob/9xeAOP2Cmu7HOBpRxda00X57yYx4UidSgEtlbOi7LLj2DuvJScix+rgPAd19aukdCiTuMmyLZQPMzVrdBxqzupJ5zqS3djSCllnQ2myEwswcSBn0Dpusgt3bnwdsCgI6B6ZgL9aR9DLnyMPMVroI6ZZH7aP7syIy1sPEsiJd3smWEULgeRElsuK+4I3YKG1Z1J2n80lmtR2gVD5tuKLFRRxnbjAqzyCEXQBTUVRc13SAFrH0b5bzFaqfxY1wdQPzP+dBSA+t25KcjnpTAAtL86H already exist for user opnfv.
2019-03-20 09:02:14,550 [salt.state       :1951][INFO    ][14963] Completed state [opnfv] at time 09:02:14.550205 duration_in_ms=51.66
2019-03-20 09:02:14,553 [salt.minion      :1711][INFO    ][14963] Returning information for job: 20190320090151709911
2019-03-20 09:02:15,130 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command state.apply with jid 20190320090215121168
2019-03-20 09:02:15,145 [salt.minion      :1432][INFO    ][15456] Starting a new job with PID 15456
2019-03-20 09:02:23,060 [salt.state       :915 ][INFO    ][15456] Loading fresh modules for state activity
2019-03-20 09:02:23,157 [salt.state       :1780][INFO    ][15456] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:02:23.157563
2019-03-20 09:02:23,157 [salt.state       :1813][INFO    ][15456] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-20 09:02:23,159 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15456] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-20 09:02:24,902 [salt.state       :300 ][INFO    ][15456] {'pid': 15483, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-20 09:02:24,903 [salt.state       :1951][INFO    ][15456] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:02:24.903608 duration_in_ms=1746.044
2019-03-20 09:02:24,906 [salt.state       :1780][INFO    ][15456] Running state [maas.process_machines] at time 09:02:24.906416
2019-03-20 09:02:24,907 [salt.state       :1813][INFO    ][15456] Executing state module.run for [maas.process_machines]
2019-03-20 09:02:24,908 [salt.utils.decorators:613 ][WARNING ][15456] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-20 09:02:25,472 [salt.loaded.ext.module.maas:412 ][WARNING ][15456] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-20 09:02:25,473 [salt.loaded.ext.module.maas:92  ][INFO    ][15456] 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=q8rgkr architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-20 09:02:26,579 [salt.loaded.ext.module.maas:412 ][WARNING ][15456] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-20 09:02:26,580 [salt.loaded.ext.module.maas:92  ][INFO    ][15456] 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=8teekt architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-20 09:02:27,671 [salt.loaded.ext.module.maas:412 ][WARNING ][15456] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-20 09:02:27,672 [salt.loaded.ext.module.maas:92  ][INFO    ][15456] 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=sskgpg architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-20 09:02:28,787 [salt.loaded.ext.module.maas:412 ][WARNING ][15456] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-20 09:02:28,788 [salt.loaded.ext.module.maas:92  ][INFO    ][15456] 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=4hcxey architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-20 09:02:29,904 [salt.loaded.ext.module.maas:412 ][WARNING ][15456] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-20 09:02:29,905 [salt.loaded.ext.module.maas:92  ][INFO    ][15456] 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=a6ggf4 architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-20 09:02:30,254 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320090230242610
2019-03-20 09:02:30,273 [salt.minion      :1432][INFO    ][15684] Starting a new job with PID 15684
2019-03-20 09:02:30,299 [salt.minion      :1711][INFO    ][15684] Returning information for job: 20190320090230242610
2019-03-20 09:02:30,986 [salt.state       :300 ][INFO    ][15456] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-03-20 09:02:30,989 [salt.state       :1951][INFO    ][15456] Completed state [maas.process_machines] at time 09:02:30.989507 duration_in_ms=6083.09
2019-03-20 09:02:30,992 [salt.minion      :1711][INFO    ][15456] Returning information for job: 20190320090215121168
2019-03-20 09:03:04,017 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command state.apply with jid 20190320090304005540
2019-03-20 09:03:04,034 [salt.minion      :1432][INFO    ][15783] Starting a new job with PID 15783
2019-03-20 09:03:11,763 [salt.state       :915 ][INFO    ][15783] Loading fresh modules for state activity
2019-03-20 09:03:11,859 [salt.state       :1780][INFO    ][15783] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:03:11.858894
2019-03-20 09:03:11,859 [salt.state       :1813][INFO    ][15783] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-20 09:03:11,861 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15783] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-20 09:03:13,579 [salt.state       :300 ][INFO    ][15783] {'pid': 15794, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-20 09:03:13,580 [salt.state       :1951][INFO    ][15783] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:03:13.579978 duration_in_ms=1721.086
2019-03-20 09:03:13,581 [salt.state       :1780][INFO    ][15783] Running state [maas.wait_for_machine_status] at time 09:03:13.581631
2019-03-20 09:03:13,581 [salt.state       :1813][INFO    ][15783] Executing state module.run for [maas.wait_for_machine_status]
2019-03-20 09:03:13,582 [salt.utils.decorators:613 ][WARNING ][15783] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-20 09:03:16,386 [salt.state       :300 ][INFO    ][15783] {'ret': True}
2019-03-20 09:03:16,386 [salt.state       :1951][INFO    ][15783] Completed state [maas.wait_for_machine_status] at time 09:03:16.386294 duration_in_ms=2804.663
2019-03-20 09:03:16,388 [salt.minion      :1711][INFO    ][15783] Returning information for job: 20190320090304005540
2019-03-20 09:03:16,928 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command state.apply with jid 20190320090316917155
2019-03-20 09:03:16,952 [salt.minion      :1432][INFO    ][15807] Starting a new job with PID 15807
2019-03-20 09:03:17,808 [salt.state       :915 ][INFO    ][15807] Loading fresh modules for state activity
2019-03-20 09:03:17,949 [salt.state       :1780][INFO    ][15807] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:03:17.949679
2019-03-20 09:03:17,950 [salt.state       :1813][INFO    ][15807] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-20 09:03:17,951 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15807] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-20 09:03:19,764 [salt.state       :300 ][INFO    ][15807] {'pid': 15814, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-20 09:03:19,765 [salt.state       :1951][INFO    ][15807] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:03:19.765420 duration_in_ms=1815.74
2019-03-20 09:03:19,770 [salt.state       :1780][INFO    ][15807] Running state [maas_machines_storage_cmp002_lvm] at time 09:03:19.770559
2019-03-20 09:03:19,771 [salt.state       :1813][INFO    ][15807] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-03-20 09:03:20,309 [salt.state       :300 ][INFO    ][15807] Machine cmp002 is not in Ready state.
2019-03-20 09:03:20,310 [salt.state       :1951][INFO    ][15807] Completed state [maas_machines_storage_cmp002_lvm] at time 09:03:20.310113 duration_in_ms=539.554
2019-03-20 09:03:20,310 [salt.state       :1780][INFO    ][15807] Running state [maas_machines_storage_cmp001_lvm] at time 09:03:20.310477
2019-03-20 09:03:20,310 [salt.state       :1813][INFO    ][15807] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-03-20 09:03:20,826 [salt.state       :300 ][INFO    ][15807] Machine cmp001 is not in Ready state.
2019-03-20 09:03:20,826 [salt.state       :1951][INFO    ][15807] Completed state [maas_machines_storage_cmp001_lvm] at time 09:03:20.826309 duration_in_ms=515.831
2019-03-20 09:03:20,829 [salt.minion      :1711][INFO    ][15807] Returning information for job: 20190320090316917155
2019-03-20 09:03:21,362 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command state.apply with jid 20190320090321354539
2019-03-20 09:03:21,382 [salt.minion      :1432][INFO    ][15824] Starting a new job with PID 15824
2019-03-20 09:03:22,264 [salt.state       :915 ][INFO    ][15824] Loading fresh modules for state activity
2019-03-20 09:03:22,346 [salt.state       :1780][INFO    ][15824] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:03:22.346717
2019-03-20 09:03:22,348 [salt.state       :1813][INFO    ][15824] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-20 09:03:22,350 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15824] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-20 09:03:24,094 [salt.state       :300 ][INFO    ][15824] {'pid': 15833, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-20 09:03:24,095 [salt.state       :1951][INFO    ][15824] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:03:24.095004 duration_in_ms=1748.287
2019-03-20 09:03:24,096 [salt.state       :1780][INFO    ][15824] Running state [maas.deploy_machines] at time 09:03:24.096784
2019-03-20 09:03:24,097 [salt.state       :1813][INFO    ][15824] Executing state module.run for [maas.deploy_machines]
2019-03-20 09:03:24,097 [salt.utils.decorators:613 ][WARNING ][15824] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-20 09:03:24,645 [salt.loaded.ext.module.maas:684 ][INFO    ][15824] deploymachines hwe_kernel=hwe-16.04 system_id=a6ggf4 distro_series=xenial
2019-03-20 09:03:26,928 [salt.state       :300 ][INFO    ][15824] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-03-20 09:03:26,928 [salt.state       :1951][INFO    ][15824] Completed state [maas.deploy_machines] at time 09:03:26.928510 duration_in_ms=2831.726
2019-03-20 09:03:26,930 [salt.minion      :1711][INFO    ][15824] Returning information for job: 20190320090321354539
2019-03-20 09:03:27,491 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command state.apply with jid 20190320090327477189
2019-03-20 09:03:27,512 [salt.minion      :1432][INFO    ][15896] Starting a new job with PID 15896
2019-03-20 09:03:35,276 [salt.state       :915 ][INFO    ][15896] Loading fresh modules for state activity
2019-03-20 09:03:35,361 [salt.state       :1780][INFO    ][15896] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:03:35.361125
2019-03-20 09:03:35,361 [salt.state       :1813][INFO    ][15896] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-20 09:03:35,363 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15896] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-20 09:03:37,095 [salt.state       :300 ][INFO    ][15896] {'pid': 15907, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-20 09:03:37,096 [salt.state       :1951][INFO    ][15896] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:03:37.096121 duration_in_ms=1734.996
2019-03-20 09:03:37,100 [salt.state       :1780][INFO    ][15896] Running state [maas.wait_for_machine_status] at time 09:03:37.100123
2019-03-20 09:03:37,100 [salt.state       :1813][INFO    ][15896] Executing state module.run for [maas.wait_for_machine_status]
2019-03-20 09:03:37,101 [salt.utils.decorators:613 ][WARNING ][15896] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-20 09:03:39,787 [salt.loaded.ext.module.maas:1023][INFO    ][15896] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.32503486s left)
2019-03-20 09:03:42,590 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320090342571656
2019-03-20 09:03:42,610 [salt.minion      :1432][INFO    ][15931] Starting a new job with PID 15931
2019-03-20 09:03:42,633 [salt.minion      :1711][INFO    ][15931] Returning information for job: 20190320090342571656
2019-03-20 09:04:12,576 [salt.loaded.ext.module.maas:1023][INFO    ][15896] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.53661394s left)
2019-03-20 09:04:12,806 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320090412752770
2019-03-20 09:04:12,828 [salt.minion      :1432][INFO    ][16004] Starting a new job with PID 16004
2019-03-20 09:04:12,860 [salt.minion      :1711][INFO    ][16004] Returning information for job: 20190320090412752770
2019-03-20 09:04:42,881 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320090442872553
2019-03-20 09:04:42,899 [salt.minion      :1432][INFO    ][16035] Starting a new job with PID 16035
2019-03-20 09:04:42,928 [salt.minion      :1711][INFO    ][16035] Returning information for job: 20190320090442872553
2019-03-20 09:04:45,261 [salt.loaded.ext.module.maas:1023][INFO    ][15896] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2181.8510499s left)
2019-03-20 09:05:12,926 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320090512910422
2019-03-20 09:05:12,944 [salt.minion      :1432][INFO    ][16119] Starting a new job with PID 16119
2019-03-20 09:05:12,969 [salt.minion      :1711][INFO    ][16119] Returning information for job: 20190320090512910422
2019-03-20 09:05:18,040 [salt.loaded.ext.module.maas:1023][INFO    ][15896] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2149.07216287s left)
2019-03-20 09:05:43,112 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320090543104323
2019-03-20 09:05:43,138 [salt.minion      :1432][INFO    ][16149] Starting a new job with PID 16149
2019-03-20 09:05:43,162 [salt.minion      :1711][INFO    ][16149] Returning information for job: 20190320090543104323
2019-03-20 09:05:50,807 [salt.loaded.ext.module.maas:1023][INFO    ][15896] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2116.30542493s left)
2019-03-20 09:06:13,316 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320090613306935
2019-03-20 09:06:13,341 [salt.minion      :1432][INFO    ][16224] Starting a new job with PID 16224
2019-03-20 09:06:13,364 [salt.minion      :1711][INFO    ][16224] Returning information for job: 20190320090613306935
2019-03-20 09:06:23,509 [salt.loaded.ext.module.maas:1023][INFO    ][15896] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2083.60314894s left)
2019-03-20 09:06:43,514 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320090643503189
2019-03-20 09:06:43,536 [salt.minion      :1432][INFO    ][16254] Starting a new job with PID 16254
2019-03-20 09:06:43,561 [salt.minion      :1711][INFO    ][16254] Returning information for job: 20190320090643503189
2019-03-20 09:06:56,249 [salt.loaded.ext.module.maas:1023][INFO    ][15896] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2050.86312389s left)
2019-03-20 09:07:13,716 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320090713708755
2019-03-20 09:07:13,737 [salt.minion      :1432][INFO    ][16332] Starting a new job with PID 16332
2019-03-20 09:07:13,762 [salt.minion      :1711][INFO    ][16332] Returning information for job: 20190320090713708755
2019-03-20 09:07:28,880 [salt.loaded.ext.module.maas:1023][INFO    ][15896] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2018.23235583s left)
2019-03-20 09:07:43,928 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320090743921007
2019-03-20 09:07:43,941 [salt.minion      :1432][INFO    ][16385] Starting a new job with PID 16385
2019-03-20 09:07:43,964 [salt.minion      :1711][INFO    ][16385] Returning information for job: 20190320090743921007
2019-03-20 09:08:01,850 [salt.loaded.ext.module.maas:1023][INFO    ][15896] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1985.262187s left)
2019-03-20 09:08:13,980 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320090813972407
2019-03-20 09:08:13,993 [salt.minion      :1432][INFO    ][16470] Starting a new job with PID 16470
2019-03-20 09:08:14,017 [salt.minion      :1711][INFO    ][16470] Returning information for job: 20190320090813972407
2019-03-20 09:08:34,653 [salt.loaded.ext.module.maas:1023][INFO    ][15896] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1952.45910192s left)
2019-03-20 09:08:44,073 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320090844059859
2019-03-20 09:08:44,089 [salt.minion      :1432][INFO    ][16518] Starting a new job with PID 16518
2019-03-20 09:08:44,118 [salt.minion      :1711][INFO    ][16518] Returning information for job: 20190320090844059859
2019-03-20 09:09:07,485 [salt.loaded.ext.module.maas:1023][INFO    ][15896] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1919.62714005s left)
2019-03-20 09:09:14,132 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320090914123637
2019-03-20 09:09:14,157 [salt.minion      :1432][INFO    ][16738] Starting a new job with PID 16738
2019-03-20 09:09:14,188 [salt.minion      :1711][INFO    ][16738] Returning information for job: 20190320090914123637
2019-03-20 09:09:40,230 [salt.loaded.ext.module.maas:1023][INFO    ][15896] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1886.88296199s left)
2019-03-20 09:09:44,216 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320090944202881
2019-03-20 09:09:44,241 [salt.minion      :1432][INFO    ][16767] Starting a new job with PID 16767
2019-03-20 09:09:44,266 [salt.minion      :1711][INFO    ][16767] Returning information for job: 20190320090944202881
2019-03-20 09:10:13,165 [salt.loaded.ext.module.maas:1023][INFO    ][15896] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1853.94749284s left)
2019-03-20 09:10:14,325 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320091014311767
2019-03-20 09:10:14,349 [salt.minion      :1432][INFO    ][16862] Starting a new job with PID 16862
2019-03-20 09:10:14,374 [salt.minion      :1711][INFO    ][16862] Returning information for job: 20190320091014311767
2019-03-20 09:10:44,431 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320091044423481
2019-03-20 09:10:44,447 [salt.minion      :1432][INFO    ][16895] Starting a new job with PID 16895
2019-03-20 09:10:44,471 [salt.minion      :1711][INFO    ][16895] Returning information for job: 20190320091044423481
2019-03-20 09:10:46,042 [salt.loaded.ext.module.maas:1023][INFO    ][15896] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1821.07080793s left)
2019-03-20 09:11:14,554 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320091114538313
2019-03-20 09:11:14,576 [salt.minion      :1432][INFO    ][17052] Starting a new job with PID 17052
2019-03-20 09:11:14,606 [salt.minion      :1711][INFO    ][17052] Returning information for job: 20190320091114538313
2019-03-20 09:11:18,884 [salt.loaded.ext.module.maas:1023][INFO    ][15896] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1788.229002s left)
2019-03-20 09:11:44,645 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320091144636983
2019-03-20 09:11:44,661 [salt.minion      :1432][INFO    ][17086] Starting a new job with PID 17086
2019-03-20 09:11:44,685 [salt.minion      :1711][INFO    ][17086] Returning information for job: 20190320091144636983
2019-03-20 09:11:51,605 [salt.loaded.ext.module.maas:1023][INFO    ][15896] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1755.50768089s left)
2019-03-20 09:12:14,694 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320091214681258
2019-03-20 09:12:14,718 [salt.minion      :1432][INFO    ][17191] Starting a new job with PID 17191
2019-03-20 09:12:14,743 [salt.minion      :1711][INFO    ][17191] Returning information for job: 20190320091214681258
2019-03-20 09:12:24,449 [salt.loaded.ext.module.maas:1023][INFO    ][15896] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1722.66373491s left)
2019-03-20 09:12:44,803 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320091244788717
2019-03-20 09:12:44,826 [salt.minion      :1432][INFO    ][17221] Starting a new job with PID 17221
2019-03-20 09:12:44,848 [salt.minion      :1711][INFO    ][17221] Returning information for job: 20190320091244788717
2019-03-20 09:12:57,340 [salt.loaded.ext.module.maas:1023][INFO    ][15896] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1689.77247882s left)
2019-03-20 09:13:14,964 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320091314953200
2019-03-20 09:13:14,984 [salt.minion      :1432][INFO    ][17292] Starting a new job with PID 17292
2019-03-20 09:13:15,008 [salt.minion      :1711][INFO    ][17292] Returning information for job: 20190320091314953200
2019-03-20 09:13:30,177 [salt.loaded.ext.module.maas:1023][INFO    ][15896] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1656.93527293s left)
2019-03-20 09:13:45,097 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320091345082733
2019-03-20 09:13:45,118 [salt.minion      :1432][INFO    ][17322] Starting a new job with PID 17322
2019-03-20 09:13:45,143 [salt.minion      :1711][INFO    ][17322] Returning information for job: 20190320091345082733
2019-03-20 09:14:03,067 [salt.loaded.ext.module.maas:1023][INFO    ][15896] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1624.04530382s left)
2019-03-20 09:14:15,196 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320091415187724
2019-03-20 09:14:15,212 [salt.minion      :1432][INFO    ][17396] Starting a new job with PID 17396
2019-03-20 09:14:15,237 [salt.minion      :1711][INFO    ][17396] Returning information for job: 20190320091415187724
2019-03-20 09:14:35,881 [salt.loaded.ext.module.maas:1023][INFO    ][15896] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1591.23134494s left)
2019-03-20 09:14:45,306 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320091445298766
2019-03-20 09:14:45,321 [salt.minion      :1432][INFO    ][17435] Starting a new job with PID 17435
2019-03-20 09:14:45,343 [salt.minion      :1711][INFO    ][17435] Returning information for job: 20190320091445298766
2019-03-20 09:15:08,802 [salt.loaded.ext.module.maas:1023][INFO    ][15896] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1558.31037784s left)
2019-03-20 09:15:15,457 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command saltutil.find_job with jid 20190320091515444593
2019-03-20 09:15:15,478 [salt.minion      :1432][INFO    ][17690] Starting a new job with PID 17690
2019-03-20 09:15:15,503 [salt.minion      :1711][INFO    ][17690] Returning information for job: 20190320091515444593
2019-03-20 09:15:41,815 [salt.state       :300 ][INFO    ][15896] {'ret': True}
2019-03-20 09:15:41,815 [salt.state       :1951][INFO    ][15896] Completed state [maas.wait_for_machine_status] at time 09:15:41.815815 duration_in_ms=724715.687
2019-03-20 09:15:41,821 [salt.minion      :1711][INFO    ][15896] Returning information for job: 20190320090327477189
2019-03-20 09:15:45,095 [salt.utils.schedule:1377][INFO    ][5608] Running scheduled job: __mine_interval
2019-03-20 10:15:45,096 [salt.utils.schedule:1377][INFO    ][5608] Running scheduled job: __mine_interval
2019-03-20 11:14:42,906 [salt.minion      :1308][INFO    ][5608] User sudo_ubuntu Executing command cp.push_dir with jid 20190320111442895144
2019-03-20 11:14:42,925 [salt.minion      :1432][INFO    ][29791] Starting a new job with PID 29791
