2019-04-25 02:06:33,238 [salt.utils.decorators:613 ][WARNING ][2363] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-25 02:06:34,295 [salt.utils.decorators:613 ][WARNING ][2363] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-25 02:06:37,991 [salt.loaded.int.states.file:2298][WARNING ][2607] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-04-25 02:07:00,589 [salt.state       :2022][WARNING ][2870] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-04-25 02:07:03,765 [salt.utils.decorators:613 ][WARNING ][2870] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-25 02:07:15,132 [salt.utils.decorators:613 ][WARNING ][2870] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-25 02:07:42,161 [salt.utils.decorators:613 ][WARNING ][2870] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-25 02:07:42,977 [salt.utils.decorators:613 ][WARNING ][2870] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-25 02:07:45,294 [salt.loaded.ext.module.maasng:1008][WARNING ][2870] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-04-25 02:07:45,295 [salt.loaded.ext.module.maasng:1011][WARNING ][2870] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-04-25 02:07:45,895 [salt.loaded.ext.module.maasng:1235][WARNING ][2870] Ignoring parameter vlan:0
2019-04-25 02:07:50,114 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5396] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-04-25 02:07:50,137 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5396] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-04-25 02:07:50,179 [salt.utils.parsers:1051][WARNING ][388] Minion received a SIGTERM. Exiting.
2019-04-25 02:07:51,285 [salt.cli.daemons :293 ][INFO    ][5477] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-04-25 02:07:51,454 [salt.cli.daemons :82  ][INFO    ][5477] Starting up the Salt Minion
2019-04-25 02:07:51,455 [salt.utils.event :1017][INFO    ][5477] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-04-25 02:07:52,620 [salt.minion      :976 ][INFO    ][5477] Creating minion process manager
2019-04-25 02:07:54,656 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][5477] Executing command ['date', '+%z'] in directory '/root'
2019-04-25 02:07:54,680 [salt.utils.schedule:568 ][INFO    ][5477] Updating job settings for scheduled job: __mine_interval
2019-04-25 02:07:54,682 [salt.minion      :1108][INFO    ][5477] Added mine.update to scheduler
2019-04-25 02:07:54,689 [salt.minion      :1975][INFO    ][5477] Minion is starting as user 'root'
2019-04-25 02:07:54,704 [salt.minion      :2336][INFO    ][5477] Minion is ready to receive requests!
2019-04-25 02:07:58,600 [salt.utils.decorators:613 ][WARNING ][5415] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-25 02:07:58,645 [salt.loaded.ext.module.maas:412 ][WARNING ][5415] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-25 02:07:59,889 [salt.loaded.ext.module.maas:412 ][WARNING ][5415] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-25 02:08:01,129 [salt.loaded.ext.module.maas:412 ][WARNING ][5415] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-25 02:08:02,421 [salt.loaded.ext.module.maas:412 ][WARNING ][5415] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-25 02:08:02,873 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425020802861616
2019-04-25 02:08:02,888 [salt.minion      :1432][INFO    ][5765] Starting a new job with PID 5765
2019-04-25 02:08:02,916 [salt.minion      :1711][INFO    ][5765] Returning information for job: 20190425020802861616
2019-04-25 02:08:03,743 [salt.loaded.ext.module.maas:412 ][WARNING ][5415] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-25 02:08:36,490 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command state.apply with jid 20190425020836476894
2019-04-25 02:08:36,510 [salt.minion      :1432][INFO    ][5897] Starting a new job with PID 5897
2019-04-25 02:08:44,375 [salt.state       :915 ][INFO    ][5897] Loading fresh modules for state activity
2019-04-25 02:08:44,449 [salt.fileclient  :1219][INFO    ][5897] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-04-25 02:08:44,494 [salt.state       :1780][INFO    ][5897] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:08:44.494724
2019-04-25 02:08:44,495 [salt.state       :1813][INFO    ][5897] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-25 02:08:44,496 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5897] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-25 02:08:46,425 [salt.state       :300 ][INFO    ][5897] {'pid': 5904, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-25 02:08:46,425 [salt.state       :1951][INFO    ][5897] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:08:46.425730 duration_in_ms=1931.006
2019-04-25 02:08:46,427 [salt.state       :1780][INFO    ][5897] Running state [maas.wait_for_machine_status] at time 02:08:46.427618
2019-04-25 02:08:46,428 [salt.state       :1813][INFO    ][5897] Executing state module.run for [maas.wait_for_machine_status]
2019-04-25 02:08:46,428 [salt.utils.decorators:613 ][WARNING ][5897] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-25 02:08:47,118 [salt.loaded.ext.module.maas:1023][INFO    ][5897] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.33196902s left)
2019-04-25 02:08:51,576 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425020851560954
2019-04-25 02:08:51,597 [salt.minion      :1432][INFO    ][5941] Starting a new job with PID 5941
2019-04-25 02:08:51,624 [salt.minion      :1711][INFO    ][5941] Returning information for job: 20190425020851560954
2019-04-25 02:09:17,968 [salt.loaded.ext.module.maas:1023][INFO    ][5897] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.48169804s left)
2019-04-25 02:09:21,654 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425020921637644
2019-04-25 02:09:21,671 [salt.minion      :1432][INFO    ][6001] Starting a new job with PID 6001
2019-04-25 02:09:21,701 [salt.minion      :1711][INFO    ][6001] Returning information for job: 20190425020921637644
2019-04-25 02:09:48,700 [salt.loaded.ext.module.maas:1023][INFO    ][5897] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.74988914s left)
2019-04-25 02:09:51,750 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425020951738547
2019-04-25 02:09:51,773 [salt.minion      :1432][INFO    ][6043] Starting a new job with PID 6043
2019-04-25 02:09:51,799 [salt.minion      :1711][INFO    ][6043] Returning information for job: 20190425020951738547
2019-04-25 02:10:19,497 [salt.loaded.ext.module.maas:1023][INFO    ][5897] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.95328522s left)
2019-04-25 02:10:21,801 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425021021793111
2019-04-25 02:10:21,823 [salt.minion      :1432][INFO    ][6106] Starting a new job with PID 6106
2019-04-25 02:10:21,849 [salt.minion      :1711][INFO    ][6106] Returning information for job: 20190425021021793111
2019-04-25 02:10:50,628 [salt.loaded.ext.module.maas:1023][INFO    ][5897] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.82132316s left)
2019-04-25 02:10:52,005 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425021051989467
2019-04-25 02:10:52,030 [salt.minion      :1432][INFO    ][6174] Starting a new job with PID 6174
2019-04-25 02:10:52,058 [salt.minion      :1711][INFO    ][6174] Returning information for job: 20190425021051989467
2019-04-25 02:11:21,696 [salt.loaded.ext.module.maas:1023][INFO    ][5897] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.75333714s left)
2019-04-25 02:11:22,070 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425021122058301
2019-04-25 02:11:22,089 [salt.minion      :1432][INFO    ][6392] Starting a new job with PID 6392
2019-04-25 02:11:22,122 [salt.minion      :1711][INFO    ][6392] Returning information for job: 20190425021122058301
2019-04-25 02:11:52,158 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425021152147951
2019-04-25 02:11:52,173 [salt.minion      :1432][INFO    ][6494] Starting a new job with PID 6494
2019-04-25 02:11:52,204 [salt.minion      :1711][INFO    ][6494] Returning information for job: 20190425021152147951
2019-04-25 02:11:53,025 [salt.loaded.ext.module.maas:1023][INFO    ][5897] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1313.42498016s left)
2019-04-25 02:12:22,249 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425021222241244
2019-04-25 02:12:22,267 [salt.minion      :1432][INFO    ][6762] Starting a new job with PID 6762
2019-04-25 02:12:22,295 [salt.minion      :1711][INFO    ][6762] Returning information for job: 20190425021222241244
2019-04-25 02:12:24,189 [salt.loaded.ext.module.maas:1023][INFO    ][5897] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1282.2611711s left)
2019-04-25 02:12:52,322 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425021252306751
2019-04-25 02:12:52,349 [salt.minion      :1432][INFO    ][6952] Starting a new job with PID 6952
2019-04-25 02:12:52,385 [salt.minion      :1711][INFO    ][6952] Returning information for job: 20190425021252306751
2019-04-25 02:12:56,202 [salt.loaded.ext.module.maas:1023][INFO    ][5897] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1250.24756718s left)
2019-04-25 02:13:22,483 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425021322474771
2019-04-25 02:13:22,498 [salt.minion      :1432][INFO    ][7477] Starting a new job with PID 7477
2019-04-25 02:13:22,524 [salt.minion      :1711][INFO    ][7477] Returning information for job: 20190425021322474771
2019-04-25 02:13:28,151 [salt.loaded.ext.module.maas:1023][INFO    ][5897] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1218.29943919s left)
2019-04-25 02:13:52,565 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425021352550562
2019-04-25 02:13:52,584 [salt.minion      :1432][INFO    ][7527] Starting a new job with PID 7527
2019-04-25 02:13:52,615 [salt.minion      :1711][INFO    ][7527] Returning information for job: 20190425021352550562
2019-04-25 02:14:00,329 [salt.loaded.ext.module.maas:1023][INFO    ][5897] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1186.12051105s left)
2019-04-25 02:14:22,609 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425021422595454
2019-04-25 02:14:22,632 [salt.minion      :1432][INFO    ][7756] Starting a new job with PID 7756
2019-04-25 02:14:22,667 [salt.minion      :1711][INFO    ][7756] Returning information for job: 20190425021422595454
2019-04-25 02:14:32,729 [salt.state       :300 ][INFO    ][5897] {'ret': True}
2019-04-25 02:14:32,730 [salt.state       :1951][INFO    ][5897] Completed state [maas.wait_for_machine_status] at time 02:14:32.729935 duration_in_ms=346302.316
2019-04-25 02:14:32,732 [salt.minion      :1711][INFO    ][5897] Returning information for job: 20190425020836476894
2019-04-25 02:14:33,404 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command state.apply with jid 20190425021433392314
2019-04-25 02:14:33,424 [salt.minion      :1432][INFO    ][7776] Starting a new job with PID 7776
2019-04-25 02:14:41,425 [salt.state       :915 ][INFO    ][7776] Loading fresh modules for state activity
2019-04-25 02:14:41,484 [salt.fileclient  :1219][INFO    ][7776] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-04-25 02:14:41,593 [salt.state       :1780][INFO    ][7776] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:14:41.593100
2019-04-25 02:14:41,593 [salt.state       :1813][INFO    ][7776] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-25 02:14:41,596 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7776] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-25 02:14:43,555 [salt.state       :300 ][INFO    ][7776] {'pid': 7803, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-25 02:14:43,556 [salt.state       :1951][INFO    ][7776] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:14:43.556028 duration_in_ms=1962.928
2019-04-25 02:14:43,559 [salt.state       :1780][INFO    ][7776] Running state [maas_machines_storage_cmp002_lvm] at time 02:14:43.559649
2019-04-25 02:14:43,560 [salt.state       :1813][INFO    ][7776] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-04-25 02:14:44,688 [salt.loaded.ext.module.maasng:610 ][INFO    ][7776] ngx6cx
2019-04-25 02:14:44,689 [salt.loaded.ext.module.maasng:626 ][INFO    ][7776] sda
2019-04-25 02:14:45,357 [salt.loaded.ext.module.maasng:361 ][INFO    ][7776] ngx6cx
2019-04-25 02:14:45,449 [salt.loaded.ext.module.maasng:367 ][INFO    ][7776] [{u'block_size': 4096, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/ngx6cx/blockdevices/1/', u'type': u'physical', u'path': u'/dev/disk/by-dname/sda', u'tags': [u'ssd'], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'system_id': u'ngx6cx', u'partition_table_type': u'MBR', u'filesystem': None, u'id': 1, u'available_size': 0, u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'a33758fc-ebee-468b-bbb1-712060c92179', u'resource_uri': u'/MAAS/api/2.0/nodes/ngx6cx/blockdevices/1/partition/1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ngx6cx', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'5c25f593-c555-484a-b087-de8fad70634e', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 1, u'device_id': 1}], u'used_size': 800106479616, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}, {u'block_size': 4096, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/ngx6cx/blockdevices/3/', u'type': u'virtual', u'path': u'/dev/disk/by-dname/lvroot', u'tags': [], u'uuid': u'9ac1afbe-aae5-4fc7-9e72-611705843cad', u'used_for': u'ext4 formatted filesystem mounted at /', u'id_path': None, u'system_id': u'ngx6cx', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'5b0d8e7a-6317-4a08-8c52-1e025b68a7ce', u'fstype': u'ext4'}, u'id': 3, u'available_size': 0, u'model': None, u'partitions': [], u'used_size': 800097042432, u'serial': None, u'size': 800097042432}]
2019-04-25 02:14:45,450 [salt.loaded.ext.module.maasng:632 ][INFO    ][7776] vgroot
2019-04-25 02:14:45,450 [salt.loaded.ext.module.maasng:635 ][INFO    ][7776] lvroot
2019-04-25 02:14:45,451 [salt.loaded.ext.module.maasng:639 ][INFO    ][7776] 107374182400
2019-04-25 02:14:46,040 [salt.loaded.ext.module.maasng:645 ][INFO    ][7776] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'swap_size': None, u'memory_test_status': -1, u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'p6cgg4', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 18}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'p6cgg4', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'p6cgg4', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'ngx6cx', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/ngx6cx/interfaces/4/'}, u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'fqdn': u'cmp002.maas', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'zone': {u'description': u'', u'id': 1, u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'commissioning_status': 2, u'testing_status': 2, u'system_id': u'ngx6cx', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'model': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'c520088e-79a9-419a-a9fc-d5ab2d33edcf', u'fstype': u'ext4'}, u'uuid': u'b1db85e2-7dbb-433e-bbaa-02d5a9025741', u'used_for': u'ext4 formatted filesystem mounted at /', u'resource_uri': u'/MAAS/api/2.0/nodes/ngx6cx/blockdevices/11/', u'system_id': u'ngx6cx', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'size': 107374182400, u'used_size': 107374182400, u'id': 11, u'partitions': []}], u'architecture': u'amd64/generic', u'status': 4, u'storage_test_status': 2, u'cpu_count': 40, u'raids': [], u'physicalblockdevice_set': [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/ngx6cx/blockdevices/1/', u'system_id': u'ngx6cx', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456, u'used_size': 800106479616, u'id': 1, u'partitions': [{u'uuid': u'e3a3c8c4-4871-4f4d-908e-a14a4b1aedc4', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ngx6cx', u'device_id': 1, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'5d9cbbfb-64df-4893-a191-d85459fff963', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/ngx6cx/blockdevices/1/partition/6'}]}], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'ngx6cx', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'boot_disk': {u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/ngx6cx/blockdevices/1/', u'system_id': u'ngx6cx', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456, u'used_size': 800106479616, u'id': 1, u'partitions': [{u'uuid': u'e3a3c8c4-4871-4f4d-908e-a14a4b1aedc4', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ngx6cx', u'device_id': 1, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'5d9cbbfb-64df-4893-a191-d85459fff963', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/ngx6cx/blockdevices/1/partition/6'}]}, u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'p6cgg4', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 18}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'p6cgg4', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'p6cgg4', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'ngx6cx', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/ngx6cx/interfaces/4/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:50', u'system_id': u'ngx6cx', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/ngx6cx/interfaces/14/'}, {u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 19}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:49', u'system_id': u'ngx6cx', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/ngx6cx/interfaces/13/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'p6cgg4', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'mode': u'link_up', u'id': 20}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'p6cgg4', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'p6cgg4', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.40'}], u'mac_address': u'9c:b6:54:8a:10:1c', u'system_id': u'ngx6cx', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/ngx6cx/interfaces/15/'}, {u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 21}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:48', u'system_id': u'ngx6cx', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/ngx6cx/interfaces/11/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:51', u'system_id': u'ngx6cx', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/ngx6cx/interfaces/12/'}], 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'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'min_hwe_kernel': u'ga-16.04', u'commissioning_status_name': u'Passed', u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'blockdevice_set': [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/ngx6cx/blockdevices/1/', u'system_id': u'ngx6cx', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456, u'used_size': 800106479616, u'id': 1, u'partitions': [{u'uuid': u'e3a3c8c4-4871-4f4d-908e-a14a4b1aedc4', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ngx6cx', u'device_id': 1, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'5d9cbbfb-64df-4893-a191-d85459fff963', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/ngx6cx/blockdevices/1/partition/6'}]}, {u'model': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'c520088e-79a9-419a-a9fc-d5ab2d33edcf', u'fstype': u'ext4'}, u'uuid': u'b1db85e2-7dbb-433e-bbaa-02d5a9025741', u'used_for': u'ext4 formatted filesystem mounted at /', u'resource_uri': u'/MAAS/api/2.0/nodes/ngx6cx/blockdevices/11/', u'system_id': u'ngx6cx', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'size': 107374182400, u'used_size': 107374182400, u'id': 11, u'partitions': []}], u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/ngx6cx/'}
2019-04-25 02:14:46,043 [salt.state       :300 ][INFO    ][7776] {'new': {'storage_layout': 'lvm'}}
2019-04-25 02:14:46,044 [salt.state       :1951][INFO    ][7776] Completed state [maas_machines_storage_cmp002_lvm] at time 02:14:46.044409 duration_in_ms=2484.758
2019-04-25 02:14:46,045 [salt.state       :1780][INFO    ][7776] Running state [maas_machines_storage_cmp001_lvm] at time 02:14:46.045172
2019-04-25 02:14:46,045 [salt.state       :1813][INFO    ][7776] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-04-25 02:14:47,286 [salt.loaded.ext.module.maasng:610 ][INFO    ][7776] wndnga
2019-04-25 02:14:47,286 [salt.loaded.ext.module.maasng:626 ][INFO    ][7776] sda
2019-04-25 02:14:47,869 [salt.loaded.ext.module.maasng:361 ][INFO    ][7776] wndnga
2019-04-25 02:14:47,962 [salt.loaded.ext.module.maasng:367 ][INFO    ][7776] [{u'block_size': 4096, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/wndnga/blockdevices/2/', u'type': u'physical', u'path': u'/dev/disk/by-dname/sda', u'tags': [u'ssd'], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'system_id': u'wndnga', u'partition_table_type': u'MBR', u'filesystem': None, u'id': 2, u'available_size': 0, u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'9d929e70-ea96-4525-9c09-a2bba42ea56f', u'resource_uri': u'/MAAS/api/2.0/nodes/wndnga/blockdevices/2/partition/2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'wndnga', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'd2244f5e-cc5f-4b60-9e03-adf862be7323', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 2, u'device_id': 2}], u'used_size': 800106479616, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, {u'block_size': 4096, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/wndnga/blockdevices/4/', u'type': u'virtual', u'path': u'/dev/disk/by-dname/lvroot', u'tags': [], u'uuid': u'319ba5f5-9931-428d-a072-a0e26a919a03', u'used_for': u'ext4 formatted filesystem mounted at /', u'id_path': None, u'system_id': u'wndnga', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'523073be-de13-4f21-96cc-06ad3e226c31', u'fstype': u'ext4'}, u'id': 4, u'available_size': 0, u'model': None, u'partitions': [], u'used_size': 800097042432, u'serial': None, u'size': 800097042432}]
2019-04-25 02:14:47,963 [salt.loaded.ext.module.maasng:632 ][INFO    ][7776] vgroot
2019-04-25 02:14:47,963 [salt.loaded.ext.module.maasng:635 ][INFO    ][7776] lvroot
2019-04-25 02:14:47,963 [salt.loaded.ext.module.maasng:639 ][INFO    ][7776] 107374182400
2019-04-25 02:14:48,506 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425021448497336
2019-04-25 02:14:48,525 [salt.minion      :1432][INFO    ][7854] Starting a new job with PID 7854
2019-04-25 02:14:48,552 [salt.loaded.ext.module.maasng:645 ][INFO    ][7776] {u'hwe_kernel': u'', u'swap_size': None, u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.39', u'192.168.11.41'], u'storage_test_status_name': u'Passed', u'owner_data': {}, u'memory_test_status_name': u'Unknown', u'boot_interface': {u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'p6cgg4', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'p6cgg4', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 24}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'p6cgg4', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'system_id': u'wndnga', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/wndnga/interfaces/5/'}, u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'wndnga', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'path': u'/dev/disk/by-dname/sda', u'partitions': [{u'uuid': u'883f33b4-da62-4d94-99b7-b18ced7145d7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'wndnga', u'device_id': 2, u'filesystem': {u'uuid': u'6bcf6057-940b-4a81-864a-297e85f489fd', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/wndnga/blockdevices/2/partition/7'}], u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/wndnga/blockdevices/2/'}, u'zone': {u'description': u'', u'id': 1, u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'current_commissioning_result_id': 4, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'wndnga', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, 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'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'disable_ipv4': False, u'blockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'wndnga', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'path': u'/dev/disk/by-dname/sda', u'partitions': [{u'uuid': u'883f33b4-da62-4d94-99b7-b18ced7145d7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'wndnga', u'device_id': 2, u'filesystem': {u'uuid': u'6bcf6057-940b-4a81-864a-297e85f489fd', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/wndnga/blockdevices/2/partition/7'}], u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/wndnga/blockdevices/2/'}, {u'size': 107374182400, u'model': None, u'block_size': 4096, u'uuid': u'c9c04326-29df-4c89-a97d-5d8a6455a580', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'wndnga', u'partition_table_type': None, u'filesystem': {u'uuid': u'6a1af339-f4e5-4355-9f02-7f1e66b28ca0', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'path': u'/dev/disk/by-dname/lvroot', u'partitions': [], u'type': u'virtual', u'id': 12, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/wndnga/blockdevices/12/'}], u'status': 4, u'storage_test_status': 2, u'cpu_count': 40, u'power_state': u'off', u'power_type': u'ipmi', u'physicalblockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'wndnga', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'path': u'/dev/disk/by-dname/sda', u'partitions': [{u'uuid': u'883f33b4-da62-4d94-99b7-b18ced7145d7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'wndnga', u'device_id': 2, u'filesystem': {u'uuid': u'6bcf6057-940b-4a81-864a-297e85f489fd', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/wndnga/blockdevices/2/partition/7'}], u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/wndnga/blockdevices/2/'}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'wndnga', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'commissioning_status_name': u'Passed', u'current_testing_result_id': 5, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'bcaches': [], u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'node_type': 0, u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'uuid': u'c9c04326-29df-4c89-a97d-5d8a6455a580', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'wndnga', u'partition_table_type': None, u'filesystem': {u'uuid': u'6a1af339-f4e5-4355-9f02-7f1e66b28ca0', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'partitions': [], u'type': u'virtual', u'id': 12, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/wndnga/blockdevices/12/'}], u'commissioning_status': 2, u'min_hwe_kernel': u'ga-16.04', u'interface_set': [{u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'p6cgg4', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'p6cgg4', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 24}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'p6cgg4', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'system_id': u'wndnga', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/wndnga/interfaces/5/'}, {u'vlan': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d4', u'system_id': u'wndnga', u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/wndnga/interfaces/19/'}, {u'vlan': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d5', u'system_id': u'wndnga', u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/wndnga/interfaces/20/'}, {u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'p6cgg4', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'p6cgg4', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'mode': u'link_up', u'id': 25}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'p6cgg4', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.41'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a4', u'system_id': u'wndnga', u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/wndnga/interfaces/16/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'name': u'ens2f1', u'links': [{u'id': 26, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cd', u'system_id': u'wndnga', u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/wndnga/interfaces/17/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'name': u'ens2f0', u'links': [{u'id': 27, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cc', u'system_id': u'wndnga', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/wndnga/interfaces/18/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/wndnga/'}
2019-04-25 02:14:48,553 [salt.state       :300 ][INFO    ][7776] {'new': {'storage_layout': 'lvm'}}
2019-04-25 02:14:48,553 [salt.state       :1951][INFO    ][7776] Completed state [maas_machines_storage_cmp001_lvm] at time 02:14:48.553848 duration_in_ms=2508.677
2019-04-25 02:14:48,560 [salt.minion      :1711][INFO    ][7776] Returning information for job: 20190425021433392314
2019-04-25 02:14:48,560 [salt.minion      :1711][INFO    ][7854] Returning information for job: 20190425021448497336
2019-04-25 02:14:49,169 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command state.apply with jid 20190425021449157522
2019-04-25 02:14:49,186 [salt.minion      :1432][INFO    ][7861] Starting a new job with PID 7861
2019-04-25 02:14:50,312 [salt.state       :915 ][INFO    ][7861] Loading fresh modules for state activity
2019-04-25 02:14:50,366 [salt.fileclient  :1219][INFO    ][7861] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-04-25 02:14:50,416 [salt.state       :1780][INFO    ][7861] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:14:50.416398
2019-04-25 02:14:50,416 [salt.state       :1813][INFO    ][7861] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-25 02:14:50,418 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7861] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-25 02:14:52,354 [salt.state       :300 ][INFO    ][7861] {'pid': 7868, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-25 02:14:52,355 [salt.state       :1951][INFO    ][7861] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:14:52.355374 duration_in_ms=1938.975
2019-04-25 02:14:52,358 [salt.state       :1780][INFO    ][7861] Running state [maas.deploy_machines] at time 02:14:52.358580
2019-04-25 02:14:52,360 [salt.state       :1813][INFO    ][7861] Executing state module.run for [maas.deploy_machines]
2019-04-25 02:14:52,361 [salt.utils.decorators:613 ][WARNING ][7861] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-25 02:14:52,931 [salt.loaded.ext.module.maas:684 ][INFO    ][7861] deploymachines hwe_kernel=ga-16.04 system_id=ngx6cx distro_series=xenial
2019-04-25 02:14:55,341 [salt.loaded.ext.module.maas:684 ][INFO    ][7861] deploymachines hwe_kernel=ga-16.04 system_id=wndnga distro_series=xenial
2019-04-25 02:14:57,577 [salt.loaded.ext.module.maas:684 ][INFO    ][7861] deploymachines hwe_kernel=ga-16.04 system_id=pyf6yk distro_series=xenial
2019-04-25 02:14:59,845 [salt.loaded.ext.module.maas:684 ][INFO    ][7861] deploymachines hwe_kernel=ga-16.04 system_id=eg4han distro_series=xenial
2019-04-25 02:15:02,238 [salt.loaded.ext.module.maas:684 ][INFO    ][7861] deploymachines hwe_kernel=ga-16.04 system_id=bdsw4p distro_series=xenial
2019-04-25 02:15:04,260 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425021504252018
2019-04-25 02:15:04,280 [salt.minion      :1432][INFO    ][8164] Starting a new job with PID 8164
2019-04-25 02:15:04,313 [salt.minion      :1711][INFO    ][8164] Returning information for job: 20190425021504252018
2019-04-25 02:15:04,794 [salt.state       :300 ][INFO    ][7861] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-04-25 02:15:04,797 [salt.state       :1951][INFO    ][7861] Completed state [maas.deploy_machines] at time 02:15:04.797666 duration_in_ms=12439.086
2019-04-25 02:15:04,800 [salt.minion      :1711][INFO    ][7861] Returning information for job: 20190425021449157522
2019-04-25 02:15:05,504 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command state.apply with jid 20190425021505489335
2019-04-25 02:15:05,522 [salt.minion      :1432][INFO    ][8180] Starting a new job with PID 8180
2019-04-25 02:15:13,576 [salt.state       :915 ][INFO    ][8180] Loading fresh modules for state activity
2019-04-25 02:15:13,637 [salt.fileclient  :1219][INFO    ][8180] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-04-25 02:15:13,687 [salt.state       :1780][INFO    ][8180] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:15:13.687718
2019-04-25 02:15:13,688 [salt.state       :1813][INFO    ][8180] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-25 02:15:13,690 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8180] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-25 02:15:15,670 [salt.state       :300 ][INFO    ][8180] {'pid': 8205, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-25 02:15:15,671 [salt.state       :1951][INFO    ][8180] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:15:15.671027 duration_in_ms=1983.31
2019-04-25 02:15:15,674 [salt.state       :1780][INFO    ][8180] Running state [maas.wait_for_machine_status] at time 02:15:15.674248
2019-04-25 02:15:15,674 [salt.state       :1813][INFO    ][8180] Executing state module.run for [maas.wait_for_machine_status]
2019-04-25 02:15:15,676 [salt.utils.decorators:613 ][WARNING ][8180] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-25 02:15:18,508 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.17998981s left)
2019-04-25 02:15:20,551 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425021520532840
2019-04-25 02:15:20,575 [salt.minion      :1432][INFO    ][8227] Starting a new job with PID 8227
2019-04-25 02:15:20,602 [salt.minion      :1711][INFO    ][8227] Returning information for job: 20190425021520532840
2019-04-25 02:15:50,759 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425021550751497
2019-04-25 02:15:50,780 [salt.minion      :1432][INFO    ][8273] Starting a new job with PID 8273
2019-04-25 02:15:50,812 [salt.minion      :1711][INFO    ][8273] Returning information for job: 20190425021550751497
2019-04-25 02:15:51,455 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.23342896s left)
2019-04-25 02:16:20,793 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425021620780103
2019-04-25 02:16:20,815 [salt.minion      :1432][INFO    ][8338] Starting a new job with PID 8338
2019-04-25 02:16:20,841 [salt.minion      :1711][INFO    ][8338] Returning information for job: 20190425021620780103
2019-04-25 02:16:24,362 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.32639194s left)
2019-04-25 02:16:50,990 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425021650979395
2019-04-25 02:16:51,012 [salt.minion      :1432][INFO    ][8391] Starting a new job with PID 8391
2019-04-25 02:16:51,041 [salt.minion      :1711][INFO    ][8391] Returning information for job: 20190425021650979395
2019-04-25 02:16:57,110 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2148.57874799s left)
2019-04-25 02:17:21,187 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425021721178739
2019-04-25 02:17:21,211 [salt.minion      :1432][INFO    ][8468] Starting a new job with PID 8468
2019-04-25 02:17:21,238 [salt.minion      :1711][INFO    ][8468] Returning information for job: 20190425021721178739
2019-04-25 02:17:29,877 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2115.81147695s left)
2019-04-25 02:17:51,257 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425021751239806
2019-04-25 02:17:51,274 [salt.minion      :1432][INFO    ][8528] Starting a new job with PID 8528
2019-04-25 02:17:51,302 [salt.minion      :1711][INFO    ][8528] Returning information for job: 20190425021751239806
2019-04-25 02:18:02,515 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2083.17346883s left)
2019-04-25 02:18:21,305 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425021821297595
2019-04-25 02:18:21,321 [salt.minion      :1432][INFO    ][8683] Starting a new job with PID 8683
2019-04-25 02:18:21,346 [salt.minion      :1711][INFO    ][8683] Returning information for job: 20190425021821297595
2019-04-25 02:18:35,616 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2050.07212782s left)
2019-04-25 02:18:51,504 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425021851496646
2019-04-25 02:18:51,518 [salt.minion      :1432][INFO    ][8760] Starting a new job with PID 8760
2019-04-25 02:18:51,550 [salt.minion      :1711][INFO    ][8760] Returning information for job: 20190425021851496646
2019-04-25 02:19:08,477 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2017.21117687s left)
2019-04-25 02:19:21,535 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425021921526566
2019-04-25 02:19:21,552 [salt.minion      :1432][INFO    ][9095] Starting a new job with PID 9095
2019-04-25 02:19:21,583 [salt.minion      :1711][INFO    ][9095] Returning information for job: 20190425021921526566
2019-04-25 02:19:41,196 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1984.49233389s left)
2019-04-25 02:19:51,628 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425021951614650
2019-04-25 02:19:51,647 [salt.minion      :1432][INFO    ][9193] Starting a new job with PID 9193
2019-04-25 02:19:51,677 [salt.minion      :1711][INFO    ][9193] Returning information for job: 20190425021951614650
2019-04-25 02:20:14,134 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1951.55418587s left)
2019-04-25 02:20:21,693 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425022021678127
2019-04-25 02:20:21,716 [salt.minion      :1432][INFO    ][9425] Starting a new job with PID 9425
2019-04-25 02:20:21,745 [salt.minion      :1711][INFO    ][9425] Returning information for job: 20190425022021678127
2019-04-25 02:20:47,065 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1918.62386799s left)
2019-04-25 02:20:51,922 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425022051905926
2019-04-25 02:20:51,945 [salt.minion      :1432][INFO    ][9489] Starting a new job with PID 9489
2019-04-25 02:20:51,973 [salt.minion      :1711][INFO    ][9489] Returning information for job: 20190425022051905926
2019-04-25 02:21:19,933 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1885.75514984s left)
2019-04-25 02:21:22,018 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425022122008695
2019-04-25 02:21:22,041 [salt.minion      :1432][INFO    ][9968] Starting a new job with PID 9968
2019-04-25 02:21:22,070 [salt.minion      :1711][INFO    ][9968] Returning information for job: 20190425022122008695
2019-04-25 02:21:52,037 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425022152025768
2019-04-25 02:21:52,054 [salt.minion      :1432][INFO    ][10025] Starting a new job with PID 10025
2019-04-25 02:21:52,088 [salt.minion      :1711][INFO    ][10025] Returning information for job: 20190425022152025768
2019-04-25 02:21:52,865 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1852.82371593s left)
2019-04-25 02:22:22,115 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425022222100012
2019-04-25 02:22:22,141 [salt.minion      :1432][INFO    ][10408] Starting a new job with PID 10408
2019-04-25 02:22:22,172 [salt.minion      :1711][INFO    ][10408] Returning information for job: 20190425022222100012
2019-04-25 02:22:25,752 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1819.93600988s left)
2019-04-25 02:22:52,138 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425022252121901
2019-04-25 02:22:52,162 [salt.minion      :1432][INFO    ][10450] Starting a new job with PID 10450
2019-04-25 02:22:52,193 [salt.minion      :1711][INFO    ][10450] Returning information for job: 20190425022252121901
2019-04-25 02:22:58,650 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1787.0388329s left)
2019-04-25 02:23:22,289 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425022322273513
2019-04-25 02:23:22,308 [salt.minion      :1432][INFO    ][10669] Starting a new job with PID 10669
2019-04-25 02:23:22,342 [salt.minion      :1711][INFO    ][10669] Returning information for job: 20190425022322273513
2019-04-25 02:23:31,698 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1753.9909029s left)
2019-04-25 02:23:52,338 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425022352321234
2019-04-25 02:23:52,363 [salt.minion      :1432][INFO    ][10732] Starting a new job with PID 10732
2019-04-25 02:23:52,393 [salt.minion      :1711][INFO    ][10732] Returning information for job: 20190425022352321234
2019-04-25 02:24:05,181 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1720.50720096s left)
2019-04-25 02:24:22,441 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425022422432033
2019-04-25 02:24:22,458 [salt.minion      :1432][INFO    ][10953] Starting a new job with PID 10953
2019-04-25 02:24:22,486 [salt.minion      :1711][INFO    ][10953] Returning information for job: 20190425022422432033
2019-04-25 02:24:37,934 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1687.75440192s left)
2019-04-25 02:24:52,471 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425022452456107
2019-04-25 02:24:52,495 [salt.minion      :1432][INFO    ][11015] Starting a new job with PID 11015
2019-04-25 02:24:52,522 [salt.minion      :1711][INFO    ][11015] Returning information for job: 20190425022452456107
2019-04-25 02:25:10,775 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1654.91310883s left)
2019-04-25 02:25:22,494 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425022522480425
2019-04-25 02:25:22,517 [salt.minion      :1432][INFO    ][11174] Starting a new job with PID 11174
2019-04-25 02:25:22,547 [salt.minion      :1711][INFO    ][11174] Returning information for job: 20190425022522480425
2019-04-25 02:25:43,604 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1622.08452392s left)
2019-04-25 02:25:52,645 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425022552631405
2019-04-25 02:25:52,667 [salt.minion      :1432][INFO    ][11225] Starting a new job with PID 11225
2019-04-25 02:25:52,694 [salt.minion      :1711][INFO    ][11225] Returning information for job: 20190425022552631405
2019-04-25 02:26:16,688 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1589.00117779s left)
2019-04-25 02:26:22,777 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425022622765705
2019-04-25 02:26:22,800 [salt.minion      :1432][INFO    ][11479] Starting a new job with PID 11479
2019-04-25 02:26:22,833 [salt.minion      :1711][INFO    ][11479] Returning information for job: 20190425022622765705
2019-04-25 02:26:49,474 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1556.21424985s left)
2019-04-25 02:26:52,837 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425022652824385
2019-04-25 02:26:52,861 [salt.minion      :1432][INFO    ][11654] Starting a new job with PID 11654
2019-04-25 02:26:52,892 [salt.minion      :1711][INFO    ][11654] Returning information for job: 20190425022652824385
2019-04-25 02:27:22,352 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1523.33683586s left)
2019-04-25 02:27:23,033 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425022723016986
2019-04-25 02:27:23,058 [salt.minion      :1432][INFO    ][11727] Starting a new job with PID 11727
2019-04-25 02:27:23,086 [salt.minion      :1711][INFO    ][11727] Returning information for job: 20190425022723016986
2019-04-25 02:27:53,173 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425022753164592
2019-04-25 02:27:53,193 [salt.minion      :1432][INFO    ][11806] Starting a new job with PID 11806
2019-04-25 02:27:53,225 [salt.minion      :1711][INFO    ][11806] Returning information for job: 20190425022753164592
2019-04-25 02:27:55,097 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1490.5914948s left)
2019-04-25 02:28:23,333 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425022823321104
2019-04-25 02:28:23,353 [salt.minion      :1432][INFO    ][11971] Starting a new job with PID 11971
2019-04-25 02:28:23,385 [salt.minion      :1711][INFO    ][11971] Returning information for job: 20190425022823321104
2019-04-25 02:28:27,950 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1457.73884988s left)
2019-04-25 02:28:53,530 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425022853517015
2019-04-25 02:28:53,552 [salt.minion      :1432][INFO    ][12017] Starting a new job with PID 12017
2019-04-25 02:28:53,582 [salt.minion      :1711][INFO    ][12017] Returning information for job: 20190425022853517015
2019-04-25 02:29:00,745 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1424.94324899s left)
2019-04-25 02:29:23,688 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425022923676961
2019-04-25 02:29:23,711 [salt.minion      :1432][INFO    ][12246] Starting a new job with PID 12246
2019-04-25 02:29:23,740 [salt.minion      :1711][INFO    ][12246] Returning information for job: 20190425022923676961
2019-04-25 02:29:33,681 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1392.00787497s left)
2019-04-25 02:29:53,770 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425022953757755
2019-04-25 02:29:53,795 [salt.minion      :1432][INFO    ][12289] Starting a new job with PID 12289
2019-04-25 02:29:53,825 [salt.minion      :1711][INFO    ][12289] Returning information for job: 20190425022953757755
2019-04-25 02:30:06,648 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1359.04003191s left)
2019-04-25 02:30:23,872 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425023023863544
2019-04-25 02:30:23,899 [salt.minion      :1432][INFO    ][12349] Starting a new job with PID 12349
2019-04-25 02:30:23,930 [salt.minion      :1711][INFO    ][12349] Returning information for job: 20190425023023863544
2019-04-25 02:30:39,454 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1326.23409986s left)
2019-04-25 02:30:53,981 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425023053972844
2019-04-25 02:30:53,997 [salt.minion      :1432][INFO    ][12397] Starting a new job with PID 12397
2019-04-25 02:30:54,028 [salt.minion      :1711][INFO    ][12397] Returning information for job: 20190425023053972844
2019-04-25 02:31:12,309 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1293.37970495s left)
2019-04-25 02:31:24,098 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425023124080518
2019-04-25 02:31:24,120 [salt.minion      :1432][INFO    ][12459] Starting a new job with PID 12459
2019-04-25 02:31:24,152 [salt.minion      :1711][INFO    ][12459] Returning information for job: 20190425023124080518
2019-04-25 02:31:45,143 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1260.5454309s left)
2019-04-25 02:31:54,241 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425023154228083
2019-04-25 02:31:54,263 [salt.minion      :1432][INFO    ][12500] Starting a new job with PID 12500
2019-04-25 02:31:54,296 [salt.minion      :1711][INFO    ][12500] Returning information for job: 20190425023154228083
2019-04-25 02:32:17,779 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1227.90957284s left)
2019-04-25 02:32:24,462 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425023224452904
2019-04-25 02:32:24,478 [salt.minion      :1432][INFO    ][12561] Starting a new job with PID 12561
2019-04-25 02:32:24,509 [salt.minion      :1711][INFO    ][12561] Returning information for job: 20190425023224452904
2019-04-25 02:32:50,462 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1195.2263s left)
2019-04-25 02:32:54,565 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425023254552263
2019-04-25 02:32:54,593 [salt.minion      :1432][INFO    ][12612] Starting a new job with PID 12612
2019-04-25 02:32:54,623 [salt.minion      :1711][INFO    ][12612] Returning information for job: 20190425023254552263
2019-04-25 02:33:23,341 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1162.347646s left)
2019-04-25 02:33:24,655 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425023324645143
2019-04-25 02:33:24,680 [salt.minion      :1432][INFO    ][12672] Starting a new job with PID 12672
2019-04-25 02:33:24,712 [salt.minion      :1711][INFO    ][12672] Returning information for job: 20190425023324645143
2019-04-25 02:33:54,794 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425023354782865
2019-04-25 02:33:54,812 [salt.minion      :1432][INFO    ][12713] Starting a new job with PID 12713
2019-04-25 02:33:54,845 [salt.minion      :1711][INFO    ][12713] Returning information for job: 20190425023354782865
2019-04-25 02:33:56,050 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1129.63864398s left)
2019-04-25 02:34:24,893 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425023424884945
2019-04-25 02:34:24,908 [salt.minion      :1432][INFO    ][12775] Starting a new job with PID 12775
2019-04-25 02:34:24,936 [salt.minion      :1711][INFO    ][12775] Returning information for job: 20190425023424884945
2019-04-25 02:34:28,685 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1097.00333285s left)
2019-04-25 02:34:55,004 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425023454995159
2019-04-25 02:34:55,025 [salt.minion      :1432][INFO    ][12816] Starting a new job with PID 12816
2019-04-25 02:34:55,058 [salt.minion      :1711][INFO    ][12816] Returning information for job: 20190425023454995159
2019-04-25 02:35:01,574 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1064.11477685s left)
2019-04-25 02:35:25,141 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425023525133193
2019-04-25 02:35:25,164 [salt.minion      :1432][INFO    ][12876] Starting a new job with PID 12876
2019-04-25 02:35:25,201 [salt.minion      :1711][INFO    ][12876] Returning information for job: 20190425023525133193
2019-04-25 02:35:34,273 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1031.41563392s left)
2019-04-25 02:35:55,291 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425023555282439
2019-04-25 02:35:55,307 [salt.minion      :1432][INFO    ][12919] Starting a new job with PID 12919
2019-04-25 02:35:55,340 [salt.minion      :1711][INFO    ][12919] Returning information for job: 20190425023555282439
2019-04-25 02:36:06,914 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (998.77418685s left)
2019-04-25 02:36:25,453 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425023625446277
2019-04-25 02:36:25,466 [salt.minion      :1432][INFO    ][12978] Starting a new job with PID 12978
2019-04-25 02:36:25,499 [salt.minion      :1711][INFO    ][12978] Returning information for job: 20190425023625446277
2019-04-25 02:36:39,704 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (965.984076977s left)
2019-04-25 02:36:55,675 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425023655657679
2019-04-25 02:36:55,702 [salt.minion      :1432][INFO    ][13038] Starting a new job with PID 13038
2019-04-25 02:36:55,740 [salt.minion      :1711][INFO    ][13038] Returning information for job: 20190425023655657679
2019-04-25 02:37:12,121 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (933.567909956s left)
2019-04-25 02:37:25,711 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425023725699448
2019-04-25 02:37:25,734 [salt.minion      :1432][INFO    ][13110] Starting a new job with PID 13110
2019-04-25 02:37:25,770 [salt.minion      :1711][INFO    ][13110] Returning information for job: 20190425023725699448
2019-04-25 02:37:44,807 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (900.881258011s left)
2019-04-25 02:37:55,916 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425023755906473
2019-04-25 02:37:55,930 [salt.minion      :1432][INFO    ][13160] Starting a new job with PID 13160
2019-04-25 02:37:55,964 [salt.minion      :1711][INFO    ][13160] Returning information for job: 20190425023755906473
2019-04-25 02:38:17,447 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (868.241008997s left)
2019-04-25 02:38:25,950 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425023825938518
2019-04-25 02:38:25,966 [salt.minion      :1432][INFO    ][13214] Starting a new job with PID 13214
2019-04-25 02:38:25,997 [salt.minion      :1711][INFO    ][13214] Returning information for job: 20190425023825938518
2019-04-25 02:38:50,133 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (835.555727005s left)
2019-04-25 02:38:56,157 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425023856150095
2019-04-25 02:38:56,170 [salt.minion      :1432][INFO    ][13262] Starting a new job with PID 13262
2019-04-25 02:38:56,205 [salt.minion      :1711][INFO    ][13262] Returning information for job: 20190425023856150095
2019-04-25 02:39:22,906 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (802.782951832s left)
2019-04-25 02:39:26,309 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425023926297451
2019-04-25 02:39:26,328 [salt.minion      :1432][INFO    ][13317] Starting a new job with PID 13317
2019-04-25 02:39:26,364 [salt.minion      :1711][INFO    ][13317] Returning information for job: 20190425023926297451
2019-04-25 02:39:55,541 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (770.147605896s left)
2019-04-25 02:39:56,352 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425023956344440
2019-04-25 02:39:56,370 [salt.minion      :1432][INFO    ][13371] Starting a new job with PID 13371
2019-04-25 02:39:56,401 [salt.minion      :1711][INFO    ][13371] Returning information for job: 20190425023956344440
2019-04-25 02:40:26,426 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425024026417928
2019-04-25 02:40:26,443 [salt.minion      :1432][INFO    ][13418] Starting a new job with PID 13418
2019-04-25 02:40:26,479 [salt.minion      :1711][INFO    ][13418] Returning information for job: 20190425024026417928
2019-04-25 02:40:28,413 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (737.27508378s left)
2019-04-25 02:40:56,620 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425024056609290
2019-04-25 02:40:56,642 [salt.minion      :1432][INFO    ][13472] Starting a new job with PID 13472
2019-04-25 02:40:56,676 [salt.minion      :1711][INFO    ][13472] Returning information for job: 20190425024056609290
2019-04-25 02:41:01,134 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (704.554317951s left)
2019-04-25 02:41:26,737 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425024126728132
2019-04-25 02:41:26,759 [salt.minion      :1432][INFO    ][13521] Starting a new job with PID 13521
2019-04-25 02:41:26,789 [salt.minion      :1711][INFO    ][13521] Returning information for job: 20190425024126728132
2019-04-25 02:41:33,824 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (671.864657879s left)
2019-04-25 02:41:56,957 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425024156943659
2019-04-25 02:41:56,975 [salt.minion      :1432][INFO    ][13575] Starting a new job with PID 13575
2019-04-25 02:41:57,008 [salt.minion      :1711][INFO    ][13575] Returning information for job: 20190425024156943659
2019-04-25 02:42:06,818 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (638.870492935s left)
2019-04-25 02:42:27,157 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425024227142949
2019-04-25 02:42:27,180 [salt.minion      :1432][INFO    ][13625] Starting a new job with PID 13625
2019-04-25 02:42:27,213 [salt.minion      :1711][INFO    ][13625] Returning information for job: 20190425024227142949
2019-04-25 02:42:39,504 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (606.18422389s left)
2019-04-25 02:42:57,169 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425024257162312
2019-04-25 02:42:57,186 [salt.minion      :1432][INFO    ][13680] Starting a new job with PID 13680
2019-04-25 02:42:57,217 [salt.minion      :1711][INFO    ][13680] Returning information for job: 20190425024257162312
2019-04-25 02:43:12,165 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (573.523919821s left)
2019-04-25 02:43:27,377 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425024327362736
2019-04-25 02:43:27,397 [salt.minion      :1432][INFO    ][13729] Starting a new job with PID 13729
2019-04-25 02:43:27,431 [salt.minion      :1711][INFO    ][13729] Returning information for job: 20190425024327362736
2019-04-25 02:43:44,902 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (540.786810875s left)
2019-04-25 02:43:57,594 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425024357586518
2019-04-25 02:43:57,620 [salt.minion      :1432][INFO    ][13783] Starting a new job with PID 13783
2019-04-25 02:43:57,653 [salt.minion      :1711][INFO    ][13783] Returning information for job: 20190425024357586518
2019-04-25 02:44:17,610 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (508.078188896s left)
2019-04-25 02:44:27,815 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425024427799704
2019-04-25 02:44:27,837 [salt.minion      :1432][INFO    ][13832] Starting a new job with PID 13832
2019-04-25 02:44:27,869 [salt.minion      :1711][INFO    ][13832] Returning information for job: 20190425024427799704
2019-04-25 02:44:50,387 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (475.301488876s left)
2019-04-25 02:44:57,876 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425024457860163
2019-04-25 02:44:57,896 [salt.minion      :1432][INFO    ][13887] Starting a new job with PID 13887
2019-04-25 02:44:57,929 [salt.minion      :1711][INFO    ][13887] Returning information for job: 20190425024457860163
2019-04-25 02:45:23,197 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (442.491290808s left)
2019-04-25 02:45:28,109 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425024528094835
2019-04-25 02:45:28,128 [salt.minion      :1432][INFO    ][13934] Starting a new job with PID 13934
2019-04-25 02:45:28,162 [salt.minion      :1711][INFO    ][13934] Returning information for job: 20190425024528094835
2019-04-25 02:45:55,871 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (409.817617893s left)
2019-04-25 02:45:58,175 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425024558160472
2019-04-25 02:45:58,197 [salt.minion      :1432][INFO    ][13990] Starting a new job with PID 13990
2019-04-25 02:45:58,231 [salt.minion      :1711][INFO    ][13990] Returning information for job: 20190425024558160472
2019-04-25 02:46:28,336 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425024628327748
2019-04-25 02:46:28,355 [salt.minion      :1432][INFO    ][14041] Starting a new job with PID 14041
2019-04-25 02:46:28,388 [salt.minion      :1711][INFO    ][14041] Returning information for job: 20190425024628327748
2019-04-25 02:46:28,625 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (377.063143969s left)
2019-04-25 02:46:58,541 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425024658528465
2019-04-25 02:46:58,570 [salt.minion      :1432][INFO    ][14229] Starting a new job with PID 14229
2019-04-25 02:46:58,605 [salt.minion      :1711][INFO    ][14229] Returning information for job: 20190425024658528465
2019-04-25 02:47:01,422 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (344.266145945s left)
2019-04-25 02:47:28,604 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425024728591491
2019-04-25 02:47:28,628 [salt.minion      :1432][INFO    ][14287] Starting a new job with PID 14287
2019-04-25 02:47:28,661 [salt.minion      :1711][INFO    ][14287] Returning information for job: 20190425024728591491
2019-04-25 02:47:34,118 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (311.570313931s left)
2019-04-25 02:47:58,797 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425024758788063
2019-04-25 02:47:58,818 [salt.minion      :1432][INFO    ][14341] Starting a new job with PID 14341
2019-04-25 02:47:58,850 [salt.minion      :1711][INFO    ][14341] Returning information for job: 20190425024758788063
2019-04-25 02:48:07,017 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (278.671395779s left)
2019-04-25 02:48:28,918 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425024828903276
2019-04-25 02:48:28,943 [salt.minion      :1432][INFO    ][14391] Starting a new job with PID 14391
2019-04-25 02:48:28,976 [salt.minion      :1711][INFO    ][14391] Returning information for job: 20190425024828903276
2019-04-25 02:48:39,670 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (246.018910885s left)
2019-04-25 02:48:59,015 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425024859006473
2019-04-25 02:48:59,039 [salt.minion      :1432][INFO    ][14446] Starting a new job with PID 14446
2019-04-25 02:48:59,071 [salt.minion      :1711][INFO    ][14446] Returning information for job: 20190425024859006473
2019-04-25 02:49:12,362 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (213.32643795s left)
2019-04-25 02:49:29,140 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425024929132268
2019-04-25 02:49:29,156 [salt.minion      :1432][INFO    ][14495] Starting a new job with PID 14495
2019-04-25 02:49:29,185 [salt.minion      :1711][INFO    ][14495] Returning information for job: 20190425024929132268
2019-04-25 02:49:45,137 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (180.5517838s left)
2019-04-25 02:49:59,300 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425024959284589
2019-04-25 02:49:59,323 [salt.minion      :1432][INFO    ][14549] Starting a new job with PID 14549
2019-04-25 02:49:59,348 [salt.minion      :1711][INFO    ][14549] Returning information for job: 20190425024959284589
2019-04-25 02:50:17,918 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (147.770465851s left)
2019-04-25 02:50:29,349 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425025029340328
2019-04-25 02:50:29,371 [salt.minion      :1432][INFO    ][14599] Starting a new job with PID 14599
2019-04-25 02:50:29,396 [salt.minion      :1711][INFO    ][14599] Returning information for job: 20190425025029340328
2019-04-25 02:50:50,592 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (115.096099854s left)
2019-04-25 02:50:59,533 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425025059523427
2019-04-25 02:50:59,553 [salt.minion      :1432][INFO    ][14655] Starting a new job with PID 14655
2019-04-25 02:50:59,579 [salt.minion      :1711][INFO    ][14655] Returning information for job: 20190425025059523427
2019-04-25 02:51:23,425 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (82.2630848885s left)
2019-04-25 02:51:29,724 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425025129708280
2019-04-25 02:51:29,746 [salt.minion      :1432][INFO    ][14703] Starting a new job with PID 14703
2019-04-25 02:51:29,770 [salt.minion      :1711][INFO    ][14703] Returning information for job: 20190425025129708280
2019-04-25 02:51:55,994 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (49.6945638657s left)
2019-04-25 02:51:59,750 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425025159735849
2019-04-25 02:51:59,770 [salt.minion      :1432][INFO    ][14759] Starting a new job with PID 14759
2019-04-25 02:51:59,795 [salt.minion      :1711][INFO    ][14759] Returning information for job: 20190425025159735849
2019-04-25 02:52:28,797 [salt.loaded.ext.module.maas:1023][INFO    ][8180] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (16.8916249275s left)
2019-04-25 02:52:29,917 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425025229905042
2019-04-25 02:52:29,940 [salt.minion      :1432][INFO    ][14810] Starting a new job with PID 14810
2019-04-25 02:52:29,968 [salt.minion      :1711][INFO    ][14810] Returning information for job: 20190425025229905042
2019-04-25 02:53:00,013 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425025300001548
2019-04-25 02:53:00,034 [salt.minion      :1432][INFO    ][14863] Starting a new job with PID 14863
2019-04-25 02:53:00,059 [salt.minion      :1711][INFO    ][14863] Returning information for job: 20190425025300001548
2019-04-25 02:53:01,606 [salt.state       :302 ][ERROR   ][8180] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-04-25 02:53:01,606 [salt.state       :1951][INFO    ][8180] Completed state [maas.wait_for_machine_status] at time 02:53:01.606845 duration_in_ms=2265932.581
2019-04-25 02:53:01,613 [salt.minion      :1711][INFO    ][8180] Returning information for job: 20190425021505489335
2019-04-25 02:53:12,402 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command pillar.get with jid 20190425025312390435
2019-04-25 02:53:12,422 [salt.minion      :1432][INFO    ][14901] Starting a new job with PID 14901
2019-04-25 02:53:12,433 [salt.minion      :1711][INFO    ][14901] Returning information for job: 20190425025312390435
2019-04-25 02:53:12,981 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command service.status with jid 20190425025312971856
2019-04-25 02:53:13,001 [salt.minion      :1432][INFO    ][14906] Starting a new job with PID 14906
2019-04-25 02:53:13,519 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][14906] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-04-25 02:53:13,553 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][14906] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-04-25 02:53:13,570 [salt.minion      :1711][INFO    ][14906] Returning information for job: 20190425025312971856
2019-04-25 02:53:14,120 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command state.apply with jid 20190425025314107410
2019-04-25 02:53:14,145 [salt.minion      :1432][INFO    ][14917] Starting a new job with PID 14917
2019-04-25 02:53:22,025 [salt.state       :915 ][INFO    ][14917] Loading fresh modules for state activity
2019-04-25 02:53:22,584 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14917] Executing command 'salt-minion --version' in directory '/root'
2019-04-25 02:53:22,858 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14917] Executing command 'salt-minion --version' in directory '/root'
2019-04-25 02:53:23,806 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14917] Executing command 'salt-minion --version' in directory '/root'
2019-04-25 02:53:24,093 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14917] Executing command 'salt-minion --version' in directory '/root'
2019-04-25 02:53:25,828 [salt.state       :1780][INFO    ][14917] Running state [salt-minion] at time 02:53:25.828308
2019-04-25 02:53:25,828 [salt.state       :1813][INFO    ][14917] Executing state pkg.installed for [salt-minion]
2019-04-25 02:53:25,829 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14917] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-25 02:53:25,934 [salt.state       :300 ][INFO    ][14917] All specified packages are already installed
2019-04-25 02:53:25,935 [salt.state       :1951][INFO    ][14917] Completed state [salt-minion] at time 02:53:25.935592 duration_in_ms=107.283
2019-04-25 02:53:25,935 [salt.state       :1780][INFO    ][14917] Running state [salt_minion_dependency_packages] at time 02:53:25.935929
2019-04-25 02:53:25,936 [salt.state       :1813][INFO    ][14917] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-04-25 02:53:25,945 [salt.state       :300 ][INFO    ][14917] All specified packages are already installed
2019-04-25 02:53:25,945 [salt.state       :1951][INFO    ][14917] Completed state [salt_minion_dependency_packages] at time 02:53:25.945804 duration_in_ms=9.874
2019-04-25 02:53:25,950 [salt.state       :1780][INFO    ][14917] Running state [/etc/salt/minion.d/minion.conf] at time 02:53:25.950052
2019-04-25 02:53:25,950 [salt.state       :1813][INFO    ][14917] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-04-25 02:53:26,222 [salt.state       :300 ][INFO    ][14917] File /etc/salt/minion.d/minion.conf is in the correct state
2019-04-25 02:53:26,222 [salt.state       :1951][INFO    ][14917] Completed state [/etc/salt/minion.d/minion.conf] at time 02:53:26.222338 duration_in_ms=272.286
2019-04-25 02:53:26,222 [salt.state       :1780][INFO    ][14917] Running state [python-netaddr] at time 02:53:26.222591
2019-04-25 02:53:26,223 [salt.state       :1813][INFO    ][14917] Executing state pkg.installed for [python-netaddr]
2019-04-25 02:53:26,232 [salt.state       :300 ][INFO    ][14917] All specified packages are already installed
2019-04-25 02:53:26,232 [salt.state       :1951][INFO    ][14917] Completed state [python-netaddr] at time 02:53:26.232807 duration_in_ms=10.216
2019-04-25 02:53:26,236 [salt.state       :1780][INFO    ][14917] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:53:26.236476
2019-04-25 02:53:26,236 [salt.state       :1813][INFO    ][14917] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-04-25 02:53:26,250 [salt.state       :300 ][INFO    ][14917] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-04-25 02:53:26,250 [salt.state       :1951][INFO    ][14917] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:53:26.250566 duration_in_ms=14.09
2019-04-25 02:53:26,252 [salt.state       :1780][INFO    ][14917] Running state [salt-minion] at time 02:53:26.252486
2019-04-25 02:53:26,252 [salt.state       :1813][INFO    ][14917] Executing state service.running for [salt-minion]
2019-04-25 02:53:26,253 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14917] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-04-25 02:53:26,292 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14917] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-04-25 02:53:26,310 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14917] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-04-25 02:53:26,328 [salt.state       :300 ][INFO    ][14917] The service salt-minion is already running
2019-04-25 02:53:26,329 [salt.state       :1951][INFO    ][14917] Completed state [salt-minion] at time 02:53:26.329149 duration_in_ms=76.663
2019-04-25 02:53:26,331 [salt.state       :1780][INFO    ][14917] Running state [/etc/salt/grains.d] at time 02:53:26.330798
2019-04-25 02:53:26,334 [salt.state       :1813][INFO    ][14917] Executing state file.directory for [/etc/salt/grains.d]
2019-04-25 02:53:26,335 [salt.state       :300 ][INFO    ][14917] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-04-25 02:53:26,335 [salt.state       :1951][INFO    ][14917] Completed state [/etc/salt/grains.d] at time 02:53:26.335333 duration_in_ms=4.534
2019-04-25 02:53:26,336 [salt.state       :1780][INFO    ][14917] Running state [/etc/salt/grains] at time 02:53:26.336002
2019-04-25 02:53:26,336 [salt.state       :1813][INFO    ][14917] Executing state file.managed for [/etc/salt/grains]
2019-04-25 02:53:26,336 [salt.state       :300 ][INFO    ][14917] File /etc/salt/grains exists with proper permissions. No changes made.
2019-04-25 02:53:26,337 [salt.state       :1951][INFO    ][14917] Completed state [/etc/salt/grains] at time 02:53:26.336959 duration_in_ms=0.957
2019-04-25 02:53:26,337 [salt.state       :1780][INFO    ][14917] Running state [/etc/salt/grains.d/placeholder] at time 02:53:26.337417
2019-04-25 02:53:26,337 [salt.state       :1813][INFO    ][14917] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-04-25 02:53:26,338 [salt.state       :300 ][INFO    ][14917] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-04-25 02:53:26,338 [salt.state       :1951][INFO    ][14917] Completed state [/etc/salt/grains.d/placeholder] at time 02:53:26.338374 duration_in_ms=0.958
2019-04-25 02:53:26,338 [salt.state       :1780][INFO    ][14917] Running state [/etc/salt/grains.d/sphinx] at time 02:53:26.338836
2019-04-25 02:53:26,339 [salt.state       :1813][INFO    ][14917] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-04-25 02:53:26,340 [salt.state       :300 ][INFO    ][14917] File /etc/salt/grains.d/sphinx is in the correct state
2019-04-25 02:53:26,340 [salt.state       :1951][INFO    ][14917] Completed state [/etc/salt/grains.d/sphinx] at time 02:53:26.340507 duration_in_ms=1.672
2019-04-25 02:53:26,342 [salt.state       :1780][INFO    ][14917] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:53:26.342563
2019-04-25 02:53:26,344 [salt.state       :1813][INFO    ][14917] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-04-25 02:53:26,345 [salt.state       :300 ][INFO    ][14917] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-04-25 02:53:26,345 [salt.state       :1951][INFO    ][14917] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:53:26.345714 duration_in_ms=3.15
2019-04-25 02:53:26,346 [salt.state       :1780][INFO    ][14917] Running state [/etc/salt/grains.d/dns_records] at time 02:53:26.346588
2019-04-25 02:53:26,347 [salt.state       :1813][INFO    ][14917] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-04-25 02:53:26,348 [salt.state       :300 ][INFO    ][14917] File /etc/salt/grains.d/dns_records is in the correct state
2019-04-25 02:53:26,349 [salt.state       :1951][INFO    ][14917] Completed state [/etc/salt/grains.d/dns_records] at time 02:53:26.349057 duration_in_ms=2.468
2019-04-25 02:53:26,350 [salt.state       :1780][INFO    ][14917] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:53:26.350683
2019-04-25 02:53:26,351 [salt.state       :1813][INFO    ][14917] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-04-25 02:53:26,351 [salt.state       :300 ][INFO    ][14917] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-04-25 02:53:26,352 [salt.state       :1951][INFO    ][14917] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:53:26.352200 duration_in_ms=1.516
2019-04-25 02:53:26,353 [salt.state       :1780][INFO    ][14917] Running state [/etc/salt/grains.d/salt] at time 02:53:26.353030
2019-04-25 02:53:26,353 [salt.state       :1813][INFO    ][14917] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-04-25 02:53:26,356 [salt.state       :300 ][INFO    ][14917] File /etc/salt/grains.d/salt is in the correct state
2019-04-25 02:53:26,356 [salt.state       :1951][INFO    ][14917] Completed state [/etc/salt/grains.d/salt] at time 02:53:26.356870 duration_in_ms=3.841
2019-04-25 02:53:26,357 [salt.state       :1780][INFO    ][14917] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:53:26.357758
2019-04-25 02:53:26,358 [salt.state       :1813][INFO    ][14917] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-04-25 02:53:26,358 [salt.state       :300 ][INFO    ][14917] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-04-25 02:53:26,358 [salt.state       :1951][INFO    ][14917] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:53:26.358526 duration_in_ms=0.768
2019-04-25 02:53:26,360 [salt.state       :1780][INFO    ][14917] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:53:26.360748
2019-04-25 02:53:26,361 [salt.state       :1813][INFO    ][14917] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-04-25 02:53:26,361 [salt.state       :300 ][INFO    ][14917] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-04-25 02:53:26,361 [salt.state       :1951][INFO    ][14917] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:53:26.361507 duration_in_ms=0.759
2019-04-25 02:53:26,362 [salt.state       :1780][INFO    ][14917] Running state [mine.update] at time 02:53:26.362171
2019-04-25 02:53:26,362 [salt.state       :1813][INFO    ][14917] Executing state module.wait for [mine.update]
2019-04-25 02:53:26,362 [salt.state       :300 ][INFO    ][14917] No changes made for mine.update
2019-04-25 02:53:26,363 [salt.state       :1951][INFO    ][14917] Completed state [mine.update] at time 02:53:26.363159 duration_in_ms=0.987
2019-04-25 02:53:26,363 [salt.state       :1780][INFO    ][14917] Running state [ca-certificates] at time 02:53:26.363405
2019-04-25 02:53:26,363 [salt.state       :1813][INFO    ][14917] Executing state pkg.installed for [ca-certificates]
2019-04-25 02:53:26,374 [salt.state       :300 ][INFO    ][14917] All specified packages are already installed
2019-04-25 02:53:26,374 [salt.state       :1951][INFO    ][14917] Completed state [ca-certificates] at time 02:53:26.374778 duration_in_ms=11.373
2019-04-25 02:53:26,375 [salt.state       :1780][INFO    ][14917] Running state [update-ca-certificates] at time 02:53:26.375478
2019-04-25 02:53:26,375 [salt.state       :1813][INFO    ][14917] Executing state cmd.wait for [update-ca-certificates]
2019-04-25 02:53:26,376 [salt.state       :300 ][INFO    ][14917] No changes made for update-ca-certificates
2019-04-25 02:53:26,376 [salt.state       :1951][INFO    ][14917] Completed state [update-ca-certificates] at time 02:53:26.376223 duration_in_ms=0.745
2019-04-25 02:53:26,376 [salt.state       :1780][INFO    ][14917] Running state [iptables] at time 02:53:26.376469
2019-04-25 02:53:26,376 [salt.state       :1813][INFO    ][14917] Executing state pkg.installed for [iptables]
2019-04-25 02:53:26,387 [salt.state       :300 ][INFO    ][14917] All specified packages are already installed
2019-04-25 02:53:26,388 [salt.state       :1951][INFO    ][14917] Completed state [iptables] at time 02:53:26.388035 duration_in_ms=11.566
2019-04-25 02:53:26,388 [salt.state       :1780][INFO    ][14917] Running state [iptables-persistent] at time 02:53:26.388282
2019-04-25 02:53:26,388 [salt.state       :1813][INFO    ][14917] Executing state pkg.installed for [iptables-persistent]
2019-04-25 02:53:26,398 [salt.state       :300 ][INFO    ][14917] All specified packages are already installed
2019-04-25 02:53:26,399 [salt.state       :1951][INFO    ][14917] Completed state [iptables-persistent] at time 02:53:26.399124 duration_in_ms=10.842
2019-04-25 02:53:26,400 [salt.state       :1780][INFO    ][14917] Running state [iptables_modules_v4_load] at time 02:53:26.400108
2019-04-25 02:53:26,400 [salt.state       :1813][INFO    ][14917] Executing state kmod.present for [iptables_modules_v4_load]
2019-04-25 02:53:26,400 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14917] Executing command 'lsmod' in directory '/root'
2019-04-25 02:53:26,419 [salt.state       :300 ][INFO    ][14917] Kernel modules iptable_filter, ip_tables are already present
2019-04-25 02:53:26,420 [salt.state       :1951][INFO    ][14917] Completed state [iptables_modules_v4_load] at time 02:53:26.419956 duration_in_ms=19.846
2019-04-25 02:53:26,421 [salt.state       :1780][INFO    ][14917] Running state [/etc/iptables/rules.v4] at time 02:53:26.421215
2019-04-25 02:53:26,421 [salt.state       :1813][INFO    ][14917] Executing state file.managed for [/etc/iptables/rules.v4]
2019-04-25 02:53:26,524 [salt.state       :300 ][INFO    ][14917] File /etc/iptables/rules.v4 is in the correct state
2019-04-25 02:53:26,524 [salt.state       :1951][INFO    ][14917] Completed state [/etc/iptables/rules.v4] at time 02:53:26.524330 duration_in_ms=103.116
2019-04-25 02:53:26,525 [salt.state       :1780][INFO    ][14917] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:53:26.525415
2019-04-25 02:53:26,525 [salt.state       :1813][INFO    ][14917] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-04-25 02:53:26,526 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14917] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-04-25 02:53:26,544 [salt.state       :300 ][INFO    ][14917] onlyif execution failed
2019-04-25 02:53:26,545 [salt.state       :1951][INFO    ][14917] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:53:26.544926 duration_in_ms=19.511
2019-04-25 02:53:26,546 [salt.state       :1780][INFO    ][14917] Running state [netfilter-persistent] at time 02:53:26.546269
2019-04-25 02:53:26,546 [salt.state       :1813][INFO    ][14917] Executing state service.running for [netfilter-persistent]
2019-04-25 02:53:26,550 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14917] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-04-25 02:53:26,570 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14917] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-04-25 02:53:26,592 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14917] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-04-25 02:53:26,614 [salt.state       :300 ][INFO    ][14917] The service netfilter-persistent is already running
2019-04-25 02:53:26,614 [salt.state       :1951][INFO    ][14917] Completed state [netfilter-persistent] at time 02:53:26.614780 duration_in_ms=68.511
2019-04-25 02:53:26,615 [salt.state       :1780][INFO    ][14917] Running state [iptables_extra.remove_stale_tables] at time 02:53:26.615679
2019-04-25 02:53:26,616 [salt.state       :1813][INFO    ][14917] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-04-25 02:53:26,616 [salt.state       :300 ][INFO    ][14917] No changes made for iptables_extra.remove_stale_tables
2019-04-25 02:53:26,616 [salt.state       :1951][INFO    ][14917] Completed state [iptables_extra.remove_stale_tables] at time 02:53:26.616508 duration_in_ms=0.829
2019-04-25 02:53:26,616 [salt.state       :1780][INFO    ][14917] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:53:26.616745
2019-04-25 02:53:26,617 [salt.state       :1813][INFO    ][14917] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-04-25 02:53:26,617 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14917] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-04-25 02:53:26,636 [salt.state       :300 ][INFO    ][14917] onlyif execution failed
2019-04-25 02:53:26,636 [salt.state       :1951][INFO    ][14917] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:53:26.636529 duration_in_ms=19.784
2019-04-25 02:53:26,638 [salt.state       :1780][INFO    ][14917] Running state [/etc/iptables/rules.v6] at time 02:53:26.637976
2019-04-25 02:53:26,638 [salt.state       :1813][INFO    ][14917] Executing state file.absent for [/etc/iptables/rules.v6]
2019-04-25 02:53:26,639 [salt.state       :300 ][INFO    ][14917] File /etc/iptables/rules.v6 is not present
2019-04-25 02:53:26,639 [salt.state       :1951][INFO    ][14917] Completed state [/etc/iptables/rules.v6] at time 02:53:26.639326 duration_in_ms=1.351
2019-04-25 02:53:26,640 [salt.state       :1780][INFO    ][14917] Running state [iptables_extra.flush_all] at time 02:53:26.640322
2019-04-25 02:53:26,640 [salt.state       :1813][INFO    ][14917] Executing state module.wait for [iptables_extra.flush_all]
2019-04-25 02:53:26,641 [salt.state       :300 ][INFO    ][14917] No changes made for iptables_extra.flush_all
2019-04-25 02:53:26,641 [salt.state       :1951][INFO    ][14917] Completed state [iptables_extra.flush_all] at time 02:53:26.641336 duration_in_ms=1.014
2019-04-25 02:53:26,647 [salt.minion      :1711][INFO    ][14917] Returning information for job: 20190425025314107410
2019-04-25 02:53:27,201 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command state.apply with jid 20190425025327189120
2019-04-25 02:53:27,228 [salt.minion      :1432][INFO    ][15002] Starting a new job with PID 15002
2019-04-25 02:53:28,329 [salt.state       :915 ][INFO    ][15002] Loading fresh modules for state activity
2019-04-25 02:53:29,216 [salt.state       :1780][INFO    ][15002] Running state [maas-rack-controller] at time 02:53:29.215995
2019-04-25 02:53:29,216 [salt.state       :1813][INFO    ][15002] Executing state pkg.installed for [maas-rack-controller]
2019-04-25 02:53:29,217 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15002] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-25 02:53:29,323 [salt.state       :300 ][INFO    ][15002] All specified packages are already installed
2019-04-25 02:53:29,323 [salt.state       :1951][INFO    ][15002] Completed state [maas-rack-controller] at time 02:53:29.323896 duration_in_ms=107.901
2019-04-25 02:53:29,324 [salt.state       :1780][INFO    ][15002] Running state [ipmitool] at time 02:53:29.324283
2019-04-25 02:53:29,324 [salt.state       :1813][INFO    ][15002] Executing state pkg.installed for [ipmitool]
2019-04-25 02:53:29,335 [salt.state       :300 ][INFO    ][15002] All specified packages are already installed
2019-04-25 02:53:29,335 [salt.state       :1951][INFO    ][15002] Completed state [ipmitool] at time 02:53:29.335504 duration_in_ms=11.221
2019-04-25 02:53:29,340 [salt.state       :1780][INFO    ][15002] Running state [/etc/maas/rackd.conf] at time 02:53:29.340229
2019-04-25 02:53:29,340 [salt.state       :1813][INFO    ][15002] Executing state file.line for [/etc/maas/rackd.conf]
2019-04-25 02:53:29,341 [salt.state       :300 ][INFO    ][15002] No changes needed to be made
2019-04-25 02:53:29,341 [salt.state       :1951][INFO    ][15002] Completed state [/etc/maas/rackd.conf] at time 02:53:29.341672 duration_in_ms=1.444
2019-04-25 02:53:29,341 [salt.state       :1780][INFO    ][15002] Running state [/etc/maas/rackd.conf] at time 02:53:29.341910
2019-04-25 02:53:29,342 [salt.state       :1813][INFO    ][15002] Executing state file.managed for [/etc/maas/rackd.conf]
2019-04-25 02:53:29,342 [salt.loaded.int.states.file:2298][WARNING ][15002] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-04-25 02:53:29,344 [salt.state       :300 ][INFO    ][15002] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-04-25 02:53:29,344 [salt.state       :1951][INFO    ][15002] Completed state [/etc/maas/rackd.conf] at time 02:53:29.344294 duration_in_ms=2.384
2019-04-25 02:53:29,345 [salt.state       :1780][INFO    ][15002] Running state [maas-rackd] at time 02:53:29.345340
2019-04-25 02:53:29,345 [salt.state       :1813][INFO    ][15002] Executing state service.running for [maas-rackd]
2019-04-25 02:53:29,346 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15002] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-04-25 02:53:29,380 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15002] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-04-25 02:53:29,395 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15002] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-04-25 02:53:29,413 [salt.state       :300 ][INFO    ][15002] The service maas-rackd is already running
2019-04-25 02:53:29,414 [salt.state       :1951][INFO    ][15002] Completed state [maas-rackd] at time 02:53:29.414287 duration_in_ms=68.947
2019-04-25 02:53:29,419 [salt.minion      :1711][INFO    ][15002] Returning information for job: 20190425025327189120
2019-04-25 02:53:29,976 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command state.apply with jid 20190425025329968543
2019-04-25 02:53:29,997 [salt.minion      :1432][INFO    ][15025] Starting a new job with PID 15025
2019-04-25 02:53:31,120 [salt.state       :915 ][INFO    ][15025] Loading fresh modules for state activity
2019-04-25 02:53:32,147 [salt.state       :1780][INFO    ][15025] Running state [maas-region-controller] at time 02:53:32.147088
2019-04-25 02:53:32,147 [salt.state       :1813][INFO    ][15025] Executing state pkg.installed for [maas-region-controller]
2019-04-25 02:53:32,148 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15025] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-25 02:53:32,262 [salt.state       :300 ][INFO    ][15025] All specified packages are already installed
2019-04-25 02:53:32,262 [salt.state       :1951][INFO    ][15025] Completed state [maas-region-controller] at time 02:53:32.262319 duration_in_ms=115.232
2019-04-25 02:53:32,262 [salt.state       :1780][INFO    ][15025] Running state [python-oauth] at time 02:53:32.262642
2019-04-25 02:53:32,262 [salt.state       :1813][INFO    ][15025] Executing state pkg.installed for [python-oauth]
2019-04-25 02:53:32,272 [salt.state       :300 ][INFO    ][15025] All specified packages are already installed
2019-04-25 02:53:32,272 [salt.state       :1951][INFO    ][15025] Completed state [python-oauth] at time 02:53:32.272929 duration_in_ms=10.287
2019-04-25 02:53:32,276 [salt.state       :1780][INFO    ][15025] Running state [/etc/maas/regiond.conf] at time 02:53:32.276085
2019-04-25 02:53:32,276 [salt.state       :1813][INFO    ][15025] Executing state file.replace for [/etc/maas/regiond.conf]
2019-04-25 02:53:32,280 [salt.state       :300 ][INFO    ][15025] No changes needed to be made
2019-04-25 02:53:32,280 [salt.state       :1951][INFO    ][15025] Completed state [/etc/maas/regiond.conf] at time 02:53:32.280870 duration_in_ms=4.785
2019-04-25 02:53:32,281 [salt.state       :1780][INFO    ][15025] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:53:32.281359
2019-04-25 02:53:32,281 [salt.state       :1813][INFO    ][15025] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-04-25 02:53:32,339 [salt.state       :300 ][INFO    ][15025] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-04-25 02:53:32,340 [salt.state       :1951][INFO    ][15025] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:53:32.340058 duration_in_ms=58.699
2019-04-25 02:53:32,340 [salt.state       :1780][INFO    ][15025] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:53:32.340606
2019-04-25 02:53:32,340 [salt.state       :1813][INFO    ][15025] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-04-25 02:53:32,345 [salt.state       :300 ][INFO    ][15025] No changes needed to be made
2019-04-25 02:53:32,345 [salt.state       :1951][INFO    ][15025] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:53:32.345668 duration_in_ms=5.062
2019-04-25 02:53:32,346 [salt.state       :1780][INFO    ][15025] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:53:32.346148
2019-04-25 02:53:32,346 [salt.state       :1813][INFO    ][15025] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-04-25 02:53:32,349 [salt.state       :300 ][INFO    ][15025] No changes needed to be made
2019-04-25 02:53:32,349 [salt.state       :1951][INFO    ][15025] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:53:32.349945 duration_in_ms=3.797
2019-04-25 02:53:32,350 [salt.state       :1780][INFO    ][15025] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:53:32.350423
2019-04-25 02:53:32,350 [salt.state       :1813][INFO    ][15025] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-04-25 02:53:32,374 [salt.state       :300 ][INFO    ][15025] No changes needed to be made
2019-04-25 02:53:32,374 [salt.state       :1951][INFO    ][15025] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:53:32.374382 duration_in_ms=23.959
2019-04-25 02:53:32,374 [salt.state       :1780][INFO    ][15025] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:53:32.374876
2019-04-25 02:53:32,375 [salt.state       :1813][INFO    ][15025] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-04-25 02:53:32,385 [salt.state       :300 ][INFO    ][15025] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-04-25 02:53:32,386 [salt.state       :1951][INFO    ][15025] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:53:32.386110 duration_in_ms=11.234
2019-04-25 02:53:32,387 [salt.state       :1780][INFO    ][15025] Running state [a2enmod headers] at time 02:53:32.387663
2019-04-25 02:53:32,387 [salt.state       :1813][INFO    ][15025] Executing state cmd.run for [a2enmod headers]
2019-04-25 02:53:32,388 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15025] Executing command 'a2enmod headers' in directory '/root'
2019-04-25 02:53:32,455 [salt.state       :300 ][INFO    ][15025] {'pid': 15045, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-04-25 02:53:32,456 [salt.state       :1951][INFO    ][15025] Completed state [a2enmod headers] at time 02:53:32.456414 duration_in_ms=68.75
2019-04-25 02:53:32,457 [salt.state       :1780][INFO    ][15025] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:53:32.457075
2019-04-25 02:53:32,457 [salt.state       :1813][INFO    ][15025] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-04-25 02:53:32,473 [salt.state       :300 ][INFO    ][15025] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-04-25 02:53:32,473 [salt.state       :1951][INFO    ][15025] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:53:32.473797 duration_in_ms=16.723
2019-04-25 02:53:32,474 [salt.state       :1780][INFO    ][15025] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:53:32.474368
2019-04-25 02:53:32,474 [salt.state       :1813][INFO    ][15025] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-04-25 02:53:32,532 [salt.state       :300 ][INFO    ][15025] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-04-25 02:53:32,533 [salt.state       :1951][INFO    ][15025] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:53:32.533003 duration_in_ms=58.635
2019-04-25 02:53:32,533 [salt.state       :1780][INFO    ][15025] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:53:32.533522
2019-04-25 02:53:32,533 [salt.state       :1813][INFO    ][15025] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-04-25 02:53:32,586 [salt.state       :300 ][INFO    ][15025] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-04-25 02:53:32,586 [salt.state       :1951][INFO    ][15025] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:53:32.586594 duration_in_ms=53.072
2019-04-25 02:53:32,587 [salt.state       :1780][INFO    ][15025] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:53:32.587151
2019-04-25 02:53:32,587 [salt.state       :1813][INFO    ][15025] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-04-25 02:53:32,653 [salt.state       :300 ][INFO    ][15025] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-04-25 02:53:32,653 [salt.state       :1951][INFO    ][15025] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:53:32.653541 duration_in_ms=66.389
2019-04-25 02:53:32,654 [salt.state       :1780][INFO    ][15025] Running state [/root/.pgpass] at time 02:53:32.653984
2019-04-25 02:53:32,654 [salt.state       :1813][INFO    ][15025] Executing state file.managed for [/root/.pgpass]
2019-04-25 02:53:32,692 [salt.state       :300 ][INFO    ][15025] File /root/.pgpass is in the correct state
2019-04-25 02:53:32,692 [salt.state       :1951][INFO    ][15025] Completed state [/root/.pgpass] at time 02:53:32.692456 duration_in_ms=38.472
2019-04-25 02:53:32,701 [salt.state       :1780][INFO    ][15025] Running state [maas-region syncdb --noinput] at time 02:53:32.701842
2019-04-25 02:53:32,702 [salt.state       :1813][INFO    ][15025] Executing state cmd.run for [maas-region syncdb --noinput]
2019-04-25 02:53:32,704 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15025] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-04-25 02:53:35,264 [salt.state       :300 ][INFO    ][15025] {'pid': 15058, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: metadataserver, sessions, maasserver, sites, contenttypes, auth, piston3\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-04-25 02:53:35,264 [salt.state       :1951][INFO    ][15025] Completed state [maas-region syncdb --noinput] at time 02:53:35.264848 duration_in_ms=2563.007
2019-04-25 02:53:35,265 [salt.state       :2022][WARNING ][15025] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-04-25 02:53:35,267 [salt.state       :1780][INFO    ][15025] Running state [maas-regiond] at time 02:53:35.267279
2019-04-25 02:53:35,267 [salt.state       :1813][INFO    ][15025] Executing state service.running for [maas-regiond]
2019-04-25 02:53:35,268 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15025] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-04-25 02:53:35,313 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15025] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-04-25 02:53:35,328 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15025] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-04-25 02:53:35,347 [salt.state       :300 ][INFO    ][15025] The service maas-regiond is already running
2019-04-25 02:53:35,348 [salt.state       :1951][INFO    ][15025] Completed state [maas-regiond] at time 02:53:35.347886 duration_in_ms=80.605
2019-04-25 02:53:35,351 [salt.state       :1780][INFO    ][15025] Running state [bind9] at time 02:53:35.351169
2019-04-25 02:53:35,351 [salt.state       :1813][INFO    ][15025] Executing state service.running for [bind9]
2019-04-25 02:53:35,352 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15025] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-04-25 02:53:35,373 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15025] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-04-25 02:53:35,388 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15025] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-04-25 02:53:35,405 [salt.state       :300 ][INFO    ][15025] The service bind9 is already running
2019-04-25 02:53:35,406 [salt.state       :1951][INFO    ][15025] Completed state [bind9] at time 02:53:35.406370 duration_in_ms=55.2
2019-04-25 02:53:35,409 [salt.state       :1780][INFO    ][15025] Running state [apache2] at time 02:53:35.409234
2019-04-25 02:53:35,409 [salt.state       :1813][INFO    ][15025] Executing state service.running for [apache2]
2019-04-25 02:53:35,410 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15025] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-04-25 02:53:35,430 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15025] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-04-25 02:53:35,444 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15025] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-04-25 02:53:35,462 [salt.state       :300 ][INFO    ][15025] The service apache2 is already running
2019-04-25 02:53:35,462 [salt.state       :1951][INFO    ][15025] Completed state [apache2] at time 02:53:35.462518 duration_in_ms=53.283
2019-04-25 02:53:35,466 [salt.state       :1780][INFO    ][15025] Running state [maasng.wait_for_http_code] at time 02:53:35.466865
2019-04-25 02:53:35,467 [salt.state       :1813][INFO    ][15025] Executing state module.run for [maasng.wait_for_http_code]
2019-04-25 02:53:35,467 [salt.utils.decorators:613 ][WARNING ][15025] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-25 02:53:35,581 [salt.state       :300 ][INFO    ][15025] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-04-25 02:53:35,581 [salt.state       :1951][INFO    ][15025] Completed state [maasng.wait_for_http_code] at time 02:53:35.581729 duration_in_ms=114.865
2019-04-25 02:53:35,582 [salt.state       :1780][INFO    ][15025] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:53:35.582591
2019-04-25 02:53:35,583 [salt.state       :1813][INFO    ][15025] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-04-25 02:53:35,584 [salt.state       :300 ][INFO    ][15025] /var/lib/maas/.setup_admin exists
2019-04-25 02:53:35,584 [salt.state       :1951][INFO    ][15025] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:53:35.584405 duration_in_ms=1.814
2019-04-25 02:53:35,585 [salt.state       :1780][INFO    ][15025] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:35.585074
2019-04-25 02:53:35,585 [salt.state       :1813][INFO    ][15025] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-25 02:53:35,585 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15025] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-25 02:53:37,280 [salt.state       :300 ][INFO    ][15025] {'pid': 15081, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-25 02:53:37,281 [salt.state       :1951][INFO    ][15025] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:37.281152 duration_in_ms=1696.076
2019-04-25 02:53:37,290 [salt.state       :1780][INFO    ][15025] Running state [maas_region_boot_source_resources_mirror] at time 02:53:37.290546
2019-04-25 02:53:37,290 [salt.state       :1813][INFO    ][15025] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-04-25 02:53:37,365 [salt.state       :300 ][INFO    ][15025] {'changes': {}}
2019-04-25 02:53:37,366 [salt.state       :1951][INFO    ][15025] Completed state [maas_region_boot_source_resources_mirror] at time 02:53:37.366167 duration_in_ms=75.62
2019-04-25 02:53:37,368 [salt.state       :1780][INFO    ][15025] Running state [maasng.boot_resources_import] at time 02:53:37.368133
2019-04-25 02:53:37,368 [salt.state       :1813][INFO    ][15025] Executing state module.run for [maasng.boot_resources_import]
2019-04-25 02:53:37,369 [salt.utils.decorators:613 ][WARNING ][15025] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-25 02:53:37,465 [salt.loaded.ext.module.maasng:1600][INFO    ][15025] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-04-25 02:53:42,558 [salt.loaded.ext.module.maasng:1600][INFO    ][15025] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-04-25 02:53:45,041 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425025345033293
2019-04-25 02:53:45,065 [salt.minion      :1432][INFO    ][15237] Starting a new job with PID 15237
2019-04-25 02:53:45,090 [salt.minion      :1711][INFO    ][15237] Returning information for job: 20190425025345033293
2019-04-25 02:53:47,657 [salt.state       :300 ][INFO    ][15025] {'ret': True}
2019-04-25 02:53:47,657 [salt.state       :1951][INFO    ][15025] Completed state [maasng.boot_resources_import] at time 02:53:47.657639 duration_in_ms=10289.506
2019-04-25 02:53:47,658 [salt.state       :1780][INFO    ][15025] Running state [maas_region_boot_sources_selection_xenial] at time 02:53:47.658728
2019-04-25 02:53:47,659 [salt.state       :1813][INFO    ][15025] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-04-25 02:53:47,826 [salt.state       :300 ][INFO    ][15025] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-04-25 02:53:47,826 [salt.state       :1951][INFO    ][15025] Completed state [maas_region_boot_sources_selection_xenial] at time 02:53:47.826710 duration_in_ms=167.981
2019-04-25 02:53:47,828 [salt.state       :1780][INFO    ][15025] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 02:53:47.828419
2019-04-25 02:53:47,828 [salt.state       :1813][INFO    ][15025] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-04-25 02:53:47,829 [salt.utils.decorators:613 ][WARNING ][15025] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-25 02:53:47,829 [salt.loaded.ext.module.maasng:1771][INFO    ][15025] boot-sources sync initiated for ALL Rack's
2019-04-25 02:53:48,769 [salt.state       :300 ][INFO    ][15025] {'ret': True}
2019-04-25 02:53:48,769 [salt.state       :1951][INFO    ][15025] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 02:53:48.769758 duration_in_ms=941.338
2019-04-25 02:53:48,771 [salt.state       :1780][INFO    ][15025] Running state [maas.process_maas_config] at time 02:53:48.771621
2019-04-25 02:53:48,771 [salt.state       :1813][INFO    ][15025] Executing state module.run for [maas.process_maas_config]
2019-04-25 02:53:48,772 [salt.utils.decorators:613 ][WARNING ][15025] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-25 02:53:48,772 [salt.loaded.ext.module.maas:92  ][INFO    ][15025] maasconfig name=enable_http_proxy value=True
2019-04-25 02:53:48,809 [salt.loaded.ext.module.maas:92  ][INFO    ][15025] maasconfig name=upstream_dns value=8.8.8.8
2019-04-25 02:53:48,851 [salt.loaded.ext.module.maas:92  ][INFO    ][15025] maasconfig name=commissioning_distro_series value=xenial
2019-04-25 02:53:48,899 [salt.loaded.ext.module.maas:92  ][INFO    ][15025] maasconfig name=default_osystem value=ubuntu
2019-04-25 02:53:48,941 [salt.loaded.ext.module.maas:92  ][INFO    ][15025] maasconfig name=active_discovery_interval value=600
2019-04-25 02:53:48,977 [salt.loaded.ext.module.maas:92  ][INFO    ][15025] maasconfig name=dnssec_validation value=no
2019-04-25 02:53:50,096 [salt.loaded.ext.module.maas:92  ][INFO    ][15025] maasconfig name=maas_name value=mas01
2019-04-25 02:53:50,134 [salt.loaded.ext.module.maas:92  ][INFO    ][15025] maasconfig name=network_discovery value=enabled
2019-04-25 02:53:50,217 [salt.loaded.ext.module.maas:92  ][INFO    ][15025] maasconfig name=enable_third_party_drivers value=True
2019-04-25 02:53:50,258 [salt.loaded.ext.module.maas:92  ][INFO    ][15025] maasconfig name=default_storage_layout value=lvm
2019-04-25 02:53:50,296 [salt.loaded.ext.module.maas:92  ][INFO    ][15025] maasconfig name=ntp_external_only value=True
2019-04-25 02:53:50,330 [salt.loaded.ext.module.maas:92  ][INFO    ][15025] maasconfig name=disk_erase_with_secure_erase value=False
2019-04-25 02:53:50,376 [salt.loaded.ext.module.maas:92  ][INFO    ][15025] maasconfig name=default_distro_series value=xenial
2019-04-25 02:53:50,436 [salt.loaded.ext.module.maas:92  ][INFO    ][15025] maasconfig name=default_min_hwe_kernel value=ga-16.04
2019-04-25 02:53:50,527 [salt.state       :300 ][INFO    ][15025] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-04-25 02:53:50,528 [salt.state       :1951][INFO    ][15025] Completed state [maas.process_maas_config] at time 02:53:50.528107 duration_in_ms=1756.485
2019-04-25 02:53:50,528 [salt.state       :1780][INFO    ][15025] Running state [pxe_admin] at time 02:53:50.528714
2019-04-25 02:53:50,529 [salt.state       :1813][INFO    ][15025] Executing state maasng.fabric_present for [pxe_admin]
2019-04-25 02:53:50,580 [salt.loaded.ext.module.maasng:945 ][INFO    ][15025] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'name': u'fabric-2', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'p6cgg4', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'name': u'pxe_admin', u'class_type': u''}]
2019-04-25 02:53:50,628 [salt.loaded.ext.module.maasng:1008][WARNING ][15025] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-04-25 02:53:50,628 [salt.loaded.ext.module.maasng:1011][WARNING ][15025] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-04-25 02:53:50,693 [salt.state       :300 ][INFO    ][15025] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-04-25 02:53:50,693 [salt.state       :1951][INFO    ][15025] Completed state [pxe_admin] at time 02:53:50.693253 duration_in_ms=164.538
2019-04-25 02:53:50,693 [salt.state       :1780][INFO    ][15025] Running state [vlan 0] at time 02:53:50.693559
2019-04-25 02:53:50,693 [salt.state       :1813][INFO    ][15025] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-04-25 02:53:50,738 [salt.loaded.ext.module.maasng:945 ][INFO    ][15025] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'p6cgg4', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-25 02:53:50,824 [salt.loaded.ext.module.maasng:945 ][INFO    ][15025] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'name': u'fabric-2', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'p6cgg4', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'name': u'pxe_admin', u'class_type': u''}]
2019-04-25 02:53:51,024 [salt.loaded.ext.module.maasng:945 ][INFO    ][15025] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'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'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'name': u'untagged', u'primary_rack': u'p6cgg4', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-04-25 02:53:51,093 [salt.state       :300 ][INFO    ][15025] {'new': 'Vlan untagged was updated'}
2019-04-25 02:53:51,094 [salt.state       :1951][INFO    ][15025] Completed state [vlan 0] at time 02:53:51.094133 duration_in_ms=400.573
2019-04-25 02:53:51,095 [salt.state       :1780][INFO    ][15025] Running state [192.168.11.0/24] at time 02:53:51.095440
2019-04-25 02:53:51,095 [salt.state       :1813][INFO    ][15025] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-04-25 02:53:51,261 [salt.loaded.ext.module.maasng:945 ][INFO    ][15025] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'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'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'name': u'untagged', u'primary_rack': u'p6cgg4', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-04-25 02:53:51,262 [salt.loaded.ext.module.maasng:1235][WARNING ][15025] Ignoring parameter vlan:0
2019-04-25 02:53:51,321 [salt.state       :300 ][INFO    ][15025] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-04-25 02:53:51,322 [salt.state       :1951][INFO    ][15025] Completed state [192.168.11.0/24] at time 02:53:51.322052 duration_in_ms=226.611
2019-04-25 02:53:51,323 [salt.state       :1780][INFO    ][15025] Running state [maas_create_iprange_1] at time 02:53:51.323403
2019-04-25 02:53:51,323 [salt.state       :1813][INFO    ][15025] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-04-25 02:53:51,368 [salt.state       :300 ][INFO    ][15025] Iprange maas_create_iprange_1 already exist.
2019-04-25 02:53:51,368 [salt.state       :1951][INFO    ][15025] Completed state [maas_create_iprange_1] at time 02:53:51.368297 duration_in_ms=44.894
2019-04-25 02:53:51,368 [salt.state       :1780][INFO    ][15025] Running state [vlan 0] at time 02:53:51.368564
2019-04-25 02:53:51,368 [salt.state       :1813][INFO    ][15025] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-04-25 02:53:51,424 [salt.loaded.ext.module.maasng:945 ][INFO    ][15025] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'name': u'fabric-2', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'p6cgg4', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'name': u'pxe_admin', u'class_type': u''}]
2019-04-25 02:53:51,517 [salt.loaded.ext.module.maasng:945 ][INFO    ][15025] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'name': u'fabric-2', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'p6cgg4', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'name': u'pxe_admin', u'class_type': u''}]
2019-04-25 02:53:51,722 [salt.loaded.ext.module.maasng:945 ][INFO    ][15025] [{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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': 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'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {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': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'p6cgg4', u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-04-25 02:53:51,826 [salt.state       :300 ][INFO    ][15025] {'new': 'Vlan untagged was updated'}
2019-04-25 02:53:51,826 [salt.state       :1951][INFO    ][15025] Completed state [vlan 0] at time 02:53:51.826377 duration_in_ms=457.811
2019-04-25 02:53:51,827 [salt.state       :1780][INFO    ][15025] Running state [opnfv] at time 02:53:51.826974
2019-04-25 02:53:51,827 [salt.state       :1813][INFO    ][15025] Executing state maasng.sshkey_present for [opnfv]
2019-04-25 02:53:51,872 [salt.loaded.ext.module.maasng:1903][INFO    ][15025] [{u'keysource': u'', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b', u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/'}]
2019-04-25 02:53:51,872 [salt.state       :300 ][INFO    ][15025] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-04-25 02:53:51,872 [salt.state       :1951][INFO    ][15025] Completed state [opnfv] at time 02:53:51.872738 duration_in_ms=45.763
2019-04-25 02:53:51,874 [salt.minion      :1711][INFO    ][15025] Returning information for job: 20190425025329968543
2019-04-25 02:53:52,494 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command state.apply with jid 20190425025352483526
2019-04-25 02:53:52,510 [salt.minion      :1432][INFO    ][15490] Starting a new job with PID 15490
2019-04-25 02:54:00,723 [salt.state       :915 ][INFO    ][15490] Loading fresh modules for state activity
2019-04-25 02:54:00,816 [salt.state       :1780][INFO    ][15490] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:00.816915
2019-04-25 02:54:00,817 [salt.state       :1813][INFO    ][15490] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-25 02:54:00,819 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15490] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-25 02:54:02,647 [salt.state       :300 ][INFO    ][15490] {'pid': 15528, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-25 02:54:02,647 [salt.state       :1951][INFO    ][15490] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:02.647840 duration_in_ms=1830.926
2019-04-25 02:54:02,649 [salt.state       :1780][INFO    ][15490] Running state [maas.process_machines] at time 02:54:02.649207
2019-04-25 02:54:02,649 [salt.state       :1813][INFO    ][15490] Executing state module.run for [maas.process_machines]
2019-04-25 02:54:02,650 [salt.utils.decorators:613 ][WARNING ][15490] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-25 02:54:03,193 [salt.loaded.ext.module.maas:412 ][WARNING ][15490] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-25 02:54:03,194 [salt.loaded.ext.module.maas:92  ][INFO    ][15490] 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=ngx6cx architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-25 02:54:04,451 [salt.loaded.ext.module.maas:412 ][WARNING ][15490] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-25 02:54:04,453 [salt.loaded.ext.module.maas:92  ][INFO    ][15490] 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=wndnga architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-25 02:54:05,571 [salt.loaded.ext.module.maas:412 ][WARNING ][15490] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-25 02:54:05,572 [salt.loaded.ext.module.maas:92  ][INFO    ][15490] 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=pyf6yk architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-25 02:54:06,685 [salt.loaded.ext.module.maas:412 ][WARNING ][15490] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-25 02:54:06,686 [salt.loaded.ext.module.maas:92  ][INFO    ][15490] 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=eg4han architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-25 02:54:07,580 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425025407571752
2019-04-25 02:54:07,598 [salt.minion      :1432][INFO    ][15728] Starting a new job with PID 15728
2019-04-25 02:54:07,625 [salt.minion      :1711][INFO    ][15728] Returning information for job: 20190425025407571752
2019-04-25 02:54:07,800 [salt.loaded.ext.module.maas:412 ][WARNING ][15490] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-25 02:54:07,801 [salt.loaded.ext.module.maas:92  ][INFO    ][15490] 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=bdsw4p architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-25 02:54:08,882 [salt.state       :300 ][INFO    ][15490] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-04-25 02:54:08,884 [salt.state       :1951][INFO    ][15490] Completed state [maas.process_machines] at time 02:54:08.882835 duration_in_ms=6233.627
2019-04-25 02:54:08,887 [salt.minion      :1711][INFO    ][15490] Returning information for job: 20190425025352483526
2019-04-25 02:54:41,857 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command state.apply with jid 20190425025441846275
2019-04-25 02:54:41,876 [salt.minion      :1432][INFO    ][15789] Starting a new job with PID 15789
2019-04-25 02:54:49,834 [salt.state       :915 ][INFO    ][15789] Loading fresh modules for state activity
2019-04-25 02:54:49,933 [salt.state       :1780][INFO    ][15789] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:49.933718
2019-04-25 02:54:49,934 [salt.state       :1813][INFO    ][15789] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-25 02:54:49,936 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15789] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-25 02:54:51,692 [salt.state       :300 ][INFO    ][15789] {'pid': 15824, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-25 02:54:51,693 [salt.state       :1951][INFO    ][15789] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:51.692966 duration_in_ms=1759.25
2019-04-25 02:54:51,694 [salt.state       :1780][INFO    ][15789] Running state [maas.wait_for_machine_status] at time 02:54:51.694315
2019-04-25 02:54:51,694 [salt.state       :1813][INFO    ][15789] Executing state module.run for [maas.wait_for_machine_status]
2019-04-25 02:54:51,695 [salt.utils.decorators:613 ][WARNING ][15789] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-25 02:54:54,467 [salt.state       :300 ][INFO    ][15789] {'ret': True}
2019-04-25 02:54:54,468 [salt.state       :1951][INFO    ][15789] Completed state [maas.wait_for_machine_status] at time 02:54:54.467949 duration_in_ms=2773.633
2019-04-25 02:54:54,473 [salt.minion      :1711][INFO    ][15789] Returning information for job: 20190425025441846275
2019-04-25 02:54:55,017 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command state.apply with jid 20190425025455007555
2019-04-25 02:54:55,031 [salt.minion      :1432][INFO    ][15837] Starting a new job with PID 15837
2019-04-25 02:54:56,162 [salt.state       :915 ][INFO    ][15837] Loading fresh modules for state activity
2019-04-25 02:54:56,325 [salt.state       :1780][INFO    ][15837] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:56.324914
2019-04-25 02:54:56,325 [salt.state       :1813][INFO    ][15837] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-25 02:54:56,327 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15837] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-25 02:54:58,132 [salt.state       :300 ][INFO    ][15837] {'pid': 15857, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-25 02:54:58,132 [salt.state       :1951][INFO    ][15837] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:58.132766 duration_in_ms=1807.853
2019-04-25 02:54:58,134 [salt.state       :1780][INFO    ][15837] Running state [maas_machines_storage_cmp002_lvm] at time 02:54:58.134743
2019-04-25 02:54:58,135 [salt.state       :1813][INFO    ][15837] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-04-25 02:54:58,742 [salt.state       :300 ][INFO    ][15837] Machine cmp002 is not in Ready state.
2019-04-25 02:54:58,744 [salt.state       :1951][INFO    ][15837] Completed state [maas_machines_storage_cmp002_lvm] at time 02:54:58.744315 duration_in_ms=609.571
2019-04-25 02:54:58,744 [salt.state       :1780][INFO    ][15837] Running state [maas_machines_storage_cmp001_lvm] at time 02:54:58.744698
2019-04-25 02:54:58,745 [salt.state       :1813][INFO    ][15837] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-04-25 02:54:59,194 [salt.state       :300 ][INFO    ][15837] Machine cmp001 is not in Ready state.
2019-04-25 02:54:59,194 [salt.state       :1951][INFO    ][15837] Completed state [maas_machines_storage_cmp001_lvm] at time 02:54:59.194737 duration_in_ms=450.037
2019-04-25 02:54:59,198 [salt.minion      :1711][INFO    ][15837] Returning information for job: 20190425025455007555
2019-04-25 02:54:59,724 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command state.apply with jid 20190425025459716321
2019-04-25 02:54:59,742 [salt.minion      :1432][INFO    ][15867] Starting a new job with PID 15867
2019-04-25 02:55:00,847 [salt.state       :915 ][INFO    ][15867] Loading fresh modules for state activity
2019-04-25 02:55:00,935 [salt.state       :1780][INFO    ][15867] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:55:00.935036
2019-04-25 02:55:00,935 [salt.state       :1813][INFO    ][15867] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-25 02:55:00,937 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15867] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-25 02:55:02,735 [salt.state       :300 ][INFO    ][15867] {'pid': 15874, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-25 02:55:02,736 [salt.state       :1951][INFO    ][15867] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:55:02.735957 duration_in_ms=1800.921
2019-04-25 02:55:02,740 [salt.state       :1780][INFO    ][15867] Running state [maas.deploy_machines] at time 02:55:02.738778
2019-04-25 02:55:02,740 [salt.state       :1813][INFO    ][15867] Executing state module.run for [maas.deploy_machines]
2019-04-25 02:55:02,741 [salt.utils.decorators:613 ][WARNING ][15867] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-25 02:55:03,281 [salt.loaded.ext.module.maas:684 ][INFO    ][15867] deploymachines hwe_kernel=ga-16.04 system_id=bdsw4p distro_series=xenial
2019-04-25 02:55:05,673 [salt.state       :300 ][INFO    ][15867] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-04-25 02:55:05,674 [salt.state       :1951][INFO    ][15867] Completed state [maas.deploy_machines] at time 02:55:05.674311 duration_in_ms=2935.532
2019-04-25 02:55:05,677 [salt.minion      :1711][INFO    ][15867] Returning information for job: 20190425025459716321
2019-04-25 02:55:06,271 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command state.apply with jid 20190425025506213372
2019-04-25 02:55:06,288 [salt.minion      :1432][INFO    ][15967] Starting a new job with PID 15967
2019-04-25 02:55:14,203 [salt.state       :915 ][INFO    ][15967] Loading fresh modules for state activity
2019-04-25 02:55:14,299 [salt.state       :1780][INFO    ][15967] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:55:14.299737
2019-04-25 02:55:14,300 [salt.state       :1813][INFO    ][15967] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-25 02:55:14,301 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15967] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-25 02:55:15,988 [salt.state       :300 ][INFO    ][15967] {'pid': 15978, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-25 02:55:15,988 [salt.state       :1951][INFO    ][15967] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:55:15.988469 duration_in_ms=1688.732
2019-04-25 02:55:15,990 [salt.state       :1780][INFO    ][15967] Running state [maas.wait_for_machine_status] at time 02:55:15.990047
2019-04-25 02:55:15,990 [salt.state       :1813][INFO    ][15967] Executing state module.run for [maas.wait_for_machine_status]
2019-04-25 02:55:15,990 [salt.utils.decorators:613 ][WARNING ][15967] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-25 02:55:18,643 [salt.loaded.ext.module.maas:1023][INFO    ][15967] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.36467195s left)
2019-04-25 02:55:21,290 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425025521275630
2019-04-25 02:55:21,311 [salt.minion      :1432][INFO    ][15997] Starting a new job with PID 15997
2019-04-25 02:55:21,338 [salt.minion      :1711][INFO    ][15997] Returning information for job: 20190425025521275630
2019-04-25 02:55:51,368 [salt.loaded.ext.module.maas:1023][INFO    ][15967] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.63969803s left)
2019-04-25 02:55:51,368 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425025551352245
2019-04-25 02:55:51,392 [salt.minion      :1432][INFO    ][16041] Starting a new job with PID 16041
2019-04-25 02:55:51,425 [salt.minion      :1711][INFO    ][16041] Returning information for job: 20190425025551352245
2019-04-25 02:56:21,405 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425025621397388
2019-04-25 02:56:21,428 [salt.minion      :1432][INFO    ][16096] Starting a new job with PID 16096
2019-04-25 02:56:21,450 [salt.minion      :1711][INFO    ][16096] Returning information for job: 20190425025621397388
2019-04-25 02:56:24,034 [salt.loaded.ext.module.maas:1023][INFO    ][15967] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2181.97358298s left)
2019-04-25 02:56:51,594 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425025651582529
2019-04-25 02:56:51,612 [salt.minion      :1432][INFO    ][16143] Starting a new job with PID 16143
2019-04-25 02:56:51,637 [salt.minion      :1711][INFO    ][16143] Returning information for job: 20190425025651582529
2019-04-25 02:56:56,696 [salt.loaded.ext.module.maas:1023][INFO    ][15967] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2149.3124361s left)
2019-04-25 02:57:21,789 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425025721780137
2019-04-25 02:57:21,812 [salt.minion      :1432][INFO    ][16217] Starting a new job with PID 16217
2019-04-25 02:57:21,837 [salt.minion      :1711][INFO    ][16217] Returning information for job: 20190425025721780137
2019-04-25 02:57:29,513 [salt.loaded.ext.module.maas:1023][INFO    ][15967] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2116.49548697s left)
2019-04-25 02:57:51,994 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425025751979754
2019-04-25 02:57:52,017 [salt.minion      :1432][INFO    ][16259] Starting a new job with PID 16259
2019-04-25 02:57:52,041 [salt.minion      :1711][INFO    ][16259] Returning information for job: 20190425025751979754
2019-04-25 02:58:02,189 [salt.loaded.ext.module.maas:1023][INFO    ][15967] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2083.81947803s left)
2019-04-25 02:58:22,058 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425025822049021
2019-04-25 02:58:22,079 [salt.minion      :1432][INFO    ][16323] Starting a new job with PID 16323
2019-04-25 02:58:22,104 [salt.minion      :1711][INFO    ][16323] Returning information for job: 20190425025822049021
2019-04-25 02:58:35,120 [salt.loaded.ext.module.maas:1023][INFO    ][15967] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2050.88802099s left)
2019-04-25 02:58:52,145 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425025852130586
2019-04-25 02:58:52,165 [salt.minion      :1432][INFO    ][16366] Starting a new job with PID 16366
2019-04-25 02:58:52,190 [salt.minion      :1711][INFO    ][16366] Returning information for job: 20190425025852130586
2019-04-25 02:59:07,813 [salt.loaded.ext.module.maas:1023][INFO    ][15967] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2018.19547796s left)
2019-04-25 02:59:22,160 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425025922150586
2019-04-25 02:59:22,178 [salt.minion      :1432][INFO    ][16449] Starting a new job with PID 16449
2019-04-25 02:59:22,202 [salt.minion      :1711][INFO    ][16449] Returning information for job: 20190425025922150586
2019-04-25 02:59:40,584 [salt.loaded.ext.module.maas:1023][INFO    ][15967] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1985.42386794s left)
2019-04-25 02:59:52,241 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425025952223893
2019-04-25 02:59:52,260 [salt.minion      :1432][INFO    ][16520] Starting a new job with PID 16520
2019-04-25 02:59:52,289 [salt.minion      :1711][INFO    ][16520] Returning information for job: 20190425025952223893
2019-04-25 03:00:13,308 [salt.loaded.ext.module.maas:1023][INFO    ][15967] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1952.70056796s left)
2019-04-25 03:00:22,344 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425030022330608
2019-04-25 03:00:22,366 [salt.minion      :1432][INFO    ][16653] Starting a new job with PID 16653
2019-04-25 03:00:22,393 [salt.minion      :1711][INFO    ][16653] Returning information for job: 20190425030022330608
2019-04-25 03:00:46,057 [salt.loaded.ext.module.maas:1023][INFO    ][15967] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1919.950881s left)
2019-04-25 03:00:52,550 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425030052539193
2019-04-25 03:00:52,580 [salt.minion      :1432][INFO    ][16699] Starting a new job with PID 16699
2019-04-25 03:00:52,606 [salt.minion      :1711][INFO    ][16699] Returning information for job: 20190425030052539193
2019-04-25 03:01:19,082 [salt.loaded.ext.module.maas:1023][INFO    ][15967] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1886.92599511s left)
2019-04-25 03:01:22,764 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425030122753696
2019-04-25 03:01:22,789 [salt.minion      :1432][INFO    ][16762] Starting a new job with PID 16762
2019-04-25 03:01:22,815 [salt.minion      :1711][INFO    ][16762] Returning information for job: 20190425030122753696
2019-04-25 03:01:51,745 [salt.loaded.ext.module.maas:1023][INFO    ][15967] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1854.26270294s left)
2019-04-25 03:01:52,860 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425030152848273
2019-04-25 03:01:52,878 [salt.minion      :1432][INFO    ][16809] Starting a new job with PID 16809
2019-04-25 03:01:52,902 [salt.minion      :1711][INFO    ][16809] Returning information for job: 20190425030152848273
2019-04-25 03:02:22,981 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425030222974070
2019-04-25 03:02:23,001 [salt.minion      :1432][INFO    ][16956] Starting a new job with PID 16956
2019-04-25 03:02:23,028 [salt.minion      :1711][INFO    ][16956] Returning information for job: 20190425030222974070
2019-04-25 03:02:24,513 [salt.loaded.ext.module.maas:1023][INFO    ][15967] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1821.49489307s left)
2019-04-25 03:02:53,202 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425030253186809
2019-04-25 03:02:53,226 [salt.minion      :1432][INFO    ][16994] Starting a new job with PID 16994
2019-04-25 03:02:53,255 [salt.minion      :1711][INFO    ][16994] Returning information for job: 20190425030253186809
2019-04-25 03:02:57,404 [salt.loaded.ext.module.maas:1023][INFO    ][15967] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1788.6043191s left)
2019-04-25 03:03:23,226 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425030323213527
2019-04-25 03:03:23,252 [salt.minion      :1432][INFO    ][17069] Starting a new job with PID 17069
2019-04-25 03:03:23,278 [salt.minion      :1711][INFO    ][17069] Returning information for job: 20190425030323213527
2019-04-25 03:03:30,157 [salt.loaded.ext.module.maas:1023][INFO    ][15967] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1755.85062408s left)
2019-04-25 03:03:53,257 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425030353242785
2019-04-25 03:03:53,286 [salt.minion      :1432][INFO    ][17110] Starting a new job with PID 17110
2019-04-25 03:03:53,313 [salt.minion      :1711][INFO    ][17110] Returning information for job: 20190425030353242785
2019-04-25 03:04:02,901 [salt.loaded.ext.module.maas:1023][INFO    ][15967] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1723.10660291s left)
2019-04-25 03:04:23,321 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425030423304665
2019-04-25 03:04:23,347 [salt.minion      :1432][INFO    ][17253] Starting a new job with PID 17253
2019-04-25 03:04:23,374 [salt.minion      :1711][INFO    ][17253] Returning information for job: 20190425030423304665
2019-04-25 03:04:35,720 [salt.loaded.ext.module.maas:1023][INFO    ][15967] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1690.2884059s left)
2019-04-25 03:04:53,349 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425030453340915
2019-04-25 03:04:53,373 [salt.minion      :1432][INFO    ][17302] Starting a new job with PID 17302
2019-04-25 03:04:53,400 [salt.minion      :1711][INFO    ][17302] Returning information for job: 20190425030453340915
2019-04-25 03:05:08,721 [salt.loaded.ext.module.maas:1023][INFO    ][15967] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1657.2869339s left)
2019-04-25 03:05:23,399 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425030523386195
2019-04-25 03:05:23,421 [salt.minion      :1432][INFO    ][17467] Starting a new job with PID 17467
2019-04-25 03:05:23,448 [salt.minion      :1711][INFO    ][17467] Returning information for job: 20190425030523386195
2019-04-25 03:05:41,454 [salt.loaded.ext.module.maas:1023][INFO    ][15967] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1624.55439305s left)
2019-04-25 03:05:53,552 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425030553542050
2019-04-25 03:05:53,568 [salt.minion      :1432][INFO    ][17508] Starting a new job with PID 17508
2019-04-25 03:05:53,591 [salt.minion      :1711][INFO    ][17508] Returning information for job: 20190425030553542050
2019-04-25 03:06:14,261 [salt.loaded.ext.module.maas:1023][INFO    ][15967] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1591.74693608s left)
2019-04-25 03:06:23,625 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425030623617507
2019-04-25 03:06:23,652 [salt.minion      :1432][INFO    ][17569] Starting a new job with PID 17569
2019-04-25 03:06:23,681 [salt.minion      :1711][INFO    ][17569] Returning information for job: 20190425030623617507
2019-04-25 03:06:47,033 [salt.loaded.ext.module.maas:1023][INFO    ][15967] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1558.97468495s left)
2019-04-25 03:06:53,825 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425030653812993
2019-04-25 03:06:53,846 [salt.minion      :1432][INFO    ][17618] Starting a new job with PID 17618
2019-04-25 03:06:53,874 [salt.minion      :1711][INFO    ][17618] Returning information for job: 20190425030653812993
2019-04-25 03:07:19,896 [salt.loaded.ext.module.maas:1023][INFO    ][15967] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1526.11175489s left)
2019-04-25 03:07:23,891 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425030723878093
2019-04-25 03:07:23,918 [salt.minion      :1432][INFO    ][17840] Starting a new job with PID 17840
2019-04-25 03:07:23,947 [salt.minion      :1711][INFO    ][17840] Returning information for job: 20190425030723878093
2019-04-25 03:07:52,782 [salt.loaded.ext.module.maas:1023][INFO    ][15967] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1493.226053s left)
2019-04-25 03:07:54,106 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425030754095230
2019-04-25 03:07:54,128 [salt.minion      :1432][INFO    ][17886] Starting a new job with PID 17886
2019-04-25 03:07:54,154 [salt.minion      :1711][INFO    ][17886] Returning information for job: 20190425030754095230
2019-04-25 03:07:55,707 [salt.utils.schedule:1377][INFO    ][5477] Running scheduled job: __mine_interval
2019-04-25 03:08:24,294 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425030824285209
2019-04-25 03:08:24,311 [salt.minion      :1432][INFO    ][17953] Starting a new job with PID 17953
2019-04-25 03:08:24,340 [salt.minion      :1711][INFO    ][17953] Returning information for job: 20190425030824285209
2019-04-25 03:08:25,703 [salt.loaded.ext.module.maas:1023][INFO    ][15967] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1460.30462909s left)
2019-04-25 03:08:54,357 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425030854345856
2019-04-25 03:08:54,381 [salt.minion      :1432][INFO    ][17991] Starting a new job with PID 17991
2019-04-25 03:08:54,408 [salt.minion      :1711][INFO    ][17991] Returning information for job: 20190425030854345856
2019-04-25 03:08:58,400 [salt.loaded.ext.module.maas:1023][INFO    ][15967] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1427.6077621s left)
2019-04-25 03:09:24,443 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425030924429145
2019-04-25 03:09:24,466 [salt.minion      :1432][INFO    ][18088] Starting a new job with PID 18088
2019-04-25 03:09:24,491 [salt.minion      :1711][INFO    ][18088] Returning information for job: 20190425030924429145
2019-04-25 03:09:31,184 [salt.state       :300 ][INFO    ][15967] {'ret': True}
2019-04-25 03:09:31,185 [salt.state       :1951][INFO    ][15967] Completed state [maas.wait_for_machine_status] at time 03:09:31.185051 duration_in_ms=855195.001
2019-04-25 03:09:31,193 [salt.minion      :1711][INFO    ][15967] Returning information for job: 20190425025506213372
2019-04-25 04:07:55,707 [salt.utils.schedule:1377][INFO    ][5477] Running scheduled job: __mine_interval
2019-04-25 04:54:02,309 [salt.minion      :1308][INFO    ][5477] User sudo_ubuntu Executing command cp.push_dir with jid 20190425045402296663
2019-04-25 04:54:02,327 [salt.minion      :1432][INFO    ][28880] Starting a new job with PID 28880
