2019-02-26 05:09:58,029 [salt.utils.decorators:613 ][WARNING ][1980] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-26 05:09:59,227 [salt.utils.decorators:613 ][WARNING ][1980] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-26 05:10:01,776 [salt.loaded.int.states.file:2298][WARNING ][2569] 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-02-26 05:10:32,497 [salt.state       :2022][WARNING ][3041] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-02-26 05:10:35,494 [salt.utils.decorators:613 ][WARNING ][3041] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-26 05:12:03,252 [salt.utils.decorators:613 ][WARNING ][3041] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-26 05:19:57,153 [salt.utils.decorators:613 ][WARNING ][3041] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-26 05:20:18,713 [salt.utils.decorators:613 ][WARNING ][3041] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-26 05:20:20,695 [salt.loaded.ext.module.maasng:1008][WARNING ][3041] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-02-26 05:20:20,696 [salt.loaded.ext.module.maasng:1011][WARNING ][3041] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-02-26 05:20:21,335 [salt.loaded.ext.module.maasng:1235][WARNING ][3041] Ignoring parameter vlan:0
2019-02-26 05:20:22,099 [salt.utils.decorators:613 ][WARNING ][3041] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-26 05:20:24,836 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16748] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-02-26 05:20:24,858 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16748] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-02-26 05:20:24,896 [salt.utils.parsers:1051][WARNING ][387] Minion received a SIGTERM. Exiting.
2019-02-26 05:20:25,882 [salt.cli.daemons :293 ][INFO    ][16823] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-02-26 05:20:25,999 [salt.cli.daemons :82  ][INFO    ][16823] Starting up the Salt Minion
2019-02-26 05:20:26,000 [salt.utils.event :1017][INFO    ][16823] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-02-26 05:20:27,027 [salt.minion      :976 ][INFO    ][16823] Creating minion process manager
2019-02-26 05:20:28,678 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][16823] Executing command ['date', '+%z'] in directory '/root'
2019-02-26 05:20:28,700 [salt.utils.schedule:568 ][INFO    ][16823] Updating job settings for scheduled job: __mine_interval
2019-02-26 05:20:28,701 [salt.minion      :1108][INFO    ][16823] Added mine.update to scheduler
2019-02-26 05:20:28,706 [salt.minion      :1975][INFO    ][16823] Minion is starting as user 'root'
2019-02-26 05:20:28,722 [salt.minion      :2336][INFO    ][16823] Minion is ready to receive requests!
2019-02-26 05:20:32,992 [salt.utils.decorators:613 ][WARNING ][16755] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-26 05:20:33,049 [salt.loaded.ext.module.maas:412 ][WARNING ][16755] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-26 05:20:34,436 [salt.loaded.ext.module.maas:412 ][WARNING ][16755] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-26 05:20:35,650 [salt.loaded.ext.module.maas:412 ][WARNING ][16755] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-26 05:20:36,753 [salt.loaded.ext.module.maas:412 ][WARNING ][16755] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-26 05:20:38,008 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226052037994364
2019-02-26 05:20:38,025 [salt.minion      :1432][INFO    ][17144] Starting a new job with PID 17144
2019-02-26 05:20:38,048 [salt.minion      :1711][INFO    ][17144] Returning information for job: 20190226052037994364
2019-02-26 05:20:38,052 [salt.loaded.ext.module.maas:412 ][WARNING ][16755] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-26 05:21:10,236 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command state.apply with jid 20190226052110223224
2019-02-26 05:21:10,253 [salt.minion      :1432][INFO    ][17264] Starting a new job with PID 17264
2019-02-26 05:21:17,898 [salt.state       :915 ][INFO    ][17264] Loading fresh modules for state activity
2019-02-26 05:21:17,953 [salt.fileclient  :1219][INFO    ][17264] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-02-26 05:21:18,000 [salt.state       :1780][INFO    ][17264] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:21:18.000312
2019-02-26 05:21:18,000 [salt.state       :1813][INFO    ][17264] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-26 05:21:18,002 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17264] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-26 05:21:19,693 [salt.state       :300 ][INFO    ][17264] {'pid': 17271, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-26 05:21:19,693 [salt.state       :1951][INFO    ][17264] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:21:19.693873 duration_in_ms=1693.562
2019-02-26 05:21:19,695 [salt.state       :1780][INFO    ][17264] Running state [maas.wait_for_machine_status] at time 05:21:19.695898
2019-02-26 05:21:19,696 [salt.state       :1813][INFO    ][17264] Executing state module.run for [maas.wait_for_machine_status]
2019-02-26 05:21:19,696 [salt.utils.decorators:613 ][WARNING ][17264] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-26 05:21:20,506 [salt.loaded.ext.module.maas:1023][INFO    ][17264] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.1972239s left)
2019-02-26 05:21:25,292 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226052125283718
2019-02-26 05:21:25,306 [salt.minion      :1432][INFO    ][17293] Starting a new job with PID 17293
2019-02-26 05:21:25,325 [salt.minion      :1711][INFO    ][17293] Returning information for job: 20190226052125283718
2019-02-26 05:21:51,403 [salt.loaded.ext.module.maas:1023][INFO    ][17264] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.30012512s left)
2019-02-26 05:21:55,468 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226052155459973
2019-02-26 05:21:55,487 [salt.minion      :1432][INFO    ][17348] Starting a new job with PID 17348
2019-02-26 05:21:55,507 [salt.minion      :1711][INFO    ][17348] Returning information for job: 20190226052155459973
2019-02-26 05:22:22,189 [salt.loaded.ext.module.maas:1023][INFO    ][17264] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.51472902s left)
2019-02-26 05:22:25,510 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226052225496704
2019-02-26 05:22:25,530 [salt.minion      :1432][INFO    ][17391] Starting a new job with PID 17391
2019-02-26 05:22:25,550 [salt.minion      :1711][INFO    ][17391] Returning information for job: 20190226052225496704
2019-02-26 05:22:53,003 [salt.loaded.ext.module.maas:1023][INFO    ][17264] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.70009303s left)
2019-02-26 05:22:55,565 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226052255551797
2019-02-26 05:22:55,586 [salt.minion      :1432][INFO    ][17448] Starting a new job with PID 17448
2019-02-26 05:22:55,607 [salt.minion      :1711][INFO    ][17448] Returning information for job: 20190226052255551797
2019-02-26 05:23:24,068 [salt.loaded.ext.module.maas:1023][INFO    ][17264] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.63551998s left)
2019-02-26 05:23:25,620 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226052325604011
2019-02-26 05:23:25,641 [salt.minion      :1432][INFO    ][17520] Starting a new job with PID 17520
2019-02-26 05:23:25,666 [salt.minion      :1711][INFO    ][17520] Returning information for job: 20190226052325604011
2019-02-26 05:23:55,106 [salt.loaded.ext.module.maas:1023][INFO    ][17264] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.59752893s left)
2019-02-26 05:23:55,840 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226052355828893
2019-02-26 05:23:55,858 [salt.minion      :1432][INFO    ][17670] Starting a new job with PID 17670
2019-02-26 05:23:55,881 [salt.minion      :1711][INFO    ][17670] Returning information for job: 20190226052355828893
2019-02-26 05:24:26,048 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226052426037338
2019-02-26 05:24:26,071 [salt.minion      :1432][INFO    ][17814] Starting a new job with PID 17814
2019-02-26 05:24:26,098 [salt.minion      :1711][INFO    ][17814] Returning information for job: 20190226052426037338
2019-02-26 05:24:26,333 [salt.loaded.ext.module.maas:1023][INFO    ][17264] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1313.37057495s left)
2019-02-26 05:24:56,069 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226052456059539
2019-02-26 05:24:56,084 [salt.minion      :1432][INFO    ][18097] Starting a new job with PID 18097
2019-02-26 05:24:56,104 [salt.minion      :1711][INFO    ][18097] Returning information for job: 20190226052456059539
2019-02-26 05:24:57,612 [salt.loaded.ext.module.maas:1023][INFO    ][17264] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1282.09141493s left)
2019-02-26 05:25:26,098 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226052526087907
2019-02-26 05:25:26,121 [salt.minion      :1432][INFO    ][18269] Starting a new job with PID 18269
2019-02-26 05:25:26,142 [salt.minion      :1711][INFO    ][18269] Returning information for job: 20190226052526087907
2019-02-26 05:25:29,335 [salt.loaded.ext.module.maas:1023][INFO    ][17264] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1250.36908793s left)
2019-02-26 05:25:56,198 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226052556186018
2019-02-26 05:25:56,219 [salt.minion      :1432][INFO    ][18588] Starting a new job with PID 18588
2019-02-26 05:25:56,242 [salt.minion      :1711][INFO    ][18588] Returning information for job: 20190226052556186018
2019-02-26 05:26:01,422 [salt.loaded.ext.module.maas:1023][INFO    ][17264] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1218.28113699s left)
2019-02-26 05:26:26,325 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226052626312833
2019-02-26 05:26:26,345 [salt.minion      :1432][INFO    ][18809] Starting a new job with PID 18809
2019-02-26 05:26:26,372 [salt.minion      :1711][INFO    ][18809] Returning information for job: 20190226052626312833
2019-02-26 05:26:33,584 [salt.loaded.ext.module.maas:1023][INFO    ][17264] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1186.11984801s left)
2019-02-26 05:26:56,438 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226052656430506
2019-02-26 05:26:56,455 [salt.minion      :1432][INFO    ][19015] Starting a new job with PID 19015
2019-02-26 05:26:56,477 [salt.minion      :1711][INFO    ][19015] Returning information for job: 20190226052656430506
2019-02-26 05:27:06,141 [salt.loaded.ext.module.maas:1023][INFO    ][17264] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03']
sleep for:30s Timeout:1500s (1153.56263399s left)
2019-02-26 05:27:26,657 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226052726645280
2019-02-26 05:27:26,676 [salt.minion      :1432][INFO    ][19168] Starting a new job with PID 19168
2019-02-26 05:27:26,698 [salt.minion      :1711][INFO    ][19168] Returning information for job: 20190226052726645280
2019-02-26 05:27:39,173 [salt.state       :300 ][INFO    ][17264] {'ret': True}
2019-02-26 05:27:39,173 [salt.state       :1951][INFO    ][17264] Completed state [maas.wait_for_machine_status] at time 05:27:39.173490 duration_in_ms=379477.59
2019-02-26 05:27:39,175 [salt.minion      :1711][INFO    ][17264] Returning information for job: 20190226052110223224
2019-02-26 05:27:39,720 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command state.apply with jid 20190226052739709223
2019-02-26 05:27:39,740 [salt.minion      :1432][INFO    ][19245] Starting a new job with PID 19245
2019-02-26 05:27:47,668 [salt.state       :915 ][INFO    ][19245] Loading fresh modules for state activity
2019-02-26 05:27:47,721 [salt.fileclient  :1219][INFO    ][19245] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-02-26 05:27:47,821 [salt.state       :1780][INFO    ][19245] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:27:47.821419
2019-02-26 05:27:47,821 [salt.state       :1813][INFO    ][19245] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-26 05:27:47,823 [salt.loaded.int.module.cmdmod:395 ][INFO    ][19245] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-26 05:27:49,559 [salt.state       :300 ][INFO    ][19245] {'pid': 19275, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-26 05:27:49,561 [salt.state       :1951][INFO    ][19245] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:27:49.560853 duration_in_ms=1739.433
2019-02-26 05:27:49,565 [salt.state       :1780][INFO    ][19245] Running state [maas_machines_storage_cmp002_lvm] at time 05:27:49.565161
2019-02-26 05:27:49,565 [salt.state       :1813][INFO    ][19245] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-02-26 05:27:50,684 [salt.loaded.ext.module.maasng:610 ][INFO    ][19245] fxkccd
2019-02-26 05:27:50,684 [salt.loaded.ext.module.maasng:626 ][INFO    ][19245] sda
2019-02-26 05:27:51,222 [salt.loaded.ext.module.maasng:361 ][INFO    ][19245] fxkccd
2019-02-26 05:27:51,318 [salt.loaded.ext.module.maasng:367 ][INFO    ][19245] [{u'size': 800109715456, u'partition_table_type': u'MBR', u'block_size': 4096, u'uuid': None, u'tags': [u'ssd'], u'used_size': 800106479616, u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'fxkccd', u'resource_uri': u'/MAAS/api/2.0/nodes/fxkccd/blockdevices/1/', 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'partitions': [{u'uuid': u'ffb9f68b-f9ef-4673-b91e-873e779ea6dc', u'resource_uri': u'/MAAS/api/2.0/nodes/fxkccd/blockdevices/1/partition/1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'fxkccd', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'fab2a77d-1382-4d48-b531-ff8f3c04208c', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 1, u'device_id': 1}], u'type': u'physical', u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'name': u'sda'}, {u'size': 800097042432, u'partition_table_type': None, u'block_size': 4096, u'uuid': u'd21ad490-3fce-45b2-8de5-86a67f70aaae', u'tags': [], u'used_size': 800097042432, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'7d9e9c3a-a652-4917-8a33-e556df6bf4ae', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'fxkccd', u'resource_uri': u'/MAAS/api/2.0/nodes/fxkccd/blockdevices/3/', u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'partitions': [], u'type': u'virtual', u'id': 3, u'serial': None, u'name': u'vgroot-lvroot'}]
2019-02-26 05:27:51,319 [salt.loaded.ext.module.maasng:632 ][INFO    ][19245] vgroot
2019-02-26 05:27:51,319 [salt.loaded.ext.module.maasng:635 ][INFO    ][19245] lvroot
2019-02-26 05:27:51,319 [salt.loaded.ext.module.maasng:639 ][INFO    ][19245] 107374182400
2019-02-26 05:27:51,921 [salt.loaded.ext.module.maasng:645 ][INFO    ][19245] {u'hwe_kernel': u'', u'swap_size': None, u'disable_ipv4': False, 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'status_action': u'', 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'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'boot_disk': {u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'partitions': [{u'uuid': u'7084a16b-d7ed-4263-ab6f-9104f08c352d', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'fxkccd', u'device_id': 1, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'da6c95b7-62c4-4bbd-9ed8-6330fb680a23', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/fxkccd/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'size': 800101236736}], u'id': 1, u'path': u'/dev/disk/by-dname/sda', u'system_id': u'fxkccd', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'size': 800109715456, u'type': u'physical', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/fxkccd/blockdevices/1/'}, u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'description': u''}, u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'fxkccd', 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'owner_data': {}, u'blockdevice_set': [{u'partition_table_type': u'MBR', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'id': 1, u'path': u'/dev/disk/by-dname/sda', u'system_id': u'fxkccd', u'resource_uri': u'/MAAS/api/2.0/nodes/fxkccd/blockdevices/1/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'size': 800109715456, u'type': u'physical', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'7084a16b-d7ed-4263-ab6f-9104f08c352d', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'fxkccd', u'device_id': 1, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'da6c95b7-62c4-4bbd-9ed8-6330fb680a23', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/fxkccd/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'size': 800101236736}]}, {u'partition_table_type': None, u'block_size': 4096, u'uuid': u'b5c99f35-9330-4da3-86ca-47d9e23d0b93', u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'id': 11, u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'fxkccd', u'resource_uri': u'/MAAS/api/2.0/nodes/fxkccd/blockdevices/11/', u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'2f613b91-feac-4c37-9047-cfc7af3c844f', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'model': None, u'size': 107374182400, u'type': u'virtual', u'serial': None, u'partitions': []}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'physicalblockdevice_set': [{u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'partitions': [{u'uuid': u'7084a16b-d7ed-4263-ab6f-9104f08c352d', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'fxkccd', u'device_id': 1, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'da6c95b7-62c4-4bbd-9ed8-6330fb680a23', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/fxkccd/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'size': 800101236736}], u'id': 1, u'path': u'/dev/disk/by-dname/sda', u'system_id': u'fxkccd', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'size': 800109715456, u'type': u'physical', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/fxkccd/blockdevices/1/'}], u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'fxkccd', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'node_type_name': u'Machine', u'current_testing_result_id': 3, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'node_type': 0, u'virtualblockdevice_set': [{u'block_size': 4096, u'uuid': u'b5c99f35-9330-4da3-86ca-47d9e23d0b93', u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'partitions': [], u'id': 11, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'fxkccd', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'2f613b91-feac-4c37-9047-cfc7af3c844f', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'model': None, u'size': 107374182400, u'type': u'virtual', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/fxkccd/blockdevices/11/'}], u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'hhp46x', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 18}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'hhp46x', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'id': 4, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'hhp46x', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'fxkccd', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/fxkccd/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'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'hhp46x', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 18}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'hhp46x', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'id': 4, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'hhp46x', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'fxkccd', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/fxkccd/interfaces/4/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'id': 11, u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:51', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'fxkccd', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/fxkccd/interfaces/11/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'id': 14, u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:50', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'fxkccd', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/fxkccd/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'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'id': 12, u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:48', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'fxkccd', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/fxkccd/interfaces/12/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'hhp46x', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'mode': u'link_up', u'id': 20}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'hhp46x', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'id': 13, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'hhp46x', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.40'}], u'mac_address': u'9c:b6:54:8a:10:1c', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'fxkccd', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/fxkccd/interfaces/13/'}, {u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 21}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'id': 15, u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:49', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'fxkccd', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/fxkccd/interfaces/15/'}], u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/fxkccd/', u'distro_series': u'', u'memory_test_status': -1}
2019-02-26 05:27:51,925 [salt.state       :300 ][INFO    ][19245] {'new': {'storage_layout': 'lvm'}}
2019-02-26 05:27:51,925 [salt.state       :1951][INFO    ][19245] Completed state [maas_machines_storage_cmp002_lvm] at time 05:27:51.925397 duration_in_ms=2360.234
2019-02-26 05:27:51,926 [salt.state       :1780][INFO    ][19245] Running state [maas_machines_storage_cmp001_lvm] at time 05:27:51.925984
2019-02-26 05:27:51,926 [salt.state       :1813][INFO    ][19245] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-02-26 05:27:53,042 [salt.loaded.ext.module.maasng:610 ][INFO    ][19245] n4pk6c
2019-02-26 05:27:53,044 [salt.loaded.ext.module.maasng:626 ][INFO    ][19245] sda
2019-02-26 05:27:53,610 [salt.loaded.ext.module.maasng:361 ][INFO    ][19245] n4pk6c
2019-02-26 05:27:53,695 [salt.loaded.ext.module.maasng:367 ][INFO    ][19245] [{u'resource_uri': u'/MAAS/api/2.0/nodes/n4pk6c/blockdevices/2/', u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'9d0c4d6e-4742-4c5a-befe-d92966af75db', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'n4pk6c', u'device_id': 2, u'filesystem': {u'mount_options': None, u'uuid': u'35330cc0-3b76-4fd2-ac38-5accbe27d67c', u'fstype': u'lvm-pv', u'mount_point': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/n4pk6c/blockdevices/2/partition/2', u'type': u'partition', u'id': 2, u'size': 800101236736}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'system_id': u'n4pk6c', u'partition_table_type': u'MBR', u'filesystem': None, u'id': 2, u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'type': u'physical', u'model': u'LOGICAL VOLUME', u'size': 800109715456}, {u'resource_uri': u'/MAAS/api/2.0/nodes/n4pk6c/blockdevices/4/', u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'partitions': [], u'uuid': u'9bd2108c-e2df-4e13-8d64-f2d3070356bd', u'used_for': u'ext4 formatted filesystem mounted at /', u'id_path': None, u'system_id': u'n4pk6c', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'4ef52996-10b6-4fa5-bcac-d04326ec01a7', u'fstype': u'ext4', u'mount_point': u'/', u'label': u'root'}, u'id': 4, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'model': None, u'size': 800097042432}]
2019-02-26 05:27:53,696 [salt.loaded.ext.module.maasng:632 ][INFO    ][19245] vgroot
2019-02-26 05:27:53,696 [salt.loaded.ext.module.maasng:635 ][INFO    ][19245] lvroot
2019-02-26 05:27:53,696 [salt.loaded.ext.module.maasng:639 ][INFO    ][19245] 107374182400
2019-02-26 05:27:54,294 [salt.loaded.ext.module.maasng:645 ][INFO    ][19245] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'testing_status_name': u'Passed', u'memory_test_status': -1, u'disable_ipv4': False, u'cpu_count': 40, u'owner_data': {}, u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'fqdn': u'cmp001.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'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default'}, u'resource_uri': u'/MAAS/api/2.0/machines/n4pk6c/', u'current_commissioning_result_id': 4, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'n4pk6c', 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'block_size': 4096, u'uuid': u'7896b7c8-b1c2-46a9-a437-a529afaae0f5', u'resource_uri': u'/MAAS/api/2.0/nodes/n4pk6c/blockdevices/12/', u'used_size': 107374182400, u'tags': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'465a6af3-346e-445a-88d5-7b01532bd90b', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'n4pk6c', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'partitions': [], u'type': u'virtual', u'id': 12, u'serial': None, u'name': u'vgroot-lvroot'}], u'blockdevice_set': [{u'size': 800109715456, u'partition_table_type': u'MBR', u'block_size': 4096, u'uuid': None, u'tags': [u'ssd'], u'used_size': 800106479616, u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'n4pk6c', u'resource_uri': u'/MAAS/api/2.0/nodes/n4pk6c/blockdevices/2/', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'01054044-8233-49d5-b333-704e8635085f', u'resource_uri': u'/MAAS/api/2.0/nodes/n4pk6c/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'n4pk6c', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'37f7410a-2882-4800-9ae9-94c922b8b584', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 7, u'device_id': 2}], u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'name': u'sda'}, {u'size': 107374182400, u'partition_table_type': None, u'block_size': 4096, u'uuid': u'7896b7c8-b1c2-46a9-a437-a529afaae0f5', u'tags': [], u'used_size': 107374182400, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'465a6af3-346e-445a-88d5-7b01532bd90b', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'n4pk6c', u'resource_uri': u'/MAAS/api/2.0/nodes/n4pk6c/blockdevices/12/', u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'partitions': [], u'type': u'virtual', u'id': 12, u'serial': None, u'name': u'vgroot-lvroot'}], u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'raids': [], u'power_type': u'ipmi', u'physicalblockdevice_set': [{u'size': 800109715456, u'block_size': 4096, u'uuid': None, u'resource_uri': u'/MAAS/api/2.0/nodes/n4pk6c/blockdevices/2/', u'used_size': 800106479616, u'tags': [u'ssd'], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'n4pk6c', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'01054044-8233-49d5-b333-704e8635085f', u'resource_uri': u'/MAAS/api/2.0/nodes/n4pk6c/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'n4pk6c', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'37f7410a-2882-4800-9ae9-94c922b8b584', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 7, u'device_id': 2}], u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'name': u'sda'}], u'ip_addresses': [u'192.168.11.39', u'192.168.11.44'], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'n4pk6c', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'size': 800109715456, u'block_size': 4096, u'uuid': None, u'resource_uri': u'/MAAS/api/2.0/nodes/n4pk6c/blockdevices/2/', u'used_size': 800106479616, u'tags': [u'ssd'], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'n4pk6c', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'01054044-8233-49d5-b333-704e8635085f', u'resource_uri': u'/MAAS/api/2.0/nodes/n4pk6c/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'n4pk6c', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'37f7410a-2882-4800-9ae9-94c922b8b584', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 7, u'device_id': 2}], u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'name': u'sda'}, u'current_testing_result_id': 5, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'bcaches': [], u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'node_type': 0, 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'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'hhp46x', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'id': 25, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'hhp46x', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'hhp46x', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'mac_address': u'9c:b6:54:8a:95:a0', u'params': u'', u'system_id': u'n4pk6c', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/n4pk6c/interfaces/5/'}, u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'hhp46x', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'id': 25, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'hhp46x', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'hhp46x', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'mac_address': u'9c:b6:54:8a:95:a0', u'params': u'', u'system_id': u'n4pk6c', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/n4pk6c/interfaces/5/'}, {u'name': u'ens2f1', u'links': [{u'id': 27, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'mac_address': u'38:ea:a7:8f:52:cd', u'params': u'', u'system_id': u'n4pk6c', u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/n4pk6c/interfaces/17/'}, {u'name': u'ens2f0', u'links': [{u'id': 28, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'mac_address': u'38:ea:a7:8f:52:cc', u'params': u'', u'system_id': u'n4pk6c', u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/n4pk6c/interfaces/20/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'vlan': None, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'mac_address': u'38:ea:a7:8f:1f:d4', u'params': u'', u'system_id': u'n4pk6c', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/n4pk6c/interfaces/18/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'vlan': None, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'mac_address': u'38:ea:a7:8f:1f:d5', u'params': u'', u'system_id': u'n4pk6c', u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/n4pk6c/interfaces/19/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'hhp46x', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 26, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'hhp46x', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'hhp46x', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.44'}], u'parents': [], u'mac_address': u'9c:b6:54:8a:95:a4', u'params': u'', u'system_id': u'n4pk6c', u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/n4pk6c/interfaces/16/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2019-02-26 05:27:54,297 [salt.state       :300 ][INFO    ][19245] {'new': {'storage_layout': 'lvm'}}
2019-02-26 05:27:54,297 [salt.state       :1951][INFO    ][19245] Completed state [maas_machines_storage_cmp001_lvm] at time 05:27:54.297311 duration_in_ms=2371.327
2019-02-26 05:27:54,300 [salt.minion      :1711][INFO    ][19245] Returning information for job: 20190226052739709223
2019-02-26 05:27:54,838 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command state.apply with jid 20190226052754828608
2019-02-26 05:27:54,857 [salt.minion      :1432][INFO    ][19296] Starting a new job with PID 19296
2019-02-26 05:27:55,748 [salt.state       :915 ][INFO    ][19296] Loading fresh modules for state activity
2019-02-26 05:27:55,796 [salt.fileclient  :1219][INFO    ][19296] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-02-26 05:27:55,835 [salt.state       :1780][INFO    ][19296] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:27:55.835228
2019-02-26 05:27:55,835 [salt.state       :1813][INFO    ][19296] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-26 05:27:55,837 [salt.loaded.int.module.cmdmod:395 ][INFO    ][19296] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-26 05:27:57,533 [salt.state       :300 ][INFO    ][19296] {'pid': 19303, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-26 05:27:57,534 [salt.state       :1951][INFO    ][19296] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:27:57.534035 duration_in_ms=1698.807
2019-02-26 05:27:57,535 [salt.state       :1780][INFO    ][19296] Running state [maas.deploy_machines] at time 05:27:57.535846
2019-02-26 05:27:57,536 [salt.state       :1813][INFO    ][19296] Executing state module.run for [maas.deploy_machines]
2019-02-26 05:27:57,536 [salt.utils.decorators:613 ][WARNING ][19296] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-26 05:27:58,027 [salt.loaded.ext.module.maas:684 ][INFO    ][19296] deploymachines hwe_kernel=hwe-16.04 system_id=fxkccd distro_series=xenial
2019-02-26 05:27:59,901 [salt.loaded.ext.module.maas:684 ][INFO    ][19296] deploymachines hwe_kernel=hwe-16.04 system_id=n4pk6c distro_series=xenial
2019-02-26 05:28:01,938 [salt.loaded.ext.module.maas:684 ][INFO    ][19296] deploymachines hwe_kernel=hwe-16.04 system_id=k6sqfg distro_series=xenial
2019-02-26 05:28:04,249 [salt.loaded.ext.module.maas:684 ][INFO    ][19296] deploymachines hwe_kernel=hwe-16.04 system_id=6bcsqy distro_series=xenial
2019-02-26 05:28:06,829 [salt.loaded.ext.module.maas:684 ][INFO    ][19296] deploymachines hwe_kernel=hwe-16.04 system_id=bcgp8g distro_series=xenial
2019-02-26 05:28:09,120 [salt.state       :300 ][INFO    ][19296] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-02-26 05:28:09,120 [salt.state       :1951][INFO    ][19296] Completed state [maas.deploy_machines] at time 05:28:09.120925 duration_in_ms=11585.077
2019-02-26 05:28:09,127 [salt.minion      :1711][INFO    ][19296] Returning information for job: 20190226052754828608
2019-02-26 05:28:09,666 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command state.apply with jid 20190226052809654779
2019-02-26 05:28:09,683 [salt.minion      :1432][INFO    ][19596] Starting a new job with PID 19596
2019-02-26 05:28:17,399 [salt.state       :915 ][INFO    ][19596] Loading fresh modules for state activity
2019-02-26 05:28:17,452 [salt.fileclient  :1219][INFO    ][19596] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-02-26 05:28:17,497 [salt.state       :1780][INFO    ][19596] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:28:17.497824
2019-02-26 05:28:17,498 [salt.state       :1813][INFO    ][19596] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-26 05:28:17,500 [salt.loaded.int.module.cmdmod:395 ][INFO    ][19596] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-26 05:28:19,199 [salt.state       :300 ][INFO    ][19596] {'pid': 19611, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-26 05:28:19,200 [salt.state       :1951][INFO    ][19596] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:28:19.200576 duration_in_ms=1702.751
2019-02-26 05:28:19,204 [salt.state       :1780][INFO    ][19596] Running state [maas.wait_for_machine_status] at time 05:28:19.204571
2019-02-26 05:28:19,204 [salt.state       :1813][INFO    ][19596] Executing state module.run for [maas.wait_for_machine_status]
2019-02-26 05:28:19,205 [salt.utils.decorators:613 ][WARNING ][19596] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-26 05:28:22,049 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.16238117s left)
2019-02-26 05:28:24,692 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226052824681872
2019-02-26 05:28:24,715 [salt.minion      :1432][INFO    ][19635] Starting a new job with PID 19635
2019-02-26 05:28:24,736 [salt.minion      :1711][INFO    ][19635] Returning information for job: 20190226052824681872
2019-02-26 05:28:54,881 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226052854869717
2019-02-26 05:28:54,895 [salt.minion      :1432][INFO    ][19691] Starting a new job with PID 19691
2019-02-26 05:28:54,914 [salt.minion      :1711][INFO    ][19691] Returning information for job: 20190226052854869717
2019-02-26 05:28:55,079 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.13303208s left)
2019-02-26 05:29:24,944 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226052924928040
2019-02-26 05:29:24,965 [salt.minion      :1432][INFO    ][19732] Starting a new job with PID 19732
2019-02-26 05:29:24,985 [salt.minion      :1711][INFO    ][19732] Returning information for job: 20190226052924928040
2019-02-26 05:29:28,001 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.21055913s left)
2019-02-26 05:29:54,998 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226052954991022
2019-02-26 05:29:55,021 [salt.minion      :1432][INFO    ][19790] Starting a new job with PID 19790
2019-02-26 05:29:55,043 [salt.minion      :1711][INFO    ][19790] Returning information for job: 20190226052954991022
2019-02-26 05:30:00,913 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2148.298388s left)
2019-02-26 05:30:25,016 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226053025002369
2019-02-26 05:30:25,034 [salt.minion      :1432][INFO    ][19841] Starting a new job with PID 19841
2019-02-26 05:30:25,056 [salt.minion      :1711][INFO    ][19841] Returning information for job: 20190226053025002369
2019-02-26 05:30:33,791 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2115.42056704s left)
2019-02-26 05:30:55,210 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226053055195522
2019-02-26 05:30:55,230 [salt.minion      :1432][INFO    ][19934] Starting a new job with PID 19934
2019-02-26 05:30:55,252 [salt.minion      :1711][INFO    ][19934] Returning information for job: 20190226053055195522
2019-02-26 05:31:06,528 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2082.68348622s left)
2019-02-26 05:31:25,257 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226053125244425
2019-02-26 05:31:25,278 [salt.minion      :1432][INFO    ][20059] Starting a new job with PID 20059
2019-02-26 05:31:25,298 [salt.minion      :1711][INFO    ][20059] Returning information for job: 20190226053125244425
2019-02-26 05:31:40,006 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2049.20592117s left)
2019-02-26 05:31:55,353 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226053155336099
2019-02-26 05:31:55,374 [salt.minion      :1432][INFO    ][20265] Starting a new job with PID 20265
2019-02-26 05:31:55,396 [salt.minion      :1711][INFO    ][20265] Returning information for job: 20190226053155336099
2019-02-26 05:32:12,899 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2016.31289601s left)
2019-02-26 05:32:25,415 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226053225403534
2019-02-26 05:32:25,428 [salt.minion      :1432][INFO    ][20411] Starting a new job with PID 20411
2019-02-26 05:32:25,449 [salt.minion      :1711][INFO    ][20411] Returning information for job: 20190226053225403534
2019-02-26 05:32:45,745 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1983.46673918s left)
2019-02-26 05:32:55,485 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226053255470313
2019-02-26 05:32:55,505 [salt.minion      :1432][INFO    ][20628] Starting a new job with PID 20628
2019-02-26 05:32:55,525 [salt.minion      :1711][INFO    ][20628] Returning information for job: 20190226053255470313
2019-02-26 05:33:18,713 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1950.4991951s left)
2019-02-26 05:33:25,571 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226053325562616
2019-02-26 05:33:25,585 [salt.minion      :1432][INFO    ][20732] Starting a new job with PID 20732
2019-02-26 05:33:25,606 [salt.minion      :1711][INFO    ][20732] Returning information for job: 20190226053325562616
2019-02-26 05:33:51,852 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1917.35944319s left)
2019-02-26 05:33:55,651 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226053355642197
2019-02-26 05:33:55,666 [salt.minion      :1432][INFO    ][21010] Starting a new job with PID 21010
2019-02-26 05:33:55,687 [salt.minion      :1711][INFO    ][21010] Returning information for job: 20190226053355642197
2019-02-26 05:34:24,606 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1884.60592008s left)
2019-02-26 05:34:25,727 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226053425720365
2019-02-26 05:34:25,747 [salt.minion      :1432][INFO    ][21138] Starting a new job with PID 21138
2019-02-26 05:34:25,765 [salt.minion      :1711][INFO    ][21138] Returning information for job: 20190226053425720365
2019-02-26 05:34:55,837 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226053455830492
2019-02-26 05:34:55,855 [salt.minion      :1432][INFO    ][21371] Starting a new job with PID 21371
2019-02-26 05:34:55,874 [salt.minion      :1711][INFO    ][21371] Returning information for job: 20190226053455830492
2019-02-26 05:34:57,421 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1851.79053712s left)
2019-02-26 05:35:25,954 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226053525940973
2019-02-26 05:35:25,975 [salt.minion      :1432][INFO    ][21585] Starting a new job with PID 21585
2019-02-26 05:35:25,994 [salt.minion      :1711][INFO    ][21585] Returning information for job: 20190226053525940973
2019-02-26 05:35:30,326 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1818.88626409s left)
2019-02-26 05:35:56,069 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226053556058911
2019-02-26 05:35:56,086 [salt.minion      :1432][INFO    ][21716] Starting a new job with PID 21716
2019-02-26 05:35:56,107 [salt.minion      :1711][INFO    ][21716] Returning information for job: 20190226053556058911
2019-02-26 05:36:03,050 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1786.16216016s left)
2019-02-26 05:36:26,192 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226053626181592
2019-02-26 05:36:26,213 [salt.minion      :1432][INFO    ][21836] Starting a new job with PID 21836
2019-02-26 05:36:26,234 [salt.minion      :1711][INFO    ][21836] Returning information for job: 20190226053626181592
2019-02-26 05:36:35,988 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1753.22374606s left)
2019-02-26 05:36:56,314 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226053656299286
2019-02-26 05:36:56,336 [salt.minion      :1432][INFO    ][22014] Starting a new job with PID 22014
2019-02-26 05:36:56,358 [salt.minion      :1711][INFO    ][22014] Returning information for job: 20190226053656299286
2019-02-26 05:37:08,737 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1720.47514415s left)
2019-02-26 05:37:26,491 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226053726478999
2019-02-26 05:37:26,512 [salt.minion      :1432][INFO    ][22100] Starting a new job with PID 22100
2019-02-26 05:37:26,538 [salt.minion      :1711][INFO    ][22100] Returning information for job: 20190226053726478999
2019-02-26 05:37:41,538 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1687.67411304s left)
2019-02-26 05:37:56,662 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226053756647419
2019-02-26 05:37:56,684 [salt.minion      :1432][INFO    ][22315] Starting a new job with PID 22315
2019-02-26 05:37:56,706 [salt.minion      :1711][INFO    ][22315] Returning information for job: 20190226053756647419
2019-02-26 05:38:14,178 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1655.03353715s left)
2019-02-26 05:38:26,817 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226053826804157
2019-02-26 05:38:26,839 [salt.minion      :1432][INFO    ][22409] Starting a new job with PID 22409
2019-02-26 05:38:26,861 [salt.minion      :1711][INFO    ][22409] Returning information for job: 20190226053826804157
2019-02-26 05:38:47,042 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1622.17048407s left)
2019-02-26 05:38:56,963 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226053856956326
2019-02-26 05:38:56,975 [salt.minion      :1432][INFO    ][22523] Starting a new job with PID 22523
2019-02-26 05:38:56,995 [salt.minion      :1711][INFO    ][22523] Returning information for job: 20190226053856956326
2019-02-26 05:39:19,711 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1589.5006752s left)
2019-02-26 05:39:27,108 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226053927098484
2019-02-26 05:39:27,126 [salt.minion      :1432][INFO    ][22571] Starting a new job with PID 22571
2019-02-26 05:39:27,149 [salt.minion      :1711][INFO    ][22571] Returning information for job: 20190226053927098484
2019-02-26 05:39:52,572 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1556.63972521s left)
2019-02-26 05:39:57,266 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226053957252178
2019-02-26 05:39:57,285 [salt.minion      :1432][INFO    ][22627] Starting a new job with PID 22627
2019-02-26 05:39:57,306 [salt.minion      :1711][INFO    ][22627] Returning information for job: 20190226053957252178
2019-02-26 05:40:25,558 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1523.65380812s left)
2019-02-26 05:40:27,414 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226054027402748
2019-02-26 05:40:27,431 [salt.minion      :1432][INFO    ][22751] Starting a new job with PID 22751
2019-02-26 05:40:27,456 [salt.minion      :1711][INFO    ][22751] Returning information for job: 20190226054027402748
2019-02-26 05:40:57,521 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226054057511853
2019-02-26 05:40:57,537 [salt.minion      :1432][INFO    ][22942] Starting a new job with PID 22942
2019-02-26 05:40:57,559 [salt.minion      :1711][INFO    ][22942] Returning information for job: 20190226054057511853
2019-02-26 05:40:58,440 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1490.77187014s left)
2019-02-26 05:41:27,698 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226054127685107
2019-02-26 05:41:27,717 [salt.minion      :1432][INFO    ][23013] Starting a new job with PID 23013
2019-02-26 05:41:27,737 [salt.minion      :1711][INFO    ][23013] Returning information for job: 20190226054127685107
2019-02-26 05:41:31,157 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1458.05484915s left)
2019-02-26 05:41:57,895 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226054157884662
2019-02-26 05:41:57,909 [salt.minion      :1432][INFO    ][23150] Starting a new job with PID 23150
2019-02-26 05:41:57,932 [salt.minion      :1711][INFO    ][23150] Returning information for job: 20190226054157884662
2019-02-26 05:42:03,836 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1425.37571716s left)
2019-02-26 05:42:28,088 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226054228076093
2019-02-26 05:42:28,109 [salt.minion      :1432][INFO    ][23201] Starting a new job with PID 23201
2019-02-26 05:42:28,129 [salt.minion      :1711][INFO    ][23201] Returning information for job: 20190226054228076093
2019-02-26 05:42:36,577 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1392.63510513s left)
2019-02-26 05:42:58,307 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226054258297663
2019-02-26 05:42:58,326 [salt.minion      :1432][INFO    ][23257] Starting a new job with PID 23257
2019-02-26 05:42:58,346 [salt.minion      :1711][INFO    ][23257] Returning information for job: 20190226054258297663
2019-02-26 05:43:09,370 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1359.84222603s left)
2019-02-26 05:43:28,521 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226054328507348
2019-02-26 05:43:28,544 [salt.minion      :1432][INFO    ][23302] Starting a new job with PID 23302
2019-02-26 05:43:28,568 [salt.minion      :1711][INFO    ][23302] Returning information for job: 20190226054328507348
2019-02-26 05:43:42,312 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1326.90012717s left)
2019-02-26 05:43:58,558 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226054358544389
2019-02-26 05:43:58,578 [salt.minion      :1432][INFO    ][23358] Starting a new job with PID 23358
2019-02-26 05:43:58,602 [salt.minion      :1711][INFO    ][23358] Returning information for job: 20190226054358544389
2019-02-26 05:44:15,007 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1294.20498514s left)
2019-02-26 05:44:28,581 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226054428569735
2019-02-26 05:44:28,598 [salt.minion      :1432][INFO    ][23410] Starting a new job with PID 23410
2019-02-26 05:44:28,620 [salt.minion      :1711][INFO    ][23410] Returning information for job: 20190226054428569735
2019-02-26 05:44:47,749 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1261.46268106s left)
2019-02-26 05:44:58,632 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226054458616512
2019-02-26 05:44:58,652 [salt.minion      :1432][INFO    ][23466] Starting a new job with PID 23466
2019-02-26 05:44:58,675 [salt.minion      :1711][INFO    ][23466] Returning information for job: 20190226054458616512
2019-02-26 05:45:20,445 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1228.76726222s left)
2019-02-26 05:45:28,685 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226054528676478
2019-02-26 05:45:28,698 [salt.minion      :1432][INFO    ][23518] Starting a new job with PID 23518
2019-02-26 05:45:28,720 [salt.minion      :1711][INFO    ][23518] Returning information for job: 20190226054528676478
2019-02-26 05:45:53,132 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1196.07975602s left)
2019-02-26 05:45:58,738 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226054558724570
2019-02-26 05:45:58,762 [salt.minion      :1432][INFO    ][23574] Starting a new job with PID 23574
2019-02-26 05:45:58,783 [salt.minion      :1711][INFO    ][23574] Returning information for job: 20190226054558724570
2019-02-26 05:46:26,042 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1163.17000413s left)
2019-02-26 05:46:28,836 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226054628823024
2019-02-26 05:46:28,857 [salt.minion      :1432][INFO    ][23622] Starting a new job with PID 23622
2019-02-26 05:46:28,879 [salt.minion      :1711][INFO    ][23622] Returning information for job: 20190226054628823024
2019-02-26 05:46:58,712 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1130.50024319s left)
2019-02-26 05:46:58,913 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226054658901002
2019-02-26 05:46:58,931 [salt.minion      :1432][INFO    ][23678] Starting a new job with PID 23678
2019-02-26 05:46:58,954 [salt.minion      :1711][INFO    ][23678] Returning information for job: 20190226054658901002
2019-02-26 05:47:29,045 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226054729036862
2019-02-26 05:47:29,061 [salt.minion      :1432][INFO    ][23720] Starting a new job with PID 23720
2019-02-26 05:47:29,082 [salt.minion      :1711][INFO    ][23720] Returning information for job: 20190226054729036862
2019-02-26 05:47:31,445 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1097.76712513s left)
2019-02-26 05:47:59,130 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226054759117230
2019-02-26 05:47:59,146 [salt.minion      :1432][INFO    ][23775] Starting a new job with PID 23775
2019-02-26 05:47:59,170 [salt.minion      :1711][INFO    ][23775] Returning information for job: 20190226054759117230
2019-02-26 05:48:04,306 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1064.90596724s left)
2019-02-26 05:48:29,265 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226054829252257
2019-02-26 05:48:29,284 [salt.minion      :1432][INFO    ][23822] Starting a new job with PID 23822
2019-02-26 05:48:29,306 [salt.minion      :1711][INFO    ][23822] Returning information for job: 20190226054829252257
2019-02-26 05:48:36,978 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1032.23409414s left)
2019-02-26 05:48:59,381 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226054859368132
2019-02-26 05:48:59,402 [salt.minion      :1432][INFO    ][23880] Starting a new job with PID 23880
2019-02-26 05:48:59,424 [salt.minion      :1711][INFO    ][23880] Returning information for job: 20190226054859368132
2019-02-26 05:49:09,730 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (999.481466055s left)
2019-02-26 05:49:29,571 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226054929558411
2019-02-26 05:49:29,592 [salt.minion      :1432][INFO    ][23927] Starting a new job with PID 23927
2019-02-26 05:49:29,614 [salt.minion      :1711][INFO    ][23927] Returning information for job: 20190226054929558411
2019-02-26 05:49:42,413 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (966.798284054s left)
2019-02-26 05:49:59,732 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226054959720721
2019-02-26 05:49:59,754 [salt.minion      :1432][INFO    ][23985] Starting a new job with PID 23985
2019-02-26 05:49:59,777 [salt.minion      :1711][INFO    ][23985] Returning information for job: 20190226054959720721
2019-02-26 05:50:15,166 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (934.045623064s left)
2019-02-26 05:50:29,928 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226055029920387
2019-02-26 05:50:29,947 [salt.minion      :1432][INFO    ][24038] Starting a new job with PID 24038
2019-02-26 05:50:29,970 [salt.minion      :1711][INFO    ][24038] Returning information for job: 20190226055029920387
2019-02-26 05:50:47,890 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (901.321631193s left)
2019-02-26 05:51:00,119 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226055100108118
2019-02-26 05:51:00,139 [salt.minion      :1432][INFO    ][24109] Starting a new job with PID 24109
2019-02-26 05:51:00,161 [salt.minion      :1711][INFO    ][24109] Returning information for job: 20190226055100108118
2019-02-26 05:51:20,809 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (868.403226137s left)
2019-02-26 05:51:30,156 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226055130140090
2019-02-26 05:51:30,176 [salt.minion      :1432][INFO    ][24156] Starting a new job with PID 24156
2019-02-26 05:51:30,199 [salt.minion      :1711][INFO    ][24156] Returning information for job: 20190226055130140090
2019-02-26 05:51:53,573 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (835.638484001s left)
2019-02-26 05:52:00,190 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226055200182955
2019-02-26 05:52:00,203 [salt.minion      :1432][INFO    ][24212] Starting a new job with PID 24212
2019-02-26 05:52:00,225 [salt.minion      :1711][INFO    ][24212] Returning information for job: 20190226055200182955
2019-02-26 05:52:26,265 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (802.946614027s left)
2019-02-26 05:52:30,413 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226055230397126
2019-02-26 05:52:30,431 [salt.minion      :1432][INFO    ][24259] Starting a new job with PID 24259
2019-02-26 05:52:30,454 [salt.minion      :1711][INFO    ][24259] Returning information for job: 20190226055230397126
2019-02-26 05:52:58,902 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (770.309777021s left)
2019-02-26 05:53:00,475 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226055300467801
2019-02-26 05:53:00,498 [salt.minion      :1432][INFO    ][24317] Starting a new job with PID 24317
2019-02-26 05:53:00,520 [salt.minion      :1711][INFO    ][24317] Returning information for job: 20190226055300467801
2019-02-26 05:53:30,513 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226055330503522
2019-02-26 05:53:30,527 [salt.minion      :1432][INFO    ][24360] Starting a new job with PID 24360
2019-02-26 05:53:30,550 [salt.minion      :1711][INFO    ][24360] Returning information for job: 20190226055330503522
2019-02-26 05:53:31,590 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (737.622215033s left)
2019-02-26 05:54:00,645 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226055400628878
2019-02-26 05:54:00,668 [salt.minion      :1432][INFO    ][24417] Starting a new job with PID 24417
2019-02-26 05:54:00,694 [salt.minion      :1711][INFO    ][24417] Returning information for job: 20190226055400628878
2019-02-26 05:54:04,338 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (704.873585224s left)
2019-02-26 05:54:30,720 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226055430706061
2019-02-26 05:54:30,742 [salt.minion      :1432][INFO    ][24465] Starting a new job with PID 24465
2019-02-26 05:54:30,766 [salt.minion      :1711][INFO    ][24465] Returning information for job: 20190226055430706061
2019-02-26 05:54:37,127 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (672.085396051s left)
2019-02-26 05:55:00,876 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226055500862794
2019-02-26 05:55:00,899 [salt.minion      :1432][INFO    ][24521] Starting a new job with PID 24521
2019-02-26 05:55:00,923 [salt.minion      :1711][INFO    ][24521] Returning information for job: 20190226055500862794
2019-02-26 05:55:09,850 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (639.361555099s left)
2019-02-26 05:55:30,974 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226055530961410
2019-02-26 05:55:30,997 [salt.minion      :1432][INFO    ][24695] Starting a new job with PID 24695
2019-02-26 05:55:31,024 [salt.minion      :1711][INFO    ][24695] Returning information for job: 20190226055530961410
2019-02-26 05:55:42,474 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (606.737735033s left)
2019-02-26 05:56:01,160 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226055601143336
2019-02-26 05:56:01,181 [salt.minion      :1432][INFO    ][24751] Starting a new job with PID 24751
2019-02-26 05:56:01,203 [salt.minion      :1711][INFO    ][24751] Returning information for job: 20190226055601143336
2019-02-26 05:56:15,257 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (573.954506159s left)
2019-02-26 05:56:31,322 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226055631310675
2019-02-26 05:56:31,342 [salt.minion      :1432][INFO    ][24796] Starting a new job with PID 24796
2019-02-26 05:56:31,370 [salt.minion      :1711][INFO    ][24796] Returning information for job: 20190226055631310675
2019-02-26 05:56:48,150 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (541.062045097s left)
2019-02-26 05:57:01,354 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226055701338829
2019-02-26 05:57:01,376 [salt.minion      :1432][INFO    ][24852] Starting a new job with PID 24852
2019-02-26 05:57:01,399 [salt.minion      :1711][INFO    ][24852] Returning information for job: 20190226055701338829
2019-02-26 05:57:20,814 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (508.397567034s left)
2019-02-26 05:57:31,554 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226055731547100
2019-02-26 05:57:31,568 [salt.minion      :1432][INFO    ][24902] Starting a new job with PID 24902
2019-02-26 05:57:31,591 [salt.minion      :1711][INFO    ][24902] Returning information for job: 20190226055731547100
2019-02-26 05:57:53,394 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (475.81748414s left)
2019-02-26 05:58:01,771 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226055801757973
2019-02-26 05:58:01,792 [salt.minion      :1432][INFO    ][24962] Starting a new job with PID 24962
2019-02-26 05:58:01,816 [salt.minion      :1711][INFO    ][24962] Returning information for job: 20190226055801757973
2019-02-26 05:58:26,165 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (443.046649218s left)
2019-02-26 05:58:31,844 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226055831832955
2019-02-26 05:58:31,862 [salt.minion      :1432][INFO    ][25007] Starting a new job with PID 25007
2019-02-26 05:58:31,893 [salt.minion      :1711][INFO    ][25007] Returning information for job: 20190226055831832955
2019-02-26 05:58:58,894 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (410.318283081s left)
2019-02-26 05:59:01,877 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226055901863585
2019-02-26 05:59:01,897 [salt.minion      :1432][INFO    ][25063] Starting a new job with PID 25063
2019-02-26 05:59:01,922 [salt.minion      :1711][INFO    ][25063] Returning information for job: 20190226055901863585
2019-02-26 05:59:31,570 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (377.64195323s left)
2019-02-26 05:59:32,025 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226055932009763
2019-02-26 05:59:32,042 [salt.minion      :1432][INFO    ][25111] Starting a new job with PID 25111
2019-02-26 05:59:32,069 [salt.minion      :1711][INFO    ][25111] Returning information for job: 20190226055932009763
2019-02-26 06:00:02,098 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226060002089599
2019-02-26 06:00:02,113 [salt.minion      :1432][INFO    ][25163] Starting a new job with PID 25163
2019-02-26 06:00:02,137 [salt.minion      :1711][INFO    ][25163] Returning information for job: 20190226060002089599
2019-02-26 06:00:04,348 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (344.863802195s left)
2019-02-26 06:00:32,262 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226060032247444
2019-02-26 06:00:32,281 [salt.minion      :1432][INFO    ][25220] Starting a new job with PID 25220
2019-02-26 06:00:32,305 [salt.minion      :1711][INFO    ][25220] Returning information for job: 20190226060032247444
2019-02-26 06:00:37,282 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (311.929345131s left)
2019-02-26 06:01:02,358 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226060102344313
2019-02-26 06:01:02,381 [salt.minion      :1432][INFO    ][25286] Starting a new job with PID 25286
2019-02-26 06:01:02,405 [salt.minion      :1711][INFO    ][25286] Returning information for job: 20190226060102344313
2019-02-26 06:01:09,953 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (279.258601189s left)
2019-02-26 06:01:32,579 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226060132565354
2019-02-26 06:01:32,601 [salt.minion      :1432][INFO    ][25337] Starting a new job with PID 25337
2019-02-26 06:01:32,624 [salt.minion      :1711][INFO    ][25337] Returning information for job: 20190226060132565354
2019-02-26 06:01:42,726 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (246.48549819s left)
2019-02-26 06:02:02,724 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226060202710981
2019-02-26 06:02:02,741 [salt.minion      :1432][INFO    ][25392] Starting a new job with PID 25392
2019-02-26 06:02:02,765 [salt.minion      :1711][INFO    ][25392] Returning information for job: 20190226060202710981
2019-02-26 06:02:15,478 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (213.733848095s left)
2019-02-26 06:02:32,744 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226060232729078
2019-02-26 06:02:32,766 [salt.minion      :1432][INFO    ][25440] Starting a new job with PID 25440
2019-02-26 06:02:32,791 [salt.minion      :1711][INFO    ][25440] Returning information for job: 20190226060232729078
2019-02-26 06:02:48,173 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (181.038730145s left)
2019-02-26 06:03:02,910 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226060302897403
2019-02-26 06:03:02,926 [salt.minion      :1432][INFO    ][25496] Starting a new job with PID 25496
2019-02-26 06:03:02,951 [salt.minion      :1711][INFO    ][25496] Returning information for job: 20190226060302897403
2019-02-26 06:03:20,934 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (148.277734041s left)
2019-02-26 06:03:33,000 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226060332987119
2019-02-26 06:03:33,021 [salt.minion      :1432][INFO    ][25544] Starting a new job with PID 25544
2019-02-26 06:03:33,047 [salt.minion      :1711][INFO    ][25544] Returning information for job: 20190226060332987119
2019-02-26 06:03:53,755 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (115.456565142s left)
2019-02-26 06:04:03,036 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226060403028489
2019-02-26 06:04:03,048 [salt.minion      :1432][INFO    ][25600] Starting a new job with PID 25600
2019-02-26 06:04:03,072 [salt.minion      :1711][INFO    ][25600] Returning information for job: 20190226060403028489
2019-02-26 06:04:26,397 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (82.8146831989s left)
2019-02-26 06:04:33,092 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226060433077315
2019-02-26 06:04:33,114 [salt.minion      :1432][INFO    ][25648] Starting a new job with PID 25648
2019-02-26 06:04:33,138 [salt.minion      :1711][INFO    ][25648] Returning information for job: 20190226060433077315
2019-02-26 06:04:59,173 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (50.0390422344s left)
2019-02-26 06:05:03,193 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226060503178826
2019-02-26 06:05:03,215 [salt.minion      :1432][INFO    ][25704] Starting a new job with PID 25704
2019-02-26 06:05:03,239 [salt.minion      :1711][INFO    ][25704] Returning information for job: 20190226060503178826
2019-02-26 06:05:31,908 [salt.loaded.ext.module.maas:1023][INFO    ][19596] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (17.3033230305s left)
2019-02-26 06:05:33,230 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226060533215598
2019-02-26 06:05:33,248 [salt.minion      :1432][INFO    ][25749] Starting a new job with PID 25749
2019-02-26 06:05:33,272 [salt.minion      :1711][INFO    ][25749] Returning information for job: 20190226060533215598
2019-02-26 06:06:03,402 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226060603393398
2019-02-26 06:06:03,418 [salt.minion      :1432][INFO    ][25809] Starting a new job with PID 25809
2019-02-26 06:06:03,441 [salt.minion      :1711][INFO    ][25809] Returning information for job: 20190226060603393398
2019-02-26 06:06:04,645 [salt.state       :302 ][ERROR   ][19596] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-02-26 06:06:04,646 [salt.state       :1951][INFO    ][19596] Completed state [maas.wait_for_machine_status] at time 06:06:04.645985 duration_in_ms=2265441.409
2019-02-26 06:06:04,653 [salt.minion      :1711][INFO    ][19596] Returning information for job: 20190226052809654779
2019-02-26 06:06:15,386 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command pillar.get with jid 20190226060615379488
2019-02-26 06:06:15,402 [salt.minion      :1432][INFO    ][25841] Starting a new job with PID 25841
2019-02-26 06:06:15,407 [salt.minion      :1711][INFO    ][25841] Returning information for job: 20190226060615379488
2019-02-26 06:06:15,925 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command service.status with jid 20190226060615917754
2019-02-26 06:06:15,938 [salt.minion      :1432][INFO    ][25846] Starting a new job with PID 25846
2019-02-26 06:06:16,427 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][25846] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-02-26 06:06:16,451 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][25846] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-02-26 06:06:16,462 [salt.minion      :1711][INFO    ][25846] Returning information for job: 20190226060615917754
2019-02-26 06:06:16,980 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command state.apply with jid 20190226060616970613
2019-02-26 06:06:16,993 [salt.minion      :1432][INFO    ][25857] Starting a new job with PID 25857
2019-02-26 06:06:24,653 [salt.state       :915 ][INFO    ][25857] Loading fresh modules for state activity
2019-02-26 06:06:25,186 [salt.loaded.int.module.cmdmod:395 ][INFO    ][25857] Executing command 'salt-minion --version' in directory '/root'
2019-02-26 06:06:25,475 [salt.loaded.int.module.cmdmod:395 ][INFO    ][25857] Executing command 'salt-minion --version' in directory '/root'
2019-02-26 06:06:26,413 [salt.loaded.int.module.cmdmod:395 ][INFO    ][25857] Executing command 'salt-minion --version' in directory '/root'
2019-02-26 06:06:26,686 [salt.loaded.int.module.cmdmod:395 ][INFO    ][25857] Executing command 'salt-minion --version' in directory '/root'
2019-02-26 06:06:28,430 [salt.state       :1780][INFO    ][25857] Running state [salt-minion] at time 06:06:28.430694
2019-02-26 06:06:28,432 [salt.state       :1813][INFO    ][25857] Executing state pkg.installed for [salt-minion]
2019-02-26 06:06:28,432 [salt.loaded.int.module.cmdmod:395 ][INFO    ][25857] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-26 06:06:28,543 [salt.state       :300 ][INFO    ][25857] All specified packages are already installed
2019-02-26 06:06:28,543 [salt.state       :1951][INFO    ][25857] Completed state [salt-minion] at time 06:06:28.543362 duration_in_ms=112.668
2019-02-26 06:06:28,543 [salt.state       :1780][INFO    ][25857] Running state [salt_minion_dependency_packages] at time 06:06:28.543691
2019-02-26 06:06:28,544 [salt.state       :1813][INFO    ][25857] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-02-26 06:06:28,553 [salt.state       :300 ][INFO    ][25857] All specified packages are already installed
2019-02-26 06:06:28,554 [salt.state       :1951][INFO    ][25857] Completed state [salt_minion_dependency_packages] at time 06:06:28.554076 duration_in_ms=10.385
2019-02-26 06:06:28,557 [salt.state       :1780][INFO    ][25857] Running state [/etc/salt/minion.d/minion.conf] at time 06:06:28.557878
2019-02-26 06:06:28,558 [salt.state       :1813][INFO    ][25857] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-02-26 06:06:28,826 [salt.state       :300 ][INFO    ][25857] File /etc/salt/minion.d/minion.conf is in the correct state
2019-02-26 06:06:28,826 [salt.state       :1951][INFO    ][25857] Completed state [/etc/salt/minion.d/minion.conf] at time 06:06:28.826264 duration_in_ms=268.387
2019-02-26 06:06:28,826 [salt.state       :1780][INFO    ][25857] Running state [python-netaddr] at time 06:06:28.826522
2019-02-26 06:06:28,826 [salt.state       :1813][INFO    ][25857] Executing state pkg.installed for [python-netaddr]
2019-02-26 06:06:28,835 [salt.state       :300 ][INFO    ][25857] All specified packages are already installed
2019-02-26 06:06:28,835 [salt.state       :1951][INFO    ][25857] Completed state [python-netaddr] at time 06:06:28.835894 duration_in_ms=9.372
2019-02-26 06:06:28,839 [salt.state       :1780][INFO    ][25857] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 06:06:28.839478
2019-02-26 06:06:28,839 [salt.state       :1813][INFO    ][25857] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-02-26 06:06:28,850 [salt.state       :300 ][INFO    ][25857] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-02-26 06:06:28,853 [salt.state       :1951][INFO    ][25857] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 06:06:28.853330 duration_in_ms=13.852
2019-02-26 06:06:28,854 [salt.state       :1780][INFO    ][25857] Running state [salt-minion] at time 06:06:28.854762
2019-02-26 06:06:28,855 [salt.state       :1813][INFO    ][25857] Executing state service.running for [salt-minion]
2019-02-26 06:06:28,855 [salt.loaded.int.module.cmdmod:395 ][INFO    ][25857] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-02-26 06:06:28,893 [salt.loaded.int.module.cmdmod:395 ][INFO    ][25857] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-02-26 06:06:28,908 [salt.loaded.int.module.cmdmod:395 ][INFO    ][25857] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-02-26 06:06:28,926 [salt.state       :300 ][INFO    ][25857] The service salt-minion is already running
2019-02-26 06:06:28,927 [salt.state       :1951][INFO    ][25857] Completed state [salt-minion] at time 06:06:28.927208 duration_in_ms=72.446
2019-02-26 06:06:28,930 [salt.state       :1780][INFO    ][25857] Running state [/etc/salt/grains.d] at time 06:06:28.930063
2019-02-26 06:06:28,930 [salt.state       :1813][INFO    ][25857] Executing state file.directory for [/etc/salt/grains.d]
2019-02-26 06:06:28,932 [salt.state       :300 ][INFO    ][25857] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-02-26 06:06:28,932 [salt.state       :1951][INFO    ][25857] Completed state [/etc/salt/grains.d] at time 06:06:28.932585 duration_in_ms=2.522
2019-02-26 06:06:28,933 [salt.state       :1780][INFO    ][25857] Running state [/etc/salt/grains] at time 06:06:28.933758
2019-02-26 06:06:28,934 [salt.state       :1813][INFO    ][25857] Executing state file.managed for [/etc/salt/grains]
2019-02-26 06:06:28,937 [salt.state       :300 ][INFO    ][25857] File /etc/salt/grains exists with proper permissions. No changes made.
2019-02-26 06:06:28,937 [salt.state       :1951][INFO    ][25857] Completed state [/etc/salt/grains] at time 06:06:28.937240 duration_in_ms=3.482
2019-02-26 06:06:28,937 [salt.state       :1780][INFO    ][25857] Running state [/etc/salt/grains.d/placeholder] at time 06:06:28.937736
2019-02-26 06:06:28,938 [salt.state       :1813][INFO    ][25857] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-02-26 06:06:28,938 [salt.state       :300 ][INFO    ][25857] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-02-26 06:06:28,938 [salt.state       :1951][INFO    ][25857] Completed state [/etc/salt/grains.d/placeholder] at time 06:06:28.938701 duration_in_ms=0.965
2019-02-26 06:06:28,939 [salt.state       :1780][INFO    ][25857] Running state [/etc/salt/grains.d/sphinx] at time 06:06:28.939195
2019-02-26 06:06:28,939 [salt.state       :1813][INFO    ][25857] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-02-26 06:06:28,940 [salt.state       :300 ][INFO    ][25857] File /etc/salt/grains.d/sphinx is in the correct state
2019-02-26 06:06:28,940 [salt.state       :1951][INFO    ][25857] Completed state [/etc/salt/grains.d/sphinx] at time 06:06:28.940723 duration_in_ms=1.528
2019-02-26 06:06:28,942 [salt.state       :1780][INFO    ][25857] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 06:06:28.942733
2019-02-26 06:06:28,943 [salt.state       :1813][INFO    ][25857] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-02-26 06:06:28,943 [salt.state       :300 ][INFO    ][25857] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-02-26 06:06:28,943 [salt.state       :1951][INFO    ][25857] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 06:06:28.943539 duration_in_ms=0.806
2019-02-26 06:06:28,944 [salt.state       :1780][INFO    ][25857] Running state [/etc/salt/grains.d/dns_records] at time 06:06:28.944030
2019-02-26 06:06:28,944 [salt.state       :1813][INFO    ][25857] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-02-26 06:06:28,945 [salt.state       :300 ][INFO    ][25857] File /etc/salt/grains.d/dns_records is in the correct state
2019-02-26 06:06:28,945 [salt.state       :1951][INFO    ][25857] Completed state [/etc/salt/grains.d/dns_records] at time 06:06:28.945392 duration_in_ms=1.361
2019-02-26 06:06:28,946 [salt.state       :1780][INFO    ][25857] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 06:06:28.946337
2019-02-26 06:06:28,946 [salt.state       :1813][INFO    ][25857] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-02-26 06:06:28,948 [salt.state       :300 ][INFO    ][25857] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-02-26 06:06:28,948 [salt.state       :1951][INFO    ][25857] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 06:06:28.948789 duration_in_ms=2.451
2019-02-26 06:06:28,949 [salt.state       :1780][INFO    ][25857] Running state [/etc/salt/grains.d/salt] at time 06:06:28.949258
2019-02-26 06:06:28,949 [salt.state       :1813][INFO    ][25857] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-02-26 06:06:28,950 [salt.state       :300 ][INFO    ][25857] File /etc/salt/grains.d/salt is in the correct state
2019-02-26 06:06:28,950 [salt.state       :1951][INFO    ][25857] Completed state [/etc/salt/grains.d/salt] at time 06:06:28.950556 duration_in_ms=1.297
2019-02-26 06:06:28,951 [salt.state       :1780][INFO    ][25857] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 06:06:28.951718
2019-02-26 06:06:28,951 [salt.state       :1813][INFO    ][25857] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-02-26 06:06:28,952 [salt.state       :300 ][INFO    ][25857] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-02-26 06:06:28,952 [salt.state       :1951][INFO    ][25857] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 06:06:28.952451 duration_in_ms=0.732
2019-02-26 06:06:28,954 [salt.state       :1780][INFO    ][25857] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 06:06:28.954338
2019-02-26 06:06:28,954 [salt.state       :1813][INFO    ][25857] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-02-26 06:06:28,954 [salt.state       :300 ][INFO    ][25857] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-02-26 06:06:28,955 [salt.state       :1951][INFO    ][25857] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 06:06:28.955098 duration_in_ms=0.76
2019-02-26 06:06:28,955 [salt.state       :1780][INFO    ][25857] Running state [mine.update] at time 06:06:28.955751
2019-02-26 06:06:28,956 [salt.state       :1813][INFO    ][25857] Executing state module.wait for [mine.update]
2019-02-26 06:06:28,956 [salt.state       :300 ][INFO    ][25857] No changes made for mine.update
2019-02-26 06:06:28,956 [salt.state       :1951][INFO    ][25857] Completed state [mine.update] at time 06:06:28.956429 duration_in_ms=0.678
2019-02-26 06:06:28,956 [salt.state       :1780][INFO    ][25857] Running state [ca-certificates] at time 06:06:28.956663
2019-02-26 06:06:28,956 [salt.state       :1813][INFO    ][25857] Executing state pkg.installed for [ca-certificates]
2019-02-26 06:06:28,967 [salt.state       :300 ][INFO    ][25857] All specified packages are already installed
2019-02-26 06:06:28,967 [salt.state       :1951][INFO    ][25857] Completed state [ca-certificates] at time 06:06:28.967649 duration_in_ms=10.986
2019-02-26 06:06:28,968 [salt.state       :1780][INFO    ][25857] Running state [update-ca-certificates] at time 06:06:28.968325
2019-02-26 06:06:28,968 [salt.state       :1813][INFO    ][25857] Executing state cmd.wait for [update-ca-certificates]
2019-02-26 06:06:28,968 [salt.state       :300 ][INFO    ][25857] No changes made for update-ca-certificates
2019-02-26 06:06:28,969 [salt.state       :1951][INFO    ][25857] Completed state [update-ca-certificates] at time 06:06:28.969031 duration_in_ms=0.706
2019-02-26 06:06:28,969 [salt.state       :1780][INFO    ][25857] Running state [iptables] at time 06:06:28.969262
2019-02-26 06:06:28,969 [salt.state       :1813][INFO    ][25857] Executing state pkg.installed for [iptables]
2019-02-26 06:06:28,979 [salt.state       :300 ][INFO    ][25857] All specified packages are already installed
2019-02-26 06:06:28,979 [salt.state       :1951][INFO    ][25857] Completed state [iptables] at time 06:06:28.979448 duration_in_ms=10.186
2019-02-26 06:06:28,979 [salt.state       :1780][INFO    ][25857] Running state [iptables-persistent] at time 06:06:28.979678
2019-02-26 06:06:28,979 [salt.state       :1813][INFO    ][25857] Executing state pkg.installed for [iptables-persistent]
2019-02-26 06:06:28,989 [salt.state       :300 ][INFO    ][25857] All specified packages are already installed
2019-02-26 06:06:28,989 [salt.state       :1951][INFO    ][25857] Completed state [iptables-persistent] at time 06:06:28.989308 duration_in_ms=9.629
2019-02-26 06:06:28,991 [salt.state       :1780][INFO    ][25857] Running state [iptables_modules_v4_load] at time 06:06:28.991164
2019-02-26 06:06:28,991 [salt.state       :1813][INFO    ][25857] Executing state kmod.present for [iptables_modules_v4_load]
2019-02-26 06:06:28,992 [salt.loaded.int.module.cmdmod:395 ][INFO    ][25857] Executing command 'lsmod' in directory '/root'
2019-02-26 06:06:29,017 [salt.state       :300 ][INFO    ][25857] Kernel modules iptable_filter, ip_tables are already present
2019-02-26 06:06:29,017 [salt.state       :1951][INFO    ][25857] Completed state [iptables_modules_v4_load] at time 06:06:29.017615 duration_in_ms=26.452
2019-02-26 06:06:29,020 [salt.state       :1780][INFO    ][25857] Running state [/etc/iptables/rules.v4] at time 06:06:29.020919
2019-02-26 06:06:29,021 [salt.state       :1813][INFO    ][25857] Executing state file.managed for [/etc/iptables/rules.v4]
2019-02-26 06:06:29,120 [salt.state       :300 ][INFO    ][25857] File /etc/iptables/rules.v4 is in the correct state
2019-02-26 06:06:29,120 [salt.state       :1951][INFO    ][25857] Completed state [/etc/iptables/rules.v4] at time 06:06:29.120280 duration_in_ms=99.361
2019-02-26 06:06:29,121 [salt.state       :1780][INFO    ][25857] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 06:06:29.121422
2019-02-26 06:06:29,121 [salt.state       :1813][INFO    ][25857] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-02-26 06:06:29,122 [salt.loaded.int.module.cmdmod:395 ][INFO    ][25857] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-02-26 06:06:29,146 [salt.state       :300 ][INFO    ][25857] onlyif execution failed
2019-02-26 06:06:29,146 [salt.state       :1951][INFO    ][25857] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 06:06:29.146599 duration_in_ms=25.177
2019-02-26 06:06:29,148 [salt.state       :1780][INFO    ][25857] Running state [netfilter-persistent] at time 06:06:29.148708
2019-02-26 06:06:29,149 [salt.state       :1813][INFO    ][25857] Executing state service.running for [netfilter-persistent]
2019-02-26 06:06:29,150 [salt.loaded.int.module.cmdmod:395 ][INFO    ][25857] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-02-26 06:06:29,173 [salt.loaded.int.module.cmdmod:395 ][INFO    ][25857] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-02-26 06:06:29,193 [salt.loaded.int.module.cmdmod:395 ][INFO    ][25857] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-02-26 06:06:29,213 [salt.state       :300 ][INFO    ][25857] The service netfilter-persistent is already running
2019-02-26 06:06:29,214 [salt.state       :1951][INFO    ][25857] Completed state [netfilter-persistent] at time 06:06:29.213981 duration_in_ms=65.273
2019-02-26 06:06:29,215 [salt.state       :1780][INFO    ][25857] Running state [iptables_extra.remove_stale_tables] at time 06:06:29.215570
2019-02-26 06:06:29,216 [salt.state       :1813][INFO    ][25857] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-02-26 06:06:29,216 [salt.state       :300 ][INFO    ][25857] No changes made for iptables_extra.remove_stale_tables
2019-02-26 06:06:29,217 [salt.state       :1951][INFO    ][25857] Completed state [iptables_extra.remove_stale_tables] at time 06:06:29.217108 duration_in_ms=1.537
2019-02-26 06:06:29,217 [salt.state       :1780][INFO    ][25857] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 06:06:29.217575
2019-02-26 06:06:29,218 [salt.state       :1813][INFO    ][25857] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-02-26 06:06:29,219 [salt.loaded.int.module.cmdmod:395 ][INFO    ][25857] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-02-26 06:06:29,237 [salt.state       :300 ][INFO    ][25857] onlyif execution failed
2019-02-26 06:06:29,237 [salt.state       :1951][INFO    ][25857] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 06:06:29.237773 duration_in_ms=20.197
2019-02-26 06:06:29,239 [salt.state       :1780][INFO    ][25857] Running state [/etc/iptables/rules.v6] at time 06:06:29.239360
2019-02-26 06:06:29,239 [salt.state       :1813][INFO    ][25857] Executing state file.absent for [/etc/iptables/rules.v6]
2019-02-26 06:06:29,240 [salt.state       :300 ][INFO    ][25857] File /etc/iptables/rules.v6 is not present
2019-02-26 06:06:29,240 [salt.state       :1951][INFO    ][25857] Completed state [/etc/iptables/rules.v6] at time 06:06:29.240904 duration_in_ms=1.543
2019-02-26 06:06:29,242 [salt.state       :1780][INFO    ][25857] Running state [iptables_extra.flush_all] at time 06:06:29.242098
2019-02-26 06:06:29,242 [salt.state       :1813][INFO    ][25857] Executing state module.wait for [iptables_extra.flush_all]
2019-02-26 06:06:29,243 [salt.state       :300 ][INFO    ][25857] No changes made for iptables_extra.flush_all
2019-02-26 06:06:29,243 [salt.state       :1951][INFO    ][25857] Completed state [iptables_extra.flush_all] at time 06:06:29.243645 duration_in_ms=1.546
2019-02-26 06:06:29,250 [salt.minion      :1711][INFO    ][25857] Returning information for job: 20190226060616970613
2019-02-26 06:06:29,793 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command state.apply with jid 20190226060629778673
2019-02-26 06:06:29,816 [salt.minion      :1432][INFO    ][25946] Starting a new job with PID 25946
2019-02-26 06:06:30,698 [salt.state       :915 ][INFO    ][25946] Loading fresh modules for state activity
2019-02-26 06:06:31,508 [salt.state       :1780][INFO    ][25946] Running state [maas-rack-controller] at time 06:06:31.508297
2019-02-26 06:06:31,508 [salt.state       :1813][INFO    ][25946] Executing state pkg.installed for [maas-rack-controller]
2019-02-26 06:06:31,509 [salt.loaded.int.module.cmdmod:395 ][INFO    ][25946] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-26 06:06:31,606 [salt.state       :300 ][INFO    ][25946] All specified packages are already installed
2019-02-26 06:06:31,606 [salt.state       :1951][INFO    ][25946] Completed state [maas-rack-controller] at time 06:06:31.606452 duration_in_ms=98.156
2019-02-26 06:06:31,606 [salt.state       :1780][INFO    ][25946] Running state [ipmitool] at time 06:06:31.606772
2019-02-26 06:06:31,607 [salt.state       :1813][INFO    ][25946] Executing state pkg.installed for [ipmitool]
2019-02-26 06:06:31,661 [salt.state       :300 ][INFO    ][25946] All specified packages are already installed
2019-02-26 06:06:31,661 [salt.state       :1951][INFO    ][25946] Completed state [ipmitool] at time 06:06:31.661521 duration_in_ms=54.748
2019-02-26 06:06:31,664 [salt.state       :1780][INFO    ][25946] Running state [/etc/maas/rackd.conf] at time 06:06:31.664747
2019-02-26 06:06:31,665 [salt.state       :1813][INFO    ][25946] Executing state file.line for [/etc/maas/rackd.conf]
2019-02-26 06:06:31,665 [salt.state       :300 ][INFO    ][25946] No changes needed to be made
2019-02-26 06:06:31,666 [salt.state       :1951][INFO    ][25946] Completed state [/etc/maas/rackd.conf] at time 06:06:31.666143 duration_in_ms=1.397
2019-02-26 06:06:31,666 [salt.state       :1780][INFO    ][25946] Running state [/etc/maas/rackd.conf] at time 06:06:31.666383
2019-02-26 06:06:31,666 [salt.state       :1813][INFO    ][25946] Executing state file.managed for [/etc/maas/rackd.conf]
2019-02-26 06:06:31,667 [salt.loaded.int.states.file:2298][WARNING ][25946] 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-02-26 06:06:31,667 [salt.state       :300 ][INFO    ][25946] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-02-26 06:06:31,668 [salt.state       :1951][INFO    ][25946] Completed state [/etc/maas/rackd.conf] at time 06:06:31.667977 duration_in_ms=1.594
2019-02-26 06:06:31,669 [salt.state       :1780][INFO    ][25946] Running state [maas-rackd] at time 06:06:31.668999
2019-02-26 06:06:31,669 [salt.state       :1813][INFO    ][25946] Executing state service.running for [maas-rackd]
2019-02-26 06:06:31,669 [salt.loaded.int.module.cmdmod:395 ][INFO    ][25946] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-02-26 06:06:31,705 [salt.loaded.int.module.cmdmod:395 ][INFO    ][25946] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-02-26 06:06:31,722 [salt.loaded.int.module.cmdmod:395 ][INFO    ][25946] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-02-26 06:06:31,738 [salt.state       :300 ][INFO    ][25946] The service maas-rackd is already running
2019-02-26 06:06:31,738 [salt.state       :1951][INFO    ][25946] Completed state [maas-rackd] at time 06:06:31.738549 duration_in_ms=69.549
2019-02-26 06:06:31,740 [salt.minion      :1711][INFO    ][25946] Returning information for job: 20190226060629778673
2019-02-26 06:06:32,280 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command state.apply with jid 20190226060632272359
2019-02-26 06:06:32,301 [salt.minion      :1432][INFO    ][25969] Starting a new job with PID 25969
2019-02-26 06:06:33,154 [salt.state       :915 ][INFO    ][25969] Loading fresh modules for state activity
2019-02-26 06:06:34,144 [salt.state       :1780][INFO    ][25969] Running state [maas-region-controller] at time 06:06:34.144517
2019-02-26 06:06:34,144 [salt.state       :1813][INFO    ][25969] Executing state pkg.installed for [maas-region-controller]
2019-02-26 06:06:34,145 [salt.loaded.int.module.cmdmod:395 ][INFO    ][25969] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-26 06:06:34,250 [salt.state       :300 ][INFO    ][25969] All specified packages are already installed
2019-02-26 06:06:34,251 [salt.state       :1951][INFO    ][25969] Completed state [maas-region-controller] at time 06:06:34.251803 duration_in_ms=107.286
2019-02-26 06:06:34,252 [salt.state       :1780][INFO    ][25969] Running state [python-oauth] at time 06:06:34.252120
2019-02-26 06:06:34,252 [salt.state       :1813][INFO    ][25969] Executing state pkg.installed for [python-oauth]
2019-02-26 06:06:34,260 [salt.state       :300 ][INFO    ][25969] All specified packages are already installed
2019-02-26 06:06:34,261 [salt.state       :1951][INFO    ][25969] Completed state [python-oauth] at time 06:06:34.261045 duration_in_ms=8.925
2019-02-26 06:06:34,265 [salt.state       :1780][INFO    ][25969] Running state [/etc/maas/regiond.conf] at time 06:06:34.264977
2019-02-26 06:06:34,265 [salt.state       :1813][INFO    ][25969] Executing state file.replace for [/etc/maas/regiond.conf]
2019-02-26 06:06:34,270 [salt.state       :300 ][INFO    ][25969] No changes needed to be made
2019-02-26 06:06:34,270 [salt.state       :1951][INFO    ][25969] Completed state [/etc/maas/regiond.conf] at time 06:06:34.270711 duration_in_ms=5.734
2019-02-26 06:06:34,271 [salt.state       :1780][INFO    ][25969] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 06:06:34.271476
2019-02-26 06:06:34,271 [salt.state       :1813][INFO    ][25969] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-02-26 06:06:34,330 [salt.state       :300 ][INFO    ][25969] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-02-26 06:06:34,330 [salt.state       :1951][INFO    ][25969] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 06:06:34.330356 duration_in_ms=58.88
2019-02-26 06:06:34,331 [salt.state       :1780][INFO    ][25969] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:06:34.331797
2019-02-26 06:06:34,332 [salt.state       :1813][INFO    ][25969] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-02-26 06:06:34,336 [salt.state       :300 ][INFO    ][25969] No changes needed to be made
2019-02-26 06:06:34,337 [salt.state       :1951][INFO    ][25969] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:06:34.336950 duration_in_ms=5.152
2019-02-26 06:06:34,337 [salt.state       :1780][INFO    ][25969] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:06:34.337434
2019-02-26 06:06:34,337 [salt.state       :1813][INFO    ][25969] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-02-26 06:06:34,341 [salt.state       :300 ][INFO    ][25969] No changes needed to be made
2019-02-26 06:06:34,341 [salt.state       :1951][INFO    ][25969] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:06:34.341322 duration_in_ms=3.888
2019-02-26 06:06:34,341 [salt.state       :1780][INFO    ][25969] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 06:06:34.341810
2019-02-26 06:06:34,342 [salt.state       :1813][INFO    ][25969] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-02-26 06:06:34,373 [salt.state       :300 ][INFO    ][25969] No changes needed to be made
2019-02-26 06:06:34,373 [salt.state       :1951][INFO    ][25969] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 06:06:34.373855 duration_in_ms=32.045
2019-02-26 06:06:34,374 [salt.state       :1780][INFO    ][25969] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 06:06:34.374358
2019-02-26 06:06:34,374 [salt.state       :1813][INFO    ][25969] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-02-26 06:06:34,387 [salt.state       :300 ][INFO    ][25969] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-02-26 06:06:34,387 [salt.state       :1951][INFO    ][25969] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 06:06:34.387766 duration_in_ms=13.408
2019-02-26 06:06:34,389 [salt.state       :1780][INFO    ][25969] Running state [a2enmod headers] at time 06:06:34.389075
2019-02-26 06:06:34,389 [salt.state       :1813][INFO    ][25969] Executing state cmd.run for [a2enmod headers]
2019-02-26 06:06:34,390 [salt.loaded.int.module.cmdmod:395 ][INFO    ][25969] Executing command 'a2enmod headers' in directory '/root'
2019-02-26 06:06:34,464 [salt.state       :300 ][INFO    ][25969] {'pid': 25988, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-02-26 06:06:34,464 [salt.state       :1951][INFO    ][25969] Completed state [a2enmod headers] at time 06:06:34.464643 duration_in_ms=75.567
2019-02-26 06:06:34,465 [salt.state       :1780][INFO    ][25969] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 06:06:34.465177
2019-02-26 06:06:34,465 [salt.state       :1813][INFO    ][25969] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-02-26 06:06:34,489 [salt.state       :300 ][INFO    ][25969] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-02-26 06:06:34,489 [salt.state       :1951][INFO    ][25969] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 06:06:34.489417 duration_in_ms=24.241
2019-02-26 06:06:34,490 [salt.state       :1780][INFO    ][25969] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 06:06:34.490526
2019-02-26 06:06:34,494 [salt.state       :1813][INFO    ][25969] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-02-26 06:06:34,547 [salt.state       :300 ][INFO    ][25969] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-02-26 06:06:34,548 [salt.state       :1951][INFO    ][25969] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 06:06:34.548080 duration_in_ms=57.554
2019-02-26 06:06:34,549 [salt.state       :1780][INFO    ][25969] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 06:06:34.549013
2019-02-26 06:06:34,549 [salt.state       :1813][INFO    ][25969] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-02-26 06:06:34,605 [salt.state       :300 ][INFO    ][25969] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-02-26 06:06:34,605 [salt.state       :1951][INFO    ][25969] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 06:06:34.605322 duration_in_ms=56.31
2019-02-26 06:06:34,605 [salt.state       :1780][INFO    ][25969] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 06:06:34.605831
2019-02-26 06:06:34,606 [salt.state       :1813][INFO    ][25969] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-02-26 06:06:34,664 [salt.state       :300 ][INFO    ][25969] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-02-26 06:06:34,664 [salt.state       :1951][INFO    ][25969] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 06:06:34.664912 duration_in_ms=59.081
2019-02-26 06:06:34,665 [salt.state       :1780][INFO    ][25969] Running state [/root/.pgpass] at time 06:06:34.665170
2019-02-26 06:06:34,665 [salt.state       :1813][INFO    ][25969] Executing state file.managed for [/root/.pgpass]
2019-02-26 06:06:34,712 [salt.state       :300 ][INFO    ][25969] File /root/.pgpass is in the correct state
2019-02-26 06:06:34,713 [salt.state       :1951][INFO    ][25969] Completed state [/root/.pgpass] at time 06:06:34.713253 duration_in_ms=48.083
2019-02-26 06:06:34,722 [salt.state       :1780][INFO    ][25969] Running state [maas-region syncdb --noinput] at time 06:06:34.722156
2019-02-26 06:06:34,722 [salt.state       :1813][INFO    ][25969] Executing state cmd.run for [maas-region syncdb --noinput]
2019-02-26 06:06:34,723 [salt.loaded.int.module.cmdmod:395 ][INFO    ][25969] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-02-26 06:06:37,325 [salt.state       :300 ][INFO    ][25969] {'pid': 26002, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: metadataserver, contenttypes, sites, auth, piston3, maasserver, sessions\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-02-26 06:06:37,325 [salt.state       :1951][INFO    ][25969] Completed state [maas-region syncdb --noinput] at time 06:06:37.325619 duration_in_ms=2603.461
2019-02-26 06:06:37,325 [salt.state       :2022][WARNING ][25969] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-02-26 06:06:37,330 [salt.state       :1780][INFO    ][25969] Running state [maas-regiond] at time 06:06:37.330199
2019-02-26 06:06:37,330 [salt.state       :1813][INFO    ][25969] Executing state service.running for [maas-regiond]
2019-02-26 06:06:37,332 [salt.loaded.int.module.cmdmod:395 ][INFO    ][25969] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-02-26 06:06:37,373 [salt.loaded.int.module.cmdmod:395 ][INFO    ][25969] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-02-26 06:06:37,392 [salt.loaded.int.module.cmdmod:395 ][INFO    ][25969] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-02-26 06:06:37,413 [salt.state       :300 ][INFO    ][25969] The service maas-regiond is already running
2019-02-26 06:06:37,414 [salt.state       :1951][INFO    ][25969] Completed state [maas-regiond] at time 06:06:37.414308 duration_in_ms=84.11
2019-02-26 06:06:37,417 [salt.state       :1780][INFO    ][25969] Running state [bind9] at time 06:06:37.417011
2019-02-26 06:06:37,417 [salt.state       :1813][INFO    ][25969] Executing state service.running for [bind9]
2019-02-26 06:06:37,418 [salt.loaded.int.module.cmdmod:395 ][INFO    ][25969] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-02-26 06:06:37,438 [salt.loaded.int.module.cmdmod:395 ][INFO    ][25969] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-02-26 06:06:37,454 [salt.loaded.int.module.cmdmod:395 ][INFO    ][25969] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-02-26 06:06:37,471 [salt.state       :300 ][INFO    ][25969] The service bind9 is already running
2019-02-26 06:06:37,472 [salt.state       :1951][INFO    ][25969] Completed state [bind9] at time 06:06:37.472316 duration_in_ms=55.306
2019-02-26 06:06:37,474 [salt.state       :1780][INFO    ][25969] Running state [apache2] at time 06:06:37.474412
2019-02-26 06:06:37,474 [salt.state       :1813][INFO    ][25969] Executing state service.running for [apache2]
2019-02-26 06:06:37,475 [salt.loaded.int.module.cmdmod:395 ][INFO    ][25969] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-02-26 06:06:37,495 [salt.loaded.int.module.cmdmod:395 ][INFO    ][25969] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-02-26 06:06:37,513 [salt.loaded.int.module.cmdmod:395 ][INFO    ][25969] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-02-26 06:06:37,537 [salt.state       :300 ][INFO    ][25969] The service apache2 is already running
2019-02-26 06:06:37,538 [salt.state       :1951][INFO    ][25969] Completed state [apache2] at time 06:06:37.538094 duration_in_ms=63.681
2019-02-26 06:06:37,543 [salt.state       :1780][INFO    ][25969] Running state [maasng.wait_for_http_code] at time 06:06:37.543003
2019-02-26 06:06:37,543 [salt.state       :1813][INFO    ][25969] Executing state module.run for [maasng.wait_for_http_code]
2019-02-26 06:06:37,544 [salt.utils.decorators:613 ][WARNING ][25969] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-26 06:06:37,655 [salt.state       :300 ][INFO    ][25969] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-02-26 06:06:37,656 [salt.state       :1951][INFO    ][25969] Completed state [maasng.wait_for_http_code] at time 06:06:37.656192 duration_in_ms=113.189
2019-02-26 06:06:37,657 [salt.state       :1780][INFO    ][25969] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 06:06:37.657439
2019-02-26 06:06:37,657 [salt.state       :1813][INFO    ][25969] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-02-26 06:06:37,658 [salt.state       :300 ][INFO    ][25969] /var/lib/maas/.setup_admin exists
2019-02-26 06:06:37,661 [salt.state       :1951][INFO    ][25969] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 06:06:37.661017 duration_in_ms=3.578
2019-02-26 06:06:37,662 [salt.state       :1780][INFO    ][25969] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:06:37.662194
2019-02-26 06:06:37,662 [salt.state       :1813][INFO    ][25969] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-26 06:06:37,663 [salt.loaded.int.module.cmdmod:395 ][INFO    ][25969] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-26 06:06:39,357 [salt.state       :300 ][INFO    ][25969] {'pid': 26051, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-26 06:06:39,358 [salt.state       :1951][INFO    ][25969] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:06:39.357931 duration_in_ms=1695.736
2019-02-26 06:06:39,366 [salt.state       :1780][INFO    ][25969] Running state [maas_region_boot_source_resources_mirror] at time 06:06:39.366541
2019-02-26 06:06:39,367 [salt.state       :1813][INFO    ][25969] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-02-26 06:06:39,450 [salt.state       :300 ][INFO    ][25969] {'changes': {}}
2019-02-26 06:06:39,450 [salt.state       :1951][INFO    ][25969] Completed state [maas_region_boot_source_resources_mirror] at time 06:06:39.450724 duration_in_ms=84.183
2019-02-26 06:06:39,451 [salt.state       :1780][INFO    ][25969] Running state [maasng.boot_resources_import] at time 06:06:39.451832
2019-02-26 06:06:39,452 [salt.state       :1813][INFO    ][25969] Executing state module.run for [maasng.boot_resources_import]
2019-02-26 06:06:39,452 [salt.utils.decorators:613 ][WARNING ][25969] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-26 06:06:39,537 [salt.loaded.ext.module.maasng:1600][INFO    ][25969] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-02-26 06:06:44,590 [salt.loaded.ext.module.maasng:1600][INFO    ][25969] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-02-26 06:06:47,400 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226060647390818
2019-02-26 06:06:47,426 [salt.minion      :1432][INFO    ][26094] Starting a new job with PID 26094
2019-02-26 06:06:47,451 [salt.minion      :1711][INFO    ][26094] Returning information for job: 20190226060647390818
2019-02-26 06:06:49,678 [salt.state       :300 ][INFO    ][25969] {'ret': True}
2019-02-26 06:06:49,680 [salt.state       :1951][INFO    ][25969] Completed state [maasng.boot_resources_import] at time 06:06:49.679907 duration_in_ms=10228.074
2019-02-26 06:06:49,681 [salt.state       :1780][INFO    ][25969] Running state [maas_region_boot_sources_selection_xenial] at time 06:06:49.680978
2019-02-26 06:06:49,681 [salt.state       :1813][INFO    ][25969] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-02-26 06:06:49,848 [salt.state       :300 ][INFO    ][25969] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-02-26 06:06:49,848 [salt.state       :1951][INFO    ][25969] Completed state [maas_region_boot_sources_selection_xenial] at time 06:06:49.848548 duration_in_ms=167.57
2019-02-26 06:06:49,849 [salt.state       :1780][INFO    ][25969] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 06:06:49.849727
2019-02-26 06:06:49,850 [salt.state       :1813][INFO    ][25969] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-02-26 06:06:49,850 [salt.utils.decorators:613 ][WARNING ][25969] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-26 06:06:49,852 [salt.loaded.ext.module.maasng:1771][INFO    ][25969] boot-sources sync initiated for ALL Rack's
2019-02-26 06:06:50,848 [salt.state       :300 ][INFO    ][25969] {'ret': True}
2019-02-26 06:06:50,848 [salt.state       :1951][INFO    ][25969] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 06:06:50.848828 duration_in_ms=999.1
2019-02-26 06:06:50,851 [salt.state       :1780][INFO    ][25969] Running state [maas.process_maas_config] at time 06:06:50.851359
2019-02-26 06:06:50,851 [salt.state       :1813][INFO    ][25969] Executing state module.run for [maas.process_maas_config]
2019-02-26 06:06:50,852 [salt.utils.decorators:613 ][WARNING ][25969] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-26 06:06:50,853 [salt.loaded.ext.module.maas:92  ][INFO    ][25969] maasconfig name=enable_http_proxy value=True
2019-02-26 06:06:50,900 [salt.loaded.ext.module.maas:92  ][INFO    ][25969] maasconfig name=upstream_dns value=8.8.8.8
2019-02-26 06:06:50,953 [salt.loaded.ext.module.maas:92  ][INFO    ][25969] maasconfig name=commissioning_distro_series value=xenial
2019-02-26 06:06:51,010 [salt.loaded.ext.module.maas:92  ][INFO    ][25969] maasconfig name=default_osystem value=ubuntu
2019-02-26 06:06:51,060 [salt.loaded.ext.module.maas:92  ][INFO    ][25969] maasconfig name=active_discovery_interval value=600
2019-02-26 06:06:51,109 [salt.loaded.ext.module.maas:92  ][INFO    ][25969] maasconfig name=dnssec_validation value=no
2019-02-26 06:06:51,160 [salt.loaded.ext.module.maas:92  ][INFO    ][25969] maasconfig name=maas_name value=mas01
2019-02-26 06:06:52,666 [salt.loaded.ext.module.maas:92  ][INFO    ][25969] maasconfig name=network_discovery value=enabled
2019-02-26 06:06:52,763 [salt.loaded.ext.module.maas:92  ][INFO    ][25969] maasconfig name=enable_third_party_drivers value=True
2019-02-26 06:06:52,819 [salt.loaded.ext.module.maas:92  ][INFO    ][25969] maasconfig name=default_storage_layout value=lvm
2019-02-26 06:06:52,861 [salt.loaded.ext.module.maas:92  ][INFO    ][25969] maasconfig name=ntp_external_only value=True
2019-02-26 06:06:52,902 [salt.loaded.ext.module.maas:92  ][INFO    ][25969] maasconfig name=disk_erase_with_secure_erase value=False
2019-02-26 06:06:52,950 [salt.loaded.ext.module.maas:92  ][INFO    ][25969] maasconfig name=default_distro_series value=xenial
2019-02-26 06:06:53,004 [salt.loaded.ext.module.maas:92  ][INFO    ][25969] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-02-26 06:06:53,134 [salt.state       :300 ][INFO    ][25969] {'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-02-26 06:06:53,135 [salt.state       :1951][INFO    ][25969] Completed state [maas.process_maas_config] at time 06:06:53.135361 duration_in_ms=2284.001
2019-02-26 06:06:53,136 [salt.state       :1780][INFO    ][25969] Running state [pxe_admin] at time 06:06:53.136165
2019-02-26 06:06:53,136 [salt.state       :1813][INFO    ][25969] Executing state maasng.fabric_present for [pxe_admin]
2019-02-26 06:06:53,187 [salt.loaded.ext.module.maasng:945 ][INFO    ][25969] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None, u'name': u'fabric-2'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'hhp46x', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u'', u'name': u'pxe_admin'}]
2019-02-26 06:06:53,251 [salt.loaded.ext.module.maasng:1008][WARNING ][25969] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-02-26 06:06:53,251 [salt.loaded.ext.module.maasng:1011][WARNING ][25969] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-02-26 06:06:53,333 [salt.state       :300 ][INFO    ][25969] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-02-26 06:06:53,334 [salt.state       :1951][INFO    ][25969] Completed state [pxe_admin] at time 06:06:53.334149 duration_in_ms=197.984
2019-02-26 06:06:53,334 [salt.state       :1780][INFO    ][25969] Running state [vlan 0] at time 06:06:53.334590
2019-02-26 06:06:53,336 [salt.state       :1813][INFO    ][25969] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-02-26 06:06:53,389 [salt.loaded.ext.module.maasng:945 ][INFO    ][25969] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None, u'name': u'fabric-2'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'hhp46x', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u'', u'name': u'pxe_admin'}]
2019-02-26 06:06:53,488 [salt.loaded.ext.module.maasng:945 ][INFO    ][25969] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None, u'name': u'fabric-2'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'hhp46x', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u'', u'name': u'pxe_admin'}]
2019-02-26 06:06:53,700 [salt.loaded.ext.module.maasng:945 ][INFO    ][25969] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None, u'name': u'fabric-2'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'hhp46x', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u'', u'name': u'pxe_admin'}]
2019-02-26 06:06:53,776 [salt.state       :300 ][INFO    ][25969] {'new': 'Vlan untagged was updated'}
2019-02-26 06:06:53,776 [salt.state       :1951][INFO    ][25969] Completed state [vlan 0] at time 06:06:53.776876 duration_in_ms=442.286
2019-02-26 06:06:53,778 [salt.state       :1780][INFO    ][25969] Running state [192.168.11.0/24] at time 06:06:53.777947
2019-02-26 06:06:53,778 [salt.state       :1813][INFO    ][25969] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-02-26 06:06:53,939 [salt.loaded.ext.module.maasng:945 ][INFO    ][25969] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'name': u'fabric-2', u'id': 2}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'hhp46x', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'name': u'pxe_admin', u'id': 1}]
2019-02-26 06:06:53,939 [salt.loaded.ext.module.maasng:1235][WARNING ][25969] Ignoring parameter vlan:0
2019-02-26 06:06:54,002 [salt.state       :300 ][INFO    ][25969] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-02-26 06:06:54,002 [salt.state       :1951][INFO    ][25969] Completed state [192.168.11.0/24] at time 06:06:54.002631 duration_in_ms=224.683
2019-02-26 06:06:54,004 [salt.state       :1780][INFO    ][25969] Running state [maas_create_iprange_1] at time 06:06:54.004870
2019-02-26 06:06:54,005 [salt.state       :1813][INFO    ][25969] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-02-26 06:06:54,052 [salt.state       :300 ][INFO    ][25969] Iprange maas_create_iprange_1 already exist.
2019-02-26 06:06:54,052 [salt.state       :1951][INFO    ][25969] Completed state [maas_create_iprange_1] at time 06:06:54.052611 duration_in_ms=47.74
2019-02-26 06:06:54,053 [salt.state       :1780][INFO    ][25969] Running state [vlan 0] at time 06:06:54.052975
2019-02-26 06:06:54,053 [salt.state       :1813][INFO    ][25969] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-02-26 06:06:54,102 [salt.loaded.ext.module.maasng:945 ][INFO    ][25969] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'hhp46x', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-02-26 06:06:54,187 [salt.loaded.ext.module.maasng:945 ][INFO    ][25969] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'hhp46x', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-02-26 06:06:54,404 [salt.loaded.ext.module.maasng:945 ][INFO    ][25969] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None, u'name': u'fabric-2'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'hhp46x', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u'', u'name': u'pxe_admin'}]
2019-02-26 06:06:54,504 [salt.state       :300 ][INFO    ][25969] {'new': 'Vlan untagged was updated'}
2019-02-26 06:06:54,504 [salt.state       :1951][INFO    ][25969] Completed state [vlan 0] at time 06:06:54.504366 duration_in_ms=451.39
2019-02-26 06:06:54,505 [salt.state       :1780][INFO    ][25969] Running state [opnfv] at time 06:06:54.505027
2019-02-26 06:06:54,505 [salt.state       :1813][INFO    ][25969] Executing state maasng.sshkey_present for [opnfv]
2019-02-26 06:06:54,560 [salt.loaded.ext.module.maasng:1903][INFO    ][25969] [{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-02-26 06:06:54,560 [salt.state       :300 ][INFO    ][25969] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCwbsLV1UY13y2+2b7H9h6zOFMrcnumZvKOz6oqAXgttZjoKci01cqGRU8OMjob/9xeAOP2Cmu7HOBpRxda00X57yYx4UidSgEtlbOi7LLj2DuvJScix+rgPAd19aukdCiTuMmyLZQPMzVrdBxqzupJ5zqS3djSCllnQ2myEwswcSBn0Dpusgt3bnwdsCgI6B6ZgL9aR9DLnyMPMVroI6ZZH7aP7syIy1sPEsiJd3smWEULgeRElsuK+4I3YKG1Z1J2n80lmtR2gVD5tuKLFRRxnbjAqzyCEXQBTUVRc13SAFrH0b5bzFaqfxY1wdQPzP+dBSA+t25KcjnpTAAtL86H already exist for user opnfv.
2019-02-26 06:06:54,560 [salt.state       :1951][INFO    ][25969] Completed state [opnfv] at time 06:06:54.560704 duration_in_ms=55.676
2019-02-26 06:06:54,561 [salt.state       :1780][INFO    ][25969] Running state [maas.process_tags] at time 06:06:54.561374
2019-02-26 06:06:54,561 [salt.state       :1813][INFO    ][25969] Executing state module.run for [maas.process_tags]
2019-02-26 06:06:54,562 [salt.utils.decorators:613 ][WARNING ][25969] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-26 06:06:54,611 [salt.loaded.ext.module.maas:92  ][INFO    ][25969] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-02-26 06:06:54,671 [salt.state       :300 ][INFO    ][25969] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-02-26 06:06:54,672 [salt.state       :1951][INFO    ][25969] Completed state [maas.process_tags] at time 06:06:54.671966 duration_in_ms=110.591
2019-02-26 06:06:54,674 [salt.minion      :1711][INFO    ][25969] Returning information for job: 20190226060632272359
2019-02-26 06:06:55,273 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command state.apply with jid 20190226060655262069
2019-02-26 06:06:55,291 [salt.minion      :1432][INFO    ][26454] Starting a new job with PID 26454
2019-02-26 06:07:03,075 [salt.state       :915 ][INFO    ][26454] Loading fresh modules for state activity
2019-02-26 06:07:03,172 [salt.state       :1780][INFO    ][26454] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:07:03.172107
2019-02-26 06:07:03,172 [salt.state       :1813][INFO    ][26454] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-26 06:07:03,174 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26454] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-26 06:07:04,938 [salt.state       :300 ][INFO    ][26454] {'pid': 26477, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-26 06:07:04,939 [salt.state       :1951][INFO    ][26454] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:07:04.939050 duration_in_ms=1766.944
2019-02-26 06:07:04,941 [salt.state       :1780][INFO    ][26454] Running state [maas.process_machines] at time 06:07:04.941718
2019-02-26 06:07:04,942 [salt.state       :1813][INFO    ][26454] Executing state module.run for [maas.process_machines]
2019-02-26 06:07:04,943 [salt.utils.decorators:613 ][WARNING ][26454] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-26 06:07:05,504 [salt.loaded.ext.module.maas:412 ][WARNING ][26454] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-26 06:07:05,505 [salt.loaded.ext.module.maas:92  ][INFO    ][26454] 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=fxkccd architecture=amd64/generic power_parameters_power_user=opnfv
2019-02-26 06:07:06,619 [salt.loaded.ext.module.maas:412 ][WARNING ][26454] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-26 06:07:06,620 [salt.loaded.ext.module.maas:92  ][INFO    ][26454] 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=n4pk6c architecture=amd64/generic power_parameters_power_user=opnfv
2019-02-26 06:07:07,711 [salt.loaded.ext.module.maas:412 ][WARNING ][26454] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-26 06:07:07,712 [salt.loaded.ext.module.maas:92  ][INFO    ][26454] 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=k6sqfg architecture=amd64/generic power_parameters_power_user=opnfv
2019-02-26 06:07:08,802 [salt.loaded.ext.module.maas:412 ][WARNING ][26454] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-26 06:07:08,803 [salt.loaded.ext.module.maas:92  ][INFO    ][26454] 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=6bcsqy architecture=amd64/generic power_parameters_power_user=opnfv
2019-02-26 06:07:09,907 [salt.loaded.ext.module.maas:412 ][WARNING ][26454] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-26 06:07:09,908 [salt.loaded.ext.module.maas:92  ][INFO    ][26454] 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=bcgp8g architecture=amd64/generic power_parameters_power_user=opnfv
2019-02-26 06:07:10,370 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226060710362346
2019-02-26 06:07:10,389 [salt.minion      :1432][INFO    ][26702] Starting a new job with PID 26702
2019-02-26 06:07:10,416 [salt.minion      :1711][INFO    ][26702] Returning information for job: 20190226060710362346
2019-02-26 06:07:10,997 [salt.state       :300 ][INFO    ][26454] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-02-26 06:07:10,997 [salt.state       :1951][INFO    ][26454] Completed state [maas.process_machines] at time 06:07:10.997567 duration_in_ms=6055.849
2019-02-26 06:07:11,001 [salt.minion      :1711][INFO    ][26454] Returning information for job: 20190226060655262069
2019-02-26 06:07:43,989 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command state.apply with jid 20190226060743976770
2019-02-26 06:07:44,012 [salt.minion      :1432][INFO    ][26774] Starting a new job with PID 26774
2019-02-26 06:07:51,671 [salt.state       :915 ][INFO    ][26774] Loading fresh modules for state activity
2019-02-26 06:07:51,761 [salt.state       :1780][INFO    ][26774] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:07:51.761119
2019-02-26 06:07:51,761 [salt.state       :1813][INFO    ][26774] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-26 06:07:51,763 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26774] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-26 06:07:53,502 [salt.state       :300 ][INFO    ][26774] {'pid': 26786, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-26 06:07:53,502 [salt.state       :1951][INFO    ][26774] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:07:53.502686 duration_in_ms=1741.568
2019-02-26 06:07:53,505 [salt.state       :1780][INFO    ][26774] Running state [maas.wait_for_machine_status] at time 06:07:53.505437
2019-02-26 06:07:53,505 [salt.state       :1813][INFO    ][26774] Executing state module.run for [maas.wait_for_machine_status]
2019-02-26 06:07:53,506 [salt.utils.decorators:613 ][WARNING ][26774] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-26 06:07:56,215 [salt.state       :300 ][INFO    ][26774] {'ret': True}
2019-02-26 06:07:56,216 [salt.state       :1951][INFO    ][26774] Completed state [maas.wait_for_machine_status] at time 06:07:56.216210 duration_in_ms=2710.771
2019-02-26 06:07:56,219 [salt.minion      :1711][INFO    ][26774] Returning information for job: 20190226060743976770
2019-02-26 06:07:56,754 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command state.apply with jid 20190226060756740819
2019-02-26 06:07:56,771 [salt.minion      :1432][INFO    ][26799] Starting a new job with PID 26799
2019-02-26 06:07:57,605 [salt.state       :915 ][INFO    ][26799] Loading fresh modules for state activity
2019-02-26 06:07:57,737 [salt.state       :1780][INFO    ][26799] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:07:57.737056
2019-02-26 06:07:57,737 [salt.state       :1813][INFO    ][26799] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-26 06:07:57,739 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26799] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-26 06:07:59,403 [salt.state       :300 ][INFO    ][26799] {'pid': 26806, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-26 06:07:59,404 [salt.state       :1951][INFO    ][26799] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:07:59.404704 duration_in_ms=1667.647
2019-02-26 06:07:59,408 [salt.state       :1780][INFO    ][26799] Running state [maas_machines_storage_cmp002_lvm] at time 06:07:59.408342
2019-02-26 06:07:59,409 [salt.state       :1813][INFO    ][26799] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-02-26 06:07:59,966 [salt.state       :300 ][INFO    ][26799] Machine cmp002 is not in Ready state.
2019-02-26 06:07:59,968 [salt.state       :1951][INFO    ][26799] Completed state [maas_machines_storage_cmp002_lvm] at time 06:07:59.968289 duration_in_ms=559.946
2019-02-26 06:07:59,968 [salt.state       :1780][INFO    ][26799] Running state [maas_machines_storage_cmp001_lvm] at time 06:07:59.968670
2019-02-26 06:07:59,968 [salt.state       :1813][INFO    ][26799] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-02-26 06:08:00,491 [salt.state       :300 ][INFO    ][26799] Machine cmp001 is not in Ready state.
2019-02-26 06:08:00,492 [salt.state       :1951][INFO    ][26799] Completed state [maas_machines_storage_cmp001_lvm] at time 06:08:00.491870 duration_in_ms=523.198
2019-02-26 06:08:00,497 [salt.minion      :1711][INFO    ][26799] Returning information for job: 20190226060756740819
2019-02-26 06:08:01,037 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command state.apply with jid 20190226060801022601
2019-02-26 06:08:01,055 [salt.minion      :1432][INFO    ][26816] Starting a new job with PID 26816
2019-02-26 06:08:01,912 [salt.state       :915 ][INFO    ][26816] Loading fresh modules for state activity
2019-02-26 06:08:01,998 [salt.state       :1780][INFO    ][26816] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:08:01.998308
2019-02-26 06:08:01,998 [salt.state       :1813][INFO    ][26816] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-26 06:08:02,000 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26816] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-26 06:08:03,690 [salt.state       :300 ][INFO    ][26816] {'pid': 26823, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-26 06:08:03,691 [salt.state       :1951][INFO    ][26816] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:08:03.691725 duration_in_ms=1693.418
2019-02-26 06:08:03,694 [salt.state       :1780][INFO    ][26816] Running state [maas.deploy_machines] at time 06:08:03.694574
2019-02-26 06:08:03,695 [salt.state       :1813][INFO    ][26816] Executing state module.run for [maas.deploy_machines]
2019-02-26 06:08:03,696 [salt.utils.decorators:613 ][WARNING ][26816] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-26 06:08:04,242 [salt.loaded.ext.module.maas:684 ][INFO    ][26816] deploymachines hwe_kernel=hwe-16.04 system_id=bcgp8g distro_series=xenial
2019-02-26 06:08:06,566 [salt.state       :300 ][INFO    ][26816] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-02-26 06:08:06,569 [salt.state       :1951][INFO    ][26816] Completed state [maas.deploy_machines] at time 06:08:06.569815 duration_in_ms=2875.241
2019-02-26 06:08:06,572 [salt.minion      :1711][INFO    ][26816] Returning information for job: 20190226060801022601
2019-02-26 06:08:07,109 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command state.apply with jid 20190226060807098366
2019-02-26 06:08:07,126 [salt.minion      :1432][INFO    ][26903] Starting a new job with PID 26903
2019-02-26 06:08:14,803 [salt.state       :915 ][INFO    ][26903] Loading fresh modules for state activity
2019-02-26 06:08:14,898 [salt.state       :1780][INFO    ][26903] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:08:14.898793
2019-02-26 06:08:14,899 [salt.state       :1813][INFO    ][26903] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-26 06:08:14,901 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26903] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-26 06:08:16,620 [salt.state       :300 ][INFO    ][26903] {'pid': 26914, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-26 06:08:16,621 [salt.state       :1951][INFO    ][26903] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:08:16.621474 duration_in_ms=1722.68
2019-02-26 06:08:16,625 [salt.state       :1780][INFO    ][26903] Running state [maas.wait_for_machine_status] at time 06:08:16.625343
2019-02-26 06:08:16,625 [salt.state       :1813][INFO    ][26903] Executing state module.run for [maas.wait_for_machine_status]
2019-02-26 06:08:16,626 [salt.utils.decorators:613 ][WARNING ][26903] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-26 06:08:19,368 [salt.loaded.ext.module.maas:1023][INFO    ][26903] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.27157807s left)
2019-02-26 06:08:22,218 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226060822201075
2019-02-26 06:08:22,235 [salt.minion      :1432][INFO    ][26938] Starting a new job with PID 26938
2019-02-26 06:08:22,259 [salt.minion      :1711][INFO    ][26938] Returning information for job: 20190226060822201075
2019-02-26 06:08:52,086 [salt.loaded.ext.module.maas:1023][INFO    ][26903] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.55343699s left)
2019-02-26 06:08:52,285 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226060852234523
2019-02-26 06:08:52,308 [salt.minion      :1432][INFO    ][26998] Starting a new job with PID 26998
2019-02-26 06:08:52,337 [salt.minion      :1711][INFO    ][26998] Returning information for job: 20190226060852234523
2019-02-26 06:09:22,309 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226060922300787
2019-02-26 06:09:22,325 [salt.minion      :1432][INFO    ][27040] Starting a new job with PID 27040
2019-02-26 06:09:22,349 [salt.minion      :1711][INFO    ][27040] Returning information for job: 20190226060922300787
2019-02-26 06:09:24,782 [salt.loaded.ext.module.maas:1023][INFO    ][26903] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2181.85727906s left)
2019-02-26 06:09:52,506 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226060952494443
2019-02-26 06:09:52,530 [salt.minion      :1432][INFO    ][27097] Starting a new job with PID 27097
2019-02-26 06:09:52,555 [salt.minion      :1711][INFO    ][27097] Returning information for job: 20190226060952494443
2019-02-26 06:09:57,680 [salt.loaded.ext.module.maas:1023][INFO    ][26903] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2148.95928502s left)
2019-02-26 06:10:22,700 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226061022686710
2019-02-26 06:10:22,723 [salt.minion      :1432][INFO    ][27147] Starting a new job with PID 27147
2019-02-26 06:10:22,748 [salt.minion      :1711][INFO    ][27147] Returning information for job: 20190226061022686710
2019-02-26 06:10:30,401 [salt.loaded.ext.module.maas:1023][INFO    ][26903] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2116.23821497s left)
2019-02-26 06:10:52,896 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226061052888105
2019-02-26 06:10:52,914 [salt.minion      :1432][INFO    ][27219] Starting a new job with PID 27219
2019-02-26 06:10:52,940 [salt.minion      :1711][INFO    ][27219] Returning information for job: 20190226061052888105
2019-02-26 06:11:03,241 [salt.loaded.ext.module.maas:1023][INFO    ][26903] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2083.39850307s left)
2019-02-26 06:11:22,921 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226061122906734
2019-02-26 06:11:22,943 [salt.minion      :1432][INFO    ][27264] Starting a new job with PID 27264
2019-02-26 06:11:22,973 [salt.minion      :1711][INFO    ][27264] Returning information for job: 20190226061122906734
2019-02-26 06:11:35,886 [salt.loaded.ext.module.maas:1023][INFO    ][26903] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2050.7533989s left)
2019-02-26 06:11:53,119 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226061153111277
2019-02-26 06:11:53,143 [salt.minion      :1432][INFO    ][27321] Starting a new job with PID 27321
2019-02-26 06:11:53,168 [salt.minion      :1711][INFO    ][27321] Returning information for job: 20190226061153111277
2019-02-26 06:12:09,257 [salt.loaded.ext.module.maas:1023][INFO    ][26903] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2017.38169098s left)
2019-02-26 06:12:23,325 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226061223316720
2019-02-26 06:12:23,340 [salt.minion      :1432][INFO    ][27538] Starting a new job with PID 27538
2019-02-26 06:12:23,365 [salt.minion      :1711][INFO    ][27538] Returning information for job: 20190226061223316720
2019-02-26 06:12:42,153 [salt.loaded.ext.module.maas:1023][INFO    ][26903] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1984.486166s left)
2019-02-26 06:12:53,385 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226061253372899
2019-02-26 06:12:53,408 [salt.minion      :1432][INFO    ][27624] Starting a new job with PID 27624
2019-02-26 06:12:53,431 [salt.minion      :1711][INFO    ][27624] Returning information for job: 20190226061253372899
2019-02-26 06:13:14,976 [salt.loaded.ext.module.maas:1023][INFO    ][26903] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1951.6629951s left)
2019-02-26 06:13:23,483 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226061323473592
2019-02-26 06:13:23,503 [salt.minion      :1432][INFO    ][27693] Starting a new job with PID 27693
2019-02-26 06:13:23,528 [salt.minion      :1711][INFO    ][27693] Returning information for job: 20190226061323473592
2019-02-26 06:13:47,954 [salt.loaded.ext.module.maas:1023][INFO    ][26903] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1918.6851809s left)
2019-02-26 06:13:53,576 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226061353568440
2019-02-26 06:13:53,594 [salt.minion      :1432][INFO    ][27865] Starting a new job with PID 27865
2019-02-26 06:13:53,616 [salt.minion      :1711][INFO    ][27865] Returning information for job: 20190226061353568440
2019-02-26 06:14:20,736 [salt.loaded.ext.module.maas:1023][INFO    ][26903] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1885.90292597s left)
2019-02-26 06:14:23,669 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226061423655315
2019-02-26 06:14:23,688 [salt.minion      :1432][INFO    ][27931] Starting a new job with PID 27931
2019-02-26 06:14:23,714 [salt.minion      :1711][INFO    ][27931] Returning information for job: 20190226061423655315
2019-02-26 06:14:53,603 [salt.loaded.ext.module.maas:1023][INFO    ][26903] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1853.03643012s left)
2019-02-26 06:14:53,784 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226061453772567
2019-02-26 06:14:53,803 [salt.minion      :1432][INFO    ][28010] Starting a new job with PID 28010
2019-02-26 06:14:53,829 [salt.minion      :1711][INFO    ][28010] Returning information for job: 20190226061453772567
2019-02-26 06:15:23,997 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226061523985336
2019-02-26 06:15:24,013 [salt.minion      :1432][INFO    ][28066] Starting a new job with PID 28066
2019-02-26 06:15:24,036 [salt.minion      :1711][INFO    ][28066] Returning information for job: 20190226061523985336
2019-02-26 06:15:26,246 [salt.loaded.ext.module.maas:1023][INFO    ][26903] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1820.39307189s left)
2019-02-26 06:15:54,205 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226061554196976
2019-02-26 06:15:54,224 [salt.minion      :1432][INFO    ][28196] Starting a new job with PID 28196
2019-02-26 06:15:54,249 [salt.minion      :1711][INFO    ][28196] Returning information for job: 20190226061554196976
2019-02-26 06:15:59,123 [salt.loaded.ext.module.maas:1023][INFO    ][26903] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1787.51624703s left)
2019-02-26 06:16:24,311 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226061624299225
2019-02-26 06:16:24,332 [salt.minion      :1432][INFO    ][28257] Starting a new job with PID 28257
2019-02-26 06:16:24,357 [salt.minion      :1711][INFO    ][28257] Returning information for job: 20190226061624299225
2019-02-26 06:16:31,965 [salt.loaded.ext.module.maas:1023][INFO    ][26903] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1754.67414212s left)
2019-02-26 06:16:54,439 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226061654426817
2019-02-26 06:16:54,462 [salt.minion      :1432][INFO    ][28316] Starting a new job with PID 28316
2019-02-26 06:16:54,485 [salt.minion      :1711][INFO    ][28316] Returning information for job: 20190226061654426817
2019-02-26 06:17:05,002 [salt.loaded.ext.module.maas:1023][INFO    ][26903] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1721.63733196s left)
2019-02-26 06:17:24,560 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226061724550089
2019-02-26 06:17:24,577 [salt.minion      :1432][INFO    ][28365] Starting a new job with PID 28365
2019-02-26 06:17:24,602 [salt.minion      :1711][INFO    ][28365] Returning information for job: 20190226061724550089
2019-02-26 06:17:37,807 [salt.loaded.ext.module.maas:1023][INFO    ][26903] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1688.8321321s left)
2019-02-26 06:17:54,581 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226061754571223
2019-02-26 06:17:54,607 [salt.minion      :1432][INFO    ][28422] Starting a new job with PID 28422
2019-02-26 06:17:54,634 [salt.minion      :1711][INFO    ][28422] Returning information for job: 20190226061754571223
2019-02-26 06:18:10,556 [salt.loaded.ext.module.maas:1023][INFO    ][26903] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1656.08316493s left)
2019-02-26 06:18:24,621 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226061824608372
2019-02-26 06:18:24,643 [salt.minion      :1432][INFO    ][28473] Starting a new job with PID 28473
2019-02-26 06:18:24,667 [salt.minion      :1711][INFO    ][28473] Returning information for job: 20190226061824608372
2019-02-26 06:18:43,411 [salt.loaded.ext.module.maas:1023][INFO    ][26903] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1623.22809505s left)
2019-02-26 06:18:54,649 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226061854638026
2019-02-26 06:18:54,674 [salt.minion      :1432][INFO    ][28530] Starting a new job with PID 28530
2019-02-26 06:18:54,701 [salt.minion      :1711][INFO    ][28530] Returning information for job: 20190226061854638026
2019-02-26 06:19:16,247 [salt.loaded.ext.module.maas:1023][INFO    ][26903] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1590.39239597s left)
2019-02-26 06:19:24,682 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226061924674087
2019-02-26 06:19:24,695 [salt.minion      :1432][INFO    ][28574] Starting a new job with PID 28574
2019-02-26 06:19:24,718 [salt.minion      :1711][INFO    ][28574] Returning information for job: 20190226061924674087
2019-02-26 06:19:49,176 [salt.loaded.ext.module.maas:1023][INFO    ][26903] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1557.46331096s left)
2019-02-26 06:19:54,881 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command saltutil.find_job with jid 20190226061954873069
2019-02-26 06:19:54,905 [salt.minion      :1432][INFO    ][28670] Starting a new job with PID 28670
2019-02-26 06:19:54,932 [salt.minion      :1711][INFO    ][28670] Returning information for job: 20190226061954873069
2019-02-26 06:20:21,989 [salt.state       :300 ][INFO    ][26903] {'ret': True}
2019-02-26 06:20:21,989 [salt.state       :1951][INFO    ][26903] Completed state [maas.wait_for_machine_status] at time 06:20:21.989881 duration_in_ms=725364.535
2019-02-26 06:20:21,992 [salt.minion      :1711][INFO    ][26903] Returning information for job: 20190226060807098366
2019-02-26 06:20:29,724 [salt.utils.schedule:1377][INFO    ][16823] Running scheduled job: __mine_interval
2019-02-26 07:20:29,724 [salt.utils.schedule:1377][INFO    ][16823] Running scheduled job: __mine_interval
2019-02-26 07:30:08,768 [salt.minion      :1308][INFO    ][16823] User sudo_ubuntu Executing command cp.push_dir with jid 20190226073008758170
2019-02-26 07:30:08,783 [salt.minion      :1432][INFO    ][36199] Starting a new job with PID 36199
