2019-06-17 05:04:11,797 [salt.utils.decorators:613 ][WARNING ][2287] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-17 05:04:12,564 [salt.utils.decorators:613 ][WARNING ][2287] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-17 05:04:15,541 [salt.loaded.int.states.file:2298][WARNING ][2497] 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-06-17 05:04:39,719 [salt.state       :2022][WARNING ][2864] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-06-17 05:04:42,886 [salt.utils.decorators:613 ][WARNING ][2864] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-17 05:04:52,655 [salt.utils.decorators:613 ][WARNING ][2864] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-17 05:05:20,514 [salt.utils.decorators:613 ][WARNING ][2864] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-17 05:05:21,577 [salt.utils.decorators:613 ][WARNING ][2864] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-17 05:05:23,952 [salt.loaded.ext.module.maasng:1008][WARNING ][2864] Detected cidr:192.168.11.0/24 in fabric:fabric-4
2019-06-17 05:05:23,953 [salt.loaded.ext.module.maasng:1011][WARNING ][2864] Guessing, that fabric with current name:fabric-4
 should be renamed to:pxe_admin
2019-06-17 05:05:24,738 [salt.loaded.ext.module.maasng:1235][WARNING ][2864] Ignoring parameter vlan:0
2019-06-17 05:05:25,760 [salt.utils.decorators:613 ][WARNING ][2864] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-17 05:05:34,821 [salt.utils.decorators:613 ][WARNING ][5319] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-17 05:05:34,875 [salt.loaded.ext.module.maas:412 ][WARNING ][5319] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-17 05:05:36,324 [salt.loaded.ext.module.maas:412 ][WARNING ][5319] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-17 05:05:37,447 [salt.loaded.ext.module.maas:412 ][WARNING ][5319] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-17 05:05:38,626 [salt.loaded.ext.module.maas:412 ][WARNING ][5319] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-17 05:05:39,834 [salt.loaded.ext.module.maas:412 ][WARNING ][5319] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-17 05:05:44,913 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5885] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-06-17 05:05:44,947 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5885] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-06-17 05:05:44,993 [salt.utils.parsers:1051][WARNING ][378] Minion received a SIGTERM. Exiting.
2019-06-17 05:05:46,007 [salt.cli.daemons :293 ][INFO    ][5965] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-06-17 05:05:46,128 [salt.cli.daemons :82  ][INFO    ][5965] Starting up the Salt Minion
2019-06-17 05:05:46,129 [salt.utils.event :1017][INFO    ][5965] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-06-17 05:05:47,362 [salt.minion      :976 ][INFO    ][5965] Creating minion process manager
2019-06-17 05:05:49,407 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][5965] Executing command ['date', '+%z'] in directory '/root'
2019-06-17 05:05:49,431 [salt.utils.schedule:568 ][INFO    ][5965] Updating job settings for scheduled job: __mine_interval
2019-06-17 05:05:49,433 [salt.minion      :1108][INFO    ][5965] Added mine.update to scheduler
2019-06-17 05:05:49,438 [salt.minion      :1975][INFO    ][5965] Minion is starting as user 'root'
2019-06-17 05:05:49,460 [salt.minion      :2336][INFO    ][5965] Minion is ready to receive requests!
2019-06-17 05:06:12,145 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command state.apply with jid 20190617050612132825
2019-06-17 05:06:12,174 [salt.minion      :1432][INFO    ][6064] Starting a new job with PID 6064
2019-06-17 05:06:18,112 [salt.state       :915 ][INFO    ][6064] Loading fresh modules for state activity
2019-06-17 05:06:18,176 [salt.fileclient  :1219][INFO    ][6064] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-06-17 05:06:18,228 [salt.state       :1780][INFO    ][6064] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:06:18.228005
2019-06-17 05:06:18,228 [salt.state       :1813][INFO    ][6064] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-17 05:06:18,230 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6064] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-17 05:06:19,942 [salt.state       :300 ][INFO    ][6064] {'pid': 6076, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-17 05:06:19,943 [salt.state       :1951][INFO    ][6064] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:06:19.943623 duration_in_ms=1715.617
2019-06-17 05:06:19,948 [salt.state       :1780][INFO    ][6064] Running state [maas.wait_for_machine_status] at time 05:06:19.948608
2019-06-17 05:06:19,949 [salt.state       :1813][INFO    ][6064] Executing state module.run for [maas.wait_for_machine_status]
2019-06-17 05:06:19,950 [salt.utils.decorators:613 ][WARNING ][6064] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-17 05:06:20,812 [salt.loaded.ext.module.maas:1023][INFO    ][6064] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.15016198s left)
2019-06-17 05:06:27,195 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617050627175598
2019-06-17 05:06:27,223 [salt.minion      :1432][INFO    ][6088] Starting a new job with PID 6088
2019-06-17 05:06:27,248 [salt.minion      :1711][INFO    ][6088] Returning information for job: 20190617050627175598
2019-06-17 05:06:52,015 [salt.loaded.ext.module.maas:1023][INFO    ][6064] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1467.94736695s left)
2019-06-17 05:06:57,273 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617050657257028
2019-06-17 05:06:57,305 [salt.minion      :1432][INFO    ][6167] Starting a new job with PID 6167
2019-06-17 05:06:57,327 [salt.minion      :1711][INFO    ][6167] Returning information for job: 20190617050657257028
2019-06-17 05:07:22,851 [salt.loaded.ext.module.maas:1023][INFO    ][6064] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.11114693s left)
2019-06-17 05:07:27,368 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617050727352343
2019-06-17 05:07:27,397 [salt.minion      :1432][INFO    ][6192] Starting a new job with PID 6192
2019-06-17 05:07:27,415 [salt.minion      :1711][INFO    ][6192] Returning information for job: 20190617050727352343
2019-06-17 05:07:53,662 [salt.loaded.ext.module.maas:1023][INFO    ][6064] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.30061007s left)
2019-06-17 05:07:57,452 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617050757437395
2019-06-17 05:07:57,480 [salt.minion      :1432][INFO    ][6244] Starting a new job with PID 6244
2019-06-17 05:07:57,502 [salt.minion      :1711][INFO    ][6244] Returning information for job: 20190617050757437395
2019-06-17 05:08:24,747 [salt.loaded.ext.module.maas:1023][INFO    ][6064] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.21545792s left)
2019-06-17 05:08:27,533 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617050827521888
2019-06-17 05:08:27,560 [salt.minion      :1432][INFO    ][6300] Starting a new job with PID 6300
2019-06-17 05:08:27,581 [salt.minion      :1711][INFO    ][6300] Returning information for job: 20190617050827521888
2019-06-17 05:08:55,878 [salt.loaded.ext.module.maas:1023][INFO    ][6064] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.08424497s left)
2019-06-17 05:08:57,623 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617050857614540
2019-06-17 05:08:57,648 [salt.minion      :1432][INFO    ][6466] Starting a new job with PID 6466
2019-06-17 05:08:57,671 [salt.minion      :1711][INFO    ][6466] Returning information for job: 20190617050857614540
2019-06-17 05:09:27,009 [salt.loaded.ext.module.maas:1023][INFO    ][6064] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1312.95325804s left)
2019-06-17 05:09:27,720 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617050927699424
2019-06-17 05:09:27,748 [salt.minion      :1432][INFO    ][6569] Starting a new job with PID 6569
2019-06-17 05:09:27,768 [salt.minion      :1711][INFO    ][6569] Returning information for job: 20190617050927699424
2019-06-17 05:09:57,841 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617050957833368
2019-06-17 05:09:57,863 [salt.minion      :1432][INFO    ][6863] Starting a new job with PID 6863
2019-06-17 05:09:57,884 [salt.minion      :1711][INFO    ][6863] Returning information for job: 20190617050957833368
2019-06-17 05:09:58,287 [salt.loaded.ext.module.maas:1023][INFO    ][6064] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1281.675632s left)
2019-06-17 05:10:27,901 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617051027886940
2019-06-17 05:10:27,929 [salt.minion      :1432][INFO    ][6993] Starting a new job with PID 6993
2019-06-17 05:10:27,949 [salt.minion      :1711][INFO    ][6993] Returning information for job: 20190617051027886940
2019-06-17 05:10:30,153 [salt.loaded.ext.module.maas:1023][INFO    ][6064] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1249.80969191s left)
2019-06-17 05:10:58,032 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617051058019487
2019-06-17 05:10:58,063 [salt.minion      :1432][INFO    ][7283] Starting a new job with PID 7283
2019-06-17 05:10:58,085 [salt.minion      :1711][INFO    ][7283] Returning information for job: 20190617051058019487
2019-06-17 05:11:02,406 [salt.loaded.ext.module.maas:1023][INFO    ][6064] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1217.55671597s left)
2019-06-17 05:11:28,146 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617051128133703
2019-06-17 05:11:28,169 [salt.minion      :1432][INFO    ][7529] Starting a new job with PID 7529
2019-06-17 05:11:28,188 [salt.minion      :1711][INFO    ][7529] Returning information for job: 20190617051128133703
2019-06-17 05:11:34,568 [salt.loaded.ext.module.maas:1023][INFO    ][6064] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1185.39471006s left)
2019-06-17 05:11:58,268 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617051158251970
2019-06-17 05:11:58,301 [salt.minion      :1432][INFO    ][7671] Starting a new job with PID 7671
2019-06-17 05:11:58,321 [salt.minion      :1711][INFO    ][7671] Returning information for job: 20190617051158251970
2019-06-17 05:12:06,747 [salt.loaded.ext.module.maas:1023][INFO    ][6064] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1153.21526909s left)
2019-06-17 05:12:28,412 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617051228398492
2019-06-17 05:12:28,443 [salt.minion      :1432][INFO    ][7818] Starting a new job with PID 7818
2019-06-17 05:12:28,464 [salt.minion      :1711][INFO    ][7818] Returning information for job: 20190617051228398492
2019-06-17 05:12:39,783 [salt.state       :300 ][INFO    ][6064] {'ret': True}
2019-06-17 05:12:39,784 [salt.state       :1951][INFO    ][6064] Completed state [maas.wait_for_machine_status] at time 05:12:39.784257 duration_in_ms=379835.648
2019-06-17 05:12:39,789 [salt.minion      :1711][INFO    ][6064] Returning information for job: 20190617050612132825
2019-06-17 05:12:40,388 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command state.apply with jid 20190617051240374221
2019-06-17 05:12:40,413 [salt.minion      :1432][INFO    ][7858] Starting a new job with PID 7858
2019-06-17 05:12:46,495 [salt.state       :915 ][INFO    ][7858] Loading fresh modules for state activity
2019-06-17 05:12:46,551 [salt.fileclient  :1219][INFO    ][7858] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-06-17 05:12:46,654 [salt.state       :1780][INFO    ][7858] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:12:46.654565
2019-06-17 05:12:46,654 [salt.state       :1813][INFO    ][7858] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-17 05:12:46,656 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7858] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-17 05:12:48,390 [salt.state       :300 ][INFO    ][7858] {'pid': 7902, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-17 05:12:48,390 [salt.state       :1951][INFO    ][7858] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:12:48.390764 duration_in_ms=1736.199
2019-06-17 05:12:48,392 [salt.state       :1780][INFO    ][7858] Running state [maas_machines_storage_cmp002_lvm] at time 05:12:48.392720
2019-06-17 05:12:48,393 [salt.state       :1813][INFO    ][7858] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-06-17 05:12:49,511 [salt.loaded.ext.module.maasng:610 ][INFO    ][7858] khxf73
2019-06-17 05:12:49,511 [salt.loaded.ext.module.maasng:626 ][INFO    ][7858] sda
2019-06-17 05:12:50,063 [salt.loaded.ext.module.maasng:361 ][INFO    ][7858] khxf73
2019-06-17 05:12:50,154 [salt.loaded.ext.module.maasng:367 ][INFO    ][7858] [{u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/khxf73/blockdevices/1/', u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'khxf73', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'f9d01c68-cafc-426a-8f6e-8936991e1f0c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'khxf73', u'device_id': 1, u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'70978b01-1310-4543-a2f1-ce1a5fb80f4d', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/khxf73/blockdevices/1/partition/1', u'type': u'partition', u'id': 1, u'size': 800101236736}]}, {u'size': 800097042432, u'resource_uri': u'/MAAS/api/2.0/nodes/khxf73/blockdevices/3/', u'available_size': 0, u'uuid': u'73c42363-ec70-4a8d-8660-0a6f5f1233ec', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'khxf73', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'uuid': u'8b3f0e6c-7a09-40bb-aed3-29a851ebc9e1', u'fstype': u'ext4', u'mount_options': None}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'used_size': 800097042432, u'id': 3, u'serial': None, u'partitions': []}]
2019-06-17 05:12:50,154 [salt.loaded.ext.module.maasng:632 ][INFO    ][7858] vgroot
2019-06-17 05:12:50,155 [salt.loaded.ext.module.maasng:635 ][INFO    ][7858] lvroot
2019-06-17 05:12:50,155 [salt.loaded.ext.module.maasng:639 ][INFO    ][7858] 107374182400
2019-06-17 05:12:50,772 [salt.loaded.ext.module.maasng:645 ][INFO    ][7858] {u'hwe_kernel': u'', u'swap_size': None, 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': 4, u'mtu': 1500, u'primary_rack': u'mh4ker', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, 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': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.38', u'id': 22, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': u'mh4ker', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'id': 4, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': u'mh4ker', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, 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': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'system_id': u'khxf73', u'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/khxf73/interfaces/4/'}, u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'cpu_count': 40, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'fqdn': u'cmp002.maas', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'commissioning_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'zone': {u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default', u'id': 1}, u'resource_uri': u'/MAAS/api/2.0/machines/khxf73/', u'current_commissioning_result_id': 2, u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'khxf73', 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'owner_data': {}, u'blockdevice_set': [{u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/khxf73/blockdevices/1/', u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'khxf73', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'd392ac39-2c95-43c2-ae3e-9a4a166f3455', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'khxf73', u'device_id': 1, u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'e7e06bff-95db-4f37-a8e9-69362f2c7aae', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/khxf73/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'size': 800101236736}]}, {u'size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/khxf73/blockdevices/11/', u'available_size': 0, u'uuid': u'bca34ef1-743c-4e20-bac0-811ac6d025f3', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'khxf73', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'uuid': u'cfba90a0-a8a8-4025-9bf6-b9bbaa74ddc3', u'fstype': u'ext4', u'mount_options': None}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 11, u'serial': None, u'partitions': []}], u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'power_state': u'off', u'physicalblockdevice_set': [{u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/khxf73/blockdevices/1/', u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'id': 1, u'system_id': u'khxf73', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'used_size': 800106479616, u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'd392ac39-2c95-43c2-ae3e-9a4a166f3455', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'khxf73', u'device_id': 1, u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'e7e06bff-95db-4f37-a8e9-69362f2c7aae', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/khxf73/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'size': 800101236736}]}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'khxf73', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/khxf73/blockdevices/1/', u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'id': 1, u'system_id': u'khxf73', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'used_size': 800106479616, u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'd392ac39-2c95-43c2-ae3e-9a4a166f3455', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'khxf73', u'device_id': 1, u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'e7e06bff-95db-4f37-a8e9-69362f2c7aae', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/khxf73/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'size': 800101236736}]}, u'current_testing_result_id': 3, u'cpu_test_status': -1, u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'node_type': 0, u'disable_ipv4': False, u'virtualblockdevice_set': [{u'size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/khxf73/blockdevices/11/', u'available_size': 0, u'uuid': u'bca34ef1-743c-4e20-bac0-811ac6d025f3', u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'id': 11, u'system_id': u'khxf73', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'uuid': u'cfba90a0-a8a8-4025-9bf6-b9bbaa74ddc3', u'fstype': u'ext4', u'mount_options': None}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'model': None, u'partitions': []}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'min_hwe_kernel': u'hwe-16.04', 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': 4, u'mtu': 1500, u'primary_rack': u'mh4ker', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, 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': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.38', u'id': 22, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': u'mh4ker', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'id': 4, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': u'mh4ker', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, 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': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'system_id': u'khxf73', u'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/khxf73/interfaces/4/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'id': 15, u'discovered': None, u'parents': [], u'system_id': u'khxf73', u'mac_address': u'38:ea:a7:8f:07:51', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/khxf73/interfaces/15/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'id': 11, u'discovered': None, u'parents': [], u'system_id': u'khxf73', u'mac_address': u'38:ea:a7:8f:07:50', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/khxf73/interfaces/11/'}, {u'name': u'ens2f1', u'links': [{u'id': 23, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'id': 12, u'discovered': None, u'parents': [], u'system_id': u'khxf73', u'mac_address': u'38:ea:a7:8f:12:49', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/khxf73/interfaces/12/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': u'mh4ker', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, 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': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'id': 24, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': u'mh4ker', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'id': 13, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': u'mh4ker', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, 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': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.40'}], u'parents': [], u'system_id': u'khxf73', u'mac_address': u'9c:b6:54:8a:10:1c', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/khxf73/interfaces/13/'}, {u'name': u'ens2f0', u'links': [{u'id': 25, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'id': 14, u'discovered': None, u'parents': [], u'system_id': u'khxf73', u'mac_address': u'38:ea:a7:8f:12:48', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/khxf73/interfaces/14/'}], u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'node_type_name': u'Machine'}
2019-06-17 05:12:50,776 [salt.state       :300 ][INFO    ][7858] {'new': {'storage_layout': 'lvm'}}
2019-06-17 05:12:50,776 [salt.state       :1951][INFO    ][7858] Completed state [maas_machines_storage_cmp002_lvm] at time 05:12:50.776664 duration_in_ms=2383.943
2019-06-17 05:12:50,777 [salt.state       :1780][INFO    ][7858] Running state [maas_machines_storage_cmp001_lvm] at time 05:12:50.777014
2019-06-17 05:12:50,777 [salt.state       :1813][INFO    ][7858] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-06-17 05:12:51,965 [salt.loaded.ext.module.maasng:610 ][INFO    ][7858] 7sswy3
2019-06-17 05:12:51,966 [salt.loaded.ext.module.maasng:626 ][INFO    ][7858] sda
2019-06-17 05:12:52,560 [salt.loaded.ext.module.maasng:361 ][INFO    ][7858] 7sswy3
2019-06-17 05:12:52,651 [salt.loaded.ext.module.maasng:367 ][INFO    ][7858] [{u'block_size': 4096, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'1bdeb060-398c-414e-8006-0e9d1897aef1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'7sswy3', u'device_id': 2, u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'e41a1218-8c21-4ffa-b88d-3cdbb674306d', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/7sswy3/blockdevices/2/partition/2'}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'7sswy3', u'partition_table_type': u'MBR', u'filesystem': None, u'id': 2, u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/7sswy3/blockdevices/2/', u'type': u'physical', u'model': u'LOGICAL VOLUME', u'size': 800109715456}, {u'block_size': 4096, u'id_path': None, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'partitions': [], u'uuid': u'668e487a-1987-4a05-8e71-b8e2a91e4d23', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'7sswy3', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'uuid': u'0f2071ea-d27f-4707-b069-6416d81d7e29', u'fstype': u'ext4', u'mount_options': None}, u'id': 4, u'available_size': 0, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/7sswy3/blockdevices/4/', u'type': u'virtual', u'model': None, u'size': 800097042432}]
2019-06-17 05:12:52,651 [salt.loaded.ext.module.maasng:632 ][INFO    ][7858] vgroot
2019-06-17 05:12:52,651 [salt.loaded.ext.module.maasng:635 ][INFO    ][7858] lvroot
2019-06-17 05:12:52,652 [salt.loaded.ext.module.maasng:639 ][INFO    ][7858] 107374182400
2019-06-17 05:12:53,263 [salt.loaded.ext.module.maasng:645 ][INFO    ][7858] {u'hwe_kernel': u'', u'swap_size': None, 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': 4, u'mtu': 1500, u'primary_rack': u'mh4ker', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, 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': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.39', u'id': 28, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': u'mh4ker', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': u'mh4ker', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, 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': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'system_id': u'7sswy3', u'mac_address': u'9c:b6:54:8a:95:a0', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/7sswy3/interfaces/5/'}, u'ip_addresses': [u'192.168.11.39', u'192.168.11.44'], u'cpu_count': 40, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'fqdn': u'cmp001.maas', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'commissioning_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'zone': {u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default', u'id': 1}, u'resource_uri': u'/MAAS/api/2.0/machines/7sswy3/', u'current_commissioning_result_id': 4, u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'7sswy3', 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'owner_data': {}, u'blockdevice_set': [{u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/7sswy3/blockdevices/2/', u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'7sswy3', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'88fd0080-e9b4-4935-9cc2-d946e84090cc', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'7sswy3', u'device_id': 2, u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'61626656-49fa-47d7-856c-af26f8d8f2bc', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/7sswy3/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}]}, {u'size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/7sswy3/blockdevices/12/', u'available_size': 0, u'uuid': u'37ccaac3-02e5-4d75-b7af-1a1664a48cfd', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'7sswy3', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'uuid': u'ed73016c-8477-4727-92fc-302f992c8d83', u'fstype': u'ext4', u'mount_options': None}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 12, u'serial': None, u'partitions': []}], u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'power_state': u'off', u'physicalblockdevice_set': [{u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/7sswy3/blockdevices/2/', u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'id': 2, u'system_id': u'7sswy3', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'used_size': 800106479616, u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'88fd0080-e9b4-4935-9cc2-d946e84090cc', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'7sswy3', u'device_id': 2, u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'61626656-49fa-47d7-856c-af26f8d8f2bc', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/7sswy3/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}]}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'7sswy3', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/7sswy3/blockdevices/2/', u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'id': 2, u'system_id': u'7sswy3', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'used_size': 800106479616, u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'88fd0080-e9b4-4935-9cc2-d946e84090cc', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'7sswy3', u'device_id': 2, u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'61626656-49fa-47d7-856c-af26f8d8f2bc', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/7sswy3/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}]}, u'current_testing_result_id': 5, u'cpu_test_status': -1, u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'node_type': 0, u'disable_ipv4': False, u'virtualblockdevice_set': [{u'size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/7sswy3/blockdevices/12/', u'available_size': 0, u'uuid': u'37ccaac3-02e5-4d75-b7af-1a1664a48cfd', u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'id': 12, u'system_id': u'7sswy3', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'uuid': u'ed73016c-8477-4727-92fc-302f992c8d83', u'fstype': u'ext4', u'mount_options': None}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'model': None, u'partitions': []}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'min_hwe_kernel': u'hwe-16.04', 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': 4, u'mtu': 1500, u'primary_rack': u'mh4ker', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, 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': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.39', u'id': 28, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': u'mh4ker', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': u'mh4ker', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, 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': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'system_id': u'7sswy3', u'mac_address': u'9c:b6:54:8a:95:a0', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/7sswy3/interfaces/5/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'id': 16, u'discovered': None, u'parents': [], u'system_id': u'7sswy3', u'mac_address': u'38:ea:a7:8f:1f:d4', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/7sswy3/interfaces/16/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'id': 20, u'discovered': None, u'parents': [], u'system_id': u'7sswy3', u'mac_address': u'38:ea:a7:8f:1f:d5', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/7sswy3/interfaces/20/'}, {u'name': u'ens2f1', u'links': [{u'id': 29, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'id': 17, u'discovered': None, u'parents': [], u'system_id': u'7sswy3', u'mac_address': u'38:ea:a7:8f:52:cd', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/7sswy3/interfaces/17/'}, {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': 4, u'mtu': 1500, u'primary_rack': u'mh4ker', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, 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': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'id': 30, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': u'mh4ker', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'id': 18, 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': 4, u'mtu': 1500, u'primary_rack': u'mh4ker', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, 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': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.44'}], u'parents': [], u'system_id': u'7sswy3', u'mac_address': u'9c:b6:54:8a:95:a4', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/7sswy3/interfaces/18/'}, {u'name': u'ens2f0', u'links': [{u'id': 31, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'id': 19, u'discovered': None, u'parents': [], u'system_id': u'7sswy3', u'mac_address': u'38:ea:a7:8f:52:cc', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/7sswy3/interfaces/19/'}], u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'node_type_name': u'Machine'}
2019-06-17 05:12:53,266 [salt.state       :300 ][INFO    ][7858] {'new': {'storage_layout': 'lvm'}}
2019-06-17 05:12:53,267 [salt.state       :1951][INFO    ][7858] Completed state [maas_machines_storage_cmp001_lvm] at time 05:12:53.267093 duration_in_ms=2490.077
2019-06-17 05:12:53,273 [salt.minion      :1711][INFO    ][7858] Returning information for job: 20190617051240374221
2019-06-17 05:12:53,840 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command state.apply with jid 20190617051253824090
2019-06-17 05:12:53,867 [salt.minion      :1432][INFO    ][7920] Starting a new job with PID 7920
2019-06-17 05:12:54,863 [salt.state       :915 ][INFO    ][7920] Loading fresh modules for state activity
2019-06-17 05:12:54,927 [salt.fileclient  :1219][INFO    ][7920] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-06-17 05:12:54,981 [salt.state       :1780][INFO    ][7920] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:12:54.981678
2019-06-17 05:12:54,982 [salt.state       :1813][INFO    ][7920] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-17 05:12:54,983 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7920] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-17 05:12:56,667 [salt.state       :300 ][INFO    ][7920] {'pid': 7927, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-17 05:12:56,668 [salt.state       :1951][INFO    ][7920] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:12:56.668110 duration_in_ms=1686.431
2019-06-17 05:12:56,671 [salt.state       :1780][INFO    ][7920] Running state [maas.deploy_machines] at time 05:12:56.671688
2019-06-17 05:12:56,672 [salt.state       :1813][INFO    ][7920] Executing state module.run for [maas.deploy_machines]
2019-06-17 05:12:56,674 [salt.utils.decorators:613 ][WARNING ][7920] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-17 05:12:57,252 [salt.loaded.ext.module.maas:684 ][INFO    ][7920] deploymachines hwe_kernel=hwe-16.04 system_id=khxf73 distro_series=xenial
2019-06-17 05:12:59,971 [salt.loaded.ext.module.maas:684 ][INFO    ][7920] deploymachines hwe_kernel=hwe-16.04 system_id=7sswy3 distro_series=xenial
2019-06-17 05:13:02,479 [salt.loaded.ext.module.maas:684 ][INFO    ][7920] deploymachines hwe_kernel=hwe-16.04 system_id=x47ewc distro_series=xenial
2019-06-17 05:13:04,975 [salt.loaded.ext.module.maas:684 ][INFO    ][7920] deploymachines hwe_kernel=hwe-16.04 system_id=amrdrd distro_series=xenial
2019-06-17 05:13:07,471 [salt.loaded.ext.module.maas:684 ][INFO    ][7920] deploymachines hwe_kernel=hwe-16.04 system_id=adcmmk distro_series=xenial
2019-06-17 05:13:08,928 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617051308915856
2019-06-17 05:13:08,955 [salt.minion      :1432][INFO    ][8183] Starting a new job with PID 8183
2019-06-17 05:13:08,976 [salt.minion      :1711][INFO    ][8183] Returning information for job: 20190617051308915856
2019-06-17 05:13:09,996 [salt.state       :300 ][INFO    ][7920] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-06-17 05:13:09,997 [salt.state       :1951][INFO    ][7920] Completed state [maas.deploy_machines] at time 05:13:09.997113 duration_in_ms=13325.426
2019-06-17 05:13:10,003 [salt.minion      :1711][INFO    ][7920] Returning information for job: 20190617051253824090
2019-06-17 05:13:10,576 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command state.apply with jid 20190617051310565363
2019-06-17 05:13:10,602 [salt.minion      :1432][INFO    ][8202] Starting a new job with PID 8202
2019-06-17 05:13:16,729 [salt.state       :915 ][INFO    ][8202] Loading fresh modules for state activity
2019-06-17 05:13:16,786 [salt.fileclient  :1219][INFO    ][8202] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-06-17 05:13:16,835 [salt.state       :1780][INFO    ][8202] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:13:16.835302
2019-06-17 05:13:16,835 [salt.state       :1813][INFO    ][8202] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-17 05:13:16,837 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8202] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-17 05:13:18,703 [salt.state       :300 ][INFO    ][8202] {'pid': 8228, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-17 05:13:18,704 [salt.state       :1951][INFO    ][8202] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:13:18.704083 duration_in_ms=1868.781
2019-06-17 05:13:18,707 [salt.state       :1780][INFO    ][8202] Running state [maas.wait_for_machine_status] at time 05:13:18.707703
2019-06-17 05:13:18,708 [salt.state       :1813][INFO    ][8202] Executing state module.run for [maas.wait_for_machine_status]
2019-06-17 05:13:18,708 [salt.utils.decorators:613 ][WARNING ][8202] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-17 05:13:21,556 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.16129494s left)
2019-06-17 05:13:25,697 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617051325680485
2019-06-17 05:13:25,723 [salt.minion      :1432][INFO    ][8244] Starting a new job with PID 8244
2019-06-17 05:13:25,746 [salt.minion      :1711][INFO    ][8244] Returning information for job: 20190617051325680485
2019-06-17 05:13:54,597 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.1201148s left)
2019-06-17 05:13:55,782 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617051355766513
2019-06-17 05:13:55,807 [salt.minion      :1432][INFO    ][8295] Starting a new job with PID 8295
2019-06-17 05:13:55,830 [salt.minion      :1711][INFO    ][8295] Returning information for job: 20190617051355766513
2019-06-17 05:14:25,855 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617051425837642
2019-06-17 05:14:25,880 [salt.minion      :1432][INFO    ][8317] Starting a new job with PID 8317
2019-06-17 05:14:25,903 [salt.minion      :1711][INFO    ][8317] Returning information for job: 20190617051425837642
2019-06-17 05:14:27,675 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.04216981s left)
2019-06-17 05:14:55,931 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617051455916130
2019-06-17 05:14:55,957 [salt.minion      :1432][INFO    ][8382] Starting a new job with PID 8382
2019-06-17 05:14:55,977 [salt.minion      :1711][INFO    ][8382] Returning information for job: 20190617051455916130
2019-06-17 05:15:00,519 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2148.19760799s left)
2019-06-17 05:15:26,015 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617051525999361
2019-06-17 05:15:26,040 [salt.minion      :1432][INFO    ][8407] Starting a new job with PID 8407
2019-06-17 05:15:26,061 [salt.minion      :1711][INFO    ][8407] Returning information for job: 20190617051525999361
2019-06-17 05:15:33,377 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2115.3404808s left)
2019-06-17 05:15:56,071 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617051556054830
2019-06-17 05:15:56,099 [salt.minion      :1432][INFO    ][8479] Starting a new job with PID 8479
2019-06-17 05:15:56,120 [salt.minion      :1711][INFO    ][8479] Returning information for job: 20190617051556054830
2019-06-17 05:16:06,087 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2082.62976289s left)
2019-06-17 05:16:26,158 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617051626140731
2019-06-17 05:16:26,190 [salt.minion      :1432][INFO    ][8579] Starting a new job with PID 8579
2019-06-17 05:16:26,210 [salt.minion      :1711][INFO    ][8579] Returning information for job: 20190617051626140731
2019-06-17 05:16:38,912 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2049.80542994s left)
2019-06-17 05:16:56,265 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617051656251479
2019-06-17 05:16:56,296 [salt.minion      :1432][INFO    ][8828] Starting a new job with PID 8828
2019-06-17 05:16:56,320 [salt.minion      :1711][INFO    ][8828] Returning information for job: 20190617051656251479
2019-06-17 05:17:12,244 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2016.47314286s left)
2019-06-17 05:17:26,344 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617051726329410
2019-06-17 05:17:26,364 [salt.minion      :1432][INFO    ][8945] Starting a new job with PID 8945
2019-06-17 05:17:26,386 [salt.minion      :1711][INFO    ][8945] Returning information for job: 20190617051726329410
2019-06-17 05:17:46,042 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1982.675529s left)
2019-06-17 05:17:56,429 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617051756415787
2019-06-17 05:17:56,456 [salt.minion      :1432][INFO    ][9144] Starting a new job with PID 9144
2019-06-17 05:17:56,478 [salt.minion      :1711][INFO    ][9144] Returning information for job: 20190617051756415787
2019-06-17 05:18:19,176 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1949.54071093s left)
2019-06-17 05:18:26,554 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617051826532815
2019-06-17 05:18:26,585 [salt.minion      :1432][INFO    ][9274] Starting a new job with PID 9274
2019-06-17 05:18:26,610 [salt.minion      :1711][INFO    ][9274] Returning information for job: 20190617051826532815
2019-06-17 05:18:52,044 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1916.67326593s left)
2019-06-17 05:18:56,711 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617051856695260
2019-06-17 05:18:56,741 [salt.minion      :1432][INFO    ][9609] Starting a new job with PID 9609
2019-06-17 05:18:56,761 [salt.minion      :1711][INFO    ][9609] Returning information for job: 20190617051856695260
2019-06-17 05:19:25,176 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1883.54092979s left)
2019-06-17 05:19:26,843 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617051926829363
2019-06-17 05:19:26,869 [salt.minion      :1432][INFO    ][9742] Starting a new job with PID 9742
2019-06-17 05:19:26,893 [salt.minion      :1711][INFO    ][9742] Returning information for job: 20190617051926829363
2019-06-17 05:19:57,000 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617051956992314
2019-06-17 05:19:57,020 [salt.minion      :1432][INFO    ][10000] Starting a new job with PID 10000
2019-06-17 05:19:57,042 [salt.minion      :1711][INFO    ][10000] Returning information for job: 20190617051956992314
2019-06-17 05:19:58,098 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1850.61930299s left)
2019-06-17 05:20:27,140 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617052027119892
2019-06-17 05:20:27,172 [salt.minion      :1432][INFO    ][10087] Starting a new job with PID 10087
2019-06-17 05:20:27,196 [salt.minion      :1711][INFO    ][10087] Returning information for job: 20190617052027119892
2019-06-17 05:20:31,185 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1817.53194189s left)
2019-06-17 05:20:57,311 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617052057298919
2019-06-17 05:20:57,342 [salt.minion      :1432][INFO    ][10304] Starting a new job with PID 10304
2019-06-17 05:20:57,372 [salt.minion      :1711][INFO    ][10304] Returning information for job: 20190617052057298919
2019-06-17 05:21:04,162 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1784.55467701s left)
2019-06-17 05:21:27,483 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617052127465377
2019-06-17 05:21:27,512 [salt.minion      :1432][INFO    ][10425] Starting a new job with PID 10425
2019-06-17 05:21:27,540 [salt.minion      :1711][INFO    ][10425] Returning information for job: 20190617052127465377
2019-06-17 05:21:37,011 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1751.70605397s left)
2019-06-17 05:21:57,667 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617052157646396
2019-06-17 05:21:57,693 [salt.minion      :1432][INFO    ][10632] Starting a new job with PID 10632
2019-06-17 05:21:57,730 [salt.minion      :1711][INFO    ][10632] Returning information for job: 20190617052157646396
2019-06-17 05:22:09,776 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1718.9407568s left)
2019-06-17 05:22:27,837 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617052227823578
2019-06-17 05:22:27,864 [salt.minion      :1432][INFO    ][10699] Starting a new job with PID 10699
2019-06-17 05:22:27,897 [salt.minion      :1711][INFO    ][10699] Returning information for job: 20190617052227823578
2019-06-17 05:22:42,858 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1685.85869384s left)
2019-06-17 05:22:58,029 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617052258017983
2019-06-17 05:22:58,053 [salt.minion      :1432][INFO    ][10860] Starting a new job with PID 10860
2019-06-17 05:22:58,083 [salt.minion      :1711][INFO    ][10860] Returning information for job: 20190617052258017983
2019-06-17 05:23:16,003 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1652.7143898s left)
2019-06-17 05:23:28,204 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617052328187506
2019-06-17 05:23:28,232 [salt.minion      :1432][INFO    ][10955] Starting a new job with PID 10955
2019-06-17 05:23:28,266 [salt.minion      :1711][INFO    ][10955] Returning information for job: 20190617052328187506
2019-06-17 05:23:49,041 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1619.67619085s left)
2019-06-17 05:23:58,401 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617052358383698
2019-06-17 05:23:58,428 [salt.minion      :1432][INFO    ][11071] Starting a new job with PID 11071
2019-06-17 05:23:58,463 [salt.minion      :1711][INFO    ][11071] Returning information for job: 20190617052358383698
2019-06-17 05:24:22,014 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1586.70330882s left)
2019-06-17 05:24:28,579 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617052428562332
2019-06-17 05:24:28,608 [salt.minion      :1432][INFO    ][11109] Starting a new job with PID 11109
2019-06-17 05:24:28,638 [salt.minion      :1711][INFO    ][11109] Returning information for job: 20190617052428562332
2019-06-17 05:24:54,945 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1553.77200484s left)
2019-06-17 05:24:58,785 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617052458771212
2019-06-17 05:24:58,812 [salt.minion      :1432][INFO    ][11306] Starting a new job with PID 11306
2019-06-17 05:24:58,843 [salt.minion      :1711][INFO    ][11306] Returning information for job: 20190617052458771212
2019-06-17 05:25:27,795 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1520.92168188s left)
2019-06-17 05:25:28,978 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617052528966260
2019-06-17 05:25:29,002 [salt.minion      :1432][INFO    ][11352] Starting a new job with PID 11352
2019-06-17 05:25:29,040 [salt.minion      :1711][INFO    ][11352] Returning information for job: 20190617052528966260
2019-06-17 05:25:59,048 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617052559034878
2019-06-17 05:25:59,074 [salt.minion      :1432][INFO    ][11493] Starting a new job with PID 11493
2019-06-17 05:25:59,112 [salt.minion      :1711][INFO    ][11493] Returning information for job: 20190617052559034878
2019-06-17 05:26:01,012 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1487.70497489s left)
2019-06-17 05:26:29,108 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617052629093419
2019-06-17 05:26:29,145 [salt.minion      :1432][INFO    ][11572] Starting a new job with PID 11572
2019-06-17 05:26:29,183 [salt.minion      :1711][INFO    ][11572] Returning information for job: 20190617052629093419
2019-06-17 05:26:34,011 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1454.70553184s left)
2019-06-17 05:26:59,153 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617052659139881
2019-06-17 05:26:59,182 [salt.minion      :1432][INFO    ][11732] Starting a new job with PID 11732
2019-06-17 05:26:59,211 [salt.minion      :1711][INFO    ][11732] Returning information for job: 20190617052659139881
2019-06-17 05:27:07,012 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1421.70493984s left)
2019-06-17 05:27:29,182 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617052729163125
2019-06-17 05:27:29,211 [salt.minion      :1432][INFO    ][11756] Starting a new job with PID 11756
2019-06-17 05:27:29,241 [salt.minion      :1711][INFO    ][11756] Returning information for job: 20190617052729163125
2019-06-17 05:27:39,828 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1388.88883996s left)
2019-06-17 05:27:59,231 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617052759216544
2019-06-17 05:27:59,261 [salt.minion      :1432][INFO    ][11813] Starting a new job with PID 11813
2019-06-17 05:27:59,299 [salt.minion      :1711][INFO    ][11813] Returning information for job: 20190617052759216544
2019-06-17 05:28:12,780 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1355.93677783s left)
2019-06-17 05:28:29,315 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617052829302367
2019-06-17 05:28:29,352 [salt.minion      :1432][INFO    ][11849] Starting a new job with PID 11849
2019-06-17 05:28:29,405 [salt.minion      :1711][INFO    ][11849] Returning information for job: 20190617052829302367
2019-06-17 05:28:45,617 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1323.10029888s left)
2019-06-17 05:28:59,417 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617052859401226
2019-06-17 05:28:59,443 [salt.minion      :1432][INFO    ][11894] Starting a new job with PID 11894
2019-06-17 05:28:59,475 [salt.minion      :1711][INFO    ][11894] Returning information for job: 20190617052859401226
2019-06-17 05:29:18,395 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1290.32195282s left)
2019-06-17 05:29:29,500 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617052929486999
2019-06-17 05:29:29,525 [salt.minion      :1432][INFO    ][11927] Starting a new job with PID 11927
2019-06-17 05:29:29,552 [salt.minion      :1711][INFO    ][11927] Returning information for job: 20190617052929486999
2019-06-17 05:29:51,075 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1257.64211679s left)
2019-06-17 05:29:59,572 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617052959563061
2019-06-17 05:29:59,593 [salt.minion      :1432][INFO    ][11977] Starting a new job with PID 11977
2019-06-17 05:29:59,622 [salt.minion      :1711][INFO    ][11977] Returning information for job: 20190617052959563061
2019-06-17 05:30:23,925 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1224.79212689s left)
2019-06-17 05:30:29,671 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617053029658056
2019-06-17 05:30:29,699 [salt.minion      :1432][INFO    ][12013] Starting a new job with PID 12013
2019-06-17 05:30:29,733 [salt.minion      :1711][INFO    ][12013] Returning information for job: 20190617053029658056
2019-06-17 05:30:56,915 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1191.80173182s left)
2019-06-17 05:30:59,779 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617053059770425
2019-06-17 05:30:59,808 [salt.minion      :1432][INFO    ][12059] Starting a new job with PID 12059
2019-06-17 05:30:59,836 [salt.minion      :1711][INFO    ][12059] Returning information for job: 20190617053059770425
2019-06-17 05:31:29,875 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1158.84214497s left)
2019-06-17 05:31:29,913 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617053129900468
2019-06-17 05:31:29,940 [salt.minion      :1432][INFO    ][12094] Starting a new job with PID 12094
2019-06-17 05:31:29,968 [salt.minion      :1711][INFO    ][12094] Returning information for job: 20190617053129900468
2019-06-17 05:32:00,054 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617053200046289
2019-06-17 05:32:00,072 [salt.minion      :1432][INFO    ][12135] Starting a new job with PID 12135
2019-06-17 05:32:00,107 [salt.minion      :1711][INFO    ][12135] Returning information for job: 20190617053200046289
2019-06-17 05:32:02,523 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1126.19409084s left)
2019-06-17 05:32:30,185 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617053230171066
2019-06-17 05:32:30,211 [salt.minion      :1432][INFO    ][12169] Starting a new job with PID 12169
2019-06-17 05:32:30,243 [salt.minion      :1711][INFO    ][12169] Returning information for job: 20190617053230171066
2019-06-17 05:32:35,241 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1093.47554684s left)
2019-06-17 05:33:00,358 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617053300341130
2019-06-17 05:33:00,383 [salt.minion      :1432][INFO    ][12213] Starting a new job with PID 12213
2019-06-17 05:33:00,416 [salt.minion      :1711][INFO    ][12213] Returning information for job: 20190617053300341130
2019-06-17 05:33:08,149 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1060.56842899s left)
2019-06-17 05:33:30,535 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617053330522721
2019-06-17 05:33:30,561 [salt.minion      :1432][INFO    ][12250] Starting a new job with PID 12250
2019-06-17 05:33:30,593 [salt.minion      :1711][INFO    ][12250] Returning information for job: 20190617053330522721
2019-06-17 05:33:41,060 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1027.65703583s left)
2019-06-17 05:34:00,732 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617053400724182
2019-06-17 05:34:00,759 [salt.minion      :1432][INFO    ][12294] Starting a new job with PID 12294
2019-06-17 05:34:00,789 [salt.minion      :1711][INFO    ][12294] Returning information for job: 20190617053400724182
2019-06-17 05:34:13,725 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (994.99250102s left)
2019-06-17 05:34:30,940 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617053430927872
2019-06-17 05:34:30,965 [salt.minion      :1432][INFO    ][12331] Starting a new job with PID 12331
2019-06-17 05:34:30,996 [salt.minion      :1711][INFO    ][12331] Returning information for job: 20190617053430927872
2019-06-17 05:34:46,661 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (962.056150913s left)
2019-06-17 05:35:00,969 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617053500960182
2019-06-17 05:35:00,996 [salt.minion      :1432][INFO    ][12394] Starting a new job with PID 12394
2019-06-17 05:35:01,027 [salt.minion      :1711][INFO    ][12394] Returning information for job: 20190617053500960182
2019-06-17 05:35:19,284 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (929.432592869s left)
2019-06-17 05:35:30,993 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617053530984579
2019-06-17 05:35:31,019 [salt.minion      :1432][INFO    ][12429] Starting a new job with PID 12429
2019-06-17 05:35:31,055 [salt.minion      :1711][INFO    ][12429] Returning information for job: 20190617053530984579
2019-06-17 05:35:52,148 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (896.56889987s left)
2019-06-17 05:36:01,049 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617053601031864
2019-06-17 05:36:01,079 [salt.minion      :1432][INFO    ][12476] Starting a new job with PID 12476
2019-06-17 05:36:01,108 [salt.minion      :1711][INFO    ][12476] Returning information for job: 20190617053601031864
2019-06-17 05:36:25,015 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (863.702226877s left)
2019-06-17 05:36:31,120 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617053631104115
2019-06-17 05:36:31,148 [salt.minion      :1432][INFO    ][12511] Starting a new job with PID 12511
2019-06-17 05:36:31,185 [salt.minion      :1711][INFO    ][12511] Returning information for job: 20190617053631104115
2019-06-17 05:36:57,730 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (830.987092018s left)
2019-06-17 05:37:01,208 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617053701189532
2019-06-17 05:37:01,236 [salt.minion      :1432][INFO    ][12557] Starting a new job with PID 12557
2019-06-17 05:37:01,267 [salt.minion      :1711][INFO    ][12557] Returning information for job: 20190617053701189532
2019-06-17 05:37:30,664 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (798.053223848s left)
2019-06-17 05:37:31,301 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617053731288365
2019-06-17 05:37:31,325 [salt.minion      :1432][INFO    ][12594] Starting a new job with PID 12594
2019-06-17 05:37:31,357 [salt.minion      :1711][INFO    ][12594] Returning information for job: 20190617053731288365
2019-06-17 05:38:01,413 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617053801402389
2019-06-17 05:38:01,433 [salt.minion      :1432][INFO    ][12636] Starting a new job with PID 12636
2019-06-17 05:38:01,471 [salt.minion      :1711][INFO    ][12636] Returning information for job: 20190617053801402389
2019-06-17 05:38:03,430 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (765.287779808s left)
2019-06-17 05:38:31,536 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617053831523866
2019-06-17 05:38:31,564 [salt.minion      :1432][INFO    ][12667] Starting a new job with PID 12667
2019-06-17 05:38:31,602 [salt.minion      :1711][INFO    ][12667] Returning information for job: 20190617053831523866
2019-06-17 05:38:36,193 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (732.524246931s left)
2019-06-17 05:39:01,663 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617053901649285
2019-06-17 05:39:01,692 [salt.minion      :1432][INFO    ][12711] Starting a new job with PID 12711
2019-06-17 05:39:01,722 [salt.minion      :1711][INFO    ][12711] Returning information for job: 20190617053901649285
2019-06-17 05:39:09,056 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (699.661779881s left)
2019-06-17 05:39:31,821 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617053931808470
2019-06-17 05:39:31,852 [salt.minion      :1432][INFO    ][12742] Starting a new job with PID 12742
2019-06-17 05:39:31,880 [salt.minion      :1711][INFO    ][12742] Returning information for job: 20190617053931808470
2019-06-17 05:39:41,783 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (666.934458971s left)
2019-06-17 05:40:01,985 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617054001969293
2019-06-17 05:40:02,014 [salt.minion      :1432][INFO    ][12789] Starting a new job with PID 12789
2019-06-17 05:40:02,043 [salt.minion      :1711][INFO    ][12789] Returning information for job: 20190617054001969293
2019-06-17 05:40:14,683 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (634.033686876s left)
2019-06-17 05:40:32,217 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617054032201654
2019-06-17 05:40:32,248 [salt.minion      :1432][INFO    ][12823] Starting a new job with PID 12823
2019-06-17 05:40:32,280 [salt.minion      :1711][INFO    ][12823] Returning information for job: 20190617054032201654
2019-06-17 05:40:47,503 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (601.214283943s left)
2019-06-17 05:41:02,248 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617054102234336
2019-06-17 05:41:02,274 [salt.minion      :1432][INFO    ][12872] Starting a new job with PID 12872
2019-06-17 05:41:02,310 [salt.minion      :1711][INFO    ][12872] Returning information for job: 20190617054102234336
2019-06-17 05:41:20,249 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (568.46851182s left)
2019-06-17 05:41:32,312 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617054132301091
2019-06-17 05:41:32,346 [salt.minion      :1432][INFO    ][12905] Starting a new job with PID 12905
2019-06-17 05:41:32,396 [salt.minion      :1711][INFO    ][12905] Returning information for job: 20190617054132301091
2019-06-17 05:41:52,977 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (535.740542889s left)
2019-06-17 05:42:02,399 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617054202385821
2019-06-17 05:42:02,433 [salt.minion      :1432][INFO    ][12946] Starting a new job with PID 12946
2019-06-17 05:42:02,468 [salt.minion      :1711][INFO    ][12946] Returning information for job: 20190617054202385821
2019-06-17 05:42:25,863 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (502.855808973s left)
2019-06-17 05:42:32,502 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617054232489080
2019-06-17 05:42:32,532 [salt.minion      :1432][INFO    ][12979] Starting a new job with PID 12979
2019-06-17 05:42:32,565 [salt.minion      :1711][INFO    ][12979] Returning information for job: 20190617054232489080
2019-06-17 05:42:58,784 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (469.93311286s left)
2019-06-17 05:43:02,545 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617054302533384
2019-06-17 05:43:02,567 [salt.minion      :1432][INFO    ][13024] Starting a new job with PID 13024
2019-06-17 05:43:02,611 [salt.minion      :1711][INFO    ][13024] Returning information for job: 20190617054302533384
2019-06-17 05:43:31,732 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (436.984679937s left)
2019-06-17 05:43:32,655 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617054332644260
2019-06-17 05:43:32,676 [salt.minion      :1432][INFO    ][13061] Starting a new job with PID 13061
2019-06-17 05:43:32,717 [salt.minion      :1711][INFO    ][13061] Returning information for job: 20190617054332644260
2019-06-17 05:44:02,840 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617054402832026
2019-06-17 05:44:02,857 [salt.minion      :1432][INFO    ][13106] Starting a new job with PID 13106
2019-06-17 05:44:02,893 [salt.minion      :1711][INFO    ][13106] Returning information for job: 20190617054402832026
2019-06-17 05:44:04,614 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (404.102790833s left)
2019-06-17 05:44:32,993 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617054432980141
2019-06-17 05:44:33,018 [salt.minion      :1432][INFO    ][13269] Starting a new job with PID 13269
2019-06-17 05:44:33,058 [salt.minion      :1711][INFO    ][13269] Returning information for job: 20190617054432980141
2019-06-17 05:44:37,391 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (371.326515913s left)
2019-06-17 05:45:03,188 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617054503172446
2019-06-17 05:45:03,216 [salt.minion      :1432][INFO    ][13330] Starting a new job with PID 13330
2019-06-17 05:45:03,256 [salt.minion      :1711][INFO    ][13330] Returning information for job: 20190617054503172446
2019-06-17 05:45:10,179 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (338.537811995s left)
2019-06-17 05:45:33,407 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617054533391694
2019-06-17 05:45:33,437 [salt.minion      :1432][INFO    ][13363] Starting a new job with PID 13363
2019-06-17 05:45:33,473 [salt.minion      :1711][INFO    ][13363] Returning information for job: 20190617054533391694
2019-06-17 05:45:43,102 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (305.615304947s left)
2019-06-17 05:46:03,636 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617054603620557
2019-06-17 05:46:03,664 [salt.minion      :1432][INFO    ][13408] Starting a new job with PID 13408
2019-06-17 05:46:03,700 [salt.minion      :1711][INFO    ][13408] Returning information for job: 20190617054603620557
2019-06-17 05:46:15,894 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (272.822751999s left)
2019-06-17 05:46:33,856 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617054633839766
2019-06-17 05:46:33,880 [salt.minion      :1432][INFO    ][13441] Starting a new job with PID 13441
2019-06-17 05:46:33,910 [salt.minion      :1711][INFO    ][13441] Returning information for job: 20190617054633839766
2019-06-17 05:46:48,777 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (239.939886808s left)
2019-06-17 05:47:03,898 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617054703889697
2019-06-17 05:47:03,925 [salt.minion      :1432][INFO    ][13487] Starting a new job with PID 13487
2019-06-17 05:47:03,956 [salt.minion      :1711][INFO    ][13487] Returning information for job: 20190617054703889697
2019-06-17 05:47:21,582 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (207.135001898s left)
2019-06-17 05:47:33,944 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617054733933177
2019-06-17 05:47:33,972 [salt.minion      :1432][INFO    ][13526] Starting a new job with PID 13526
2019-06-17 05:47:34,003 [salt.minion      :1711][INFO    ][13526] Returning information for job: 20190617054733933177
2019-06-17 05:47:54,450 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (174.267336845s left)
2019-06-17 05:48:04,036 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617054804022484
2019-06-17 05:48:04,064 [salt.minion      :1432][INFO    ][13569] Starting a new job with PID 13569
2019-06-17 05:48:04,099 [salt.minion      :1711][INFO    ][13569] Returning information for job: 20190617054804022484
2019-06-17 05:48:27,151 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (141.565541983s left)
2019-06-17 05:48:34,103 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617054834094850
2019-06-17 05:48:34,129 [salt.minion      :1432][INFO    ][13606] Starting a new job with PID 13606
2019-06-17 05:48:34,160 [salt.minion      :1711][INFO    ][13606] Returning information for job: 20190617054834094850
2019-06-17 05:48:59,849 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (108.868177891s left)
2019-06-17 05:49:04,244 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617054904227506
2019-06-17 05:49:04,272 [salt.minion      :1432][INFO    ][13651] Starting a new job with PID 13651
2019-06-17 05:49:04,303 [salt.minion      :1711][INFO    ][13651] Returning information for job: 20190617054904227506
2019-06-17 05:49:32,727 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (75.9902238846s left)
2019-06-17 05:49:34,415 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617054934399924
2019-06-17 05:49:34,440 [salt.minion      :1432][INFO    ][13685] Starting a new job with PID 13685
2019-06-17 05:49:34,471 [salt.minion      :1711][INFO    ][13685] Returning information for job: 20190617054934399924
2019-06-17 05:50:04,607 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617055004597304
2019-06-17 05:50:04,631 [salt.minion      :1432][INFO    ][13731] Starting a new job with PID 13731
2019-06-17 05:50:04,661 [salt.minion      :1711][INFO    ][13731] Returning information for job: 20190617055004597304
2019-06-17 05:50:05,619 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (43.0976638794s left)
2019-06-17 05:50:34,812 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617055034801106
2019-06-17 05:50:34,843 [salt.minion      :1432][INFO    ][13761] Starting a new job with PID 13761
2019-06-17 05:50:34,875 [salt.minion      :1711][INFO    ][13761] Returning information for job: 20190617055034801106
2019-06-17 05:50:38,290 [salt.loaded.ext.module.maas:1023][INFO    ][8202] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (10.4267609119s left)
2019-06-17 05:51:04,956 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617055104943207
2019-06-17 05:51:04,984 [salt.minion      :1432][INFO    ][13806] Starting a new job with PID 13806
2019-06-17 05:51:05,014 [salt.minion      :1711][INFO    ][13806] Returning information for job: 20190617055104943207
2019-06-17 05:51:11,248 [salt.state       :302 ][ERROR   ][8202] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-06-17 05:51:11,248 [salt.state       :1951][INFO    ][8202] Completed state [maas.wait_for_machine_status] at time 05:51:11.248828 duration_in_ms=2272541.123
2019-06-17 05:51:11,256 [salt.minion      :1711][INFO    ][8202] Returning information for job: 20190617051310565363
2019-06-17 05:51:22,244 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command pillar.get with jid 20190617055122231270
2019-06-17 05:51:22,272 [salt.minion      :1432][INFO    ][13827] Starting a new job with PID 13827
2019-06-17 05:51:22,282 [salt.minion      :1711][INFO    ][13827] Returning information for job: 20190617055122231270
2019-06-17 05:51:22,999 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command service.status with jid 20190617055122987715
2019-06-17 05:51:23,027 [salt.minion      :1432][INFO    ][13832] Starting a new job with PID 13832
2019-06-17 05:51:23,817 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13832] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-06-17 05:51:23,861 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13832] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-06-17 05:51:23,883 [salt.minion      :1711][INFO    ][13832] Returning information for job: 20190617055122987715
2019-06-17 05:51:24,608 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command state.apply with jid 20190617055124594968
2019-06-17 05:51:24,636 [salt.minion      :1432][INFO    ][13843] Starting a new job with PID 13843
2019-06-17 05:51:30,625 [salt.state       :915 ][INFO    ][13843] Loading fresh modules for state activity
2019-06-17 05:51:31,227 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13843] Executing command 'salt-minion --version' in directory '/root'
2019-06-17 05:51:31,566 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13843] Executing command 'salt-minion --version' in directory '/root'
2019-06-17 05:51:32,635 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13843] Executing command 'salt-minion --version' in directory '/root'
2019-06-17 05:51:33,019 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13843] Executing command 'salt-minion --version' in directory '/root'
2019-06-17 05:51:35,280 [salt.state       :1780][INFO    ][13843] Running state [salt-minion] at time 05:51:35.280220
2019-06-17 05:51:35,280 [salt.state       :1813][INFO    ][13843] Executing state pkg.installed for [salt-minion]
2019-06-17 05:51:35,281 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13843] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-17 05:51:35,405 [salt.state       :300 ][INFO    ][13843] All specified packages are already installed
2019-06-17 05:51:35,406 [salt.state       :1951][INFO    ][13843] Completed state [salt-minion] at time 05:51:35.406168 duration_in_ms=125.948
2019-06-17 05:51:35,406 [salt.state       :1780][INFO    ][13843] Running state [salt_minion_dependency_packages] at time 05:51:35.406529
2019-06-17 05:51:35,406 [salt.state       :1813][INFO    ][13843] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-06-17 05:51:35,416 [salt.state       :300 ][INFO    ][13843] All specified packages are already installed
2019-06-17 05:51:35,416 [salt.state       :1951][INFO    ][13843] Completed state [salt_minion_dependency_packages] at time 05:51:35.416620 duration_in_ms=10.091
2019-06-17 05:51:35,419 [salt.state       :1780][INFO    ][13843] Running state [/etc/salt/minion.d/minion.conf] at time 05:51:35.419843
2019-06-17 05:51:35,420 [salt.state       :1813][INFO    ][13843] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-06-17 05:51:35,691 [salt.state       :300 ][INFO    ][13843] File /etc/salt/minion.d/minion.conf is in the correct state
2019-06-17 05:51:35,691 [salt.state       :1951][INFO    ][13843] Completed state [/etc/salt/minion.d/minion.conf] at time 05:51:35.691519 duration_in_ms=271.675
2019-06-17 05:51:35,691 [salt.state       :1780][INFO    ][13843] Running state [python-netaddr] at time 05:51:35.691814
2019-06-17 05:51:35,692 [salt.state       :1813][INFO    ][13843] Executing state pkg.installed for [python-netaddr]
2019-06-17 05:51:35,709 [salt.state       :300 ][INFO    ][13843] All specified packages are already installed
2019-06-17 05:51:35,709 [salt.state       :1951][INFO    ][13843] Completed state [python-netaddr] at time 05:51:35.709629 duration_in_ms=17.814
2019-06-17 05:51:35,713 [salt.state       :1780][INFO    ][13843] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 05:51:35.712997
2019-06-17 05:51:35,713 [salt.state       :1813][INFO    ][13843] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-06-17 05:51:35,731 [salt.state       :300 ][INFO    ][13843] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-06-17 05:51:35,732 [salt.state       :1951][INFO    ][13843] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 05:51:35.732309 duration_in_ms=19.311
2019-06-17 05:51:35,735 [salt.state       :1780][INFO    ][13843] Running state [salt-minion] at time 05:51:35.734935
2019-06-17 05:51:35,735 [salt.state       :1813][INFO    ][13843] Executing state service.running for [salt-minion]
2019-06-17 05:51:35,736 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13843] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-06-17 05:51:35,784 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13843] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-06-17 05:51:35,806 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13843] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-06-17 05:51:35,829 [salt.state       :300 ][INFO    ][13843] The service salt-minion is already running
2019-06-17 05:51:35,830 [salt.state       :1951][INFO    ][13843] Completed state [salt-minion] at time 05:51:35.830157 duration_in_ms=95.223
2019-06-17 05:51:35,833 [salt.state       :1780][INFO    ][13843] Running state [/etc/salt/grains.d] at time 05:51:35.833007
2019-06-17 05:51:35,833 [salt.state       :1813][INFO    ][13843] Executing state file.directory for [/etc/salt/grains.d]
2019-06-17 05:51:35,837 [salt.state       :300 ][INFO    ][13843] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-06-17 05:51:35,837 [salt.state       :1951][INFO    ][13843] Completed state [/etc/salt/grains.d] at time 05:51:35.837424 duration_in_ms=4.417
2019-06-17 05:51:35,838 [salt.state       :1780][INFO    ][13843] Running state [/etc/salt/grains] at time 05:51:35.838648
2019-06-17 05:51:35,839 [salt.state       :1813][INFO    ][13843] Executing state file.managed for [/etc/salt/grains]
2019-06-17 05:51:35,839 [salt.state       :300 ][INFO    ][13843] File /etc/salt/grains exists with proper permissions. No changes made.
2019-06-17 05:51:35,840 [salt.state       :1951][INFO    ][13843] Completed state [/etc/salt/grains] at time 05:51:35.840099 duration_in_ms=1.451
2019-06-17 05:51:35,840 [salt.state       :1780][INFO    ][13843] Running state [/etc/salt/grains.d/placeholder] at time 05:51:35.840752
2019-06-17 05:51:35,841 [salt.state       :1813][INFO    ][13843] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-06-17 05:51:35,842 [salt.state       :300 ][INFO    ][13843] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-06-17 05:51:35,842 [salt.state       :1951][INFO    ][13843] Completed state [/etc/salt/grains.d/placeholder] at time 05:51:35.842556 duration_in_ms=1.804
2019-06-17 05:51:35,843 [salt.state       :1780][INFO    ][13843] Running state [/etc/salt/grains.d/sphinx] at time 05:51:35.843218
2019-06-17 05:51:35,843 [salt.state       :1813][INFO    ][13843] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-06-17 05:51:35,845 [salt.state       :300 ][INFO    ][13843] File /etc/salt/grains.d/sphinx is in the correct state
2019-06-17 05:51:35,845 [salt.state       :1951][INFO    ][13843] Completed state [/etc/salt/grains.d/sphinx] at time 05:51:35.845577 duration_in_ms=2.359
2019-06-17 05:51:35,850 [salt.state       :1780][INFO    ][13843] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 05:51:35.850286
2019-06-17 05:51:35,850 [salt.state       :1813][INFO    ][13843] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-06-17 05:51:35,851 [salt.state       :300 ][INFO    ][13843] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-06-17 05:51:35,851 [salt.state       :1951][INFO    ][13843] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 05:51:35.851785 duration_in_ms=1.498
2019-06-17 05:51:35,852 [salt.state       :1780][INFO    ][13843] Running state [/etc/salt/grains.d/dns_records] at time 05:51:35.852683
2019-06-17 05:51:35,853 [salt.state       :1813][INFO    ][13843] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-06-17 05:51:35,855 [salt.state       :300 ][INFO    ][13843] File /etc/salt/grains.d/dns_records is in the correct state
2019-06-17 05:51:35,855 [salt.state       :1951][INFO    ][13843] Completed state [/etc/salt/grains.d/dns_records] at time 05:51:35.855390 duration_in_ms=2.708
2019-06-17 05:51:35,857 [salt.state       :1780][INFO    ][13843] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 05:51:35.857074
2019-06-17 05:51:35,857 [salt.state       :1813][INFO    ][13843] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-06-17 05:51:35,858 [salt.state       :300 ][INFO    ][13843] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-06-17 05:51:35,859 [salt.state       :1951][INFO    ][13843] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 05:51:35.859107 duration_in_ms=2.035
2019-06-17 05:51:35,859 [salt.state       :1780][INFO    ][13843] Running state [/etc/salt/grains.d/salt] at time 05:51:35.859632
2019-06-17 05:51:35,859 [salt.state       :1813][INFO    ][13843] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-06-17 05:51:35,861 [salt.state       :300 ][INFO    ][13843] File /etc/salt/grains.d/salt is in the correct state
2019-06-17 05:51:35,861 [salt.state       :1951][INFO    ][13843] Completed state [/etc/salt/grains.d/salt] at time 05:51:35.861253 duration_in_ms=1.621
2019-06-17 05:51:35,862 [salt.state       :1780][INFO    ][13843] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 05:51:35.862505
2019-06-17 05:51:35,862 [salt.state       :1813][INFO    ][13843] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-06-17 05:51:35,863 [salt.state       :300 ][INFO    ][13843] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-06-17 05:51:35,863 [salt.state       :1951][INFO    ][13843] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 05:51:35.863332 duration_in_ms=0.828
2019-06-17 05:51:35,865 [salt.state       :1780][INFO    ][13843] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 05:51:35.865256
2019-06-17 05:51:35,865 [salt.state       :1813][INFO    ][13843] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-06-17 05:51:35,865 [salt.state       :300 ][INFO    ][13843] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-06-17 05:51:35,866 [salt.state       :1951][INFO    ][13843] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 05:51:35.866121 duration_in_ms=0.865
2019-06-17 05:51:35,866 [salt.state       :1780][INFO    ][13843] Running state [mine.update] at time 05:51:35.866811
2019-06-17 05:51:35,867 [salt.state       :1813][INFO    ][13843] Executing state module.wait for [mine.update]
2019-06-17 05:51:35,867 [salt.state       :300 ][INFO    ][13843] No changes made for mine.update
2019-06-17 05:51:35,867 [salt.state       :1951][INFO    ][13843] Completed state [mine.update] at time 05:51:35.867595 duration_in_ms=0.784
2019-06-17 05:51:35,867 [salt.state       :1780][INFO    ][13843] Running state [ca-certificates] at time 05:51:35.867874
2019-06-17 05:51:35,868 [salt.state       :1813][INFO    ][13843] Executing state pkg.installed for [ca-certificates]
2019-06-17 05:51:35,879 [salt.state       :300 ][INFO    ][13843] All specified packages are already installed
2019-06-17 05:51:35,879 [salt.state       :1951][INFO    ][13843] Completed state [ca-certificates] at time 05:51:35.879581 duration_in_ms=11.706
2019-06-17 05:51:35,880 [salt.state       :1780][INFO    ][13843] Running state [update-ca-certificates] at time 05:51:35.880307
2019-06-17 05:51:35,880 [salt.state       :1813][INFO    ][13843] Executing state cmd.wait for [update-ca-certificates]
2019-06-17 05:51:35,880 [salt.state       :300 ][INFO    ][13843] No changes made for update-ca-certificates
2019-06-17 05:51:35,881 [salt.state       :1951][INFO    ][13843] Completed state [update-ca-certificates] at time 05:51:35.881134 duration_in_ms=0.827
2019-06-17 05:51:35,881 [salt.state       :1780][INFO    ][13843] Running state [iptables] at time 05:51:35.881413
2019-06-17 05:51:35,882 [salt.state       :1813][INFO    ][13843] Executing state pkg.installed for [iptables]
2019-06-17 05:51:35,891 [salt.state       :300 ][INFO    ][13843] All specified packages are already installed
2019-06-17 05:51:35,891 [salt.state       :1951][INFO    ][13843] Completed state [iptables] at time 05:51:35.891467 duration_in_ms=10.054
2019-06-17 05:51:35,891 [salt.state       :1780][INFO    ][13843] Running state [iptables-persistent] at time 05:51:35.891746
2019-06-17 05:51:35,892 [salt.state       :1813][INFO    ][13843] Executing state pkg.installed for [iptables-persistent]
2019-06-17 05:51:35,901 [salt.state       :300 ][INFO    ][13843] All specified packages are already installed
2019-06-17 05:51:35,902 [salt.state       :1951][INFO    ][13843] Completed state [iptables-persistent] at time 05:51:35.902368 duration_in_ms=10.621
2019-06-17 05:51:35,903 [salt.state       :1780][INFO    ][13843] Running state [iptables_modules_v4_load] at time 05:51:35.903409
2019-06-17 05:51:35,903 [salt.state       :1813][INFO    ][13843] Executing state kmod.present for [iptables_modules_v4_load]
2019-06-17 05:51:35,904 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13843] Executing command 'lsmod' in directory '/root'
2019-06-17 05:51:35,932 [salt.state       :300 ][INFO    ][13843] Kernel modules iptable_filter, ip_tables are already present
2019-06-17 05:51:35,932 [salt.state       :1951][INFO    ][13843] Completed state [iptables_modules_v4_load] at time 05:51:35.932511 duration_in_ms=29.101
2019-06-17 05:51:35,933 [salt.state       :1780][INFO    ][13843] Running state [/etc/iptables/rules.v4] at time 05:51:35.933185
2019-06-17 05:51:35,933 [salt.state       :1813][INFO    ][13843] Executing state file.managed for [/etc/iptables/rules.v4]
2019-06-17 05:51:36,040 [salt.state       :300 ][INFO    ][13843] File /etc/iptables/rules.v4 is in the correct state
2019-06-17 05:51:36,041 [salt.state       :1951][INFO    ][13843] Completed state [/etc/iptables/rules.v4] at time 05:51:36.040992 duration_in_ms=107.807
2019-06-17 05:51:36,042 [salt.state       :1780][INFO    ][13843] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 05:51:36.042523
2019-06-17 05:51:36,042 [salt.state       :1813][INFO    ][13843] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-06-17 05:51:36,043 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13843] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-06-17 05:51:36,066 [salt.state       :300 ][INFO    ][13843] onlyif execution failed
2019-06-17 05:51:36,066 [salt.state       :1951][INFO    ][13843] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 05:51:36.066645 duration_in_ms=24.121
2019-06-17 05:51:36,068 [salt.state       :1780][INFO    ][13843] Running state [netfilter-persistent] at time 05:51:36.068181
2019-06-17 05:51:36,068 [salt.state       :1813][INFO    ][13843] Executing state service.running for [netfilter-persistent]
2019-06-17 05:51:36,069 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13843] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-06-17 05:51:36,094 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13843] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-06-17 05:51:36,119 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13843] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-06-17 05:51:36,144 [salt.state       :300 ][INFO    ][13843] The service netfilter-persistent is already running
2019-06-17 05:51:36,144 [salt.state       :1951][INFO    ][13843] Completed state [netfilter-persistent] at time 05:51:36.144548 duration_in_ms=76.366
2019-06-17 05:51:36,147 [salt.state       :1780][INFO    ][13843] Running state [iptables_extra.remove_stale_tables] at time 05:51:36.147428
2019-06-17 05:51:36,147 [salt.state       :1813][INFO    ][13843] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-06-17 05:51:36,148 [salt.state       :300 ][INFO    ][13843] No changes made for iptables_extra.remove_stale_tables
2019-06-17 05:51:36,148 [salt.state       :1951][INFO    ][13843] Completed state [iptables_extra.remove_stale_tables] at time 05:51:36.148597 duration_in_ms=1.169
2019-06-17 05:51:36,148 [salt.state       :1780][INFO    ][13843] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 05:51:36.148931
2019-06-17 05:51:36,149 [salt.state       :1813][INFO    ][13843] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-06-17 05:51:36,150 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13843] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-06-17 05:51:36,167 [salt.state       :300 ][INFO    ][13843] onlyif execution failed
2019-06-17 05:51:36,167 [salt.state       :1951][INFO    ][13843] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 05:51:36.167549 duration_in_ms=18.618
2019-06-17 05:51:36,168 [salt.state       :1780][INFO    ][13843] Running state [/etc/iptables/rules.v6] at time 05:51:36.168707
2019-06-17 05:51:36,169 [salt.state       :1813][INFO    ][13843] Executing state file.absent for [/etc/iptables/rules.v6]
2019-06-17 05:51:36,169 [salt.state       :300 ][INFO    ][13843] File /etc/iptables/rules.v6 is not present
2019-06-17 05:51:36,172 [salt.state       :1951][INFO    ][13843] Completed state [/etc/iptables/rules.v6] at time 05:51:36.172221 duration_in_ms=3.514
2019-06-17 05:51:36,173 [salt.state       :1780][INFO    ][13843] Running state [iptables_extra.flush_all] at time 05:51:36.173362
2019-06-17 05:51:36,174 [salt.state       :1813][INFO    ][13843] Executing state module.wait for [iptables_extra.flush_all]
2019-06-17 05:51:36,174 [salt.state       :300 ][INFO    ][13843] No changes made for iptables_extra.flush_all
2019-06-17 05:51:36,174 [salt.state       :1951][INFO    ][13843] Completed state [iptables_extra.flush_all] at time 05:51:36.174877 duration_in_ms=1.516
2019-06-17 05:51:36,180 [salt.minion      :1711][INFO    ][13843] Returning information for job: 20190617055124594968
2019-06-17 05:51:36,888 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command state.apply with jid 20190617055136879230
2019-06-17 05:51:36,913 [salt.minion      :1432][INFO    ][13931] Starting a new job with PID 13931
2019-06-17 05:51:37,866 [salt.state       :915 ][INFO    ][13931] Loading fresh modules for state activity
2019-06-17 05:51:39,176 [salt.state       :1780][INFO    ][13931] Running state [maas-rack-controller] at time 05:51:39.175992
2019-06-17 05:51:39,176 [salt.state       :1813][INFO    ][13931] Executing state pkg.installed for [maas-rack-controller]
2019-06-17 05:51:39,177 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13931] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-17 05:51:39,312 [salt.state       :300 ][INFO    ][13931] All specified packages are already installed
2019-06-17 05:51:39,313 [salt.state       :1951][INFO    ][13931] Completed state [maas-rack-controller] at time 05:51:39.312975 duration_in_ms=136.984
2019-06-17 05:51:39,313 [salt.state       :1780][INFO    ][13931] Running state [ipmitool] at time 05:51:39.313343
2019-06-17 05:51:39,313 [salt.state       :1813][INFO    ][13931] Executing state pkg.installed for [ipmitool]
2019-06-17 05:51:39,323 [salt.state       :300 ][INFO    ][13931] All specified packages are already installed
2019-06-17 05:51:39,323 [salt.state       :1951][INFO    ][13931] Completed state [ipmitool] at time 05:51:39.323710 duration_in_ms=10.366
2019-06-17 05:51:39,328 [salt.state       :1780][INFO    ][13931] Running state [/etc/maas/rackd.conf] at time 05:51:39.327973
2019-06-17 05:51:39,328 [salt.state       :1813][INFO    ][13931] Executing state file.line for [/etc/maas/rackd.conf]
2019-06-17 05:51:39,329 [salt.state       :300 ][INFO    ][13931] No changes needed to be made
2019-06-17 05:51:39,329 [salt.state       :1951][INFO    ][13931] Completed state [/etc/maas/rackd.conf] at time 05:51:39.329508 duration_in_ms=1.536
2019-06-17 05:51:39,329 [salt.state       :1780][INFO    ][13931] Running state [/etc/maas/rackd.conf] at time 05:51:39.329816
2019-06-17 05:51:39,330 [salt.state       :1813][INFO    ][13931] Executing state file.managed for [/etc/maas/rackd.conf]
2019-06-17 05:51:39,330 [salt.loaded.int.states.file:2298][WARNING ][13931] 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-06-17 05:51:39,331 [salt.state       :300 ][INFO    ][13931] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-06-17 05:51:39,331 [salt.state       :1951][INFO    ][13931] Completed state [/etc/maas/rackd.conf] at time 05:51:39.331463 duration_in_ms=1.647
2019-06-17 05:51:39,332 [salt.state       :1780][INFO    ][13931] Running state [maas-rackd] at time 05:51:39.332511
2019-06-17 05:51:39,332 [salt.state       :1813][INFO    ][13931] Executing state service.running for [maas-rackd]
2019-06-17 05:51:39,333 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13931] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-06-17 05:51:39,382 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13931] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-06-17 05:51:39,407 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13931] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-06-17 05:51:39,427 [salt.state       :300 ][INFO    ][13931] The service maas-rackd is already running
2019-06-17 05:51:39,428 [salt.state       :1951][INFO    ][13931] Completed state [maas-rackd] at time 05:51:39.427875 duration_in_ms=95.361
2019-06-17 05:51:39,431 [salt.minion      :1711][INFO    ][13931] Returning information for job: 20190617055136879230
2019-06-17 05:51:40,135 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command state.apply with jid 20190617055140119954
2019-06-17 05:51:40,163 [salt.minion      :1432][INFO    ][13955] Starting a new job with PID 13955
2019-06-17 05:51:41,141 [salt.state       :915 ][INFO    ][13955] Loading fresh modules for state activity
2019-06-17 05:51:42,593 [salt.state       :1780][INFO    ][13955] Running state [maas-region-controller] at time 05:51:42.592951
2019-06-17 05:51:42,593 [salt.state       :1813][INFO    ][13955] Executing state pkg.installed for [maas-region-controller]
2019-06-17 05:51:42,594 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13955] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-17 05:51:42,715 [salt.state       :300 ][INFO    ][13955] All specified packages are already installed
2019-06-17 05:51:42,716 [salt.state       :1951][INFO    ][13955] Completed state [maas-region-controller] at time 05:51:42.716045 duration_in_ms=123.095
2019-06-17 05:51:42,716 [salt.state       :1780][INFO    ][13955] Running state [python-oauth] at time 05:51:42.716405
2019-06-17 05:51:42,716 [salt.state       :1813][INFO    ][13955] Executing state pkg.installed for [python-oauth]
2019-06-17 05:51:42,727 [salt.state       :300 ][INFO    ][13955] All specified packages are already installed
2019-06-17 05:51:42,728 [salt.state       :1951][INFO    ][13955] Completed state [python-oauth] at time 05:51:42.728164 duration_in_ms=11.758
2019-06-17 05:51:42,731 [salt.state       :1780][INFO    ][13955] Running state [/etc/maas/regiond.conf] at time 05:51:42.731407
2019-06-17 05:51:42,731 [salt.state       :1813][INFO    ][13955] Executing state file.replace for [/etc/maas/regiond.conf]
2019-06-17 05:51:42,738 [salt.state       :300 ][INFO    ][13955] No changes needed to be made
2019-06-17 05:51:42,739 [salt.state       :1951][INFO    ][13955] Completed state [/etc/maas/regiond.conf] at time 05:51:42.738970 duration_in_ms=7.563
2019-06-17 05:51:42,739 [salt.state       :1780][INFO    ][13955] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:51:42.739496
2019-06-17 05:51:42,739 [salt.state       :1813][INFO    ][13955] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-06-17 05:51:42,800 [salt.state       :300 ][INFO    ][13955] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-06-17 05:51:42,800 [salt.state       :1951][INFO    ][13955] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:51:42.800738 duration_in_ms=61.24
2019-06-17 05:51:42,802 [salt.state       :1780][INFO    ][13955] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:51:42.802154
2019-06-17 05:51:42,802 [salt.state       :1813][INFO    ][13955] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-06-17 05:51:42,812 [salt.state       :300 ][INFO    ][13955] No changes needed to be made
2019-06-17 05:51:42,812 [salt.state       :1951][INFO    ][13955] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:51:42.812501 duration_in_ms=10.348
2019-06-17 05:51:42,813 [salt.state       :1780][INFO    ][13955] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:51:42.813411
2019-06-17 05:51:42,814 [salt.state       :1813][INFO    ][13955] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-06-17 05:51:42,823 [salt.state       :300 ][INFO    ][13955] No changes needed to be made
2019-06-17 05:51:42,823 [salt.state       :1951][INFO    ][13955] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:51:42.823842 duration_in_ms=10.431
2019-06-17 05:51:42,824 [salt.state       :1780][INFO    ][13955] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:51:42.824863
2019-06-17 05:51:42,825 [salt.state       :1813][INFO    ][13955] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-06-17 05:51:42,859 [salt.state       :300 ][INFO    ][13955] No changes needed to be made
2019-06-17 05:51:42,859 [salt.state       :1951][INFO    ][13955] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:51:42.859627 duration_in_ms=34.764
2019-06-17 05:51:42,860 [salt.state       :1780][INFO    ][13955] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:51:42.860200
2019-06-17 05:51:42,860 [salt.state       :1813][INFO    ][13955] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-06-17 05:51:42,875 [salt.state       :300 ][INFO    ][13955] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-06-17 05:51:42,876 [salt.state       :1951][INFO    ][13955] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:51:42.876099 duration_in_ms=15.899
2019-06-17 05:51:42,877 [salt.state       :1780][INFO    ][13955] Running state [a2enmod headers] at time 05:51:42.877540
2019-06-17 05:51:42,879 [salt.state       :1813][INFO    ][13955] Executing state cmd.run for [a2enmod headers]
2019-06-17 05:51:42,880 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13955] Executing command 'a2enmod headers' in directory '/root'
2019-06-17 05:51:42,956 [salt.state       :300 ][INFO    ][13955] {'pid': 13977, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-06-17 05:51:42,957 [salt.state       :1951][INFO    ][13955] Completed state [a2enmod headers] at time 05:51:42.956795 duration_in_ms=79.253
2019-06-17 05:51:42,957 [salt.state       :1780][INFO    ][13955] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:51:42.957694
2019-06-17 05:51:42,958 [salt.state       :1813][INFO    ][13955] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-06-17 05:51:42,983 [salt.state       :300 ][INFO    ][13955] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-06-17 05:51:42,983 [salt.state       :1951][INFO    ][13955] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:51:42.983713 duration_in_ms=26.019
2019-06-17 05:51:42,984 [salt.state       :1780][INFO    ][13955] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:51:42.984555
2019-06-17 05:51:42,984 [salt.state       :1813][INFO    ][13955] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-06-17 05:51:43,067 [salt.state       :300 ][INFO    ][13955] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-06-17 05:51:43,067 [salt.state       :1951][INFO    ][13955] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:51:43.067305 duration_in_ms=82.75
2019-06-17 05:51:43,067 [salt.state       :1780][INFO    ][13955] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:51:43.067900
2019-06-17 05:51:43,068 [salt.state       :1813][INFO    ][13955] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-06-17 05:51:43,145 [salt.state       :300 ][INFO    ][13955] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-06-17 05:51:43,145 [salt.state       :1951][INFO    ][13955] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:51:43.145268 duration_in_ms=77.368
2019-06-17 05:51:43,145 [salt.state       :1780][INFO    ][13955] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:51:43.145819
2019-06-17 05:51:43,146 [salt.state       :1813][INFO    ][13955] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-06-17 05:51:43,236 [salt.state       :300 ][INFO    ][13955] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-06-17 05:51:43,237 [salt.state       :1951][INFO    ][13955] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:51:43.237073 duration_in_ms=91.253
2019-06-17 05:51:43,237 [salt.state       :1780][INFO    ][13955] Running state [/root/.pgpass] at time 05:51:43.237415
2019-06-17 05:51:43,238 [salt.state       :1813][INFO    ][13955] Executing state file.managed for [/root/.pgpass]
2019-06-17 05:51:43,295 [salt.state       :300 ][INFO    ][13955] File /root/.pgpass is in the correct state
2019-06-17 05:51:43,295 [salt.state       :1951][INFO    ][13955] Completed state [/root/.pgpass] at time 05:51:43.295775 duration_in_ms=58.36
2019-06-17 05:51:43,301 [salt.state       :1780][INFO    ][13955] Running state [maas-region syncdb --noinput] at time 05:51:43.301384
2019-06-17 05:51:43,301 [salt.state       :1813][INFO    ][13955] Executing state cmd.run for [maas-region syncdb --noinput]
2019-06-17 05:51:43,302 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13955] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-06-17 05:51:46,118 [salt.state       :300 ][INFO    ][13955] {'pid': 14005, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: contenttypes, sessions, auth, sites, maasserver, piston3, metadataserver\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-06-17 05:51:46,118 [salt.state       :1951][INFO    ][13955] Completed state [maas-region syncdb --noinput] at time 05:51:46.118683 duration_in_ms=2817.297
2019-06-17 05:51:46,119 [salt.state       :2022][WARNING ][13955] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-06-17 05:51:46,123 [salt.state       :1780][INFO    ][13955] Running state [maas-regiond] at time 05:51:46.123148
2019-06-17 05:51:46,123 [salt.state       :1813][INFO    ][13955] Executing state service.running for [maas-regiond]
2019-06-17 05:51:46,125 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13955] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-06-17 05:51:46,165 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13955] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-06-17 05:51:46,184 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13955] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-06-17 05:51:46,206 [salt.state       :300 ][INFO    ][13955] The service maas-regiond is already running
2019-06-17 05:51:46,207 [salt.state       :1951][INFO    ][13955] Completed state [maas-regiond] at time 05:51:46.206964 duration_in_ms=83.815
2019-06-17 05:51:46,210 [salt.state       :1780][INFO    ][13955] Running state [bind9] at time 05:51:46.210162
2019-06-17 05:51:46,210 [salt.state       :1813][INFO    ][13955] Executing state service.running for [bind9]
2019-06-17 05:51:46,212 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13955] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-06-17 05:51:46,232 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13955] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-06-17 05:51:46,248 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13955] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-06-17 05:51:46,267 [salt.state       :300 ][INFO    ][13955] The service bind9 is already running
2019-06-17 05:51:46,268 [salt.state       :1951][INFO    ][13955] Completed state [bind9] at time 05:51:46.268298 duration_in_ms=58.136
2019-06-17 05:51:46,271 [salt.state       :1780][INFO    ][13955] Running state [apache2] at time 05:51:46.271162
2019-06-17 05:51:46,271 [salt.state       :1813][INFO    ][13955] Executing state service.running for [apache2]
2019-06-17 05:51:46,272 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13955] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-06-17 05:51:46,293 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13955] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-06-17 05:51:46,309 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13955] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-06-17 05:51:46,340 [salt.state       :300 ][INFO    ][13955] The service apache2 is already running
2019-06-17 05:51:46,340 [salt.state       :1951][INFO    ][13955] Completed state [apache2] at time 05:51:46.340606 duration_in_ms=69.443
2019-06-17 05:51:46,343 [salt.state       :1780][INFO    ][13955] Running state [maasng.wait_for_http_code] at time 05:51:46.343392
2019-06-17 05:51:46,344 [salt.state       :1813][INFO    ][13955] Executing state module.run for [maasng.wait_for_http_code]
2019-06-17 05:51:46,344 [salt.utils.decorators:613 ][WARNING ][13955] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-17 05:51:46,361 [salt.state       :300 ][INFO    ][13955] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-06-17 05:51:46,362 [salt.state       :1951][INFO    ][13955] Completed state [maasng.wait_for_http_code] at time 05:51:46.362467 duration_in_ms=19.075
2019-06-17 05:51:46,363 [salt.state       :1780][INFO    ][13955] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:51:46.363651
2019-06-17 05:51:46,364 [salt.state       :1813][INFO    ][13955] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-06-17 05:51:46,364 [salt.state       :300 ][INFO    ][13955] /var/lib/maas/.setup_admin exists
2019-06-17 05:51:46,365 [salt.state       :1951][INFO    ][13955] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:51:46.365101 duration_in_ms=1.45
2019-06-17 05:51:46,366 [salt.state       :1780][INFO    ][13955] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:51:46.366131
2019-06-17 05:51:46,366 [salt.state       :1813][INFO    ][13955] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-17 05:51:46,367 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13955] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-17 05:51:48,262 [salt.state       :300 ][INFO    ][13955] {'pid': 14033, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-17 05:51:48,263 [salt.state       :1951][INFO    ][13955] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:51:48.262965 duration_in_ms=1896.834
2019-06-17 05:51:48,270 [salt.state       :1780][INFO    ][13955] Running state [maas_region_boot_source_resources_mirror] at time 05:51:48.270073
2019-06-17 05:51:48,270 [salt.state       :1813][INFO    ][13955] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-06-17 05:51:48,354 [salt.state       :300 ][INFO    ][13955] {'changes': {}}
2019-06-17 05:51:48,355 [salt.state       :1951][INFO    ][13955] Completed state [maas_region_boot_source_resources_mirror] at time 05:51:48.354977 duration_in_ms=84.904
2019-06-17 05:51:48,356 [salt.state       :1780][INFO    ][13955] Running state [maasng.boot_resources_import] at time 05:51:48.355966
2019-06-17 05:51:48,356 [salt.state       :1813][INFO    ][13955] Executing state module.run for [maasng.boot_resources_import]
2019-06-17 05:51:48,356 [salt.utils.decorators:613 ][WARNING ][13955] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-17 05:51:48,461 [salt.loaded.ext.module.maasng:1600][INFO    ][13955] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-06-17 05:51:53,511 [salt.loaded.ext.module.maasng:1600][INFO    ][13955] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-06-17 05:51:55,201 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617055155187354
2019-06-17 05:51:55,225 [salt.minion      :1432][INFO    ][14061] Starting a new job with PID 14061
2019-06-17 05:51:55,260 [salt.minion      :1711][INFO    ][14061] Returning information for job: 20190617055155187354
2019-06-17 05:51:58,615 [salt.state       :300 ][INFO    ][13955] {'ret': True}
2019-06-17 05:51:58,615 [salt.state       :1951][INFO    ][13955] Completed state [maasng.boot_resources_import] at time 05:51:58.615511 duration_in_ms=10259.544
2019-06-17 05:51:58,617 [salt.state       :1780][INFO    ][13955] Running state [maas_region_boot_sources_selection_xenial] at time 05:51:58.617106
2019-06-17 05:51:58,618 [salt.state       :1813][INFO    ][13955] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-06-17 05:51:58,798 [salt.state       :300 ][INFO    ][13955] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-06-17 05:51:58,799 [salt.state       :1951][INFO    ][13955] Completed state [maas_region_boot_sources_selection_xenial] at time 05:51:58.799029 duration_in_ms=181.924
2019-06-17 05:51:58,800 [salt.state       :1780][INFO    ][13955] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 05:51:58.800606
2019-06-17 05:51:58,801 [salt.state       :1813][INFO    ][13955] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-06-17 05:51:58,803 [salt.utils.decorators:613 ][WARNING ][13955] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-17 05:51:58,803 [salt.loaded.ext.module.maasng:1771][INFO    ][13955] boot-sources sync initiated for ALL Rack's
2019-06-17 05:51:59,927 [salt.state       :300 ][INFO    ][13955] {'ret': True}
2019-06-17 05:51:59,927 [salt.state       :1951][INFO    ][13955] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 05:51:59.927337 duration_in_ms=1126.73
2019-06-17 05:51:59,931 [salt.state       :1780][INFO    ][13955] Running state [maas.process_maas_config] at time 05:51:59.929657
2019-06-17 05:51:59,931 [salt.state       :1813][INFO    ][13955] Executing state module.run for [maas.process_maas_config]
2019-06-17 05:51:59,932 [salt.utils.decorators:613 ][WARNING ][13955] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-17 05:51:59,932 [salt.loaded.ext.module.maas:92  ][INFO    ][13955] maasconfig name=enable_http_proxy value=True
2019-06-17 05:51:59,986 [salt.loaded.ext.module.maas:92  ][INFO    ][13955] maasconfig name=upstream_dns value=8.8.8.8
2019-06-17 05:52:00,047 [salt.loaded.ext.module.maas:92  ][INFO    ][13955] maasconfig name=commissioning_distro_series value=xenial
2019-06-17 05:52:00,106 [salt.loaded.ext.module.maas:92  ][INFO    ][13955] maasconfig name=default_osystem value=ubuntu
2019-06-17 05:52:00,165 [salt.loaded.ext.module.maas:92  ][INFO    ][13955] maasconfig name=active_discovery_interval value=600
2019-06-17 05:52:00,217 [salt.loaded.ext.module.maas:92  ][INFO    ][13955] maasconfig name=dnssec_validation value=no
2019-06-17 05:52:00,280 [salt.loaded.ext.module.maas:92  ][INFO    ][13955] maasconfig name=maas_name value=mas01
2019-06-17 05:52:00,331 [salt.loaded.ext.module.maas:92  ][INFO    ][13955] maasconfig name=network_discovery value=enabled
2019-06-17 05:52:00,438 [salt.loaded.ext.module.maas:92  ][INFO    ][13955] maasconfig name=enable_third_party_drivers value=True
2019-06-17 05:52:00,486 [salt.loaded.ext.module.maas:92  ][INFO    ][13955] maasconfig name=default_storage_layout value=lvm
2019-06-17 05:52:00,540 [salt.loaded.ext.module.maas:92  ][INFO    ][13955] maasconfig name=ntp_external_only value=True
2019-06-17 05:52:00,587 [salt.loaded.ext.module.maas:92  ][INFO    ][13955] maasconfig name=disk_erase_with_secure_erase value=False
2019-06-17 05:52:01,397 [salt.loaded.ext.module.maas:92  ][INFO    ][13955] maasconfig name=default_distro_series value=xenial
2019-06-17 05:52:01,505 [salt.loaded.ext.module.maas:92  ][INFO    ][13955] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-06-17 05:52:01,627 [salt.state       :300 ][INFO    ][13955] {'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-06-17 05:52:01,627 [salt.state       :1951][INFO    ][13955] Completed state [maas.process_maas_config] at time 05:52:01.627659 duration_in_ms=1698.001
2019-06-17 05:52:01,628 [salt.state       :1780][INFO    ][13955] Running state [pxe_admin] at time 05:52:01.628803
2019-06-17 05:52:01,629 [salt.state       :1813][INFO    ][13955] Executing state maasng.fabric_present for [pxe_admin]
2019-06-17 05:52:01,683 [salt.loaded.ext.module.maasng:945 ][INFO    ][13955] [{u'name': u'fabric-0', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'name': u'fabric-3', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 3, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-3', u'relay_vlan': None, u'primary_rack': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/3/', u'id': 3}, {u'name': u'pxe_admin', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'mh4ker', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/', u'id': 4}]
2019-06-17 05:52:01,762 [salt.loaded.ext.module.maasng:1008][WARNING ][13955] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-06-17 05:52:01,762 [salt.loaded.ext.module.maasng:1011][WARNING ][13955] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-06-17 05:52:01,835 [salt.state       :300 ][INFO    ][13955] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-06-17 05:52:01,835 [salt.state       :1951][INFO    ][13955] Completed state [pxe_admin] at time 05:52:01.835368 duration_in_ms=206.564
2019-06-17 05:52:01,835 [salt.state       :1780][INFO    ][13955] Running state [vlan 0] at time 05:52:01.835813
2019-06-17 05:52:01,836 [salt.state       :1813][INFO    ][13955] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-06-17 05:52:01,900 [salt.loaded.ext.module.maasng:945 ][INFO    ][13955] [{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'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-3', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 3, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'id': 3, u'name': u'fabric-3', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/'}, {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': 4, u'name': u'untagged', u'primary_rack': u'mh4ker', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'id': 4, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/'}]
2019-06-17 05:52:02,026 [salt.loaded.ext.module.maasng:945 ][INFO    ][13955] [{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'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-3', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 3, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'id': 3, u'name': u'fabric-3', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/'}, {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': 4, u'name': u'untagged', u'primary_rack': u'mh4ker', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'id': 4, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/'}]
2019-06-17 05:52:02,276 [salt.loaded.ext.module.maasng:945 ][INFO    ][13955] [{u'name': u'fabric-0', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'name': u'fabric-3', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 3, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-3', u'relay_vlan': None, u'primary_rack': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/3/', u'id': 3}, {u'name': u'pxe_admin', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'mh4ker', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/', u'id': 4}]
2019-06-17 05:52:02,358 [salt.state       :300 ][INFO    ][13955] {'new': 'Vlan untagged was updated'}
2019-06-17 05:52:02,358 [salt.state       :1951][INFO    ][13955] Completed state [vlan 0] at time 05:52:02.358850 duration_in_ms=523.036
2019-06-17 05:52:02,360 [salt.state       :1780][INFO    ][13955] Running state [192.168.11.0/24] at time 05:52:02.360602
2019-06-17 05:52:02,361 [salt.state       :1813][INFO    ][13955] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-06-17 05:52:02,551 [salt.loaded.ext.module.maasng:945 ][INFO    ][13955] [{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'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-3', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 3, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'id': 3, u'name': u'fabric-3', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 4, u'name': u'untagged', u'primary_rack': u'mh4ker', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'id': 4, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/'}]
2019-06-17 05:52:02,551 [salt.loaded.ext.module.maasng:1235][WARNING ][13955] Ignoring parameter vlan:0
2019-06-17 05:52:02,625 [salt.state       :300 ][INFO    ][13955] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-06-17 05:52:02,625 [salt.state       :1951][INFO    ][13955] Completed state [192.168.11.0/24] at time 05:52:02.625543 duration_in_ms=264.94
2019-06-17 05:52:02,626 [salt.state       :1780][INFO    ][13955] Running state [maas_create_iprange_1] at time 05:52:02.626705
2019-06-17 05:52:02,627 [salt.state       :1813][INFO    ][13955] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-06-17 05:52:02,689 [salt.state       :300 ][INFO    ][13955] Iprange maas_create_iprange_1 already exist.
2019-06-17 05:52:02,689 [salt.state       :1951][INFO    ][13955] Completed state [maas_create_iprange_1] at time 05:52:02.689411 duration_in_ms=62.706
2019-06-17 05:52:02,691 [salt.state       :1780][INFO    ][13955] Running state [vlan 0] at time 05:52:02.690913
2019-06-17 05:52:02,691 [salt.state       :1813][INFO    ][13955] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-06-17 05:52:02,742 [salt.loaded.ext.module.maasng:945 ][INFO    ][13955] [{u'name': u'fabric-0', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'name': u'fabric-3', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 3, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-3', u'relay_vlan': None, u'primary_rack': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/3/', u'id': 3}, {u'name': u'pxe_admin', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'mh4ker', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/', u'id': 4}]
2019-06-17 05:52:02,844 [salt.loaded.ext.module.maasng:945 ][INFO    ][13955] [{u'name': u'fabric-0', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'name': u'fabric-3', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 3, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-3', u'relay_vlan': None, u'primary_rack': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/3/', u'id': 3}, {u'name': u'pxe_admin', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'mh4ker', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/', u'id': 4}]
2019-06-17 05:52:03,073 [salt.loaded.ext.module.maasng:945 ][INFO    ][13955] [{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'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-3', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 3, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'id': 3, u'name': u'fabric-3', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 4, u'name': u'untagged', u'primary_rack': u'mh4ker', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'id': 4, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/'}]
2019-06-17 05:52:03,188 [salt.state       :300 ][INFO    ][13955] {'new': 'Vlan untagged was updated'}
2019-06-17 05:52:03,188 [salt.state       :1951][INFO    ][13955] Completed state [vlan 0] at time 05:52:03.188470 duration_in_ms=497.556
2019-06-17 05:52:03,189 [salt.state       :1780][INFO    ][13955] Running state [opnfv] at time 05:52:03.189352
2019-06-17 05:52:03,190 [salt.state       :1813][INFO    ][13955] Executing state maasng.sshkey_present for [opnfv]
2019-06-17 05:52:03,259 [salt.loaded.ext.module.maasng:1903][INFO    ][13955] [{u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b', u'keysource': u''}]
2019-06-17 05:52:03,259 [salt.state       :300 ][INFO    ][13955] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-06-17 05:52:03,260 [salt.state       :1951][INFO    ][13955] Completed state [opnfv] at time 05:52:03.260270 duration_in_ms=70.917
2019-06-17 05:52:03,261 [salt.state       :1780][INFO    ][13955] Running state [maas.process_tags] at time 05:52:03.261282
2019-06-17 05:52:03,261 [salt.state       :1813][INFO    ][13955] Executing state module.run for [maas.process_tags]
2019-06-17 05:52:03,262 [salt.utils.decorators:613 ][WARNING ][13955] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-17 05:52:03,308 [salt.loaded.ext.module.maas:92  ][INFO    ][13955] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-06-17 05:52:03,385 [salt.state       :300 ][INFO    ][13955] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-06-17 05:52:03,388 [salt.state       :1951][INFO    ][13955] Completed state [maas.process_tags] at time 05:52:03.388337 duration_in_ms=127.054
2019-06-17 05:52:03,391 [salt.minion      :1711][INFO    ][13955] Returning information for job: 20190617055140119954
2019-06-17 05:52:04,145 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command state.apply with jid 20190617055204132210
2019-06-17 05:52:04,164 [salt.minion      :1432][INFO    ][14420] Starting a new job with PID 14420
2019-06-17 05:52:10,236 [salt.state       :915 ][INFO    ][14420] Loading fresh modules for state activity
2019-06-17 05:52:10,356 [salt.state       :1780][INFO    ][14420] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:52:10.355931
2019-06-17 05:52:10,356 [salt.state       :1813][INFO    ][14420] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-17 05:52:10,358 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14420] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-17 05:52:12,295 [salt.state       :300 ][INFO    ][14420] {'pid': 14445, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-17 05:52:12,296 [salt.state       :1951][INFO    ][14420] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:52:12.296135 duration_in_ms=1940.203
2019-06-17 05:52:12,299 [salt.state       :1780][INFO    ][14420] Running state [maas.process_machines] at time 05:52:12.299767
2019-06-17 05:52:12,300 [salt.state       :1813][INFO    ][14420] Executing state module.run for [maas.process_machines]
2019-06-17 05:52:12,303 [salt.utils.decorators:613 ][WARNING ][14420] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-17 05:52:12,881 [salt.loaded.ext.module.maas:412 ][WARNING ][14420] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-17 05:52:12,882 [salt.loaded.ext.module.maas:92  ][INFO    ][14420] 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=khxf73 architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-17 05:52:14,105 [salt.loaded.ext.module.maas:412 ][WARNING ][14420] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-17 05:52:14,107 [salt.loaded.ext.module.maas:92  ][INFO    ][14420] 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=7sswy3 architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-17 05:52:15,322 [salt.loaded.ext.module.maas:412 ][WARNING ][14420] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-17 05:52:15,323 [salt.loaded.ext.module.maas:92  ][INFO    ][14420] 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=x47ewc architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-17 05:52:16,585 [salt.loaded.ext.module.maas:412 ][WARNING ][14420] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-17 05:52:16,586 [salt.loaded.ext.module.maas:92  ][INFO    ][14420] 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=amrdrd architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-17 05:52:17,813 [salt.loaded.ext.module.maas:412 ][WARNING ][14420] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-17 05:52:17,814 [salt.loaded.ext.module.maas:92  ][INFO    ][14420] 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=adcmmk architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-17 05:52:19,015 [salt.state       :300 ][INFO    ][14420] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-06-17 05:52:19,016 [salt.state       :1951][INFO    ][14420] Completed state [maas.process_machines] at time 05:52:19.015943 duration_in_ms=6716.175
2019-06-17 05:52:19,021 [salt.minion      :1711][INFO    ][14420] Returning information for job: 20190617055204132210
2019-06-17 05:52:52,805 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command state.apply with jid 20190617055252794558
2019-06-17 05:52:52,824 [salt.minion      :1432][INFO    ][14717] Starting a new job with PID 14717
2019-06-17 05:52:58,795 [salt.state       :915 ][INFO    ][14717] Loading fresh modules for state activity
2019-06-17 05:52:58,905 [salt.state       :1780][INFO    ][14717] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:52:58.905391
2019-06-17 05:52:58,905 [salt.state       :1813][INFO    ][14717] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-17 05:52:58,909 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14717] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-17 05:53:00,837 [salt.state       :300 ][INFO    ][14717] {'pid': 14724, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-17 05:53:00,839 [salt.state       :1951][INFO    ][14717] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:53:00.838799 duration_in_ms=1933.408
2019-06-17 05:53:00,844 [salt.state       :1780][INFO    ][14717] Running state [maas.wait_for_machine_status] at time 05:53:00.844039
2019-06-17 05:53:00,844 [salt.state       :1813][INFO    ][14717] Executing state module.run for [maas.wait_for_machine_status]
2019-06-17 05:53:00,844 [salt.utils.decorators:613 ][WARNING ][14717] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-17 05:53:03,681 [salt.state       :300 ][INFO    ][14717] {'ret': True}
2019-06-17 05:53:03,683 [salt.state       :1951][INFO    ][14717] Completed state [maas.wait_for_machine_status] at time 05:53:03.683566 duration_in_ms=2839.525
2019-06-17 05:53:03,689 [salt.minion      :1711][INFO    ][14717] Returning information for job: 20190617055252794558
2019-06-17 05:53:04,432 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command state.apply with jid 20190617055304373582
2019-06-17 05:53:04,457 [salt.minion      :1432][INFO    ][14746] Starting a new job with PID 14746
2019-06-17 05:53:05,424 [salt.state       :915 ][INFO    ][14746] Loading fresh modules for state activity
2019-06-17 05:53:05,584 [salt.state       :1780][INFO    ][14746] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:53:05.584385
2019-06-17 05:53:05,584 [salt.state       :1813][INFO    ][14746] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-17 05:53:05,586 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14746] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-17 05:53:07,491 [salt.state       :300 ][INFO    ][14746] {'pid': 14753, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-17 05:53:07,492 [salt.state       :1951][INFO    ][14746] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:53:07.492092 duration_in_ms=1907.706
2019-06-17 05:53:07,496 [salt.state       :1780][INFO    ][14746] Running state [maas_machines_storage_cmp002_lvm] at time 05:53:07.496361
2019-06-17 05:53:07,497 [salt.state       :1813][INFO    ][14746] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-06-17 05:53:08,080 [salt.state       :300 ][INFO    ][14746] Machine cmp002 is not in Ready state.
2019-06-17 05:53:08,080 [salt.state       :1951][INFO    ][14746] Completed state [maas_machines_storage_cmp002_lvm] at time 05:53:08.080545 duration_in_ms=584.184
2019-06-17 05:53:08,081 [salt.state       :1780][INFO    ][14746] Running state [maas_machines_storage_cmp001_lvm] at time 05:53:08.081012
2019-06-17 05:53:08,081 [salt.state       :1813][INFO    ][14746] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-06-17 05:53:08,624 [salt.state       :300 ][INFO    ][14746] Machine cmp001 is not in Ready state.
2019-06-17 05:53:08,624 [salt.state       :1951][INFO    ][14746] Completed state [maas_machines_storage_cmp001_lvm] at time 05:53:08.624428 duration_in_ms=543.415
2019-06-17 05:53:08,628 [salt.minion      :1711][INFO    ][14746] Returning information for job: 20190617055304373582
2019-06-17 05:53:09,335 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command state.apply with jid 20190617055309320139
2019-06-17 05:53:09,361 [salt.minion      :1432][INFO    ][14763] Starting a new job with PID 14763
2019-06-17 05:53:10,374 [salt.state       :915 ][INFO    ][14763] Loading fresh modules for state activity
2019-06-17 05:53:10,491 [salt.state       :1780][INFO    ][14763] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:53:10.491116
2019-06-17 05:53:10,491 [salt.state       :1813][INFO    ][14763] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-17 05:53:10,493 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14763] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-17 05:53:12,451 [salt.state       :300 ][INFO    ][14763] {'pid': 14770, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-17 05:53:12,452 [salt.state       :1951][INFO    ][14763] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:53:12.452793 duration_in_ms=1961.676
2019-06-17 05:53:12,456 [salt.state       :1780][INFO    ][14763] Running state [maas.deploy_machines] at time 05:53:12.456366
2019-06-17 05:53:12,457 [salt.state       :1813][INFO    ][14763] Executing state module.run for [maas.deploy_machines]
2019-06-17 05:53:12,457 [salt.utils.decorators:613 ][WARNING ][14763] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-17 05:53:13,038 [salt.loaded.ext.module.maas:684 ][INFO    ][14763] deploymachines hwe_kernel=hwe-16.04 system_id=adcmmk distro_series=xenial
2019-06-17 05:53:15,489 [salt.state       :300 ][INFO    ][14763] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-06-17 05:53:15,491 [salt.state       :1951][INFO    ][14763] Completed state [maas.deploy_machines] at time 05:53:15.490989 duration_in_ms=3034.621
2019-06-17 05:53:15,497 [salt.minion      :1711][INFO    ][14763] Returning information for job: 20190617055309320139
2019-06-17 05:53:16,197 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command state.apply with jid 20190617055316186690
2019-06-17 05:53:16,222 [salt.minion      :1432][INFO    ][14839] Starting a new job with PID 14839
2019-06-17 05:53:22,164 [salt.state       :915 ][INFO    ][14839] Loading fresh modules for state activity
2019-06-17 05:53:22,288 [salt.state       :1780][INFO    ][14839] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:53:22.288622
2019-06-17 05:53:22,288 [salt.state       :1813][INFO    ][14839] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-17 05:53:22,291 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14839] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-17 05:53:24,235 [salt.state       :300 ][INFO    ][14839] {'pid': 14853, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-17 05:53:24,237 [salt.state       :1951][INFO    ][14839] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:53:24.236865 duration_in_ms=1948.242
2019-06-17 05:53:24,241 [salt.state       :1780][INFO    ][14839] Running state [maas.wait_for_machine_status] at time 05:53:24.241209
2019-06-17 05:53:24,242 [salt.state       :1813][INFO    ][14839] Executing state module.run for [maas.wait_for_machine_status]
2019-06-17 05:53:24,243 [salt.utils.decorators:613 ][WARNING ][14839] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-17 05:53:27,131 [salt.loaded.ext.module.maas:1023][INFO    ][14839] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.14062095s left)
2019-06-17 05:53:31,245 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617055331232383
2019-06-17 05:53:31,272 [salt.minion      :1432][INFO    ][14877] Starting a new job with PID 14877
2019-06-17 05:53:31,299 [salt.minion      :1711][INFO    ][14877] Returning information for job: 20190617055331232383
2019-06-17 05:53:59,848 [salt.loaded.ext.module.maas:1023][INFO    ][14839] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.42368388s left)
2019-06-17 05:54:01,324 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617055401308506
2019-06-17 05:54:01,350 [salt.minion      :1432][INFO    ][14922] Starting a new job with PID 14922
2019-06-17 05:54:01,380 [salt.minion      :1711][INFO    ][14922] Returning information for job: 20190617055401308506
2019-06-17 05:54:31,415 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617055431404125
2019-06-17 05:54:31,437 [salt.minion      :1432][INFO    ][14959] Starting a new job with PID 14959
2019-06-17 05:54:31,464 [salt.minion      :1711][INFO    ][14959] Returning information for job: 20190617055431404125
2019-06-17 05:54:32,699 [salt.loaded.ext.module.maas:1023][INFO    ][14839] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2181.57276392s left)
2019-06-17 05:55:01,505 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617055501492058
2019-06-17 05:55:01,529 [salt.minion      :1432][INFO    ][15015] Starting a new job with PID 15015
2019-06-17 05:55:01,558 [salt.minion      :1711][INFO    ][15015] Returning information for job: 20190617055501492058
2019-06-17 05:55:05,456 [salt.loaded.ext.module.maas:1023][INFO    ][14839] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2148.81551003s left)
2019-06-17 05:55:31,607 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617055531589233
2019-06-17 05:55:31,635 [salt.minion      :1432][INFO    ][15047] Starting a new job with PID 15047
2019-06-17 05:55:31,666 [salt.minion      :1711][INFO    ][15047] Returning information for job: 20190617055531589233
2019-06-17 05:55:38,200 [salt.loaded.ext.module.maas:1023][INFO    ][14839] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2116.07144094s left)
2019-06-17 05:56:01,715 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617055601696733
2019-06-17 05:56:01,741 [salt.minion      :1432][INFO    ][15090] Starting a new job with PID 15090
2019-06-17 05:56:01,772 [salt.minion      :1711][INFO    ][15090] Returning information for job: 20190617055601696733
2019-06-17 05:56:11,077 [salt.loaded.ext.module.maas:1023][INFO    ][14839] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2083.19496608s left)
2019-06-17 05:56:31,807 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617055631791272
2019-06-17 05:56:31,832 [salt.minion      :1432][INFO    ][15124] Starting a new job with PID 15124
2019-06-17 05:56:31,861 [salt.minion      :1711][INFO    ][15124] Returning information for job: 20190617055631791272
2019-06-17 05:56:44,011 [salt.loaded.ext.module.maas:1023][INFO    ][14839] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2050.26110101s left)
2019-06-17 05:57:01,913 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617055701895815
2019-06-17 05:57:01,943 [salt.minion      :1432][INFO    ][15166] Starting a new job with PID 15166
2019-06-17 05:57:01,979 [salt.minion      :1711][INFO    ][15166] Returning information for job: 20190617055701895815
2019-06-17 05:57:16,753 [salt.loaded.ext.module.maas:1023][INFO    ][14839] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2017.51888704s left)
2019-06-17 05:57:32,035 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617055732022738
2019-06-17 05:57:32,058 [salt.minion      :1432][INFO    ][15221] Starting a new job with PID 15221
2019-06-17 05:57:32,087 [salt.minion      :1711][INFO    ][15221] Returning information for job: 20190617055732022738
2019-06-17 05:57:49,713 [salt.loaded.ext.module.maas:1023][INFO    ][14839] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1984.55861902s left)
2019-06-17 05:58:02,148 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617055802133541
2019-06-17 05:58:02,174 [salt.minion      :1432][INFO    ][15275] Starting a new job with PID 15275
2019-06-17 05:58:02,204 [salt.minion      :1711][INFO    ][15275] Returning information for job: 20190617055802133541
2019-06-17 05:58:22,485 [salt.loaded.ext.module.maas:1023][INFO    ][14839] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1951.78710794s left)
2019-06-17 05:58:32,265 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617055832247661
2019-06-17 05:58:32,297 [salt.minion      :1432][INFO    ][15350] Starting a new job with PID 15350
2019-06-17 05:58:32,327 [salt.minion      :1711][INFO    ][15350] Returning information for job: 20190617055832247661
2019-06-17 05:58:55,552 [salt.loaded.ext.module.maas:1023][INFO    ][14839] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1918.71935201s left)
2019-06-17 05:59:02,400 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617055902386906
2019-06-17 05:59:02,430 [salt.minion      :1432][INFO    ][15500] Starting a new job with PID 15500
2019-06-17 05:59:02,460 [salt.minion      :1711][INFO    ][15500] Returning information for job: 20190617055902386906
2019-06-17 05:59:28,449 [salt.loaded.ext.module.maas:1023][INFO    ][14839] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1885.82273793s left)
2019-06-17 05:59:32,523 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617055932509611
2019-06-17 05:59:32,552 [salt.minion      :1432][INFO    ][15551] Starting a new job with PID 15551
2019-06-17 05:59:32,599 [salt.minion      :1711][INFO    ][15551] Returning information for job: 20190617055932509611
2019-06-17 06:00:01,320 [salt.loaded.ext.module.maas:1023][INFO    ][14839] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1852.9513669s left)
2019-06-17 06:00:02,681 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617060002666549
2019-06-17 06:00:02,713 [salt.minion      :1432][INFO    ][15615] Starting a new job with PID 15615
2019-06-17 06:00:02,754 [salt.minion      :1711][INFO    ][15615] Returning information for job: 20190617060002666549
2019-06-17 06:00:32,842 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617060032832602
2019-06-17 06:00:32,862 [salt.minion      :1432][INFO    ][15655] Starting a new job with PID 15655
2019-06-17 06:00:32,891 [salt.minion      :1711][INFO    ][15655] Returning information for job: 20190617060032832602
2019-06-17 06:00:34,328 [salt.loaded.ext.module.maas:1023][INFO    ][14839] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1819.94356704s left)
2019-06-17 06:01:02,999 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617060102984177
2019-06-17 06:01:03,031 [salt.minion      :1432][INFO    ][15774] Starting a new job with PID 15774
2019-06-17 06:01:03,064 [salt.minion      :1711][INFO    ][15774] Returning information for job: 20190617060102984177
2019-06-17 06:01:07,377 [salt.loaded.ext.module.maas:1023][INFO    ][14839] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1786.89491391s left)
2019-06-17 06:01:33,179 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617060133165123
2019-06-17 06:01:33,204 [salt.minion      :1432][INFO    ][15846] Starting a new job with PID 15846
2019-06-17 06:01:33,234 [salt.minion      :1711][INFO    ][15846] Returning information for job: 20190617060133165123
2019-06-17 06:01:40,052 [salt.loaded.ext.module.maas:1023][INFO    ][14839] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1754.21991801s left)
2019-06-17 06:02:03,344 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617060203331339
2019-06-17 06:02:03,376 [salt.minion      :1432][INFO    ][15953] Starting a new job with PID 15953
2019-06-17 06:02:03,408 [salt.minion      :1711][INFO    ][15953] Returning information for job: 20190617060203331339
2019-06-17 06:02:12,977 [salt.loaded.ext.module.maas:1023][INFO    ][14839] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1721.29441285s left)
2019-06-17 06:02:33,535 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617060233519144
2019-06-17 06:02:33,565 [salt.minion      :1432][INFO    ][15993] Starting a new job with PID 15993
2019-06-17 06:02:33,598 [salt.minion      :1711][INFO    ][15993] Returning information for job: 20190617060233519144
2019-06-17 06:02:46,041 [salt.loaded.ext.module.maas:1023][INFO    ][14839] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1688.23069096s left)
2019-06-17 06:03:03,715 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617060303698800
2019-06-17 06:03:03,744 [salt.minion      :1432][INFO    ][16037] Starting a new job with PID 16037
2019-06-17 06:03:03,774 [salt.minion      :1711][INFO    ][16037] Returning information for job: 20190617060303698800
2019-06-17 06:03:18,886 [salt.loaded.ext.module.maas:1023][INFO    ][14839] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1655.38575387s left)
2019-06-17 06:03:33,921 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617060333904309
2019-06-17 06:03:33,953 [salt.minion      :1432][INFO    ][16070] Starting a new job with PID 16070
2019-06-17 06:03:33,987 [salt.minion      :1711][INFO    ][16070] Returning information for job: 20190617060333904309
2019-06-17 06:03:51,681 [salt.loaded.ext.module.maas:1023][INFO    ][14839] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1622.59106803s left)
2019-06-17 06:04:04,116 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617060404103010
2019-06-17 06:04:04,146 [salt.minion      :1432][INFO    ][16113] Starting a new job with PID 16113
2019-06-17 06:04:04,186 [salt.minion      :1711][INFO    ][16113] Returning information for job: 20190617060404103010
2019-06-17 06:04:24,534 [salt.loaded.ext.module.maas:1023][INFO    ][14839] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1589.73765397s left)
2019-06-17 06:04:34,325 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617060434312287
2019-06-17 06:04:34,355 [salt.minion      :1432][INFO    ][16155] Starting a new job with PID 16155
2019-06-17 06:04:34,388 [salt.minion      :1711][INFO    ][16155] Returning information for job: 20190617060434312287
2019-06-17 06:04:57,345 [salt.loaded.ext.module.maas:1023][INFO    ][14839] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1556.92714596s left)
2019-06-17 06:05:04,518 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617060504507166
2019-06-17 06:05:04,544 [salt.minion      :1432][INFO    ][16356] Starting a new job with PID 16356
2019-06-17 06:05:04,573 [salt.minion      :1711][INFO    ][16356] Returning information for job: 20190617060504507166
2019-06-17 06:05:30,204 [salt.loaded.ext.module.maas:1023][INFO    ][14839] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1524.06748796s left)
2019-06-17 06:05:34,744 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617060534730875
2019-06-17 06:05:34,771 [salt.minion      :1432][INFO    ][16388] Starting a new job with PID 16388
2019-06-17 06:05:34,799 [salt.minion      :1711][INFO    ][16388] Returning information for job: 20190617060534730875
2019-06-17 06:05:50,463 [salt.utils.schedule:1377][INFO    ][5965] Running scheduled job: __mine_interval
2019-06-17 06:06:02,886 [salt.loaded.ext.module.maas:1023][INFO    ][14839] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1491.3860209s left)
2019-06-17 06:06:04,960 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617060604946805
2019-06-17 06:06:04,991 [salt.minion      :1432][INFO    ][16439] Starting a new job with PID 16439
2019-06-17 06:06:05,023 [salt.minion      :1711][INFO    ][16439] Returning information for job: 20190617060604946805
2019-06-17 06:06:34,985 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617060634969603
2019-06-17 06:06:35,010 [salt.minion      :1432][INFO    ][16491] Starting a new job with PID 16491
2019-06-17 06:06:35,040 [salt.minion      :1711][INFO    ][16491] Returning information for job: 20190617060634969603
2019-06-17 06:06:36,054 [salt.loaded.ext.module.maas:1023][INFO    ][14839] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1458.21757007s left)
2019-06-17 06:07:05,148 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command saltutil.find_job with jid 20190617060705137380
2019-06-17 06:07:05,169 [salt.minion      :1432][INFO    ][16550] Starting a new job with PID 16550
2019-06-17 06:07:05,198 [salt.minion      :1711][INFO    ][16550] Returning information for job: 20190617060705137380
2019-06-17 06:07:08,932 [salt.state       :300 ][INFO    ][14839] {'ret': True}
2019-06-17 06:07:08,932 [salt.state       :1951][INFO    ][14839] Completed state [maas.wait_for_machine_status] at time 06:07:08.932351 duration_in_ms=824691.139
2019-06-17 06:07:08,936 [salt.minion      :1711][INFO    ][14839] Returning information for job: 20190617055316186690
2019-06-17 07:05:50,463 [salt.utils.schedule:1377][INFO    ][5965] Running scheduled job: __mine_interval
2019-06-17 07:29:17,073 [salt.minion      :1308][INFO    ][5965] User sudo_ubuntu Executing command cp.push_dir with jid 20190617072917063322
2019-06-17 07:29:17,098 [salt.minion      :1432][INFO    ][22543] Starting a new job with PID 22543
