2019-03-22 05:09:10,321 [salt.utils.decorators:613 ][WARNING ][2099] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-22 05:09:11,279 [salt.utils.decorators:613 ][WARNING ][2099] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-22 05:09:13,810 [salt.loaded.int.states.file:2298][WARNING ][2429] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-03-22 05:09:42,657 [salt.state       :2022][WARNING ][2998] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-03-22 05:09:44,615 [salt.utils.decorators:613 ][WARNING ][2998] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-22 05:09:58,278 [salt.utils.decorators:613 ][WARNING ][2998] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-22 05:10:25,121 [salt.utils.decorators:613 ][WARNING ][2998] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-22 05:10:25,960 [salt.utils.decorators:613 ][WARNING ][2998] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-22 05:10:27,864 [salt.loaded.ext.module.maasng:1008][WARNING ][2998] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-03-22 05:10:27,865 [salt.loaded.ext.module.maasng:1011][WARNING ][2998] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-03-22 05:10:28,513 [salt.loaded.ext.module.maasng:1235][WARNING ][2998] Ignoring parameter vlan:0
2019-03-22 05:10:29,178 [salt.utils.decorators:613 ][WARNING ][2998] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-22 05:10:31,758 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5479] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-03-22 05:10:31,780 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5479] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-03-22 05:10:31,822 [salt.utils.parsers:1051][WARNING ][382] Minion received a SIGTERM. Exiting.
2019-03-22 05:10:32,838 [salt.cli.daemons :293 ][INFO    ][5556] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-03-22 05:10:32,983 [salt.cli.daemons :82  ][INFO    ][5556] Starting up the Salt Minion
2019-03-22 05:10:32,983 [salt.utils.event :1017][INFO    ][5556] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-03-22 05:10:34,026 [salt.minion      :976 ][INFO    ][5556] Creating minion process manager
2019-03-22 05:10:35,581 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][5556] Executing command ['date', '+%z'] in directory '/root'
2019-03-22 05:10:35,601 [salt.utils.schedule:568 ][INFO    ][5556] Updating job settings for scheduled job: __mine_interval
2019-03-22 05:10:35,603 [salt.minion      :1108][INFO    ][5556] Added mine.update to scheduler
2019-03-22 05:10:35,610 [salt.minion      :1975][INFO    ][5556] Minion is starting as user 'root'
2019-03-22 05:10:35,624 [salt.minion      :2336][INFO    ][5556] Minion is ready to receive requests!
2019-03-22 05:10:37,929 [salt.utils.decorators:613 ][WARNING ][5490] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-22 05:10:37,994 [salt.loaded.ext.module.maas:412 ][WARNING ][5490] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-22 05:10:39,468 [salt.loaded.ext.module.maas:412 ][WARNING ][5490] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-22 05:10:40,855 [salt.loaded.ext.module.maas:412 ][WARNING ][5490] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-22 05:10:42,144 [salt.loaded.ext.module.maas:412 ][WARNING ][5490] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-22 05:10:43,207 [salt.loaded.ext.module.maas:412 ][WARNING ][5490] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-22 05:11:15,295 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command state.apply with jid 20190322051115278616
2019-03-22 05:11:15,318 [salt.minion      :1432][INFO    ][5998] Starting a new job with PID 5998
2019-03-22 05:11:20,993 [salt.state       :915 ][INFO    ][5998] Loading fresh modules for state activity
2019-03-22 05:11:21,048 [salt.fileclient  :1219][INFO    ][5998] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-03-22 05:11:21,092 [salt.state       :1780][INFO    ][5998] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:11:21.092420
2019-03-22 05:11:21,092 [salt.state       :1813][INFO    ][5998] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-22 05:11:21,094 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5998] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-22 05:11:22,807 [salt.state       :300 ][INFO    ][5998] {'pid': 6010, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-22 05:11:22,808 [salt.state       :1951][INFO    ][5998] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:11:22.808534 duration_in_ms=1716.113
2019-03-22 05:11:22,811 [salt.state       :1780][INFO    ][5998] Running state [maas.wait_for_machine_status] at time 05:11:22.811400
2019-03-22 05:11:22,812 [salt.state       :1813][INFO    ][5998] Executing state module.run for [maas.wait_for_machine_status]
2019-03-22 05:11:22,812 [salt.utils.decorators:613 ][WARNING ][5998] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-22 05:11:23,552 [salt.loaded.ext.module.maas:1023][INFO    ][5998] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.26954508s left)
2019-03-22 05:11:30,404 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322051130391168
2019-03-22 05:11:30,424 [salt.minion      :1432][INFO    ][6022] Starting a new job with PID 6022
2019-03-22 05:11:30,444 [salt.minion      :1711][INFO    ][6022] Returning information for job: 20190322051130391168
2019-03-22 05:11:54,368 [salt.loaded.ext.module.maas:1023][INFO    ][5998] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.45328093s left)
2019-03-22 05:12:00,472 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322051200452661
2019-03-22 05:12:00,490 [salt.minion      :1432][INFO    ][6099] Starting a new job with PID 6099
2019-03-22 05:12:00,511 [salt.minion      :1711][INFO    ][6099] Returning information for job: 20190322051200452661
2019-03-22 05:12:25,156 [salt.loaded.ext.module.maas:1023][INFO    ][5998] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.66564012s left)
2019-03-22 05:12:30,541 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322051230522205
2019-03-22 05:12:30,563 [salt.minion      :1432][INFO    ][6121] Starting a new job with PID 6121
2019-03-22 05:12:30,584 [salt.minion      :1711][INFO    ][6121] Returning information for job: 20190322051230522205
2019-03-22 05:12:55,835 [salt.loaded.ext.module.maas:1023][INFO    ][5998] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.98616004s left)
2019-03-22 05:13:00,741 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322051300725088
2019-03-22 05:13:00,764 [salt.minion      :1432][INFO    ][6204] Starting a new job with PID 6204
2019-03-22 05:13:00,785 [salt.minion      :1711][INFO    ][6204] Returning information for job: 20190322051300725088
2019-03-22 05:13:26,824 [salt.loaded.ext.module.maas:1023][INFO    ][5998] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.99797201s left)
2019-03-22 05:13:30,793 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322051330777503
2019-03-22 05:13:30,813 [salt.minion      :1432][INFO    ][6246] Starting a new job with PID 6246
2019-03-22 05:13:30,835 [salt.minion      :1711][INFO    ][6246] Returning information for job: 20190322051330777503
2019-03-22 05:13:57,957 [salt.loaded.ext.module.maas:1023][INFO    ][5998] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.86500597s left)
2019-03-22 05:14:00,835 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322051400826210
2019-03-22 05:14:00,848 [salt.minion      :1432][INFO    ][6431] Starting a new job with PID 6431
2019-03-22 05:14:00,866 [salt.minion      :1711][INFO    ][6431] Returning information for job: 20190322051400826210
2019-03-22 05:14:29,081 [salt.loaded.ext.module.maas:1023][INFO    ][5998] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1313.74095201s left)
2019-03-22 05:14:30,899 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322051430882466
2019-03-22 05:14:30,920 [salt.minion      :1432][INFO    ][6501] Starting a new job with PID 6501
2019-03-22 05:14:30,940 [salt.minion      :1711][INFO    ][6501] Returning information for job: 20190322051430882466
2019-03-22 05:15:00,263 [salt.loaded.ext.module.maas:1023][INFO    ][5998] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1282.55831409s left)
2019-03-22 05:15:00,937 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322051500927859
2019-03-22 05:15:00,949 [salt.minion      :1432][INFO    ][6842] Starting a new job with PID 6842
2019-03-22 05:15:00,982 [salt.minion      :1711][INFO    ][6842] Returning information for job: 20190322051500927859
2019-03-22 05:15:31,014 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322051531003054
2019-03-22 05:15:31,031 [salt.minion      :1432][INFO    ][6950] Starting a new job with PID 6950
2019-03-22 05:15:31,051 [salt.minion      :1711][INFO    ][6950] Returning information for job: 20190322051531003054
2019-03-22 05:15:31,974 [salt.loaded.ext.module.maas:1023][INFO    ][5998] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1250.84775496s left)
2019-03-22 05:16:01,129 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322051601113090
2019-03-22 05:16:01,149 [salt.minion      :1432][INFO    ][7204] Starting a new job with PID 7204
2019-03-22 05:16:01,170 [salt.minion      :1711][INFO    ][7204] Returning information for job: 20190322051601113090
2019-03-22 05:16:03,937 [salt.loaded.ext.module.maas:1023][INFO    ][5998] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1218.88503003s left)
2019-03-22 05:16:31,216 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322051631199928
2019-03-22 05:16:31,234 [salt.minion      :1432][INFO    ][7327] Starting a new job with PID 7327
2019-03-22 05:16:31,256 [salt.minion      :1711][INFO    ][7327] Returning information for job: 20190322051631199928
2019-03-22 05:16:35,675 [salt.loaded.ext.module.maas:1023][INFO    ][5998] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1187.147228s left)
2019-03-22 05:17:01,312 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322051701298592
2019-03-22 05:17:01,332 [salt.minion      :1432][INFO    ][7536] Starting a new job with PID 7536
2019-03-22 05:17:01,353 [salt.minion      :1711][INFO    ][7536] Returning information for job: 20190322051701298592
2019-03-22 05:17:07,739 [salt.loaded.ext.module.maas:1023][INFO    ][5998] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1155.08324289s left)
2019-03-22 05:17:31,412 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322051731395612
2019-03-22 05:17:31,430 [salt.minion      :1432][INFO    ][7662] Starting a new job with PID 7662
2019-03-22 05:17:31,458 [salt.minion      :1711][INFO    ][7662] Returning information for job: 20190322051731395612
2019-03-22 05:17:40,558 [salt.loaded.ext.module.maas:1023][INFO    ][5998] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1122.26343608s left)
2019-03-22 05:18:01,549 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322051801535215
2019-03-22 05:18:01,568 [salt.minion      :1432][INFO    ][7940] Starting a new job with PID 7940
2019-03-22 05:18:01,593 [salt.minion      :1711][INFO    ][7940] Returning information for job: 20190322051801535215
2019-03-22 05:18:13,096 [salt.loaded.ext.module.maas:1023][INFO    ][5998] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1089.72523093s left)
2019-03-22 05:18:31,631 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322051831621402
2019-03-22 05:18:31,651 [salt.minion      :1432][INFO    ][7960] Starting a new job with PID 7960
2019-03-22 05:18:31,672 [salt.minion      :1711][INFO    ][7960] Returning information for job: 20190322051831621402
2019-03-22 05:18:45,802 [salt.loaded.ext.module.maas:1023][INFO    ][5998] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1057.01932907s left)
2019-03-22 05:19:01,726 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322051901718085
2019-03-22 05:19:01,749 [salt.minion      :1432][INFO    ][8086] Starting a new job with PID 8086
2019-03-22 05:19:01,771 [salt.minion      :1711][INFO    ][8086] Returning information for job: 20190322051901718085
2019-03-22 05:19:18,697 [salt.state       :300 ][INFO    ][5998] {'ret': True}
2019-03-22 05:19:18,697 [salt.state       :1951][INFO    ][5998] Completed state [maas.wait_for_machine_status] at time 05:19:18.697689 duration_in_ms=475886.288
2019-03-22 05:19:18,702 [salt.minion      :1711][INFO    ][5998] Returning information for job: 20190322051115278616
2019-03-22 05:19:19,248 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command state.apply with jid 20190322051919240365
2019-03-22 05:19:19,269 [salt.minion      :1432][INFO    ][8114] Starting a new job with PID 8114
2019-03-22 05:19:25,192 [salt.state       :915 ][INFO    ][8114] Loading fresh modules for state activity
2019-03-22 05:19:25,261 [salt.fileclient  :1219][INFO    ][8114] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-03-22 05:19:25,361 [salt.state       :1780][INFO    ][8114] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:19:25.361795
2019-03-22 05:19:25,362 [salt.state       :1813][INFO    ][8114] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-22 05:19:25,365 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8114] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-22 05:19:27,103 [salt.state       :300 ][INFO    ][8114] {'pid': 8122, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-22 05:19:27,104 [salt.state       :1951][INFO    ][8114] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:19:27.104051 duration_in_ms=1742.258
2019-03-22 05:19:27,106 [salt.state       :1780][INFO    ][8114] Running state [maas_machines_storage_cmp002_lvm] at time 05:19:27.106299
2019-03-22 05:19:27,106 [salt.state       :1813][INFO    ][8114] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-03-22 05:19:28,058 [salt.loaded.ext.module.maasng:610 ][INFO    ][8114] 6e7krn
2019-03-22 05:19:28,060 [salt.loaded.ext.module.maasng:626 ][INFO    ][8114] sda
2019-03-22 05:19:28,535 [salt.loaded.ext.module.maasng:361 ][INFO    ][8114] 6e7krn
2019-03-22 05:19:28,608 [salt.loaded.ext.module.maasng:367 ][INFO    ][8114] [{u'size': 800109715456, u'partition_table_type': u'MBR', u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'uuid': None, u'name': u'sda', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'6e7krn', u'resource_uri': u'/MAAS/api/2.0/nodes/6e7krn/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'block_size': 4096, u'type': u'physical', u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'f3f01fb7-a834-42fa-bc28-38b573a9a337', u'resource_uri': u'/MAAS/api/2.0/nodes/6e7krn/blockdevices/1/partition/1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6e7krn', u'filesystem': {u'uuid': u'91d14b20-77c4-4024-aa52-7b262cc9690f', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 1, u'size': 800101236736}]}, {u'size': 800097042432, u'partition_table_type': None, u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 800097042432, u'uuid': u'8f08ccc7-a634-45ba-815e-fbf0f389fd97', u'name': u'vgroot-lvroot', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'6e7krn', u'resource_uri': u'/MAAS/api/2.0/nodes/6e7krn/blockdevices/3/', u'filesystem': {u'uuid': u'a4d79e3a-32e0-4204-b92a-26ff8d234883', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 3, u'serial': None, u'partitions': []}]
2019-03-22 05:19:28,609 [salt.loaded.ext.module.maasng:632 ][INFO    ][8114] vgroot
2019-03-22 05:19:28,609 [salt.loaded.ext.module.maasng:635 ][INFO    ][8114] lvroot
2019-03-22 05:19:28,609 [salt.loaded.ext.module.maasng:639 ][INFO    ][8114] 107374182400
2019-03-22 05:19:29,086 [salt.loaded.ext.module.maasng:645 ][INFO    ][8114] {u'hwe_kernel': u'', u'status_action': u'', u'swap_size': None, u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'68nbf4', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'id': 17, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'68nbf4', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'68nbf4', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'children': [], u'system_id': u'6e7krn', u'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/6e7krn/interfaces/4/'}, u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'partition_table_type': u'MBR', u'block_size': 4096, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'name': u'sda', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'6e7krn', u'resource_uri': u'/MAAS/api/2.0/nodes/6e7krn/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'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'ee969151-a1bf-47d0-9b31-17902506ff37', u'resource_uri': u'/MAAS/api/2.0/nodes/6e7krn/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6e7krn', u'filesystem': {u'label': None, u'uuid': u'9ae29927-a3cd-4fe9-8a43-e680fb661d4b', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'device_id': 1}]}, u'blockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'partition_table_type': u'MBR', u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'name': u'sda', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'6e7krn', u'resource_uri': u'/MAAS/api/2.0/nodes/6e7krn/blockdevices/1/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'partitions': [{u'uuid': u'ee969151-a1bf-47d0-9b31-17902506ff37', u'resource_uri': u'/MAAS/api/2.0/nodes/6e7krn/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6e7krn', u'filesystem': {u'label': None, u'uuid': u'9ae29927-a3cd-4fe9-8a43-e680fb661d4b', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'device_id': 1}]}, {u'size': 107374182400, u'model': None, u'partition_table_type': None, u'uuid': u'214bc46a-f6ee-4714-ba5e-7e31cb350f7b', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'name': u'vgroot-lvroot', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'6e7krn', u'resource_uri': u'/MAAS/api/2.0/nodes/6e7krn/blockdevices/11/', u'filesystem': {u'label': u'root', u'uuid': u'7498818a-474b-439b-8146-355713862ee6', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 11, u'partitions': []}], u'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'id': 1}, u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'6e7krn', 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'disable_ipv4': False, u'architecture': u'amd64/generic', u'status': 4, u'bcaches': [], u'cpu_count': 40, u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'6e7krn', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'node_type_name': u'Machine', u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'68nbf4', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'id': 17, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'68nbf4', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'68nbf4', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'children': [], u'system_id': u'6e7krn', u'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/6e7krn/interfaces/4/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'children': [], u'system_id': u'6e7krn', u'mac_address': u'38:ea:a7:8f:07:50', u'params': u'', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/6e7krn/interfaces/11/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'children': [], u'system_id': u'6e7krn', u'mac_address': u'38:ea:a7:8f:07:51', u'params': u'', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/6e7krn/interfaces/13/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'68nbf4', 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': 18, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'68nbf4', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'68nbf4', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.40'}], u'children': [], u'system_id': u'6e7krn', u'mac_address': u'9c:b6:54:8a:10:1c', u'params': u'', u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/6e7krn/interfaces/15/'}, {u'name': u'ens2f1', u'links': [{u'id': 19, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'children': [], u'system_id': u'6e7krn', u'mac_address': u'38:ea:a7:8f:12:49', u'params': u'', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/6e7krn/interfaces/12/'}, {u'name': u'ens2f0', u'links': [{u'id': 20, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'children': [], u'system_id': u'6e7krn', u'mac_address': u'38:ea:a7:8f:12:48', u'params': u'', u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/6e7krn/interfaces/14/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'partition_table_type': u'MBR', u'block_size': 4096, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'name': u'sda', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'6e7krn', u'resource_uri': u'/MAAS/api/2.0/nodes/6e7krn/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'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'ee969151-a1bf-47d0-9b31-17902506ff37', u'resource_uri': u'/MAAS/api/2.0/nodes/6e7krn/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6e7krn', u'filesystem': {u'label': None, u'uuid': u'9ae29927-a3cd-4fe9-8a43-e680fb661d4b', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'device_id': 1}]}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'partition_table_type': None, u'block_size': 4096, u'uuid': u'214bc46a-f6ee-4714-ba5e-7e31cb350f7b', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'name': u'vgroot-lvroot', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'6e7krn', u'resource_uri': u'/MAAS/api/2.0/nodes/6e7krn/blockdevices/11/', u'filesystem': {u'label': u'root', u'uuid': u'7498818a-474b-439b-8146-355713862ee6', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'size': 107374182400, u'used_size': 107374182400, u'id': 11, u'serial': None, u'partitions': []}], u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'commissioning_status_name': u'Passed', u'current_commissioning_result_id': 2, u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/6e7krn/'}
2019-03-22 05:19:29,090 [salt.state       :300 ][INFO    ][8114] {'new': {'storage_layout': 'lvm'}}
2019-03-22 05:19:29,092 [salt.state       :1951][INFO    ][8114] Completed state [maas_machines_storage_cmp002_lvm] at time 05:19:29.090786 duration_in_ms=1984.485
2019-03-22 05:19:29,093 [salt.state       :1780][INFO    ][8114] Running state [maas_machines_storage_cmp001_lvm] at time 05:19:29.093119
2019-03-22 05:19:29,093 [salt.state       :1813][INFO    ][8114] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-03-22 05:19:30,034 [salt.loaded.ext.module.maasng:610 ][INFO    ][8114] gtgdpm
2019-03-22 05:19:30,034 [salt.loaded.ext.module.maasng:626 ][INFO    ][8114] sda
2019-03-22 05:19:30,510 [salt.loaded.ext.module.maasng:361 ][INFO    ][8114] gtgdpm
2019-03-22 05:19:30,579 [salt.loaded.ext.module.maasng:367 ][INFO    ][8114] [{u'size': 800109715456, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'3e260185-e64c-464a-a506-efdd04063d02', u'resource_uri': u'/MAAS/api/2.0/nodes/gtgdpm/blockdevices/2/partition/2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'gtgdpm', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'26d81de1-284c-4485-a0e5-40f4a1ebd724', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 2, u'size': 800101236736}], u'filesystem': None, 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'gtgdpm', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id': 2, u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'type': u'physical', u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/gtgdpm/blockdevices/2/'}, {u'size': 800097042432, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'partitions': [], u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'f0f2854a-6218-4102-91d7-ea2e8b65c6aa', u'label': u'root'}, u'uuid': u'7268906e-cd03-4db7-a91c-3f42e7e86afc', u'used_for': u'ext4 formatted filesystem mounted at /', u'id_path': None, u'system_id': u'gtgdpm', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id': 4, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/gtgdpm/blockdevices/4/'}]
2019-03-22 05:19:30,580 [salt.loaded.ext.module.maasng:632 ][INFO    ][8114] vgroot
2019-03-22 05:19:30,580 [salt.loaded.ext.module.maasng:635 ][INFO    ][8114] lvroot
2019-03-22 05:19:30,580 [salt.loaded.ext.module.maasng:639 ][INFO    ][8114] 107374182400
2019-03-22 05:19:31,065 [salt.loaded.ext.module.maasng:645 ][INFO    ][8114] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'swap_size': None, u'disable_ipv4': False, u'cpu_count': 40, u'power_type': u'ipmi', u'hwe_kernel': u'', 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'68nbf4', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 23}], u'tags': [u'sriov'], u'enabled': True, 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'68nbf4', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, 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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'68nbf4', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'mac_address': u'9c:b6:54:8a:95:a0', u'system_id': u'gtgdpm', u'params': u'', u'parents': [], u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/gtgdpm/interfaces/5/'}, u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'status_action': u'', u'zone': {u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default', u'description': u''}, u'resource_uri': u'/MAAS/api/2.0/machines/gtgdpm/', u'current_commissioning_result_id': 4, u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'gtgdpm', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'ip_addresses': [u'192.168.11.39', u'192.168.11.43'], u'blockdevice_set': [{u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'filesystem': None, u'uuid': None, u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/gtgdpm/blockdevices/2/', u'system_id': u'gtgdpm', 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'size': 800109715456, u'type': u'physical', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'size': 800101236736, u'uuid': u'47e26633-b0ab-487b-b483-71b84ddf9aa1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'gtgdpm', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'5ff3977e-d039-4c44-9ea6-7d18aa28d7df', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/gtgdpm/blockdevices/2/partition/7'}]}, {u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'f058902e-b0bf-46c1-afb0-b6809d3ec26d', u'fstype': u'ext4'}, u'uuid': u'1da08bcd-3d83-4fcf-8a5c-34bc5a10be8c', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/gtgdpm/blockdevices/12/', u'system_id': u'gtgdpm', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'size': 107374182400, u'type': u'virtual', u'serial': None, u'partitions': []}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'power_state': u'off', u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'gtgdpm', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'filesystem': None, u'uuid': None, u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/gtgdpm/blockdevices/2/', u'system_id': u'gtgdpm', 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'size': 800109715456, u'type': u'physical', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'size': 800101236736, u'uuid': u'47e26633-b0ab-487b-b483-71b84ddf9aa1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'gtgdpm', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'5ff3977e-d039-4c44-9ea6-7d18aa28d7df', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/gtgdpm/blockdevices/2/partition/7'}]}, u'current_testing_result_id': 5, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'filesystem': None, u'uuid': None, u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/gtgdpm/blockdevices/2/', u'system_id': u'gtgdpm', 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'size': 800109715456, u'type': u'physical', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'size': 800101236736, u'uuid': u'47e26633-b0ab-487b-b483-71b84ddf9aa1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'gtgdpm', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'5ff3977e-d039-4c44-9ea6-7d18aa28d7df', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/gtgdpm/blockdevices/2/partition/7'}]}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'f058902e-b0bf-46c1-afb0-b6809d3ec26d', u'fstype': u'ext4'}, u'uuid': u'1da08bcd-3d83-4fcf-8a5c-34bc5a10be8c', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/gtgdpm/blockdevices/12/', u'system_id': u'gtgdpm', 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'size': 107374182400, u'type': u'virtual', u'serial': None, u'partitions': []}], u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'commissioning_status_name': u'Passed', u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'68nbf4', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 23}], u'tags': [u'sriov'], u'enabled': True, 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'68nbf4', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, 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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'68nbf4', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'mac_address': u'9c:b6:54:8a:95:a0', u'system_id': u'gtgdpm', u'params': u'', u'parents': [], u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/gtgdpm/interfaces/5/'}, {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'mac_address': u'38:ea:a7:8f:1f:d4', u'system_id': u'gtgdpm', u'params': u'', u'parents': [], u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/gtgdpm/interfaces/17/'}, {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'mac_address': u'38:ea:a7:8f:1f:d5', u'system_id': u'gtgdpm', u'params': u'', u'parents': [], u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/gtgdpm/interfaces/19/'}, {u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 24}], u'tags': [u'sriov'], u'enabled': True, 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cd', u'system_id': u'gtgdpm', u'params': u'', u'parents': [], u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/gtgdpm/interfaces/16/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'68nbf4', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'mode': u'link_up', u'id': 25}], u'tags': [u'sriov'], u'enabled': True, 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'68nbf4', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, 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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'68nbf4', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.43'}], u'mac_address': u'9c:b6:54:8a:95:a4', u'system_id': u'gtgdpm', u'params': u'', u'parents': [], u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/gtgdpm/interfaces/18/'}, {u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 26}], u'tags': [u'sriov'], u'enabled': True, 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cc', u'system_id': u'gtgdpm', u'params': u'', u'parents': [], u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/gtgdpm/interfaces/20/'}], u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'node_type_name': u'Machine'}
2019-03-22 05:19:31,066 [salt.state       :300 ][INFO    ][8114] {'new': {'storage_layout': 'lvm'}}
2019-03-22 05:19:31,067 [salt.state       :1951][INFO    ][8114] Completed state [maas_machines_storage_cmp001_lvm] at time 05:19:31.067451 duration_in_ms=1974.332
2019-03-22 05:19:31,069 [salt.minion      :1711][INFO    ][8114] Returning information for job: 20190322051919240365
2019-03-22 05:19:31,584 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command state.apply with jid 20190322051931577357
2019-03-22 05:19:31,597 [salt.minion      :1432][INFO    ][8140] Starting a new job with PID 8140
2019-03-22 05:19:32,285 [salt.state       :915 ][INFO    ][8140] Loading fresh modules for state activity
2019-03-22 05:19:32,337 [salt.fileclient  :1219][INFO    ][8140] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-03-22 05:19:32,386 [salt.state       :1780][INFO    ][8140] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:19:32.385944
2019-03-22 05:19:32,386 [salt.state       :1813][INFO    ][8140] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-22 05:19:32,388 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8140] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-22 05:19:34,151 [salt.state       :300 ][INFO    ][8140] {'pid': 8147, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-22 05:19:34,152 [salt.state       :1951][INFO    ][8140] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:19:34.152669 duration_in_ms=1766.724
2019-03-22 05:19:34,156 [salt.state       :1780][INFO    ][8140] Running state [maas.deploy_machines] at time 05:19:34.156615
2019-03-22 05:19:34,157 [salt.state       :1813][INFO    ][8140] Executing state module.run for [maas.deploy_machines]
2019-03-22 05:19:34,158 [salt.utils.decorators:613 ][WARNING ][8140] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-22 05:19:34,735 [salt.loaded.ext.module.maas:684 ][INFO    ][8140] deploymachines hwe_kernel=hwe-16.04 system_id=6e7krn distro_series=xenial
2019-03-22 05:19:37,038 [salt.loaded.ext.module.maas:684 ][INFO    ][8140] deploymachines hwe_kernel=hwe-16.04 system_id=gtgdpm distro_series=xenial
2019-03-22 05:19:39,321 [salt.loaded.ext.module.maas:684 ][INFO    ][8140] deploymachines hwe_kernel=hwe-16.04 system_id=qdfgsn distro_series=xenial
2019-03-22 05:19:41,641 [salt.loaded.ext.module.maas:684 ][INFO    ][8140] deploymachines hwe_kernel=hwe-16.04 system_id=adm3qp distro_series=xenial
2019-03-22 05:19:43,987 [salt.loaded.ext.module.maas:684 ][INFO    ][8140] deploymachines hwe_kernel=hwe-16.04 system_id=syymyb distro_series=xenial
2019-03-22 05:19:46,475 [salt.state       :300 ][INFO    ][8140] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-03-22 05:19:46,475 [salt.state       :1951][INFO    ][8140] Completed state [maas.deploy_machines] at time 05:19:46.475760 duration_in_ms=12319.145
2019-03-22 05:19:46,477 [salt.minion      :1711][INFO    ][8140] Returning information for job: 20190322051931577357
2019-03-22 05:19:47,042 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command state.apply with jid 20190322051947029764
2019-03-22 05:19:47,063 [salt.minion      :1432][INFO    ][8489] Starting a new job with PID 8489
2019-03-22 05:19:52,745 [salt.state       :915 ][INFO    ][8489] Loading fresh modules for state activity
2019-03-22 05:19:52,797 [salt.fileclient  :1219][INFO    ][8489] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-03-22 05:19:52,849 [salt.state       :1780][INFO    ][8489] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:19:52.849543
2019-03-22 05:19:52,849 [salt.state       :1813][INFO    ][8489] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-22 05:19:52,852 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8489] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-22 05:19:54,542 [salt.state       :300 ][INFO    ][8489] {'pid': 8523, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-22 05:19:54,545 [salt.state       :1951][INFO    ][8489] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:19:54.544934 duration_in_ms=1695.39
2019-03-22 05:19:54,547 [salt.state       :1780][INFO    ][8489] Running state [maas.wait_for_machine_status] at time 05:19:54.547620
2019-03-22 05:19:54,548 [salt.state       :1813][INFO    ][8489] Executing state module.run for [maas.wait_for_machine_status]
2019-03-22 05:19:54,549 [salt.utils.decorators:613 ][WARNING ][8489] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-22 05:19:57,424 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.13597202s left)
2019-03-22 05:20:02,154 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322052002142014
2019-03-22 05:20:02,173 [salt.minion      :1432][INFO    ][8547] Starting a new job with PID 8547
2019-03-22 05:20:02,193 [salt.minion      :1711][INFO    ][8547] Returning information for job: 20190322052002142014
2019-03-22 05:20:30,311 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.24926209s left)
2019-03-22 05:20:32,212 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322052032200859
2019-03-22 05:20:32,232 [salt.minion      :1432][INFO    ][8580] Starting a new job with PID 8580
2019-03-22 05:20:32,255 [salt.minion      :1711][INFO    ][8580] Returning information for job: 20190322052032200859
2019-03-22 05:21:02,256 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322052102247505
2019-03-22 05:21:02,269 [salt.minion      :1432][INFO    ][8662] Starting a new job with PID 8662
2019-03-22 05:21:02,289 [salt.minion      :1711][INFO    ][8662] Returning information for job: 20190322052102247505
2019-03-22 05:21:03,274 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.28593922s left)
2019-03-22 05:21:32,300 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322052132292281
2019-03-22 05:21:32,312 [salt.minion      :1432][INFO    ][8684] Starting a new job with PID 8684
2019-03-22 05:21:32,332 [salt.minion      :1711][INFO    ][8684] Returning information for job: 20190322052132292281
2019-03-22 05:21:36,145 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2148.41472721s left)
2019-03-22 05:22:02,336 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322052202328830
2019-03-22 05:22:02,353 [salt.minion      :1432][INFO    ][8769] Starting a new job with PID 8769
2019-03-22 05:22:02,373 [salt.minion      :1711][INFO    ][8769] Returning information for job: 20190322052202328830
2019-03-22 05:22:08,910 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2115.64988613s left)
2019-03-22 05:22:32,372 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322052232357456
2019-03-22 05:22:32,389 [salt.minion      :1432][INFO    ][8808] Starting a new job with PID 8808
2019-03-22 05:22:32,413 [salt.minion      :1711][INFO    ][8808] Returning information for job: 20190322052232357456
2019-03-22 05:22:41,688 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2082.87201905s left)
2019-03-22 05:23:02,433 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322052302418615
2019-03-22 05:23:02,455 [salt.minion      :1432][INFO    ][8937] Starting a new job with PID 8937
2019-03-22 05:23:02,477 [salt.minion      :1711][INFO    ][8937] Returning information for job: 20190322052302418615
2019-03-22 05:23:14,674 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2049.88593102s left)
2019-03-22 05:23:32,623 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322052332613472
2019-03-22 05:23:32,645 [salt.minion      :1432][INFO    ][8991] Starting a new job with PID 8991
2019-03-22 05:23:32,666 [salt.minion      :1711][INFO    ][8991] Returning information for job: 20190322052332613472
2019-03-22 05:23:49,044 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2015.51645207s left)
2019-03-22 05:24:02,737 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322052402719248
2019-03-22 05:24:02,757 [salt.minion      :1432][INFO    ][9345] Starting a new job with PID 9345
2019-03-22 05:24:02,781 [salt.minion      :1711][INFO    ][9345] Returning information for job: 20190322052402719248
2019-03-22 05:24:21,668 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1982.892344s left)
2019-03-22 05:24:32,754 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322052432745675
2019-03-22 05:24:32,769 [salt.minion      :1432][INFO    ][9396] Starting a new job with PID 9396
2019-03-22 05:24:32,794 [salt.minion      :1711][INFO    ][9396] Returning information for job: 20190322052432745675
2019-03-22 05:24:54,661 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1949.89884806s left)
2019-03-22 05:25:02,924 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322052502911418
2019-03-22 05:25:02,940 [salt.minion      :1432][INFO    ][9703] Starting a new job with PID 9703
2019-03-22 05:25:02,965 [salt.minion      :1711][INFO    ][9703] Returning information for job: 20190322052502911418
2019-03-22 05:25:27,852 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1916.70817423s left)
2019-03-22 05:25:33,081 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322052533071012
2019-03-22 05:25:33,098 [salt.minion      :1432][INFO    ][9770] Starting a new job with PID 9770
2019-03-22 05:25:33,125 [salt.minion      :1711][INFO    ][9770] Returning information for job: 20190322052533071012
2019-03-22 05:26:01,051 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1883.508775s left)
2019-03-22 05:26:03,286 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322052603273590
2019-03-22 05:26:03,300 [salt.minion      :1432][INFO    ][10153] Starting a new job with PID 10153
2019-03-22 05:26:03,321 [salt.minion      :1711][INFO    ][10153] Returning information for job: 20190322052603273590
2019-03-22 05:26:33,389 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322052633379452
2019-03-22 05:26:33,403 [salt.minion      :1432][INFO    ][10208] Starting a new job with PID 10208
2019-03-22 05:26:33,425 [salt.minion      :1711][INFO    ][10208] Returning information for job: 20190322052633379452
2019-03-22 05:26:33,777 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1850.78309417s left)
2019-03-22 05:27:03,549 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322052703536670
2019-03-22 05:27:03,565 [salt.minion      :1432][INFO    ][10549] Starting a new job with PID 10549
2019-03-22 05:27:03,600 [salt.minion      :1711][INFO    ][10549] Returning information for job: 20190322052703536670
2019-03-22 05:27:06,544 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1818.01629114s left)
2019-03-22 05:27:33,691 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322052733674167
2019-03-22 05:27:33,708 [salt.minion      :1432][INFO    ][10585] Starting a new job with PID 10585
2019-03-22 05:27:33,729 [salt.minion      :1711][INFO    ][10585] Returning information for job: 20190322052733674167
2019-03-22 05:27:39,449 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1785.11101222s left)
2019-03-22 05:28:03,860 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322052803840867
2019-03-22 05:28:03,881 [salt.minion      :1432][INFO    ][10878] Starting a new job with PID 10878
2019-03-22 05:28:03,902 [salt.minion      :1711][INFO    ][10878] Returning information for job: 20190322052803840867
2019-03-22 05:28:12,188 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1752.37200403s left)
2019-03-22 05:28:33,984 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322052833967444
2019-03-22 05:28:34,001 [salt.minion      :1432][INFO    ][10928] Starting a new job with PID 10928
2019-03-22 05:28:34,028 [salt.minion      :1711][INFO    ][10928] Returning information for job: 20190322052833967444
2019-03-22 05:28:45,157 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1719.40303111s left)
2019-03-22 05:29:04,053 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322052904036479
2019-03-22 05:29:04,073 [salt.minion      :1432][INFO    ][11028] Starting a new job with PID 11028
2019-03-22 05:29:04,093 [salt.minion      :1711][INFO    ][11028] Returning information for job: 20190322052904036479
2019-03-22 05:29:17,885 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1686.67491317s left)
2019-03-22 05:29:34,198 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322052934186661
2019-03-22 05:29:34,213 [salt.minion      :1432][INFO    ][11212] Starting a new job with PID 11212
2019-03-22 05:29:34,241 [salt.minion      :1711][INFO    ][11212] Returning information for job: 20190322052934186661
2019-03-22 05:29:50,981 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1653.57926202s left)
2019-03-22 05:30:04,236 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322053004223855
2019-03-22 05:30:04,257 [salt.minion      :1432][INFO    ][11480] Starting a new job with PID 11480
2019-03-22 05:30:04,283 [salt.minion      :1711][INFO    ][11480] Returning information for job: 20190322053004223855
2019-03-22 05:30:23,723 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1620.83706808s left)
2019-03-22 05:30:34,316 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322053034303974
2019-03-22 05:30:34,340 [salt.minion      :1432][INFO    ][11508] Starting a new job with PID 11508
2019-03-22 05:30:34,363 [salt.minion      :1711][INFO    ][11508] Returning information for job: 20190322053034303974
2019-03-22 05:30:56,555 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1588.00537205s left)
2019-03-22 05:31:04,446 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322053104433537
2019-03-22 05:31:04,464 [salt.minion      :1432][INFO    ][11629] Starting a new job with PID 11629
2019-03-22 05:31:04,487 [salt.minion      :1711][INFO    ][11629] Returning information for job: 20190322053104433537
2019-03-22 05:31:29,351 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1555.20902419s left)
2019-03-22 05:31:34,604 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322053134588101
2019-03-22 05:31:34,626 [salt.minion      :1432][INFO    ][11658] Starting a new job with PID 11658
2019-03-22 05:31:34,646 [salt.minion      :1711][INFO    ][11658] Returning information for job: 20190322053134588101
2019-03-22 05:32:02,218 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1522.3422122s left)
2019-03-22 05:32:04,809 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322053204792625
2019-03-22 05:32:04,831 [salt.minion      :1432][INFO    ][11788] Starting a new job with PID 11788
2019-03-22 05:32:04,852 [salt.minion      :1711][INFO    ][11788] Returning information for job: 20190322053204792625
2019-03-22 05:32:34,906 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1489.65401101s left)
2019-03-22 05:32:35,029 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322053235021714
2019-03-22 05:32:35,041 [salt.minion      :1432][INFO    ][11860] Starting a new job with PID 11860
2019-03-22 05:32:35,061 [salt.minion      :1711][INFO    ][11860] Returning information for job: 20190322053235021714
2019-03-22 05:33:05,245 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322053305238070
2019-03-22 05:33:05,263 [salt.minion      :1432][INFO    ][12141] Starting a new job with PID 12141
2019-03-22 05:33:05,288 [salt.minion      :1711][INFO    ][12141] Returning information for job: 20190322053305238070
2019-03-22 05:33:08,046 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1456.51382709s left)
2019-03-22 05:33:35,470 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322053335454786
2019-03-22 05:33:35,492 [salt.minion      :1432][INFO    ][12171] Starting a new job with PID 12171
2019-03-22 05:33:35,514 [salt.minion      :1711][INFO    ][12171] Returning information for job: 20190322053335454786
2019-03-22 05:33:40,614 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1423.94611621s left)
2019-03-22 05:34:05,686 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322053405672146
2019-03-22 05:34:05,708 [salt.minion      :1432][INFO    ][12247] Starting a new job with PID 12247
2019-03-22 05:34:05,730 [salt.minion      :1711][INFO    ][12247] Returning information for job: 20190322053405672146
2019-03-22 05:34:13,433 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1391.12736011s left)
2019-03-22 05:34:35,730 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322053435714380
2019-03-22 05:34:35,755 [salt.minion      :1432][INFO    ][12276] Starting a new job with PID 12276
2019-03-22 05:34:35,778 [salt.minion      :1711][INFO    ][12276] Returning information for job: 20190322053435714380
2019-03-22 05:34:46,267 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1358.29354405s left)
2019-03-22 05:35:05,778 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322053505750777
2019-03-22 05:35:05,796 [salt.minion      :1432][INFO    ][12350] Starting a new job with PID 12350
2019-03-22 05:35:05,825 [salt.minion      :1711][INFO    ][12350] Returning information for job: 20190322053505750777
2019-03-22 05:35:19,012 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1325.54804015s left)
2019-03-22 05:35:35,854 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322053535839314
2019-03-22 05:35:35,874 [salt.minion      :1432][INFO    ][12379] Starting a new job with PID 12379
2019-03-22 05:35:35,895 [salt.minion      :1711][INFO    ][12379] Returning information for job: 20190322053535839314
2019-03-22 05:35:51,809 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1292.75123215s left)
2019-03-22 05:36:05,901 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322053605886602
2019-03-22 05:36:05,921 [salt.minion      :1432][INFO    ][12467] Starting a new job with PID 12467
2019-03-22 05:36:05,943 [salt.minion      :1711][INFO    ][12467] Returning information for job: 20190322053605886602
2019-03-22 05:36:24,545 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1260.0155592s left)
2019-03-22 05:36:36,011 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322053635989585
2019-03-22 05:36:36,032 [salt.minion      :1432][INFO    ][12496] Starting a new job with PID 12496
2019-03-22 05:36:36,053 [salt.minion      :1711][INFO    ][12496] Returning information for job: 20190322053635989585
2019-03-22 05:36:57,084 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1227.47657704s left)
2019-03-22 05:37:06,080 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322053706069119
2019-03-22 05:37:06,101 [salt.minion      :1432][INFO    ][12572] Starting a new job with PID 12572
2019-03-22 05:37:06,122 [salt.minion      :1711][INFO    ][12572] Returning information for job: 20190322053706069119
2019-03-22 05:37:29,729 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1194.83157706s left)
2019-03-22 05:37:36,197 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322053736183666
2019-03-22 05:37:36,216 [salt.minion      :1432][INFO    ][12601] Starting a new job with PID 12601
2019-03-22 05:37:36,239 [salt.minion      :1711][INFO    ][12601] Returning information for job: 20190322053736183666
2019-03-22 05:38:02,533 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1162.02685618s left)
2019-03-22 05:38:06,290 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322053806281813
2019-03-22 05:38:06,305 [salt.minion      :1432][INFO    ][12674] Starting a new job with PID 12674
2019-03-22 05:38:06,324 [salt.minion      :1711][INFO    ][12674] Returning information for job: 20190322053806281813
2019-03-22 05:38:35,234 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1129.326473s left)
2019-03-22 05:38:36,382 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322053836363051
2019-03-22 05:38:36,405 [salt.minion      :1432][INFO    ][12703] Starting a new job with PID 12703
2019-03-22 05:38:36,427 [salt.minion      :1711][INFO    ][12703] Returning information for job: 20190322053836363051
2019-03-22 05:39:06,542 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322053906532288
2019-03-22 05:39:06,561 [salt.minion      :1432][INFO    ][12775] Starting a new job with PID 12775
2019-03-22 05:39:06,583 [salt.minion      :1711][INFO    ][12775] Returning information for job: 20190322053906532288
2019-03-22 05:39:07,810 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1096.74985218s left)
2019-03-22 05:39:36,696 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322053936679984
2019-03-22 05:39:36,716 [salt.minion      :1432][INFO    ][12807] Starting a new job with PID 12807
2019-03-22 05:39:36,739 [salt.minion      :1711][INFO    ][12807] Returning information for job: 20190322053936679984
2019-03-22 05:39:40,602 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1063.95832109s left)
2019-03-22 05:40:06,908 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322054006888219
2019-03-22 05:40:06,930 [salt.minion      :1432][INFO    ][12900] Starting a new job with PID 12900
2019-03-22 05:40:06,950 [salt.minion      :1711][INFO    ][12900] Returning information for job: 20190322054006888219
2019-03-22 05:40:13,249 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1031.31077814s left)
2019-03-22 05:40:37,074 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322054037052381
2019-03-22 05:40:37,097 [salt.minion      :1432][INFO    ][12931] Starting a new job with PID 12931
2019-03-22 05:40:37,121 [salt.minion      :1711][INFO    ][12931] Returning information for job: 20190322054037052381
2019-03-22 05:40:45,956 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (998.60413003s left)
2019-03-22 05:41:07,124 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322054107090066
2019-03-22 05:41:07,146 [salt.minion      :1432][INFO    ][13005] Starting a new job with PID 13005
2019-03-22 05:41:07,167 [salt.minion      :1711][INFO    ][13005] Returning information for job: 20190322054107090066
2019-03-22 05:41:18,660 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (965.900093079s left)
2019-03-22 05:41:37,325 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322054137306468
2019-03-22 05:41:37,343 [salt.minion      :1432][INFO    ][13034] Starting a new job with PID 13034
2019-03-22 05:41:37,366 [salt.minion      :1711][INFO    ][13034] Returning information for job: 20190322054137306468
2019-03-22 05:41:51,294 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (933.266468048s left)
2019-03-22 05:42:07,397 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322054207365486
2019-03-22 05:42:07,417 [salt.minion      :1432][INFO    ][13107] Starting a new job with PID 13107
2019-03-22 05:42:07,443 [salt.minion      :1711][INFO    ][13107] Returning information for job: 20190322054207365486
2019-03-22 05:42:23,881 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (900.679187059s left)
2019-03-22 05:42:37,530 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322054237520092
2019-03-22 05:42:37,545 [salt.minion      :1432][INFO    ][13136] Starting a new job with PID 13136
2019-03-22 05:42:37,566 [salt.minion      :1711][INFO    ][13136] Returning information for job: 20190322054237520092
2019-03-22 05:42:56,281 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (868.279230118s left)
2019-03-22 05:43:07,571 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322054307550268
2019-03-22 05:43:07,585 [salt.minion      :1432][INFO    ][13210] Starting a new job with PID 13210
2019-03-22 05:43:07,616 [salt.minion      :1711][INFO    ][13210] Returning information for job: 20190322054307550268
2019-03-22 05:43:28,665 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (835.895007133s left)
2019-03-22 05:43:37,600 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322054337592031
2019-03-22 05:43:37,615 [salt.minion      :1432][INFO    ][13241] Starting a new job with PID 13241
2019-03-22 05:43:37,633 [salt.minion      :1711][INFO    ][13241] Returning information for job: 20190322054337592031
2019-03-22 05:44:00,929 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (803.630734205s left)
2019-03-22 05:44:07,625 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322054407616936
2019-03-22 05:44:07,640 [salt.minion      :1432][INFO    ][13317] Starting a new job with PID 13317
2019-03-22 05:44:07,658 [salt.minion      :1711][INFO    ][13317] Returning information for job: 20190322054407616936
2019-03-22 05:44:33,345 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (771.215467215s left)
2019-03-22 05:44:37,818 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322054437809326
2019-03-22 05:44:37,833 [salt.minion      :1432][INFO    ][13348] Starting a new job with PID 13348
2019-03-22 05:44:37,854 [salt.minion      :1711][INFO    ][13348] Returning information for job: 20190322054437809326
2019-03-22 05:45:05,764 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (738.795932055s left)
2019-03-22 05:45:07,871 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322054507864262
2019-03-22 05:45:07,889 [salt.minion      :1432][INFO    ][13424] Starting a new job with PID 13424
2019-03-22 05:45:07,907 [salt.minion      :1711][INFO    ][13424] Returning information for job: 20190322054507864262
2019-03-22 05:45:37,917 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322054537909372
2019-03-22 05:45:37,933 [salt.minion      :1432][INFO    ][13453] Starting a new job with PID 13453
2019-03-22 05:45:37,956 [salt.minion      :1711][INFO    ][13453] Returning information for job: 20190322054537909372
2019-03-22 05:45:38,021 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (706.539276123s left)
2019-03-22 05:46:07,990 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322054607982364
2019-03-22 05:46:08,006 [salt.minion      :1432][INFO    ][13522] Starting a new job with PID 13522
2019-03-22 05:46:08,026 [salt.minion      :1711][INFO    ][13522] Returning information for job: 20190322054607982364
2019-03-22 05:46:10,493 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (674.067366123s left)
2019-03-22 05:46:38,061 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322054638053671
2019-03-22 05:46:38,075 [salt.minion      :1432][INFO    ][13551] Starting a new job with PID 13551
2019-03-22 05:46:38,096 [salt.minion      :1711][INFO    ][13551] Returning information for job: 20190322054638053671
2019-03-22 05:46:43,005 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (641.555163145s left)
2019-03-22 05:47:08,152 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322054708143320
2019-03-22 05:47:08,167 [salt.minion      :1432][INFO    ][13625] Starting a new job with PID 13625
2019-03-22 05:47:08,188 [salt.minion      :1711][INFO    ][13625] Returning information for job: 20190322054708143320
2019-03-22 05:47:15,273 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (609.287248135s left)
2019-03-22 05:47:38,243 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322054738234680
2019-03-22 05:47:38,257 [salt.minion      :1432][INFO    ][13654] Starting a new job with PID 13654
2019-03-22 05:47:38,281 [salt.minion      :1711][INFO    ][13654] Returning information for job: 20190322054738234680
2019-03-22 05:47:47,958 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (576.602062225s left)
2019-03-22 05:48:08,357 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322054808343543
2019-03-22 05:48:08,370 [salt.minion      :1432][INFO    ][13726] Starting a new job with PID 13726
2019-03-22 05:48:08,390 [salt.minion      :1711][INFO    ][13726] Returning information for job: 20190322054808343543
2019-03-22 05:48:20,428 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (544.133767128s left)
2019-03-22 05:48:38,472 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322054838465007
2019-03-22 05:48:38,484 [salt.minion      :1432][INFO    ][13761] Starting a new job with PID 13761
2019-03-22 05:48:38,506 [salt.minion      :1711][INFO    ][13761] Returning information for job: 20190322054838465007
2019-03-22 05:48:52,714 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (511.845872164s left)
2019-03-22 05:49:08,636 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322054908626494
2019-03-22 05:49:08,650 [salt.minion      :1432][INFO    ][13837] Starting a new job with PID 13837
2019-03-22 05:49:08,673 [salt.minion      :1711][INFO    ][13837] Returning information for job: 20190322054908626494
2019-03-22 05:49:25,016 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (479.54372716s left)
2019-03-22 05:49:38,780 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322054938772734
2019-03-22 05:49:38,794 [salt.minion      :1432][INFO    ][13999] Starting a new job with PID 13999
2019-03-22 05:49:38,817 [salt.minion      :1711][INFO    ][13999] Returning information for job: 20190322054938772734
2019-03-22 05:49:57,833 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (446.727232218s left)
2019-03-22 05:50:08,828 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322055008817882
2019-03-22 05:50:08,846 [salt.minion      :1432][INFO    ][14086] Starting a new job with PID 14086
2019-03-22 05:50:08,872 [salt.minion      :1711][INFO    ][14086] Returning information for job: 20190322055008817882
2019-03-22 05:50:30,485 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (414.075754166s left)
2019-03-22 05:50:38,997 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322055038985370
2019-03-22 05:50:39,019 [salt.minion      :1432][INFO    ][14115] Starting a new job with PID 14115
2019-03-22 05:50:39,052 [salt.minion      :1711][INFO    ][14115] Returning information for job: 20190322055038985370
2019-03-22 05:51:03,209 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (381.351533175s left)
2019-03-22 05:51:09,160 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322055109152381
2019-03-22 05:51:09,174 [salt.minion      :1432][INFO    ][14187] Starting a new job with PID 14187
2019-03-22 05:51:09,193 [salt.minion      :1711][INFO    ][14187] Returning information for job: 20190322055109152381
2019-03-22 05:51:36,098 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (348.461953163s left)
2019-03-22 05:51:39,329 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322055139312733
2019-03-22 05:51:39,348 [salt.minion      :1432][INFO    ][14216] Starting a new job with PID 14216
2019-03-22 05:51:39,368 [salt.minion      :1711][INFO    ][14216] Returning information for job: 20190322055139312733
2019-03-22 05:52:08,770 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (315.79067421s left)
2019-03-22 05:52:09,349 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322055209336533
2019-03-22 05:52:09,371 [salt.minion      :1432][INFO    ][14291] Starting a new job with PID 14291
2019-03-22 05:52:09,393 [salt.minion      :1711][INFO    ][14291] Returning information for job: 20190322055209336533
2019-03-22 05:52:39,530 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322055239520817
2019-03-22 05:52:39,548 [salt.minion      :1432][INFO    ][14321] Starting a new job with PID 14321
2019-03-22 05:52:39,569 [salt.minion      :1711][INFO    ][14321] Returning information for job: 20190322055239520817
2019-03-22 05:52:41,456 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (283.104626179s left)
2019-03-22 05:53:09,593 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322055309584834
2019-03-22 05:53:09,611 [salt.minion      :1432][INFO    ][14393] Starting a new job with PID 14393
2019-03-22 05:53:09,636 [salt.minion      :1711][INFO    ][14393] Returning information for job: 20190322055309584834
2019-03-22 05:53:14,142 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (250.418612003s left)
2019-03-22 05:53:39,790 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322055339778008
2019-03-22 05:53:39,812 [salt.minion      :1432][INFO    ][14422] Starting a new job with PID 14422
2019-03-22 05:53:39,834 [salt.minion      :1711][INFO    ][14422] Returning information for job: 20190322055339778008
2019-03-22 05:53:46,831 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (217.729776144s left)
2019-03-22 05:54:10,017 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322055410010037
2019-03-22 05:54:10,039 [salt.minion      :1432][INFO    ][14497] Starting a new job with PID 14497
2019-03-22 05:54:10,065 [salt.minion      :1711][INFO    ][14497] Returning information for job: 20190322055410010037
2019-03-22 05:54:19,629 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (184.931193113s left)
2019-03-22 05:54:40,066 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322055440059536
2019-03-22 05:54:40,082 [salt.minion      :1432][INFO    ][14526] Starting a new job with PID 14526
2019-03-22 05:54:40,105 [salt.minion      :1711][INFO    ][14526] Returning information for job: 20190322055440059536
2019-03-22 05:54:52,162 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (152.398494005s left)
2019-03-22 05:55:10,191 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322055510177914
2019-03-22 05:55:10,214 [salt.minion      :1432][INFO    ][14601] Starting a new job with PID 14601
2019-03-22 05:55:10,238 [salt.minion      :1711][INFO    ][14601] Returning information for job: 20190322055510177914
2019-03-22 05:55:24,800 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (119.759817123s left)
2019-03-22 05:55:40,306 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322055540292222
2019-03-22 05:55:40,326 [salt.minion      :1432][INFO    ][14630] Starting a new job with PID 14630
2019-03-22 05:55:40,353 [salt.minion      :1711][INFO    ][14630] Returning information for job: 20190322055540292222
2019-03-22 05:55:57,563 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (86.997202158s left)
2019-03-22 05:56:10,461 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322055610448863
2019-03-22 05:56:10,482 [salt.minion      :1432][INFO    ][14703] Starting a new job with PID 14703
2019-03-22 05:56:10,506 [salt.minion      :1711][INFO    ][14703] Returning information for job: 20190322055610448863
2019-03-22 05:56:30,301 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (54.2594001293s left)
2019-03-22 05:56:40,588 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322055640573292
2019-03-22 05:56:40,605 [salt.minion      :1432][INFO    ][14732] Starting a new job with PID 14732
2019-03-22 05:56:40,628 [salt.minion      :1711][INFO    ][14732] Returning information for job: 20190322055640573292
2019-03-22 05:57:03,044 [salt.loaded.ext.module.maas:1023][INFO    ][8489] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (21.5156731606s left)
2019-03-22 05:57:10,598 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322055710590700
2019-03-22 05:57:10,618 [salt.minion      :1432][INFO    ][14808] Starting a new job with PID 14808
2019-03-22 05:57:10,639 [salt.minion      :1711][INFO    ][14808] Returning information for job: 20190322055710590700
2019-03-22 05:57:35,801 [salt.state       :302 ][ERROR   ][8489] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-03-22 05:57:35,801 [salt.state       :1951][INFO    ][8489] Completed state [maas.wait_for_machine_status] at time 05:57:35.801774 duration_in_ms=2261254.151
2019-03-22 05:57:35,806 [salt.minion      :1711][INFO    ][8489] Returning information for job: 20190322051947029764
2019-03-22 05:57:46,690 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command pillar.get with jid 20190322055746680696
2019-03-22 05:57:46,706 [salt.minion      :1432][INFO    ][14886] Starting a new job with PID 14886
2019-03-22 05:57:46,717 [salt.minion      :1711][INFO    ][14886] Returning information for job: 20190322055746680696
2019-03-22 05:57:47,266 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command service.status with jid 20190322055747252646
2019-03-22 05:57:47,289 [salt.minion      :1432][INFO    ][14891] Starting a new job with PID 14891
2019-03-22 05:57:47,926 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][14891] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-03-22 05:57:47,963 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][14891] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-03-22 05:57:47,980 [salt.minion      :1711][INFO    ][14891] Returning information for job: 20190322055747252646
2019-03-22 05:57:48,534 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command state.apply with jid 20190322055748520906
2019-03-22 05:57:48,550 [salt.minion      :1432][INFO    ][14907] Starting a new job with PID 14907
2019-03-22 05:57:54,827 [salt.state       :915 ][INFO    ][14907] Loading fresh modules for state activity
2019-03-22 05:57:55,435 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14907] Executing command 'salt-minion --version' in directory '/root'
2019-03-22 05:57:55,785 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14907] Executing command 'salt-minion --version' in directory '/root'
2019-03-22 05:57:56,707 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14907] Executing command 'salt-minion --version' in directory '/root'
2019-03-22 05:57:56,986 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14907] Executing command 'salt-minion --version' in directory '/root'
2019-03-22 05:57:58,829 [salt.state       :1780][INFO    ][14907] Running state [salt-minion] at time 05:57:58.829165
2019-03-22 05:57:58,829 [salt.state       :1813][INFO    ][14907] Executing state pkg.installed for [salt-minion]
2019-03-22 05:57:58,830 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14907] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-03-22 05:57:58,937 [salt.state       :300 ][INFO    ][14907] All specified packages are already installed
2019-03-22 05:57:58,937 [salt.state       :1951][INFO    ][14907] Completed state [salt-minion] at time 05:57:58.937499 duration_in_ms=108.334
2019-03-22 05:57:58,937 [salt.state       :1780][INFO    ][14907] Running state [salt_minion_dependency_packages] at time 05:57:58.937820
2019-03-22 05:57:58,938 [salt.state       :1813][INFO    ][14907] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-03-22 05:57:58,946 [salt.state       :300 ][INFO    ][14907] All specified packages are already installed
2019-03-22 05:57:58,947 [salt.state       :1951][INFO    ][14907] Completed state [salt_minion_dependency_packages] at time 05:57:58.947705 duration_in_ms=9.884
2019-03-22 05:57:58,954 [salt.state       :1780][INFO    ][14907] Running state [/etc/salt/minion.d/minion.conf] at time 05:57:58.954005
2019-03-22 05:57:58,954 [salt.state       :1813][INFO    ][14907] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-03-22 05:57:59,233 [salt.state       :300 ][INFO    ][14907] File /etc/salt/minion.d/minion.conf is in the correct state
2019-03-22 05:57:59,234 [salt.state       :1951][INFO    ][14907] Completed state [/etc/salt/minion.d/minion.conf] at time 05:57:59.234317 duration_in_ms=280.312
2019-03-22 05:57:59,235 [salt.state       :1780][INFO    ][14907] Running state [python-netaddr] at time 05:57:59.234828
2019-03-22 05:57:59,235 [salt.state       :1813][INFO    ][14907] Executing state pkg.installed for [python-netaddr]
2019-03-22 05:57:59,250 [salt.state       :300 ][INFO    ][14907] All specified packages are already installed
2019-03-22 05:57:59,251 [salt.state       :1951][INFO    ][14907] Completed state [python-netaddr] at time 05:57:59.251060 duration_in_ms=16.232
2019-03-22 05:57:59,258 [salt.state       :1780][INFO    ][14907] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 05:57:59.258666
2019-03-22 05:57:59,260 [salt.state       :1813][INFO    ][14907] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-03-22 05:57:59,274 [salt.state       :300 ][INFO    ][14907] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-03-22 05:57:59,274 [salt.state       :1951][INFO    ][14907] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 05:57:59.274314 duration_in_ms=15.648
2019-03-22 05:57:59,275 [salt.state       :1780][INFO    ][14907] Running state [salt-minion] at time 05:57:59.275785
2019-03-22 05:57:59,276 [salt.state       :1813][INFO    ][14907] Executing state service.running for [salt-minion]
2019-03-22 05:57:59,276 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14907] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-03-22 05:57:59,318 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14907] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-03-22 05:57:59,337 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14907] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-03-22 05:57:59,357 [salt.state       :300 ][INFO    ][14907] The service salt-minion is already running
2019-03-22 05:57:59,358 [salt.state       :1951][INFO    ][14907] Completed state [salt-minion] at time 05:57:59.358200 duration_in_ms=82.414
2019-03-22 05:57:59,361 [salt.state       :1780][INFO    ][14907] Running state [/etc/salt/grains.d] at time 05:57:59.360965
2019-03-22 05:57:59,361 [salt.state       :1813][INFO    ][14907] Executing state file.directory for [/etc/salt/grains.d]
2019-03-22 05:57:59,363 [salt.state       :300 ][INFO    ][14907] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-03-22 05:57:59,363 [salt.state       :1951][INFO    ][14907] Completed state [/etc/salt/grains.d] at time 05:57:59.363716 duration_in_ms=2.75
2019-03-22 05:57:59,364 [salt.state       :1780][INFO    ][14907] Running state [/etc/salt/grains] at time 05:57:59.364785
2019-03-22 05:57:59,365 [salt.state       :1813][INFO    ][14907] Executing state file.managed for [/etc/salt/grains]
2019-03-22 05:57:59,366 [salt.state       :300 ][INFO    ][14907] File /etc/salt/grains exists with proper permissions. No changes made.
2019-03-22 05:57:59,366 [salt.state       :1951][INFO    ][14907] Completed state [/etc/salt/grains] at time 05:57:59.366398 duration_in_ms=1.613
2019-03-22 05:57:59,368 [salt.state       :1780][INFO    ][14907] Running state [/etc/salt/grains.d/placeholder] at time 05:57:59.368468
2019-03-22 05:57:59,368 [salt.state       :1813][INFO    ][14907] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-03-22 05:57:59,369 [salt.state       :300 ][INFO    ][14907] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-03-22 05:57:59,369 [salt.state       :1951][INFO    ][14907] Completed state [/etc/salt/grains.d/placeholder] at time 05:57:59.369410 duration_in_ms=0.942
2019-03-22 05:57:59,369 [salt.state       :1780][INFO    ][14907] Running state [/etc/salt/grains.d/sphinx] at time 05:57:59.369885
2019-03-22 05:57:59,370 [salt.state       :1813][INFO    ][14907] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-03-22 05:57:59,371 [salt.state       :300 ][INFO    ][14907] File /etc/salt/grains.d/sphinx is in the correct state
2019-03-22 05:57:59,371 [salt.state       :1951][INFO    ][14907] Completed state [/etc/salt/grains.d/sphinx] at time 05:57:59.371575 duration_in_ms=1.69
2019-03-22 05:57:59,374 [salt.state       :1780][INFO    ][14907] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 05:57:59.374009
2019-03-22 05:57:59,374 [salt.state       :1813][INFO    ][14907] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-03-22 05:57:59,374 [salt.state       :300 ][INFO    ][14907] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-03-22 05:57:59,374 [salt.state       :1951][INFO    ][14907] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 05:57:59.374759 duration_in_ms=0.75
2019-03-22 05:57:59,375 [salt.state       :1780][INFO    ][14907] Running state [/etc/salt/grains.d/dns_records] at time 05:57:59.375237
2019-03-22 05:57:59,375 [salt.state       :1813][INFO    ][14907] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-03-22 05:57:59,376 [salt.state       :300 ][INFO    ][14907] File /etc/salt/grains.d/dns_records is in the correct state
2019-03-22 05:57:59,376 [salt.state       :1951][INFO    ][14907] Completed state [/etc/salt/grains.d/dns_records] at time 05:57:59.376749 duration_in_ms=1.512
2019-03-22 05:57:59,377 [salt.state       :1780][INFO    ][14907] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 05:57:59.377656
2019-03-22 05:57:59,377 [salt.state       :1813][INFO    ][14907] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-03-22 05:57:59,378 [salt.state       :300 ][INFO    ][14907] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-03-22 05:57:59,378 [salt.state       :1951][INFO    ][14907] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 05:57:59.378385 duration_in_ms=0.729
2019-03-22 05:57:59,380 [salt.state       :1780][INFO    ][14907] Running state [/etc/salt/grains.d/salt] at time 05:57:59.380223
2019-03-22 05:57:59,380 [salt.state       :1813][INFO    ][14907] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-03-22 05:57:59,381 [salt.state       :300 ][INFO    ][14907] File /etc/salt/grains.d/salt is in the correct state
2019-03-22 05:57:59,381 [salt.state       :1951][INFO    ][14907] Completed state [/etc/salt/grains.d/salt] at time 05:57:59.381752 duration_in_ms=1.529
2019-03-22 05:57:59,382 [salt.state       :1780][INFO    ][14907] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 05:57:59.382617
2019-03-22 05:57:59,383 [salt.state       :1813][INFO    ][14907] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-03-22 05:57:59,383 [salt.state       :300 ][INFO    ][14907] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-03-22 05:57:59,383 [salt.state       :1951][INFO    ][14907] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 05:57:59.383639 duration_in_ms=1.022
2019-03-22 05:57:59,385 [salt.state       :1780][INFO    ][14907] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 05:57:59.385547
2019-03-22 05:57:59,385 [salt.state       :1813][INFO    ][14907] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-03-22 05:57:59,386 [salt.state       :300 ][INFO    ][14907] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-03-22 05:57:59,386 [salt.state       :1951][INFO    ][14907] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 05:57:59.386299 duration_in_ms=0.752
2019-03-22 05:57:59,387 [salt.state       :1780][INFO    ][14907] Running state [mine.update] at time 05:57:59.386968
2019-03-22 05:57:59,387 [salt.state       :1813][INFO    ][14907] Executing state module.wait for [mine.update]
2019-03-22 05:57:59,387 [salt.state       :300 ][INFO    ][14907] No changes made for mine.update
2019-03-22 05:57:59,387 [salt.state       :1951][INFO    ][14907] Completed state [mine.update] at time 05:57:59.387651 duration_in_ms=0.683
2019-03-22 05:57:59,387 [salt.state       :1780][INFO    ][14907] Running state [ca-certificates] at time 05:57:59.387881
2019-03-22 05:57:59,388 [salt.state       :1813][INFO    ][14907] Executing state pkg.installed for [ca-certificates]
2019-03-22 05:57:59,398 [salt.state       :300 ][INFO    ][14907] All specified packages are already installed
2019-03-22 05:57:59,398 [salt.state       :1951][INFO    ][14907] Completed state [ca-certificates] at time 05:57:59.398311 duration_in_ms=10.429
2019-03-22 05:57:59,399 [salt.state       :1780][INFO    ][14907] Running state [update-ca-certificates] at time 05:57:59.398993
2019-03-22 05:57:59,399 [salt.state       :1813][INFO    ][14907] Executing state cmd.wait for [update-ca-certificates]
2019-03-22 05:57:59,399 [salt.state       :300 ][INFO    ][14907] No changes made for update-ca-certificates
2019-03-22 05:57:59,399 [salt.state       :1951][INFO    ][14907] Completed state [update-ca-certificates] at time 05:57:59.399701 duration_in_ms=0.708
2019-03-22 05:57:59,399 [salt.state       :1780][INFO    ][14907] Running state [iptables] at time 05:57:59.399934
2019-03-22 05:57:59,400 [salt.state       :1813][INFO    ][14907] Executing state pkg.installed for [iptables]
2019-03-22 05:57:59,409 [salt.state       :300 ][INFO    ][14907] All specified packages are already installed
2019-03-22 05:57:59,409 [salt.state       :1951][INFO    ][14907] Completed state [iptables] at time 05:57:59.409575 duration_in_ms=9.641
2019-03-22 05:57:59,409 [salt.state       :1780][INFO    ][14907] Running state [iptables-persistent] at time 05:57:59.409808
2019-03-22 05:57:59,410 [salt.state       :1813][INFO    ][14907] Executing state pkg.installed for [iptables-persistent]
2019-03-22 05:57:59,419 [salt.state       :300 ][INFO    ][14907] All specified packages are already installed
2019-03-22 05:57:59,419 [salt.state       :1951][INFO    ][14907] Completed state [iptables-persistent] at time 05:57:59.419267 duration_in_ms=9.458
2019-03-22 05:57:59,420 [salt.state       :1780][INFO    ][14907] Running state [iptables_modules_v4_load] at time 05:57:59.420450
2019-03-22 05:57:59,420 [salt.state       :1813][INFO    ][14907] Executing state kmod.present for [iptables_modules_v4_load]
2019-03-22 05:57:59,421 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14907] Executing command 'lsmod' in directory '/root'
2019-03-22 05:57:59,442 [salt.state       :300 ][INFO    ][14907] Kernel modules iptable_filter, ip_tables are already present
2019-03-22 05:57:59,443 [salt.state       :1951][INFO    ][14907] Completed state [iptables_modules_v4_load] at time 05:57:59.443182 duration_in_ms=22.732
2019-03-22 05:57:59,444 [salt.state       :1780][INFO    ][14907] Running state [/etc/iptables/rules.v4] at time 05:57:59.443949
2019-03-22 05:57:59,444 [salt.state       :1813][INFO    ][14907] Executing state file.managed for [/etc/iptables/rules.v4]
2019-03-22 05:57:59,541 [salt.state       :300 ][INFO    ][14907] File /etc/iptables/rules.v4 is in the correct state
2019-03-22 05:57:59,541 [salt.state       :1951][INFO    ][14907] Completed state [/etc/iptables/rules.v4] at time 05:57:59.541284 duration_in_ms=97.336
2019-03-22 05:57:59,543 [salt.state       :1780][INFO    ][14907] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 05:57:59.543807
2019-03-22 05:57:59,544 [salt.state       :1813][INFO    ][14907] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-03-22 05:57:59,545 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14907] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-03-22 05:57:59,566 [salt.state       :300 ][INFO    ][14907] onlyif execution failed
2019-03-22 05:57:59,567 [salt.state       :1951][INFO    ][14907] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 05:57:59.566965 duration_in_ms=23.159
2019-03-22 05:57:59,568 [salt.state       :1780][INFO    ][14907] Running state [netfilter-persistent] at time 05:57:59.568751
2019-03-22 05:57:59,569 [salt.state       :1813][INFO    ][14907] Executing state service.running for [netfilter-persistent]
2019-03-22 05:57:59,570 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14907] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-03-22 05:57:59,593 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14907] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-03-22 05:57:59,605 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14907] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-03-22 05:57:59,618 [salt.state       :300 ][INFO    ][14907] The service netfilter-persistent is already running
2019-03-22 05:57:59,618 [salt.state       :1951][INFO    ][14907] Completed state [netfilter-persistent] at time 05:57:59.618447 duration_in_ms=49.696
2019-03-22 05:57:59,621 [salt.state       :1780][INFO    ][14907] Running state [iptables_extra.remove_stale_tables] at time 05:57:59.621817
2019-03-22 05:57:59,622 [salt.state       :1813][INFO    ][14907] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-03-22 05:57:59,622 [salt.state       :300 ][INFO    ][14907] No changes made for iptables_extra.remove_stale_tables
2019-03-22 05:57:59,622 [salt.state       :1951][INFO    ][14907] Completed state [iptables_extra.remove_stale_tables] at time 05:57:59.622710 duration_in_ms=0.892
2019-03-22 05:57:59,623 [salt.state       :1780][INFO    ][14907] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 05:57:59.622993
2019-03-22 05:57:59,623 [salt.state       :1813][INFO    ][14907] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-03-22 05:57:59,623 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14907] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-03-22 05:57:59,634 [salt.state       :300 ][INFO    ][14907] onlyif execution failed
2019-03-22 05:57:59,635 [salt.state       :1951][INFO    ][14907] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 05:57:59.634929 duration_in_ms=11.936
2019-03-22 05:57:59,635 [salt.state       :1780][INFO    ][14907] Running state [/etc/iptables/rules.v6] at time 05:57:59.635891
2019-03-22 05:57:59,636 [salt.state       :1813][INFO    ][14907] Executing state file.absent for [/etc/iptables/rules.v6]
2019-03-22 05:57:59,636 [salt.state       :300 ][INFO    ][14907] File /etc/iptables/rules.v6 is not present
2019-03-22 05:57:59,636 [salt.state       :1951][INFO    ][14907] Completed state [/etc/iptables/rules.v6] at time 05:57:59.636848 duration_in_ms=0.956
2019-03-22 05:57:59,637 [salt.state       :1780][INFO    ][14907] Running state [iptables_extra.flush_all] at time 05:57:59.637560
2019-03-22 05:57:59,637 [salt.state       :1813][INFO    ][14907] Executing state module.wait for [iptables_extra.flush_all]
2019-03-22 05:57:59,638 [salt.state       :300 ][INFO    ][14907] No changes made for iptables_extra.flush_all
2019-03-22 05:57:59,638 [salt.state       :1951][INFO    ][14907] Completed state [iptables_extra.flush_all] at time 05:57:59.638332 duration_in_ms=0.771
2019-03-22 05:57:59,641 [salt.minion      :1711][INFO    ][14907] Returning information for job: 20190322055748520906
2019-03-22 05:58:00,201 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command state.apply with jid 20190322055800185510
2019-03-22 05:58:00,221 [salt.minion      :1432][INFO    ][14996] Starting a new job with PID 14996
2019-03-22 05:58:01,081 [salt.state       :915 ][INFO    ][14996] Loading fresh modules for state activity
2019-03-22 05:58:01,955 [salt.state       :1780][INFO    ][14996] Running state [maas-rack-controller] at time 05:58:01.955218
2019-03-22 05:58:01,955 [salt.state       :1813][INFO    ][14996] Executing state pkg.installed for [maas-rack-controller]
2019-03-22 05:58:01,956 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14996] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-03-22 05:58:02,060 [salt.state       :300 ][INFO    ][14996] All specified packages are already installed
2019-03-22 05:58:02,060 [salt.state       :1951][INFO    ][14996] Completed state [maas-rack-controller] at time 05:58:02.060482 duration_in_ms=105.263
2019-03-22 05:58:02,060 [salt.state       :1780][INFO    ][14996] Running state [ipmitool] at time 05:58:02.060936
2019-03-22 05:58:02,061 [salt.state       :1813][INFO    ][14996] Executing state pkg.installed for [ipmitool]
2019-03-22 05:58:02,071 [salt.state       :300 ][INFO    ][14996] All specified packages are already installed
2019-03-22 05:58:02,071 [salt.state       :1951][INFO    ][14996] Completed state [ipmitool] at time 05:58:02.071259 duration_in_ms=10.323
2019-03-22 05:58:02,076 [salt.state       :1780][INFO    ][14996] Running state [/etc/maas/rackd.conf] at time 05:58:02.076245
2019-03-22 05:58:02,076 [salt.state       :1813][INFO    ][14996] Executing state file.line for [/etc/maas/rackd.conf]
2019-03-22 05:58:02,077 [salt.state       :300 ][INFO    ][14996] No changes needed to be made
2019-03-22 05:58:02,077 [salt.state       :1951][INFO    ][14996] Completed state [/etc/maas/rackd.conf] at time 05:58:02.077929 duration_in_ms=1.684
2019-03-22 05:58:02,078 [salt.state       :1780][INFO    ][14996] Running state [/etc/maas/rackd.conf] at time 05:58:02.078170
2019-03-22 05:58:02,078 [salt.state       :1813][INFO    ][14996] Executing state file.managed for [/etc/maas/rackd.conf]
2019-03-22 05:58:02,078 [salt.loaded.int.states.file:2298][WARNING ][14996] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-03-22 05:58:02,079 [salt.state       :300 ][INFO    ][14996] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-03-22 05:58:02,080 [salt.state       :1951][INFO    ][14996] Completed state [/etc/maas/rackd.conf] at time 05:58:02.080070 duration_in_ms=1.9
2019-03-22 05:58:02,081 [salt.state       :1780][INFO    ][14996] Running state [maas-rackd] at time 05:58:02.081148
2019-03-22 05:58:02,081 [salt.state       :1813][INFO    ][14996] Executing state service.running for [maas-rackd]
2019-03-22 05:58:02,082 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14996] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-03-22 05:58:02,119 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14996] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-03-22 05:58:02,138 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14996] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-03-22 05:58:02,156 [salt.state       :300 ][INFO    ][14996] The service maas-rackd is already running
2019-03-22 05:58:02,157 [salt.state       :1951][INFO    ][14996] Completed state [maas-rackd] at time 05:58:02.157480 duration_in_ms=76.331
2019-03-22 05:58:02,160 [salt.minion      :1711][INFO    ][14996] Returning information for job: 20190322055800185510
2019-03-22 05:58:02,700 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command state.apply with jid 20190322055802691945
2019-03-22 05:58:02,722 [salt.minion      :1432][INFO    ][15020] Starting a new job with PID 15020
2019-03-22 05:58:03,745 [salt.state       :915 ][INFO    ][15020] Loading fresh modules for state activity
2019-03-22 05:58:04,744 [salt.state       :1780][INFO    ][15020] Running state [maas-region-controller] at time 05:58:04.744247
2019-03-22 05:58:04,744 [salt.state       :1813][INFO    ][15020] Executing state pkg.installed for [maas-region-controller]
2019-03-22 05:58:04,745 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15020] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-03-22 05:58:04,849 [salt.state       :300 ][INFO    ][15020] All specified packages are already installed
2019-03-22 05:58:04,849 [salt.state       :1951][INFO    ][15020] Completed state [maas-region-controller] at time 05:58:04.849748 duration_in_ms=105.502
2019-03-22 05:58:04,850 [salt.state       :1780][INFO    ][15020] Running state [python-oauth] at time 05:58:04.850090
2019-03-22 05:58:04,850 [salt.state       :1813][INFO    ][15020] Executing state pkg.installed for [python-oauth]
2019-03-22 05:58:04,860 [salt.state       :300 ][INFO    ][15020] All specified packages are already installed
2019-03-22 05:58:04,860 [salt.state       :1951][INFO    ][15020] Completed state [python-oauth] at time 05:58:04.860930 duration_in_ms=10.84
2019-03-22 05:58:04,864 [salt.state       :1780][INFO    ][15020] Running state [/etc/maas/regiond.conf] at time 05:58:04.864369
2019-03-22 05:58:04,864 [salt.state       :1813][INFO    ][15020] Executing state file.replace for [/etc/maas/regiond.conf]
2019-03-22 05:58:04,869 [salt.state       :300 ][INFO    ][15020] No changes needed to be made
2019-03-22 05:58:04,869 [salt.state       :1951][INFO    ][15020] Completed state [/etc/maas/regiond.conf] at time 05:58:04.869462 duration_in_ms=5.093
2019-03-22 05:58:04,870 [salt.state       :1780][INFO    ][15020] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:58:04.869965
2019-03-22 05:58:04,870 [salt.state       :1813][INFO    ][15020] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-03-22 05:58:04,929 [salt.state       :300 ][INFO    ][15020] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-03-22 05:58:04,930 [salt.state       :1951][INFO    ][15020] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:58:04.930010 duration_in_ms=60.045
2019-03-22 05:58:04,930 [salt.state       :1780][INFO    ][15020] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:58:04.930570
2019-03-22 05:58:04,930 [salt.state       :1813][INFO    ][15020] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-03-22 05:58:04,934 [salt.state       :300 ][INFO    ][15020] No changes needed to be made
2019-03-22 05:58:04,934 [salt.state       :1951][INFO    ][15020] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:58:04.934757 duration_in_ms=4.187
2019-03-22 05:58:04,936 [salt.state       :1780][INFO    ][15020] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:58:04.936125
2019-03-22 05:58:04,936 [salt.state       :1813][INFO    ][15020] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-03-22 05:58:04,939 [salt.state       :300 ][INFO    ][15020] No changes needed to be made
2019-03-22 05:58:04,939 [salt.state       :1951][INFO    ][15020] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:58:04.939933 duration_in_ms=3.807
2019-03-22 05:58:04,940 [salt.state       :1780][INFO    ][15020] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:58:04.940444
2019-03-22 05:58:04,940 [salt.state       :1813][INFO    ][15020] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-03-22 05:58:04,972 [salt.state       :300 ][INFO    ][15020] No changes needed to be made
2019-03-22 05:58:04,972 [salt.state       :1951][INFO    ][15020] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:58:04.972708 duration_in_ms=32.265
2019-03-22 05:58:04,973 [salt.state       :1780][INFO    ][15020] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:58:04.973184
2019-03-22 05:58:04,973 [salt.state       :1813][INFO    ][15020] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-03-22 05:58:04,990 [salt.state       :300 ][INFO    ][15020] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-03-22 05:58:04,990 [salt.state       :1951][INFO    ][15020] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:58:04.990425 duration_in_ms=17.241
2019-03-22 05:58:04,992 [salt.state       :1780][INFO    ][15020] Running state [a2enmod headers] at time 05:58:04.992573
2019-03-22 05:58:04,992 [salt.state       :1813][INFO    ][15020] Executing state cmd.run for [a2enmod headers]
2019-03-22 05:58:04,993 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15020] Executing command 'a2enmod headers' in directory '/root'
2019-03-22 05:58:05,064 [salt.state       :300 ][INFO    ][15020] {'pid': 15043, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-03-22 05:58:05,065 [salt.state       :1951][INFO    ][15020] Completed state [a2enmod headers] at time 05:58:05.065036 duration_in_ms=72.462
2019-03-22 05:58:05,065 [salt.state       :1780][INFO    ][15020] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:58:05.065689
2019-03-22 05:58:05,066 [salt.state       :1813][INFO    ][15020] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-03-22 05:58:05,084 [salt.state       :300 ][INFO    ][15020] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-03-22 05:58:05,085 [salt.state       :1951][INFO    ][15020] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:58:05.085151 duration_in_ms=19.462
2019-03-22 05:58:05,085 [salt.state       :1780][INFO    ][15020] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:58:05.085719
2019-03-22 05:58:05,086 [salt.state       :1813][INFO    ][15020] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-03-22 05:58:05,142 [salt.state       :300 ][INFO    ][15020] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-03-22 05:58:05,143 [salt.state       :1951][INFO    ][15020] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:58:05.143074 duration_in_ms=57.354
2019-03-22 05:58:05,143 [salt.state       :1780][INFO    ][15020] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:58:05.143700
2019-03-22 05:58:05,144 [salt.state       :1813][INFO    ][15020] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-03-22 05:58:05,197 [salt.state       :300 ][INFO    ][15020] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-03-22 05:58:05,197 [salt.state       :1951][INFO    ][15020] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:58:05.197572 duration_in_ms=53.873
2019-03-22 05:58:05,198 [salt.state       :1780][INFO    ][15020] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:58:05.198084
2019-03-22 05:58:05,198 [salt.state       :1813][INFO    ][15020] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-03-22 05:58:05,266 [salt.state       :300 ][INFO    ][15020] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-03-22 05:58:05,266 [salt.state       :1951][INFO    ][15020] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:58:05.266839 duration_in_ms=68.755
2019-03-22 05:58:05,267 [salt.state       :1780][INFO    ][15020] Running state [/root/.pgpass] at time 05:58:05.267106
2019-03-22 05:58:05,267 [salt.state       :1813][INFO    ][15020] Executing state file.managed for [/root/.pgpass]
2019-03-22 05:58:05,316 [salt.state       :300 ][INFO    ][15020] File /root/.pgpass is in the correct state
2019-03-22 05:58:05,317 [salt.state       :1951][INFO    ][15020] Completed state [/root/.pgpass] at time 05:58:05.317124 duration_in_ms=50.017
2019-03-22 05:58:05,323 [salt.state       :1780][INFO    ][15020] Running state [maas-region syncdb --noinput] at time 05:58:05.323206
2019-03-22 05:58:05,323 [salt.state       :1813][INFO    ][15020] Executing state cmd.run for [maas-region syncdb --noinput]
2019-03-22 05:58:05,324 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15020] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-03-22 05:58:08,041 [salt.state       :300 ][INFO    ][15020] {'pid': 15056, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: piston3, sessions, sites, contenttypes, metadataserver, auth, maasserver\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-03-22 05:58:08,042 [salt.state       :1951][INFO    ][15020] Completed state [maas-region syncdb --noinput] at time 05:58:08.042054 duration_in_ms=2718.848
2019-03-22 05:58:08,042 [salt.state       :2022][WARNING ][15020] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-03-22 05:58:08,046 [salt.state       :1780][INFO    ][15020] Running state [maas-regiond] at time 05:58:08.046688
2019-03-22 05:58:08,047 [salt.state       :1813][INFO    ][15020] Executing state service.running for [maas-regiond]
2019-03-22 05:58:08,048 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15020] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-03-22 05:58:08,088 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15020] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-03-22 05:58:08,107 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15020] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-03-22 05:58:08,123 [salt.state       :300 ][INFO    ][15020] The service maas-regiond is already running
2019-03-22 05:58:08,123 [salt.state       :1951][INFO    ][15020] Completed state [maas-regiond] at time 05:58:08.123548 duration_in_ms=76.861
2019-03-22 05:58:08,125 [salt.state       :1780][INFO    ][15020] Running state [bind9] at time 05:58:08.125902
2019-03-22 05:58:08,126 [salt.state       :1813][INFO    ][15020] Executing state service.running for [bind9]
2019-03-22 05:58:08,129 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15020] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-03-22 05:58:08,149 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15020] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-03-22 05:58:08,168 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15020] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-03-22 05:58:08,185 [salt.state       :300 ][INFO    ][15020] The service bind9 is already running
2019-03-22 05:58:08,185 [salt.state       :1951][INFO    ][15020] Completed state [bind9] at time 05:58:08.185503 duration_in_ms=59.599
2019-03-22 05:58:08,190 [salt.state       :1780][INFO    ][15020] Running state [apache2] at time 05:58:08.190139
2019-03-22 05:58:08,190 [salt.state       :1813][INFO    ][15020] Executing state service.running for [apache2]
2019-03-22 05:58:08,192 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15020] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-03-22 05:58:08,209 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15020] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-03-22 05:58:08,227 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15020] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-03-22 05:58:08,244 [salt.state       :300 ][INFO    ][15020] The service apache2 is already running
2019-03-22 05:58:08,244 [salt.state       :1951][INFO    ][15020] Completed state [apache2] at time 05:58:08.244407 duration_in_ms=54.269
2019-03-22 05:58:08,245 [salt.state       :1780][INFO    ][15020] Running state [maasng.wait_for_http_code] at time 05:58:08.245425
2019-03-22 05:58:08,245 [salt.state       :1813][INFO    ][15020] Executing state module.run for [maasng.wait_for_http_code]
2019-03-22 05:58:08,246 [salt.utils.decorators:613 ][WARNING ][15020] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-22 05:58:08,406 [salt.state       :300 ][INFO    ][15020] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-03-22 05:58:08,406 [salt.state       :1951][INFO    ][15020] Completed state [maasng.wait_for_http_code] at time 05:58:08.406510 duration_in_ms=161.084
2019-03-22 05:58:08,408 [salt.state       :1780][INFO    ][15020] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:58:08.407998
2019-03-22 05:58:08,408 [salt.state       :1813][INFO    ][15020] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-03-22 05:58:08,409 [salt.state       :300 ][INFO    ][15020] /var/lib/maas/.setup_admin exists
2019-03-22 05:58:08,409 [salt.state       :1951][INFO    ][15020] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:58:08.409423 duration_in_ms=1.426
2019-03-22 05:58:08,410 [salt.state       :1780][INFO    ][15020] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:58:08.410435
2019-03-22 05:58:08,411 [salt.state       :1813][INFO    ][15020] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-22 05:58:08,412 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15020] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-22 05:58:10,189 [salt.state       :300 ][INFO    ][15020] {'pid': 15077, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-22 05:58:10,190 [salt.state       :1951][INFO    ][15020] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:58:10.189972 duration_in_ms=1779.536
2019-03-22 05:58:10,198 [salt.state       :1780][INFO    ][15020] Running state [maas_region_boot_source_resources_mirror] at time 05:58:10.198390
2019-03-22 05:58:10,199 [salt.state       :1813][INFO    ][15020] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-03-22 05:58:10,283 [salt.state       :300 ][INFO    ][15020] {'changes': {}}
2019-03-22 05:58:10,283 [salt.state       :1951][INFO    ][15020] Completed state [maas_region_boot_source_resources_mirror] at time 05:58:10.283486 duration_in_ms=85.097
2019-03-22 05:58:10,284 [salt.state       :1780][INFO    ][15020] Running state [maasng.boot_resources_import] at time 05:58:10.284202
2019-03-22 05:58:10,284 [salt.state       :1813][INFO    ][15020] Executing state module.run for [maasng.boot_resources_import]
2019-03-22 05:58:10,284 [salt.utils.decorators:613 ][WARNING ][15020] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-22 05:58:10,365 [salt.loaded.ext.module.maasng:1600][INFO    ][15020] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-03-22 05:58:15,412 [salt.loaded.ext.module.maasng:1600][INFO    ][15020] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-03-22 05:58:17,770 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322055817761489
2019-03-22 05:58:17,790 [salt.minion      :1432][INFO    ][15233] Starting a new job with PID 15233
2019-03-22 05:58:17,812 [salt.minion      :1711][INFO    ][15233] Returning information for job: 20190322055817761489
2019-03-22 05:58:20,509 [salt.state       :300 ][INFO    ][15020] {'ret': True}
2019-03-22 05:58:20,510 [salt.state       :1951][INFO    ][15020] Completed state [maasng.boot_resources_import] at time 05:58:20.509970 duration_in_ms=10225.766
2019-03-22 05:58:20,512 [salt.state       :1780][INFO    ][15020] Running state [maas_region_boot_sources_selection_xenial] at time 05:58:20.512395
2019-03-22 05:58:20,512 [salt.state       :1813][INFO    ][15020] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-03-22 05:58:20,670 [salt.state       :300 ][INFO    ][15020] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-03-22 05:58:20,670 [salt.state       :1951][INFO    ][15020] Completed state [maas_region_boot_sources_selection_xenial] at time 05:58:20.670513 duration_in_ms=158.117
2019-03-22 05:58:20,671 [salt.state       :1780][INFO    ][15020] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 05:58:20.671672
2019-03-22 05:58:20,671 [salt.state       :1813][INFO    ][15020] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-03-22 05:58:20,672 [salt.utils.decorators:613 ][WARNING ][15020] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-22 05:58:20,672 [salt.loaded.ext.module.maasng:1771][INFO    ][15020] boot-sources sync initiated for ALL Rack's
2019-03-22 05:58:21,579 [salt.state       :300 ][INFO    ][15020] {'ret': True}
2019-03-22 05:58:21,579 [salt.state       :1951][INFO    ][15020] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 05:58:21.579588 duration_in_ms=907.913
2019-03-22 05:58:21,581 [salt.state       :1780][INFO    ][15020] Running state [maas.process_maas_config] at time 05:58:21.581893
2019-03-22 05:58:21,582 [salt.state       :1813][INFO    ][15020] Executing state module.run for [maas.process_maas_config]
2019-03-22 05:58:21,582 [salt.utils.decorators:613 ][WARNING ][15020] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-22 05:58:21,583 [salt.loaded.ext.module.maas:92  ][INFO    ][15020] maasconfig name=enable_http_proxy value=True
2019-03-22 05:58:21,630 [salt.loaded.ext.module.maas:92  ][INFO    ][15020] maasconfig name=upstream_dns value=8.8.8.8
2019-03-22 05:58:21,677 [salt.loaded.ext.module.maas:92  ][INFO    ][15020] maasconfig name=commissioning_distro_series value=xenial
2019-03-22 05:58:22,888 [salt.loaded.ext.module.maas:92  ][INFO    ][15020] maasconfig name=default_osystem value=ubuntu
2019-03-22 05:58:22,938 [salt.loaded.ext.module.maas:92  ][INFO    ][15020] maasconfig name=active_discovery_interval value=600
2019-03-22 05:58:22,981 [salt.loaded.ext.module.maas:92  ][INFO    ][15020] maasconfig name=dnssec_validation value=no
2019-03-22 05:58:23,022 [salt.loaded.ext.module.maas:92  ][INFO    ][15020] maasconfig name=maas_name value=mas01
2019-03-22 05:58:23,078 [salt.loaded.ext.module.maas:92  ][INFO    ][15020] maasconfig name=network_discovery value=enabled
2019-03-22 05:58:23,192 [salt.loaded.ext.module.maas:92  ][INFO    ][15020] maasconfig name=enable_third_party_drivers value=True
2019-03-22 05:58:23,232 [salt.loaded.ext.module.maas:92  ][INFO    ][15020] maasconfig name=default_storage_layout value=lvm
2019-03-22 05:58:23,276 [salt.loaded.ext.module.maas:92  ][INFO    ][15020] maasconfig name=ntp_external_only value=True
2019-03-22 05:58:23,331 [salt.loaded.ext.module.maas:92  ][INFO    ][15020] maasconfig name=disk_erase_with_secure_erase value=False
2019-03-22 05:58:23,368 [salt.loaded.ext.module.maas:92  ][INFO    ][15020] maasconfig name=default_distro_series value=xenial
2019-03-22 05:58:23,409 [salt.loaded.ext.module.maas:92  ][INFO    ][15020] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-03-22 05:58:23,502 [salt.state       :300 ][INFO    ][15020] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-03-22 05:58:23,502 [salt.state       :1951][INFO    ][15020] Completed state [maas.process_maas_config] at time 05:58:23.502662 duration_in_ms=1920.768
2019-03-22 05:58:23,503 [salt.state       :1780][INFO    ][15020] Running state [pxe_admin] at time 05:58:23.503821
2019-03-22 05:58:23,504 [salt.state       :1813][INFO    ][15020] Executing state maasng.fabric_present for [pxe_admin]
2019-03-22 05:58:23,548 [salt.loaded.ext.module.maasng:945 ][INFO    ][15020] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'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'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'68nbf4', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}]
2019-03-22 05:58:23,628 [salt.loaded.ext.module.maasng:1008][WARNING ][15020] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-03-22 05:58:23,629 [salt.loaded.ext.module.maasng:1011][WARNING ][15020] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-03-22 05:58:23,683 [salt.state       :300 ][INFO    ][15020] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-03-22 05:58:23,684 [salt.state       :1951][INFO    ][15020] Completed state [pxe_admin] at time 05:58:23.683996 duration_in_ms=180.174
2019-03-22 05:58:23,684 [salt.state       :1780][INFO    ][15020] Running state [vlan 0] at time 05:58:23.684311
2019-03-22 05:58:23,684 [salt.state       :1813][INFO    ][15020] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-03-22 05:58:23,733 [salt.loaded.ext.module.maasng:945 ][INFO    ][15020] [{u'id': 0, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'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'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'68nbf4', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-03-22 05:58:23,822 [salt.loaded.ext.module.maasng:945 ][INFO    ][15020] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'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'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'68nbf4', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}]
2019-03-22 05:58:24,036 [salt.loaded.ext.module.maasng:945 ][INFO    ][15020] [{u'class_type': None, 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'68nbf4', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-03-22 05:58:24,112 [salt.state       :300 ][INFO    ][15020] {'new': 'Vlan untagged was updated'}
2019-03-22 05:58:24,113 [salt.state       :1951][INFO    ][15020] Completed state [vlan 0] at time 05:58:24.113142 duration_in_ms=428.83
2019-03-22 05:58:24,114 [salt.state       :1780][INFO    ][15020] Running state [192.168.11.0/24] at time 05:58:24.114153
2019-03-22 05:58:24,114 [salt.state       :1813][INFO    ][15020] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-03-22 05:58:24,314 [salt.loaded.ext.module.maasng:945 ][INFO    ][15020] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'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'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'68nbf4', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}]
2019-03-22 05:58:24,316 [salt.loaded.ext.module.maasng:1235][WARNING ][15020] Ignoring parameter vlan:0
2019-03-22 05:58:24,379 [salt.state       :300 ][INFO    ][15020] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-03-22 05:58:24,380 [salt.state       :1951][INFO    ][15020] Completed state [192.168.11.0/24] at time 05:58:24.380009 duration_in_ms=265.855
2019-03-22 05:58:24,380 [salt.state       :1780][INFO    ][15020] Running state [maas_create_iprange_1] at time 05:58:24.380802
2019-03-22 05:58:24,381 [salt.state       :1813][INFO    ][15020] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-03-22 05:58:24,422 [salt.state       :300 ][INFO    ][15020] Iprange maas_create_iprange_1 already exist.
2019-03-22 05:58:24,422 [salt.state       :1951][INFO    ][15020] Completed state [maas_create_iprange_1] at time 05:58:24.422765 duration_in_ms=41.963
2019-03-22 05:58:24,424 [salt.state       :1780][INFO    ][15020] Running state [vlan 0] at time 05:58:24.424427
2019-03-22 05:58:24,424 [salt.state       :1813][INFO    ][15020] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-03-22 05:58:24,465 [salt.loaded.ext.module.maasng:945 ][INFO    ][15020] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'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'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'68nbf4', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}]
2019-03-22 05:58:24,559 [salt.loaded.ext.module.maasng:945 ][INFO    ][15020] [{u'id': 0, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'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'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'68nbf4', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-03-22 05:58:24,746 [salt.loaded.ext.module.maasng:945 ][INFO    ][15020] [{u'id': 0, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'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'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'68nbf4', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-03-22 05:58:24,849 [salt.state       :300 ][INFO    ][15020] {'new': 'Vlan untagged was updated'}
2019-03-22 05:58:24,849 [salt.state       :1951][INFO    ][15020] Completed state [vlan 0] at time 05:58:24.849563 duration_in_ms=425.135
2019-03-22 05:58:24,850 [salt.state       :1780][INFO    ][15020] Running state [opnfv] at time 05:58:24.850270
2019-03-22 05:58:24,850 [salt.state       :1813][INFO    ][15020] Executing state maasng.sshkey_present for [opnfv]
2019-03-22 05:58:24,901 [salt.loaded.ext.module.maasng:1903][INFO    ][15020] [{u'keysource': u'', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCwbsLV1UY13y2+2b7H9h6zOFMrcnumZvKOz6oqAXgttZjoKci01cqGRU8OMjob/9xeAOP2Cmu7HOBpRxda00X57yYx4UidSgEtlbOi7LLj2DuvJScix+rgPAd19aukdCiTuMmyLZQPMzVrdBxqzupJ5zqS3djSCllnQ2myEwswcSBn0Dpusgt3bnwdsCgI6B6ZgL9aR9DLnyMPMVroI6ZZH7aP7syIy1sPEsiJd3smWEULgeRElsuK+4I3YKG1Z1J2n80lmtR2gVD5tuKLFRRxnbjAqzyCEXQBTUVRc13SAFrH0b5bzFaqfxY1wdQPzP+dBSA+t25KcjnpTAAtL86H', u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/'}]
2019-03-22 05:58:24,902 [salt.state       :300 ][INFO    ][15020] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCwbsLV1UY13y2+2b7H9h6zOFMrcnumZvKOz6oqAXgttZjoKci01cqGRU8OMjob/9xeAOP2Cmu7HOBpRxda00X57yYx4UidSgEtlbOi7LLj2DuvJScix+rgPAd19aukdCiTuMmyLZQPMzVrdBxqzupJ5zqS3djSCllnQ2myEwswcSBn0Dpusgt3bnwdsCgI6B6ZgL9aR9DLnyMPMVroI6ZZH7aP7syIy1sPEsiJd3smWEULgeRElsuK+4I3YKG1Z1J2n80lmtR2gVD5tuKLFRRxnbjAqzyCEXQBTUVRc13SAFrH0b5bzFaqfxY1wdQPzP+dBSA+t25KcjnpTAAtL86H already exist for user opnfv.
2019-03-22 05:58:24,902 [salt.state       :1951][INFO    ][15020] Completed state [opnfv] at time 05:58:24.902326 duration_in_ms=52.054
2019-03-22 05:58:24,905 [salt.state       :1780][INFO    ][15020] Running state [maas.process_tags] at time 05:58:24.905560
2019-03-22 05:58:24,905 [salt.state       :1813][INFO    ][15020] Executing state module.run for [maas.process_tags]
2019-03-22 05:58:24,906 [salt.utils.decorators:613 ][WARNING ][15020] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-22 05:58:24,944 [salt.loaded.ext.module.maas:92  ][INFO    ][15020] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-03-22 05:58:24,993 [salt.state       :300 ][INFO    ][15020] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-03-22 05:58:24,993 [salt.state       :1951][INFO    ][15020] Completed state [maas.process_tags] at time 05:58:24.993631 duration_in_ms=88.07
2019-03-22 05:58:24,995 [salt.minion      :1711][INFO    ][15020] Returning information for job: 20190322055802691945
2019-03-22 05:58:25,609 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command state.apply with jid 20190322055825601383
2019-03-22 05:58:25,622 [salt.minion      :1432][INFO    ][15463] Starting a new job with PID 15463
2019-03-22 05:58:32,140 [salt.state       :915 ][INFO    ][15463] Loading fresh modules for state activity
2019-03-22 05:58:32,234 [salt.state       :1780][INFO    ][15463] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:58:32.234766
2019-03-22 05:58:32,235 [salt.state       :1813][INFO    ][15463] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-22 05:58:32,237 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15463] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-22 05:58:33,996 [salt.state       :300 ][INFO    ][15463] {'pid': 15486, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-22 05:58:33,997 [salt.state       :1951][INFO    ][15463] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:58:33.997550 duration_in_ms=1762.782
2019-03-22 05:58:34,001 [salt.state       :1780][INFO    ][15463] Running state [maas.process_machines] at time 05:58:34.001207
2019-03-22 05:58:34,001 [salt.state       :1813][INFO    ][15463] Executing state module.run for [maas.process_machines]
2019-03-22 05:58:34,003 [salt.utils.decorators:613 ][WARNING ][15463] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-22 05:58:34,565 [salt.loaded.ext.module.maas:412 ][WARNING ][15463] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-22 05:58:34,566 [salt.loaded.ext.module.maas:92  ][INFO    ][15463] 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=6e7krn architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-22 05:58:35,688 [salt.loaded.ext.module.maas:412 ][WARNING ][15463] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-22 05:58:35,689 [salt.loaded.ext.module.maas:92  ][INFO    ][15463] 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=gtgdpm architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-22 05:58:36,793 [salt.loaded.ext.module.maas:412 ][WARNING ][15463] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-22 05:58:36,794 [salt.loaded.ext.module.maas:92  ][INFO    ][15463] 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=qdfgsn architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-22 05:58:37,888 [salt.loaded.ext.module.maas:412 ][WARNING ][15463] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-22 05:58:37,889 [salt.loaded.ext.module.maas:92  ][INFO    ][15463] 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=adm3qp architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-22 05:58:38,988 [salt.loaded.ext.module.maas:412 ][WARNING ][15463] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-22 05:58:38,989 [salt.loaded.ext.module.maas:92  ][INFO    ][15463] 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=syymyb architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-22 05:58:40,036 [salt.state       :300 ][INFO    ][15463] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-03-22 05:58:40,037 [salt.state       :1951][INFO    ][15463] Completed state [maas.process_machines] at time 05:58:40.037193 duration_in_ms=6035.986
2019-03-22 05:58:40,040 [salt.minion      :1711][INFO    ][15463] Returning information for job: 20190322055825601383
2019-03-22 05:59:13,017 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command state.apply with jid 20190322055913006751
2019-03-22 05:59:13,037 [salt.minion      :1432][INFO    ][15775] Starting a new job with PID 15775
2019-03-22 05:59:18,923 [salt.state       :915 ][INFO    ][15775] Loading fresh modules for state activity
2019-03-22 05:59:19,013 [salt.state       :1780][INFO    ][15775] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:59:19.012935
2019-03-22 05:59:19,013 [salt.state       :1813][INFO    ][15775] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-22 05:59:19,015 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15775] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-22 05:59:20,751 [salt.state       :300 ][INFO    ][15775] {'pid': 15787, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-22 05:59:20,752 [salt.state       :1951][INFO    ][15775] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:59:20.751991 duration_in_ms=1739.058
2019-03-22 05:59:20,753 [salt.state       :1780][INFO    ][15775] Running state [maas.wait_for_machine_status] at time 05:59:20.753715
2019-03-22 05:59:20,754 [salt.state       :1813][INFO    ][15775] Executing state module.run for [maas.wait_for_machine_status]
2019-03-22 05:59:20,754 [salt.utils.decorators:613 ][WARNING ][15775] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-22 05:59:23,402 [salt.state       :300 ][INFO    ][15775] {'ret': True}
2019-03-22 05:59:23,404 [salt.state       :1951][INFO    ][15775] Completed state [maas.wait_for_machine_status] at time 05:59:23.404294 duration_in_ms=2650.577
2019-03-22 05:59:23,407 [salt.minion      :1711][INFO    ][15775] Returning information for job: 20190322055913006751
2019-03-22 05:59:23,938 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command state.apply with jid 20190322055923928184
2019-03-22 05:59:23,959 [salt.minion      :1432][INFO    ][15801] Starting a new job with PID 15801
2019-03-22 05:59:24,888 [salt.state       :915 ][INFO    ][15801] Loading fresh modules for state activity
2019-03-22 05:59:25,033 [salt.state       :1780][INFO    ][15801] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:59:25.033282
2019-03-22 05:59:25,033 [salt.state       :1813][INFO    ][15801] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-22 05:59:25,035 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15801] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-22 05:59:26,820 [salt.state       :300 ][INFO    ][15801] {'pid': 15808, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-22 05:59:26,821 [salt.state       :1951][INFO    ][15801] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:59:26.821715 duration_in_ms=1788.431
2019-03-22 05:59:26,826 [salt.state       :1780][INFO    ][15801] Running state [maas_machines_storage_cmp002_lvm] at time 05:59:26.826033
2019-03-22 05:59:26,826 [salt.state       :1813][INFO    ][15801] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-03-22 05:59:27,485 [salt.state       :300 ][INFO    ][15801] Machine cmp002 is not in Ready state.
2019-03-22 05:59:27,485 [salt.state       :1951][INFO    ][15801] Completed state [maas_machines_storage_cmp002_lvm] at time 05:59:27.485643 duration_in_ms=659.611
2019-03-22 05:59:27,486 [salt.state       :1780][INFO    ][15801] Running state [maas_machines_storage_cmp001_lvm] at time 05:59:27.485998
2019-03-22 05:59:27,486 [salt.state       :1813][INFO    ][15801] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-03-22 05:59:28,132 [salt.state       :300 ][INFO    ][15801] Machine cmp001 is not in Ready state.
2019-03-22 05:59:28,133 [salt.state       :1951][INFO    ][15801] Completed state [maas_machines_storage_cmp001_lvm] at time 05:59:28.133032 duration_in_ms=647.033
2019-03-22 05:59:28,135 [salt.minion      :1711][INFO    ][15801] Returning information for job: 20190322055923928184
2019-03-22 05:59:28,658 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command state.apply with jid 20190322055928649730
2019-03-22 05:59:28,677 [salt.minion      :1432][INFO    ][15818] Starting a new job with PID 15818
2019-03-22 05:59:29,672 [salt.state       :915 ][INFO    ][15818] Loading fresh modules for state activity
2019-03-22 05:59:29,769 [salt.state       :1780][INFO    ][15818] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:59:29.769841
2019-03-22 05:59:29,770 [salt.state       :1813][INFO    ][15818] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-22 05:59:29,771 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15818] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-22 05:59:31,630 [salt.state       :300 ][INFO    ][15818] {'pid': 15825, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-22 05:59:31,632 [salt.state       :1951][INFO    ][15818] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:59:31.632478 duration_in_ms=1862.637
2019-03-22 05:59:31,636 [salt.state       :1780][INFO    ][15818] Running state [maas.deploy_machines] at time 05:59:31.636035
2019-03-22 05:59:31,636 [salt.state       :1813][INFO    ][15818] Executing state module.run for [maas.deploy_machines]
2019-03-22 05:59:31,637 [salt.utils.decorators:613 ][WARNING ][15818] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-22 05:59:32,177 [salt.loaded.ext.module.maas:684 ][INFO    ][15818] deploymachines hwe_kernel=hwe-16.04 system_id=syymyb distro_series=xenial
2019-03-22 05:59:34,519 [salt.state       :300 ][INFO    ][15818] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-03-22 05:59:34,519 [salt.state       :1951][INFO    ][15818] Completed state [maas.deploy_machines] at time 05:59:34.519694 duration_in_ms=2883.657
2019-03-22 05:59:34,522 [salt.minion      :1711][INFO    ][15818] Returning information for job: 20190322055928649730
2019-03-22 05:59:35,075 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command state.apply with jid 20190322055935064038
2019-03-22 05:59:35,096 [salt.minion      :1432][INFO    ][15904] Starting a new job with PID 15904
2019-03-22 05:59:41,255 [salt.state       :915 ][INFO    ][15904] Loading fresh modules for state activity
2019-03-22 05:59:41,345 [salt.state       :1780][INFO    ][15904] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:59:41.345396
2019-03-22 05:59:41,345 [salt.state       :1813][INFO    ][15904] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-22 05:59:41,347 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15904] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-22 05:59:43,154 [salt.state       :300 ][INFO    ][15904] {'pid': 15918, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-22 05:59:43,156 [salt.state       :1951][INFO    ][15904] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:59:43.156023 duration_in_ms=1810.626
2019-03-22 05:59:43,160 [salt.state       :1780][INFO    ][15904] Running state [maas.wait_for_machine_status] at time 05:59:43.159973
2019-03-22 05:59:43,160 [salt.state       :1813][INFO    ][15904] Executing state module.run for [maas.wait_for_machine_status]
2019-03-22 05:59:43,161 [salt.utils.decorators:613 ][WARNING ][15904] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-22 05:59:45,811 [salt.loaded.ext.module.maas:1023][INFO    ][15904] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.36094379s left)
2019-03-22 05:59:50,117 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322055950101648
2019-03-22 05:59:50,137 [salt.minion      :1432][INFO    ][15978] Starting a new job with PID 15978
2019-03-22 05:59:50,169 [salt.minion      :1711][INFO    ][15978] Returning information for job: 20190322055950101648
2019-03-22 06:00:18,473 [salt.loaded.ext.module.maas:1023][INFO    ][15904] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.69884682s left)
2019-03-22 06:00:20,360 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322060020296130
2019-03-22 06:00:20,375 [salt.minion      :1432][INFO    ][16020] Starting a new job with PID 16020
2019-03-22 06:00:20,397 [salt.minion      :1711][INFO    ][16020] Returning information for job: 20190322060020296130
2019-03-22 06:00:50,421 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322060050412787
2019-03-22 06:00:50,436 [salt.minion      :1432][INFO    ][16089] Starting a new job with PID 16089
2019-03-22 06:00:50,459 [salt.minion      :1711][INFO    ][16089] Returning information for job: 20190322060050412787
2019-03-22 06:00:51,254 [salt.loaded.ext.module.maas:1023][INFO    ][15904] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2181.91815996s left)
2019-03-22 06:01:20,470 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322060120456384
2019-03-22 06:01:20,491 [salt.minion      :1432][INFO    ][16116] Starting a new job with PID 16116
2019-03-22 06:01:20,514 [salt.minion      :1711][INFO    ][16116] Returning information for job: 20190322060120456384
2019-03-22 06:01:23,902 [salt.loaded.ext.module.maas:1023][INFO    ][15904] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2149.27005696s left)
2019-03-22 06:01:50,549 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322060150532680
2019-03-22 06:01:50,568 [salt.minion      :1432][INFO    ][16184] Starting a new job with PID 16184
2019-03-22 06:01:50,592 [salt.minion      :1711][INFO    ][16184] Returning information for job: 20190322060150532680
2019-03-22 06:01:56,700 [salt.loaded.ext.module.maas:1023][INFO    ][15904] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2116.47201896s left)
2019-03-22 06:02:20,606 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322060220592480
2019-03-22 06:02:20,626 [salt.minion      :1432][INFO    ][16222] Starting a new job with PID 16222
2019-03-22 06:02:20,654 [salt.minion      :1711][INFO    ][16222] Returning information for job: 20190322060220592480
2019-03-22 06:02:29,466 [salt.loaded.ext.module.maas:1023][INFO    ][15904] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2083.70630884s left)
2019-03-22 06:02:50,686 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322060250671335
2019-03-22 06:02:50,707 [salt.minion      :1432][INFO    ][16293] Starting a new job with PID 16293
2019-03-22 06:02:50,729 [salt.minion      :1711][INFO    ][16293] Returning information for job: 20190322060250671335
2019-03-22 06:03:02,358 [salt.loaded.ext.module.maas:1023][INFO    ][15904] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2050.8143909s left)
2019-03-22 06:03:20,749 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322060320737866
2019-03-22 06:03:20,770 [salt.minion      :1432][INFO    ][16325] Starting a new job with PID 16325
2019-03-22 06:03:20,792 [salt.minion      :1711][INFO    ][16325] Returning information for job: 20190322060320737866
2019-03-22 06:03:35,052 [salt.loaded.ext.module.maas:1023][INFO    ][15904] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2018.11977792s left)
2019-03-22 06:03:50,836 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322060350827238
2019-03-22 06:03:50,851 [salt.minion      :1432][INFO    ][16418] Starting a new job with PID 16418
2019-03-22 06:03:50,870 [salt.minion      :1711][INFO    ][16418] Returning information for job: 20190322060350827238
2019-03-22 06:04:07,794 [salt.loaded.ext.module.maas:1023][INFO    ][15904] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1985.3782959s left)
2019-03-22 06:04:20,874 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322060420855415
2019-03-22 06:04:20,890 [salt.minion      :1432][INFO    ][16455] Starting a new job with PID 16455
2019-03-22 06:04:20,913 [salt.minion      :1711][INFO    ][16455] Returning information for job: 20190322060420855415
2019-03-22 06:04:40,629 [salt.loaded.ext.module.maas:1023][INFO    ][15904] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1952.54263878s left)
2019-03-22 06:04:50,983 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322060450968556
2019-03-22 06:04:51,008 [salt.minion      :1432][INFO    ][16629] Starting a new job with PID 16629
2019-03-22 06:04:51,031 [salt.minion      :1711][INFO    ][16629] Returning information for job: 20190322060450968556
2019-03-22 06:05:13,396 [salt.loaded.ext.module.maas:1023][INFO    ][15904] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1919.77585793s left)
2019-03-22 06:05:21,069 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322060521061552
2019-03-22 06:05:21,084 [salt.minion      :1432][INFO    ][16664] Starting a new job with PID 16664
2019-03-22 06:05:21,105 [salt.minion      :1711][INFO    ][16664] Returning information for job: 20190322060521061552
2019-03-22 06:05:46,537 [salt.loaded.ext.module.maas:1023][INFO    ][15904] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1886.634727s left)
2019-03-22 06:05:51,160 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322060551147529
2019-03-22 06:05:51,178 [salt.minion      :1432][INFO    ][16823] Starting a new job with PID 16823
2019-03-22 06:05:51,199 [salt.minion      :1711][INFO    ][16823] Returning information for job: 20190322060551147529
2019-03-22 06:06:19,297 [salt.loaded.ext.module.maas:1023][INFO    ][15904] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1853.8746798s left)
2019-03-22 06:06:21,259 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322060621248487
2019-03-22 06:06:21,277 [salt.minion      :1432][INFO    ][16855] Starting a new job with PID 16855
2019-03-22 06:06:21,296 [salt.minion      :1711][INFO    ][16855] Returning information for job: 20190322060621248487
2019-03-22 06:06:51,353 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322060651340078
2019-03-22 06:06:51,371 [salt.minion      :1432][INFO    ][16979] Starting a new job with PID 16979
2019-03-22 06:06:51,395 [salt.minion      :1711][INFO    ][16979] Returning information for job: 20190322060651340078
2019-03-22 06:06:51,960 [salt.loaded.ext.module.maas:1023][INFO    ][15904] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1821.21218991s left)
2019-03-22 06:07:21,470 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322060721461101
2019-03-22 06:07:21,491 [salt.minion      :1432][INFO    ][17018] Starting a new job with PID 17018
2019-03-22 06:07:21,516 [salt.minion      :1711][INFO    ][17018] Returning information for job: 20190322060721461101
2019-03-22 06:07:24,824 [salt.loaded.ext.module.maas:1023][INFO    ][15904] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1788.34770799s left)
2019-03-22 06:07:51,595 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322060751582647
2019-03-22 06:07:51,618 [salt.minion      :1432][INFO    ][17152] Starting a new job with PID 17152
2019-03-22 06:07:51,641 [salt.minion      :1711][INFO    ][17152] Returning information for job: 20190322060751582647
2019-03-22 06:07:57,732 [salt.loaded.ext.module.maas:1023][INFO    ][15904] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1755.4396348s left)
2019-03-22 06:08:21,738 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322060821727201
2019-03-22 06:08:21,761 [salt.minion      :1432][INFO    ][17185] Starting a new job with PID 17185
2019-03-22 06:08:21,792 [salt.minion      :1711][INFO    ][17185] Returning information for job: 20190322060821727201
2019-03-22 06:08:30,397 [salt.loaded.ext.module.maas:1023][INFO    ][15904] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1722.77480292s left)
2019-03-22 06:08:51,865 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322060851857910
2019-03-22 06:08:51,878 [salt.minion      :1432][INFO    ][17257] Starting a new job with PID 17257
2019-03-22 06:08:51,905 [salt.minion      :1711][INFO    ][17257] Returning information for job: 20190322060851857910
2019-03-22 06:09:03,293 [salt.loaded.ext.module.maas:1023][INFO    ][15904] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1689.87952781s left)
2019-03-22 06:09:22,021 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322060922013795
2019-03-22 06:09:22,037 [salt.minion      :1432][INFO    ][17287] Starting a new job with PID 17287
2019-03-22 06:09:22,063 [salt.minion      :1711][INFO    ][17287] Returning information for job: 20190322060922013795
2019-03-22 06:09:36,057 [salt.loaded.ext.module.maas:1023][INFO    ][15904] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1657.11536884s left)
2019-03-22 06:09:52,146 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322060952136631
2019-03-22 06:09:52,161 [salt.minion      :1432][INFO    ][17397] Starting a new job with PID 17397
2019-03-22 06:09:52,186 [salt.minion      :1711][INFO    ][17397] Returning information for job: 20190322060952136631
2019-03-22 06:10:09,133 [salt.loaded.ext.module.maas:1023][INFO    ][15904] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1624.039361s left)
2019-03-22 06:10:22,313 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322061022300122
2019-03-22 06:10:22,329 [salt.minion      :1432][INFO    ][17557] Starting a new job with PID 17557
2019-03-22 06:10:22,352 [salt.minion      :1711][INFO    ][17557] Returning information for job: 20190322061022300122
2019-03-22 06:10:36,627 [salt.utils.schedule:1377][INFO    ][5556] Running scheduled job: __mine_interval
2019-03-22 06:10:41,910 [salt.loaded.ext.module.maas:1023][INFO    ][15904] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1591.26260495s left)
2019-03-22 06:10:52,459 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322061052449089
2019-03-22 06:10:52,476 [salt.minion      :1432][INFO    ][17639] Starting a new job with PID 17639
2019-03-22 06:10:52,500 [salt.minion      :1711][INFO    ][17639] Returning information for job: 20190322061052449089
2019-03-22 06:11:14,728 [salt.loaded.ext.module.maas:1023][INFO    ][15904] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1558.44382286s left)
2019-03-22 06:11:22,638 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command saltutil.find_job with jid 20190322061122628075
2019-03-22 06:11:22,661 [salt.minion      :1432][INFO    ][17691] Starting a new job with PID 17691
2019-03-22 06:11:22,684 [salt.minion      :1711][INFO    ][17691] Returning information for job: 20190322061122628075
2019-03-22 06:11:47,981 [salt.state       :300 ][INFO    ][15904] {'ret': True}
2019-03-22 06:11:47,982 [salt.state       :1951][INFO    ][15904] Completed state [maas.wait_for_machine_status] at time 06:11:47.982254 duration_in_ms=724822.278
2019-03-22 06:11:47,985 [salt.minion      :1711][INFO    ][15904] Returning information for job: 20190322055935064038
2019-03-22 07:10:36,627 [salt.utils.schedule:1377][INFO    ][5556] Running scheduled job: __mine_interval
2019-03-22 08:10:36,627 [salt.utils.schedule:1377][INFO    ][5556] Running scheduled job: __mine_interval
2019-03-22 08:23:35,336 [salt.minion      :1308][INFO    ][5556] User sudo_ubuntu Executing command cp.push_dir with jid 20190322082335325475
2019-03-22 08:23:35,359 [salt.minion      :1432][INFO    ][31116] Starting a new job with PID 31116
