2019-03-12 05:10:22,677 [salt.utils.decorators:613 ][WARNING ][2117] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-12 05:10:23,376 [salt.utils.decorators:613 ][WARNING ][2117] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-12 05:10:25,783 [salt.loaded.int.states.file:2298][WARNING ][2525] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-03-12 05:10:53,833 [salt.state       :2022][WARNING ][3023] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-03-12 05:10:56,848 [salt.utils.decorators:613 ][WARNING ][3023] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-12 05:11:44,777 [salt.utils.decorators:613 ][WARNING ][3023] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-12 05:12:12,794 [salt.utils.decorators:613 ][WARNING ][3023] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-12 05:12:13,833 [salt.utils.decorators:613 ][WARNING ][3023] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-12 05:12:17,035 [salt.loaded.ext.module.maasng:1008][WARNING ][3023] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-03-12 05:12:17,035 [salt.loaded.ext.module.maasng:1011][WARNING ][3023] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-03-12 05:12:17,738 [salt.loaded.ext.module.maasng:1235][WARNING ][3023] Ignoring parameter vlan:0
2019-03-12 05:12:18,490 [salt.utils.decorators:613 ][WARNING ][3023] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-12 05:12:27,204 [salt.utils.decorators:613 ][WARNING ][6448] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-12 05:12:27,252 [salt.loaded.ext.module.maas:412 ][WARNING ][6448] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-12 05:12:28,465 [salt.loaded.ext.module.maas:412 ][WARNING ][6448] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-12 05:12:29,617 [salt.loaded.ext.module.maas:412 ][WARNING ][6448] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-12 05:12:30,656 [salt.loaded.ext.module.maas:412 ][WARNING ][6448] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-12 05:12:31,800 [salt.loaded.ext.module.maas:412 ][WARNING ][6448] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-12 05:12:35,408 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7018] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-03-12 05:12:35,440 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7018] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-03-12 05:12:35,479 [salt.utils.parsers:1051][WARNING ][380] Minion received a SIGTERM. Exiting.
2019-03-12 05:12:36,492 [salt.cli.daemons :293 ][INFO    ][7071] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-03-12 05:12:36,614 [salt.cli.daemons :82  ][INFO    ][7071] Starting up the Salt Minion
2019-03-12 05:12:36,615 [salt.utils.event :1017][INFO    ][7071] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-03-12 05:12:37,782 [salt.minion      :976 ][INFO    ][7071] Creating minion process manager
2019-03-12 05:12:39,396 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][7071] Executing command ['date', '+%z'] in directory '/root'
2019-03-12 05:12:39,426 [salt.utils.schedule:568 ][INFO    ][7071] Updating job settings for scheduled job: __mine_interval
2019-03-12 05:12:39,428 [salt.minion      :1108][INFO    ][7071] Added mine.update to scheduler
2019-03-12 05:12:39,433 [salt.minion      :1975][INFO    ][7071] Minion is starting as user 'root'
2019-03-12 05:12:39,451 [salt.minion      :2336][INFO    ][7071] Minion is ready to receive requests!
2019-03-12 05:13:03,876 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command state.apply with jid 20190312051303865878
2019-03-12 05:13:03,888 [salt.minion      :1432][INFO    ][7208] Starting a new job with PID 7208
2019-03-12 05:13:09,547 [salt.state       :915 ][INFO    ][7208] Loading fresh modules for state activity
2019-03-12 05:13:09,600 [salt.fileclient  :1219][INFO    ][7208] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-03-12 05:13:09,647 [salt.state       :1780][INFO    ][7208] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:13:09.647645
2019-03-12 05:13:09,647 [salt.state       :1813][INFO    ][7208] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-12 05:13:09,649 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7208] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-12 05:13:11,344 [salt.state       :300 ][INFO    ][7208] {'pid': 7227, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-12 05:13:11,345 [salt.state       :1951][INFO    ][7208] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:13:11.344925 duration_in_ms=1697.279
2019-03-12 05:13:11,347 [salt.state       :1780][INFO    ][7208] Running state [maas.wait_for_machine_status] at time 05:13:11.347671
2019-03-12 05:13:11,348 [salt.state       :1813][INFO    ][7208] Executing state module.run for [maas.wait_for_machine_status]
2019-03-12 05:13:11,349 [salt.utils.decorators:613 ][WARNING ][7208] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-12 05:13:12,094 [salt.loaded.ext.module.maas:1023][INFO    ][7208] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.26608515s left)
2019-03-12 05:13:18,962 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312051318944871
2019-03-12 05:13:18,984 [salt.minion      :1432][INFO    ][7244] Starting a new job with PID 7244
2019-03-12 05:13:19,009 [salt.minion      :1711][INFO    ][7244] Returning information for job: 20190312051318944871
2019-03-12 05:13:42,861 [salt.loaded.ext.module.maas:1023][INFO    ][7208] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.49886298s left)
2019-03-12 05:13:49,150 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312051349142790
2019-03-12 05:13:49,175 [salt.minion      :1432][INFO    ][7284] Starting a new job with PID 7284
2019-03-12 05:13:49,197 [salt.minion      :1711][INFO    ][7284] Returning information for job: 20190312051349142790
2019-03-12 05:14:13,535 [salt.loaded.ext.module.maas:1023][INFO    ][7208] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.82479405s left)
2019-03-12 05:14:19,374 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312051419353652
2019-03-12 05:14:19,398 [salt.minion      :1432][INFO    ][7340] Starting a new job with PID 7340
2019-03-12 05:14:19,418 [salt.minion      :1711][INFO    ][7340] Returning information for job: 20190312051419353652
2019-03-12 05:14:44,432 [salt.loaded.ext.module.maas:1023][INFO    ][7208] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.92815399s left)
2019-03-12 05:14:49,569 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312051449553675
2019-03-12 05:14:49,592 [salt.minion      :1432][INFO    ][7384] Starting a new job with PID 7384
2019-03-12 05:14:49,615 [salt.minion      :1711][INFO    ][7384] Returning information for job: 20190312051449553675
2019-03-12 05:15:15,289 [salt.loaded.ext.module.maas:1023][INFO    ][7208] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1376.07119298s left)
2019-03-12 05:15:19,770 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312051519761721
2019-03-12 05:15:19,787 [salt.minion      :1432][INFO    ][7463] Starting a new job with PID 7463
2019-03-12 05:15:19,808 [salt.minion      :1711][INFO    ][7463] Returning information for job: 20190312051519761721
2019-03-12 05:15:46,500 [salt.loaded.ext.module.maas:1023][INFO    ][7208] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.860358s left)
2019-03-12 05:15:49,821 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312051549810687
2019-03-12 05:15:49,845 [salt.minion      :1432][INFO    ][7575] Starting a new job with PID 7575
2019-03-12 05:15:49,865 [salt.minion      :1711][INFO    ][7575] Returning information for job: 20190312051549810687
2019-03-12 05:16:17,588 [salt.loaded.ext.module.maas:1023][INFO    ][7208] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1313.77183819s left)
2019-03-12 05:16:19,919 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312051619911518
2019-03-12 05:16:19,931 [salt.minion      :1432][INFO    ][7730] Starting a new job with PID 7730
2019-03-12 05:16:19,950 [salt.minion      :1711][INFO    ][7730] Returning information for job: 20190312051619911518
2019-03-12 05:16:48,782 [salt.loaded.ext.module.maas:1023][INFO    ][7208] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1282.57817602s left)
2019-03-12 05:16:49,990 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312051649981089
2019-03-12 05:16:50,004 [salt.minion      :1432][INFO    ][7927] Starting a new job with PID 7927
2019-03-12 05:16:50,025 [salt.minion      :1711][INFO    ][7927] Returning information for job: 20190312051649981089
2019-03-12 05:17:19,949 [salt.loaded.ext.module.maas:1023][INFO    ][7208] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1251.41107798s left)
2019-03-12 05:17:20,196 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312051720187968
2019-03-12 05:17:20,209 [salt.minion      :1432][INFO    ][8164] Starting a new job with PID 8164
2019-03-12 05:17:20,229 [salt.minion      :1711][INFO    ][8164] Returning information for job: 20190312051720187968
2019-03-12 05:17:50,272 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312051750263597
2019-03-12 05:17:50,289 [salt.minion      :1432][INFO    ][8397] Starting a new job with PID 8397
2019-03-12 05:17:50,312 [salt.minion      :1711][INFO    ][8397] Returning information for job: 20190312051750263597
2019-03-12 05:17:52,114 [salt.loaded.ext.module.maas:1023][INFO    ][7208] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1219.24630618s left)
2019-03-12 05:18:20,302 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312051820294590
2019-03-12 05:18:20,318 [salt.minion      :1432][INFO    ][8844] Starting a new job with PID 8844
2019-03-12 05:18:20,340 [salt.minion      :1711][INFO    ][8844] Returning information for job: 20190312051820294590
2019-03-12 05:18:24,179 [salt.loaded.ext.module.maas:1023][INFO    ][7208] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1187.18149018s left)
2019-03-12 05:18:50,384 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312051850368209
2019-03-12 05:18:50,405 [salt.minion      :1432][INFO    ][8910] Starting a new job with PID 8910
2019-03-12 05:18:50,424 [salt.minion      :1711][INFO    ][8910] Returning information for job: 20190312051850368209
2019-03-12 05:18:56,357 [salt.loaded.ext.module.maas:1023][INFO    ][7208] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1155.00287914s left)
2019-03-12 05:19:20,492 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312051920480977
2019-03-12 05:19:20,514 [salt.minion      :1432][INFO    ][9092] Starting a new job with PID 9092
2019-03-12 05:19:20,536 [salt.minion      :1711][INFO    ][9092] Returning information for job: 20190312051920480977
2019-03-12 05:19:29,302 [salt.state       :300 ][INFO    ][7208] {'ret': True}
2019-03-12 05:19:29,303 [salt.state       :1951][INFO    ][7208] Completed state [maas.wait_for_machine_status] at time 05:19:29.303446 duration_in_ms=377955.775
2019-03-12 05:19:29,306 [salt.minion      :1711][INFO    ][7208] Returning information for job: 20190312051303865878
2019-03-12 05:19:29,949 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command state.apply with jid 20190312051929937660
2019-03-12 05:19:29,970 [salt.minion      :1432][INFO    ][9116] Starting a new job with PID 9116
2019-03-12 05:19:35,664 [salt.state       :915 ][INFO    ][9116] Loading fresh modules for state activity
2019-03-12 05:19:35,716 [salt.fileclient  :1219][INFO    ][9116] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-03-12 05:19:35,812 [salt.state       :1780][INFO    ][9116] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:19:35.812486
2019-03-12 05:19:35,812 [salt.state       :1813][INFO    ][9116] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-12 05:19:35,814 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9116] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-12 05:19:37,561 [salt.state       :300 ][INFO    ][9116] {'pid': 9133, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-12 05:19:37,561 [salt.state       :1951][INFO    ][9116] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:19:37.561500 duration_in_ms=1749.015
2019-03-12 05:19:37,563 [salt.state       :1780][INFO    ][9116] Running state [maas_machines_storage_cmp002_lvm] at time 05:19:37.563747
2019-03-12 05:19:37,564 [salt.state       :1813][INFO    ][9116] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-03-12 05:19:38,555 [salt.loaded.ext.module.maasng:610 ][INFO    ][9116] nhxwyc
2019-03-12 05:19:38,555 [salt.loaded.ext.module.maasng:626 ][INFO    ][9116] sda
2019-03-12 05:19:39,048 [salt.loaded.ext.module.maasng:361 ][INFO    ][9116] nhxwyc
2019-03-12 05:19:39,125 [salt.loaded.ext.module.maasng:367 ][INFO    ][9116] [{u'size': 800109715456, u'block_size': 4096, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'id': 1, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'nhxwyc', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'3ff2cc9a-fc0f-49e9-b987-1b09453d7bae', u'resource_uri': u'/MAAS/api/2.0/nodes/nhxwyc/blockdevices/1/partition/1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'nhxwyc', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'0db31d57-36fb-4ffd-a0e3-df85bccacb38', u'label': None}, 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'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/nhxwyc/blockdevices/1/'}, {u'size': 800097042432, u'block_size': 4096, u'available_size': 0, u'uuid': u'8141f7a8-71d0-4129-9dcd-85dc13a88db4', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'id': 3, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'nhxwyc', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'7dccf11c-946d-4ac0-9eea-7fdaddf978e2', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'partitions': [], u'used_size': 800097042432, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/nhxwyc/blockdevices/3/'}]
2019-03-12 05:19:39,126 [salt.loaded.ext.module.maasng:632 ][INFO    ][9116] vgroot
2019-03-12 05:19:39,126 [salt.loaded.ext.module.maasng:635 ][INFO    ][9116] lvroot
2019-03-12 05:19:39,126 [salt.loaded.ext.module.maasng:639 ][INFO    ][9116] 107374182400
2019-03-12 05:19:39,617 [salt.loaded.ext.module.maasng:645 ][INFO    ][9116] {u'hwe_kernel': u'', u'swap_size': None, u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'knwm4m', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'id': 20, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'knwm4m', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'knwm4m', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'effective_mtu': 1500, u'id': 4, u'system_id': u'nhxwyc', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/nhxwyc/interfaces/4/'}, u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'cpu_count': 40, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 800109715456, u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'f821e8dd-87c2-4a2b-ab64-cfafe9b82e18', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'nhxwyc', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'de8e45aa-e0d5-4b46-8e92-bbb03515457a', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/nhxwyc/blockdevices/1/partition/6'}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'nhxwyc', 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'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/nhxwyc/blockdevices/1/', u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'uuid': None}, u'blockdevice_set': [{u'size': 800109715456, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'f821e8dd-87c2-4a2b-ab64-cfafe9b82e18', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'nhxwyc', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'de8e45aa-e0d5-4b46-8e92-bbb03515457a', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/nhxwyc/blockdevices/1/partition/6'}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'nhxwyc', 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'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/nhxwyc/blockdevices/1/'}, {u'size': 107374182400, u'uuid': u'6834475a-4a27-4953-8221-32f4c62f3e1e', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'8fc55559-37ae-45b6-a7a0-963719b4a230', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'nhxwyc', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 11, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/nhxwyc/blockdevices/11/'}], u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'disable_ipv4': False, u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'nhxwyc', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'8fc55559-37ae-45b6-a7a0-963719b4a230', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'nhxwyc', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/nhxwyc/blockdevices/11/', u'used_size': 107374182400, u'id': 11, u'serial': None, u'uuid': u'6834475a-4a27-4953-8221-32f4c62f3e1e'}], u'architecture': u'amd64/generic', u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'power_state': u'off', u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'nhxwyc', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'node_type_name': u'Machine', u'current_testing_result_id': 3, u'cpu_test_status': -1, u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 800109715456, u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'f821e8dd-87c2-4a2b-ab64-cfafe9b82e18', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'nhxwyc', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'de8e45aa-e0d5-4b46-8e92-bbb03515457a', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/nhxwyc/blockdevices/1/partition/6'}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'nhxwyc', 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'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/nhxwyc/blockdevices/1/', u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'uuid': None}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'node_type': 0, u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'commissioning_status_name': u'Passed', u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'knwm4m', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'id': 20, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'knwm4m', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'knwm4m', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'effective_mtu': 1500, u'id': 4, u'system_id': u'nhxwyc', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/nhxwyc/interfaces/4/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:50', u'params': u'', u'effective_mtu': 1500, u'id': 13, u'system_id': u'nhxwyc', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/nhxwyc/interfaces/13/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:51', u'params': u'', u'effective_mtu': 1500, u'id': 14, u'system_id': u'nhxwyc', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/nhxwyc/interfaces/14/'}, {u'name': u'ens2f0', u'links': [{u'id': 21, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:48', u'params': u'', u'effective_mtu': 1500, u'id': 15, u'system_id': u'nhxwyc', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/nhxwyc/interfaces/15/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'knwm4m', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 22, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'knwm4m', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'knwm4m', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.40'}], u'mac_address': u'9c:b6:54:8a:10:1c', u'params': u'', u'effective_mtu': 1500, u'id': 11, u'system_id': u'nhxwyc', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/nhxwyc/interfaces/11/'}, {u'name': u'ens2f1', u'links': [{u'id': 23, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:49', u'params': u'', u'effective_mtu': 1500, u'id': 12, u'system_id': u'nhxwyc', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/nhxwyc/interfaces/12/'}], u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/nhxwyc/'}
2019-03-12 05:19:39,619 [salt.state       :300 ][INFO    ][9116] {'new': {'storage_layout': 'lvm'}}
2019-03-12 05:19:39,620 [salt.state       :1951][INFO    ][9116] Completed state [maas_machines_storage_cmp002_lvm] at time 05:19:39.620164 duration_in_ms=2056.415
2019-03-12 05:19:39,620 [salt.state       :1780][INFO    ][9116] Running state [maas_machines_storage_cmp001_lvm] at time 05:19:39.620518
2019-03-12 05:19:39,620 [salt.state       :1813][INFO    ][9116] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-03-12 05:19:40,593 [salt.loaded.ext.module.maasng:610 ][INFO    ][9116] qyhmyg
2019-03-12 05:19:40,593 [salt.loaded.ext.module.maasng:626 ][INFO    ][9116] sda
2019-03-12 05:19:41,076 [salt.loaded.ext.module.maasng:361 ][INFO    ][9116] qyhmyg
2019-03-12 05:19:41,170 [salt.loaded.ext.module.maasng:367 ][INFO    ][9116] [{u'size': 800109715456, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'3c3dc88f-1e0d-4714-ae0b-87c4d5198081', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qyhmyg', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'30280ebe-05e2-4190-b43a-17b26e003b76', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/qyhmyg/blockdevices/2/partition/2'}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'qyhmyg', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/qyhmyg/blockdevices/2/'}, {u'size': 800097042432, u'uuid': u'1371ab81-70e2-4b15-b3b2-69dcc388864c', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'20dd6c55-4ac0-4abe-8f99-c5b90af2687c', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'qyhmyg', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'used_size': 800097042432, u'id': 4, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/qyhmyg/blockdevices/4/'}]
2019-03-12 05:19:41,171 [salt.loaded.ext.module.maasng:632 ][INFO    ][9116] vgroot
2019-03-12 05:19:41,171 [salt.loaded.ext.module.maasng:635 ][INFO    ][9116] lvroot
2019-03-12 05:19:41,172 [salt.loaded.ext.module.maasng:639 ][INFO    ][9116] 107374182400
2019-03-12 05:19:41,738 [salt.loaded.ext.module.maasng:645 ][INFO    ][9116] {u'hwe_kernel': u'', u'swap_size': None, u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'knwm4m', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'id': 26, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'knwm4m', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'knwm4m', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'mac_address': u'9c:b6:54:8a:95:a0', u'params': u'', u'effective_mtu': 1500, u'id': 5, u'system_id': u'qyhmyg', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qyhmyg/interfaces/5/'}, u'ip_addresses': [u'192.168.11.39', u'192.168.11.44'], u'cpu_count': 40, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 800109715456, u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'6180499b-186d-482e-aec4-24077222373e', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qyhmyg', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'308cafbd-3af0-4f81-93cd-a9456ecad7b4', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/qyhmyg/blockdevices/2/partition/7'}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'qyhmyg', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/qyhmyg/blockdevices/2/', u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'uuid': None}, u'blockdevice_set': [{u'size': 800109715456, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'6180499b-186d-482e-aec4-24077222373e', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qyhmyg', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'308cafbd-3af0-4f81-93cd-a9456ecad7b4', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/qyhmyg/blockdevices/2/partition/7'}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'qyhmyg', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/qyhmyg/blockdevices/2/'}, {u'size': 107374182400, u'uuid': u'3d0c34c8-aa2f-4b57-be71-d9de75f3501c', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'90e4e505-52ed-4238-b08a-0faaa344ad99', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'qyhmyg', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 12, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/qyhmyg/blockdevices/12/'}], u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'disable_ipv4': False, u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'qyhmyg', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'90e4e505-52ed-4238-b08a-0faaa344ad99', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'qyhmyg', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/qyhmyg/blockdevices/12/', u'used_size': 107374182400, u'id': 12, u'serial': None, u'uuid': u'3d0c34c8-aa2f-4b57-be71-d9de75f3501c'}], u'architecture': u'amd64/generic', u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'power_state': u'off', u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'qyhmyg', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'node_type_name': u'Machine', u'current_testing_result_id': 5, u'cpu_test_status': -1, u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 800109715456, u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'6180499b-186d-482e-aec4-24077222373e', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qyhmyg', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'308cafbd-3af0-4f81-93cd-a9456ecad7b4', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/qyhmyg/blockdevices/2/partition/7'}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'qyhmyg', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/qyhmyg/blockdevices/2/', u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'uuid': None}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'node_type': 0, u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'commissioning_status_name': u'Passed', u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'knwm4m', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'id': 26, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'knwm4m', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'knwm4m', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'mac_address': u'9c:b6:54:8a:95:a0', u'params': u'', u'effective_mtu': 1500, u'id': 5, u'system_id': u'qyhmyg', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qyhmyg/interfaces/5/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d4', u'params': u'', u'effective_mtu': 1500, u'id': 18, u'system_id': u'qyhmyg', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qyhmyg/interfaces/18/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d5', u'params': u'', u'effective_mtu': 1500, u'id': 19, u'system_id': u'qyhmyg', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qyhmyg/interfaces/19/'}, {u'name': u'ens2f1', u'links': [{u'id': 27, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cd', u'params': u'', u'effective_mtu': 1500, u'id': 16, u'system_id': u'qyhmyg', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qyhmyg/interfaces/16/'}, {u'name': u'ens2f0', u'links': [{u'id': 28, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cc', u'params': u'', u'effective_mtu': 1500, u'id': 17, u'system_id': u'qyhmyg', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qyhmyg/interfaces/17/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'knwm4m', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 29, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'knwm4m', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'knwm4m', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.44'}], u'mac_address': u'9c:b6:54:8a:95:a4', u'params': u'', u'effective_mtu': 1500, u'id': 20, u'system_id': u'qyhmyg', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qyhmyg/interfaces/20/'}], u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/qyhmyg/'}
2019-03-12 05:19:41,741 [salt.state       :300 ][INFO    ][9116] {'new': {'storage_layout': 'lvm'}}
2019-03-12 05:19:41,742 [salt.state       :1951][INFO    ][9116] Completed state [maas_machines_storage_cmp001_lvm] at time 05:19:41.741903 duration_in_ms=2121.382
2019-03-12 05:19:41,745 [salt.minion      :1711][INFO    ][9116] Returning information for job: 20190312051929937660
2019-03-12 05:19:42,282 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command state.apply with jid 20190312051942266556
2019-03-12 05:19:42,306 [salt.minion      :1432][INFO    ][9158] Starting a new job with PID 9158
2019-03-12 05:19:43,000 [salt.state       :915 ][INFO    ][9158] Loading fresh modules for state activity
2019-03-12 05:19:43,052 [salt.fileclient  :1219][INFO    ][9158] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-03-12 05:19:43,097 [salt.state       :1780][INFO    ][9158] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:19:43.097137
2019-03-12 05:19:43,097 [salt.state       :1813][INFO    ][9158] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-12 05:19:43,099 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9158] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-12 05:19:44,887 [salt.state       :300 ][INFO    ][9158] {'pid': 9165, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-12 05:19:44,888 [salt.state       :1951][INFO    ][9158] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:19:44.888521 duration_in_ms=1791.383
2019-03-12 05:19:44,891 [salt.state       :1780][INFO    ][9158] Running state [maas.deploy_machines] at time 05:19:44.891504
2019-03-12 05:19:44,892 [salt.state       :1813][INFO    ][9158] Executing state module.run for [maas.deploy_machines]
2019-03-12 05:19:44,892 [salt.utils.decorators:613 ][WARNING ][9158] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-12 05:19:45,412 [salt.loaded.ext.module.maas:684 ][INFO    ][9158] deploymachines hwe_kernel=hwe-16.04 system_id=nhxwyc distro_series=xenial
2019-03-12 05:19:47,491 [salt.loaded.ext.module.maas:684 ][INFO    ][9158] deploymachines hwe_kernel=hwe-16.04 system_id=qyhmyg distro_series=xenial
2019-03-12 05:19:49,382 [salt.loaded.ext.module.maas:684 ][INFO    ][9158] deploymachines hwe_kernel=hwe-16.04 system_id=fm8fk8 distro_series=xenial
2019-03-12 05:19:51,547 [salt.loaded.ext.module.maas:684 ][INFO    ][9158] deploymachines hwe_kernel=hwe-16.04 system_id=reqpkf distro_series=xenial
2019-03-12 05:19:53,594 [salt.loaded.ext.module.maas:684 ][INFO    ][9158] deploymachines hwe_kernel=hwe-16.04 system_id=wdahmd distro_series=xenial
2019-03-12 05:19:55,614 [salt.state       :300 ][INFO    ][9158] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-03-12 05:19:55,614 [salt.state       :1951][INFO    ][9158] Completed state [maas.deploy_machines] at time 05:19:55.614522 duration_in_ms=10723.017
2019-03-12 05:19:55,616 [salt.minion      :1711][INFO    ][9158] Returning information for job: 20190312051942266556
2019-03-12 05:19:56,197 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command state.apply with jid 20190312051956189732
2019-03-12 05:19:56,214 [salt.minion      :1432][INFO    ][9470] Starting a new job with PID 9470
2019-03-12 05:20:02,089 [salt.state       :915 ][INFO    ][9470] Loading fresh modules for state activity
2019-03-12 05:20:02,148 [salt.fileclient  :1219][INFO    ][9470] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-03-12 05:20:02,196 [salt.state       :1780][INFO    ][9470] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:20:02.196820
2019-03-12 05:20:02,197 [salt.state       :1813][INFO    ][9470] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-12 05:20:02,198 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9470] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-12 05:20:03,989 [salt.state       :300 ][INFO    ][9470] {'pid': 9510, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-12 05:20:03,990 [salt.state       :1951][INFO    ][9470] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:20:03.990588 duration_in_ms=1793.768
2019-03-12 05:20:03,993 [salt.state       :1780][INFO    ][9470] Running state [maas.wait_for_machine_status] at time 05:20:03.993763
2019-03-12 05:20:03,994 [salt.state       :1813][INFO    ][9470] Executing state module.run for [maas.wait_for_machine_status]
2019-03-12 05:20:03,994 [salt.utils.decorators:613 ][WARNING ][9470] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-12 05:20:06,827 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.1784811s left)
2019-03-12 05:20:11,279 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312052011264979
2019-03-12 05:20:11,300 [salt.minion      :1432][INFO    ][9537] Starting a new job with PID 9537
2019-03-12 05:20:11,320 [salt.minion      :1711][INFO    ][9537] Returning information for job: 20190312052011264979
2019-03-12 05:20:39,812 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.19428611s left)
2019-03-12 05:20:41,328 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312052041316794
2019-03-12 05:20:41,346 [salt.minion      :1432][INFO    ][9564] Starting a new job with PID 9564
2019-03-12 05:20:41,370 [salt.minion      :1711][INFO    ][9564] Returning information for job: 20190312052041316794
2019-03-12 05:21:11,377 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312052111368237
2019-03-12 05:21:11,394 [salt.minion      :1432][INFO    ][9661] Starting a new job with PID 9661
2019-03-12 05:21:11,414 [salt.minion      :1711][INFO    ][9661] Returning information for job: 20190312052111368237
2019-03-12 05:21:12,618 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.38829303s left)
2019-03-12 05:21:41,433 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312052141421143
2019-03-12 05:21:41,453 [salt.minion      :1432][INFO    ][9679] Starting a new job with PID 9679
2019-03-12 05:21:41,474 [salt.minion      :1711][INFO    ][9679] Returning information for job: 20190312052141421143
2019-03-12 05:21:45,377 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2148.62855816s left)
2019-03-12 05:22:11,497 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312052211484035
2019-03-12 05:22:11,515 [salt.minion      :1432][INFO    ][9758] Starting a new job with PID 9758
2019-03-12 05:22:11,536 [salt.minion      :1711][INFO    ][9758] Returning information for job: 20190312052211484035
2019-03-12 05:22:18,162 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2115.84403419s left)
2019-03-12 05:22:41,529 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312052241518359
2019-03-12 05:22:41,546 [salt.minion      :1432][INFO    ][9798] Starting a new job with PID 9798
2019-03-12 05:22:41,568 [salt.minion      :1711][INFO    ][9798] Returning information for job: 20190312052241518359
2019-03-12 05:22:50,717 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2083.28920007s left)
2019-03-12 05:23:11,597 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312052311583653
2019-03-12 05:23:11,615 [salt.minion      :1432][INFO    ][10002] Starting a new job with PID 10002
2019-03-12 05:23:11,636 [salt.minion      :1711][INFO    ][10002] Returning information for job: 20190312052311583653
2019-03-12 05:23:23,627 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2050.37865019s left)
2019-03-12 05:23:41,670 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312052341659239
2019-03-12 05:23:41,686 [salt.minion      :1432][INFO    ][10032] Starting a new job with PID 10032
2019-03-12 05:23:41,707 [salt.minion      :1711][INFO    ][10032] Returning information for job: 20190312052341659239
2019-03-12 05:23:56,423 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2017.58292007s left)
2019-03-12 05:24:11,750 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312052411738398
2019-03-12 05:24:11,773 [salt.minion      :1432][INFO    ][10365] Starting a new job with PID 10365
2019-03-12 05:24:11,793 [salt.minion      :1711][INFO    ][10365] Returning information for job: 20190312052411738398
2019-03-12 05:24:29,301 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1984.70465207s left)
2019-03-12 05:24:41,817 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312052441799929
2019-03-12 05:24:41,839 [salt.minion      :1432][INFO    ][10435] Starting a new job with PID 10435
2019-03-12 05:24:41,860 [salt.minion      :1711][INFO    ][10435] Returning information for job: 20190312052441799929
2019-03-12 05:25:02,189 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1951.81666708s left)
2019-03-12 05:25:11,914 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312052511904123
2019-03-12 05:25:11,928 [salt.minion      :1432][INFO    ][10721] Starting a new job with PID 10721
2019-03-12 05:25:11,950 [salt.minion      :1711][INFO    ][10721] Returning information for job: 20190312052511904123
2019-03-12 05:25:35,114 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1918.89223909s left)
2019-03-12 05:25:41,994 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312052541977797
2019-03-12 05:25:42,012 [salt.minion      :1432][INFO    ][10776] Starting a new job with PID 10776
2019-03-12 05:25:42,038 [salt.minion      :1711][INFO    ][10776] Returning information for job: 20190312052541977797
2019-03-12 05:26:08,122 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1885.88424516s left)
2019-03-12 05:26:12,038 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312052612030675
2019-03-12 05:26:12,049 [salt.minion      :1432][INFO    ][11316] Starting a new job with PID 11316
2019-03-12 05:26:12,070 [salt.minion      :1711][INFO    ][11316] Returning information for job: 20190312052612030675
2019-03-12 05:26:41,023 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1852.98276615s left)
2019-03-12 05:26:42,116 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312052642103711
2019-03-12 05:26:42,138 [salt.minion      :1432][INFO    ][11344] Starting a new job with PID 11344
2019-03-12 05:26:42,158 [salt.minion      :1711][INFO    ][11344] Returning information for job: 20190312052642103711
2019-03-12 05:27:12,237 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312052712228739
2019-03-12 05:27:12,251 [salt.minion      :1432][INFO    ][11524] Starting a new job with PID 11524
2019-03-12 05:27:12,270 [salt.minion      :1711][INFO    ][11524] Returning information for job: 20190312052712228739
2019-03-12 05:27:13,979 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1820.02659702s left)
2019-03-12 05:27:42,335 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312052742320654
2019-03-12 05:27:42,357 [salt.minion      :1432][INFO    ][11555] Starting a new job with PID 11555
2019-03-12 05:27:42,376 [salt.minion      :1711][INFO    ][11555] Returning information for job: 20190312052742320654
2019-03-12 05:27:47,125 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1786.88117504s left)
2019-03-12 05:28:12,487 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312052812475789
2019-03-12 05:28:12,505 [salt.minion      :1432][INFO    ][11841] Starting a new job with PID 11841
2019-03-12 05:28:12,526 [salt.minion      :1711][INFO    ][11841] Returning information for job: 20190312052812475789
2019-03-12 05:28:19,904 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1754.10178518s left)
2019-03-12 05:28:42,613 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312052842603514
2019-03-12 05:28:42,633 [salt.minion      :1432][INFO    ][11920] Starting a new job with PID 11920
2019-03-12 05:28:42,654 [salt.minion      :1711][INFO    ][11920] Returning information for job: 20190312052842603514
2019-03-12 05:28:52,936 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1721.06963515s left)
2019-03-12 05:29:12,751 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312052912741097
2019-03-12 05:29:12,766 [salt.minion      :1432][INFO    ][12105] Starting a new job with PID 12105
2019-03-12 05:29:12,787 [salt.minion      :1711][INFO    ][12105] Returning information for job: 20190312052912741097
2019-03-12 05:29:25,705 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1688.30072808s left)
2019-03-12 05:29:42,793 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312052942780453
2019-03-12 05:29:42,812 [salt.minion      :1432][INFO    ][12146] Starting a new job with PID 12146
2019-03-12 05:29:42,839 [salt.minion      :1711][INFO    ][12146] Returning information for job: 20190312052942780453
2019-03-12 05:29:59,215 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1654.79082799s left)
2019-03-12 05:30:12,960 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312053012946091
2019-03-12 05:30:12,976 [salt.minion      :1432][INFO    ][12357] Starting a new job with PID 12357
2019-03-12 05:30:12,997 [salt.minion      :1711][INFO    ][12357] Returning information for job: 20190312053012946091
2019-03-12 05:30:31,987 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1622.01874304s left)
2019-03-12 05:30:43,080 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312053043066984
2019-03-12 05:30:43,100 [salt.minion      :1432][INFO    ][12378] Starting a new job with PID 12378
2019-03-12 05:30:43,122 [salt.minion      :1711][INFO    ][12378] Returning information for job: 20190312053043066984
2019-03-12 05:31:04,786 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1589.21977615s left)
2019-03-12 05:31:13,256 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312053113234394
2019-03-12 05:31:13,279 [salt.minion      :1432][INFO    ][12601] Starting a new job with PID 12601
2019-03-12 05:31:13,300 [salt.minion      :1711][INFO    ][12601] Returning information for job: 20190312053113234394
2019-03-12 05:31:37,681 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1556.3252871s left)
2019-03-12 05:31:43,400 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312053143390033
2019-03-12 05:31:43,421 [salt.minion      :1432][INFO    ][12643] Starting a new job with PID 12643
2019-03-12 05:31:43,442 [salt.minion      :1711][INFO    ][12643] Returning information for job: 20190312053143390033
2019-03-12 05:32:10,708 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1523.29777408s left)
2019-03-12 05:32:13,477 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312053213469967
2019-03-12 05:32:13,499 [salt.minion      :1432][INFO    ][12806] Starting a new job with PID 12806
2019-03-12 05:32:13,521 [salt.minion      :1711][INFO    ][12806] Returning information for job: 20190312053213469967
2019-03-12 05:32:43,424 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1490.58184099s left)
2019-03-12 05:32:43,524 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312053243517415
2019-03-12 05:32:43,536 [salt.minion      :1432][INFO    ][12852] Starting a new job with PID 12852
2019-03-12 05:32:43,559 [salt.minion      :1711][INFO    ][12852] Returning information for job: 20190312053243517415
2019-03-12 05:33:13,717 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312053313709673
2019-03-12 05:33:13,733 [salt.minion      :1432][INFO    ][13147] Starting a new job with PID 13147
2019-03-12 05:33:13,757 [salt.minion      :1711][INFO    ][13147] Returning information for job: 20190312053313709673
2019-03-12 05:33:16,493 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1457.51266909s left)
2019-03-12 05:33:43,927 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312053343910713
2019-03-12 05:33:43,950 [salt.minion      :1432][INFO    ][13182] Starting a new job with PID 13182
2019-03-12 05:33:43,972 [salt.minion      :1711][INFO    ][13182] Returning information for job: 20190312053343910713
2019-03-12 05:33:49,154 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1424.85191011s left)
2019-03-12 05:34:14,123 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312053414114617
2019-03-12 05:34:14,141 [salt.minion      :1432][INFO    ][13253] Starting a new job with PID 13253
2019-03-12 05:34:14,164 [salt.minion      :1711][INFO    ][13253] Returning information for job: 20190312053414114617
2019-03-12 05:34:22,016 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1391.99029398s left)
2019-03-12 05:34:44,345 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312053444331967
2019-03-12 05:34:44,363 [salt.minion      :1432][INFO    ][13283] Starting a new job with PID 13283
2019-03-12 05:34:44,385 [salt.minion      :1711][INFO    ][13283] Returning information for job: 20190312053444331967
2019-03-12 05:34:54,684 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1359.32187915s left)
2019-03-12 05:35:14,563 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312053514552621
2019-03-12 05:35:14,577 [salt.minion      :1432][INFO    ][13355] Starting a new job with PID 13355
2019-03-12 05:35:14,600 [salt.minion      :1711][INFO    ][13355] Returning information for job: 20190312053514552621
2019-03-12 05:35:27,509 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1326.49731517s left)
2019-03-12 05:35:44,600 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312053544585773
2019-03-12 05:35:44,622 [salt.minion      :1432][INFO    ][13391] Starting a new job with PID 13391
2019-03-12 05:35:44,644 [salt.minion      :1711][INFO    ][13391] Returning information for job: 20190312053544585773
2019-03-12 05:36:00,092 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1293.91434717s left)
2019-03-12 05:36:14,636 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312053614621929
2019-03-12 05:36:14,658 [salt.minion      :1432][INFO    ][13466] Starting a new job with PID 13466
2019-03-12 05:36:14,677 [salt.minion      :1711][INFO    ][13466] Returning information for job: 20190312053614621929
2019-03-12 05:36:32,897 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1261.10905218s left)
2019-03-12 05:36:44,676 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312053644668367
2019-03-12 05:36:44,696 [salt.minion      :1432][INFO    ][13503] Starting a new job with PID 13503
2019-03-12 05:36:44,723 [salt.minion      :1711][INFO    ][13503] Returning information for job: 20190312053644668367
2019-03-12 05:37:05,558 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1228.44829702s left)
2019-03-12 05:37:14,729 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312053714714493
2019-03-12 05:37:14,749 [salt.minion      :1432][INFO    ][13578] Starting a new job with PID 13578
2019-03-12 05:37:14,771 [salt.minion      :1711][INFO    ][13578] Returning information for job: 20190312053714714493
2019-03-12 05:37:38,182 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1195.8239162s left)
2019-03-12 05:37:44,814 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312053744801381
2019-03-12 05:37:44,836 [salt.minion      :1432][INFO    ][13608] Starting a new job with PID 13608
2019-03-12 05:37:44,857 [salt.minion      :1711][INFO    ][13608] Returning information for job: 20190312053744801381
2019-03-12 05:38:10,754 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1163.25232005s left)
2019-03-12 05:38:14,875 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312053814868357
2019-03-12 05:38:14,890 [salt.minion      :1432][INFO    ][13682] Starting a new job with PID 13682
2019-03-12 05:38:14,910 [salt.minion      :1711][INFO    ][13682] Returning information for job: 20190312053814868357
2019-03-12 05:38:43,644 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1130.3615942s left)
2019-03-12 05:38:44,973 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312053844960220
2019-03-12 05:38:44,994 [salt.minion      :1432][INFO    ][13712] Starting a new job with PID 13712
2019-03-12 05:38:45,016 [salt.minion      :1711][INFO    ][13712] Returning information for job: 20190312053844960220
2019-03-12 05:39:15,093 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312053915082437
2019-03-12 05:39:15,108 [salt.minion      :1432][INFO    ][13785] Starting a new job with PID 13785
2019-03-12 05:39:15,128 [salt.minion      :1711][INFO    ][13785] Returning information for job: 20190312053915082437
2019-03-12 05:39:16,394 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1097.61183s left)
2019-03-12 05:39:45,201 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312053945192058
2019-03-12 05:39:45,222 [salt.minion      :1432][INFO    ][13812] Starting a new job with PID 13812
2019-03-12 05:39:45,244 [salt.minion      :1711][INFO    ][13812] Returning information for job: 20190312053945192058
2019-03-12 05:39:49,025 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1064.9812932s left)
2019-03-12 05:40:15,340 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312054015323972
2019-03-12 05:40:15,359 [salt.minion      :1432][INFO    ][13884] Starting a new job with PID 13884
2019-03-12 05:40:15,379 [salt.minion      :1711][INFO    ][13884] Returning information for job: 20190312054015323972
2019-03-12 05:40:21,730 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1032.27587318s left)
2019-03-12 05:40:45,473 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312054045459368
2019-03-12 05:40:45,493 [salt.minion      :1432][INFO    ][13918] Starting a new job with PID 13918
2019-03-12 05:40:45,518 [salt.minion      :1711][INFO    ][13918] Returning information for job: 20190312054045459368
2019-03-12 05:40:54,558 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (999.447802067s left)
2019-03-12 05:41:15,654 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312054115645283
2019-03-12 05:41:15,672 [salt.minion      :1432][INFO    ][14012] Starting a new job with PID 14012
2019-03-12 05:41:15,696 [salt.minion      :1711][INFO    ][14012] Returning information for job: 20190312054115645283
2019-03-12 05:41:27,108 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (966.898382187s left)
2019-03-12 05:41:45,804 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312054145792818
2019-03-12 05:41:45,824 [salt.minion      :1432][INFO    ][14057] Starting a new job with PID 14057
2019-03-12 05:41:45,849 [salt.minion      :1711][INFO    ][14057] Returning information for job: 20190312054145792818
2019-03-12 05:41:59,805 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (934.201253176s left)
2019-03-12 05:42:16,026 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312054216015228
2019-03-12 05:42:16,051 [salt.minion      :1432][INFO    ][14118] Starting a new job with PID 14118
2019-03-12 05:42:16,074 [salt.minion      :1711][INFO    ][14118] Returning information for job: 20190312054216015228
2019-03-12 05:42:32,469 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (901.536883116s left)
2019-03-12 05:42:46,229 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312054246216203
2019-03-12 05:42:46,246 [salt.minion      :1432][INFO    ][14164] Starting a new job with PID 14164
2019-03-12 05:42:46,270 [salt.minion      :1711][INFO    ][14164] Returning information for job: 20190312054246216203
2019-03-12 05:43:05,205 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (868.800770044s left)
2019-03-12 05:43:16,277 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312054316260727
2019-03-12 05:43:16,294 [salt.minion      :1432][INFO    ][14223] Starting a new job with PID 14223
2019-03-12 05:43:16,316 [salt.minion      :1711][INFO    ][14223] Returning information for job: 20190312054316260727
2019-03-12 05:43:37,840 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (836.166300058s left)
2019-03-12 05:43:46,499 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312054346486605
2019-03-12 05:43:46,520 [salt.minion      :1432][INFO    ][14269] Starting a new job with PID 14269
2019-03-12 05:43:46,544 [salt.minion      :1711][INFO    ][14269] Returning information for job: 20190312054346486605
2019-03-12 05:44:10,483 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (803.523132086s left)
2019-03-12 05:44:16,569 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312054416557695
2019-03-12 05:44:16,591 [salt.minion      :1432][INFO    ][14326] Starting a new job with PID 14326
2019-03-12 05:44:16,613 [salt.minion      :1711][INFO    ][14326] Returning information for job: 20190312054416557695
2019-03-12 05:44:43,144 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (770.862157106s left)
2019-03-12 05:44:46,593 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312054446584599
2019-03-12 05:44:46,607 [salt.minion      :1432][INFO    ][14374] Starting a new job with PID 14374
2019-03-12 05:44:46,627 [salt.minion      :1711][INFO    ][14374] Returning information for job: 20190312054446584599
2019-03-12 05:45:15,906 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (738.099894047s left)
2019-03-12 05:45:16,653 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312054516645434
2019-03-12 05:45:16,670 [salt.minion      :1432][INFO    ][14431] Starting a new job with PID 14431
2019-03-12 05:45:16,692 [salt.minion      :1711][INFO    ][14431] Returning information for job: 20190312054516645434
2019-03-12 05:45:46,769 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312054546759891
2019-03-12 05:45:46,785 [salt.minion      :1432][INFO    ][14474] Starting a new job with PID 14474
2019-03-12 05:45:46,808 [salt.minion      :1711][INFO    ][14474] Returning information for job: 20190312054546759891
2019-03-12 05:45:48,506 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (705.500082016s left)
2019-03-12 05:46:16,854 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312054616840478
2019-03-12 05:46:16,877 [salt.minion      :1432][INFO    ][14533] Starting a new job with PID 14533
2019-03-12 05:46:16,900 [salt.minion      :1711][INFO    ][14533] Returning information for job: 20190312054616840478
2019-03-12 05:46:21,287 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (672.71938014s left)
2019-03-12 05:46:47,004 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312054646991635
2019-03-12 05:46:47,024 [salt.minion      :1432][INFO    ][14579] Starting a new job with PID 14579
2019-03-12 05:46:47,048 [salt.minion      :1711][INFO    ][14579] Returning information for job: 20190312054646991635
2019-03-12 05:46:53,959 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (640.046984196s left)
2019-03-12 05:47:17,114 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312054717100665
2019-03-12 05:47:17,138 [salt.minion      :1432][INFO    ][14636] Starting a new job with PID 14636
2019-03-12 05:47:17,159 [salt.minion      :1711][INFO    ][14636] Returning information for job: 20190312054717100665
2019-03-12 05:47:26,622 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (607.384260178s left)
2019-03-12 05:47:47,304 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312054747294038
2019-03-12 05:47:47,324 [salt.minion      :1432][INFO    ][14686] Starting a new job with PID 14686
2019-03-12 05:47:47,354 [salt.minion      :1711][INFO    ][14686] Returning information for job: 20190312054747294038
2019-03-12 05:47:59,208 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (574.79749608s left)
2019-03-12 05:48:17,479 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312054817468003
2019-03-12 05:48:17,498 [salt.minion      :1432][INFO    ][14743] Starting a new job with PID 14743
2019-03-12 05:48:17,525 [salt.minion      :1711][INFO    ][14743] Returning information for job: 20190312054817468003
2019-03-12 05:48:31,985 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (542.021393061s left)
2019-03-12 05:48:47,517 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312054847502374
2019-03-12 05:48:47,537 [salt.minion      :1432][INFO    ][14787] Starting a new job with PID 14787
2019-03-12 05:48:47,560 [salt.minion      :1711][INFO    ][14787] Returning information for job: 20190312054847502374
2019-03-12 05:49:04,768 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (509.237840176s left)
2019-03-12 05:49:17,726 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312054917712579
2019-03-12 05:49:17,748 [salt.minion      :1432][INFO    ][14844] Starting a new job with PID 14844
2019-03-12 05:49:17,772 [salt.minion      :1711][INFO    ][14844] Returning information for job: 20190312054917712579
2019-03-12 05:49:37,405 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (476.600520134s left)
2019-03-12 05:49:47,770 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312054947756788
2019-03-12 05:49:47,790 [salt.minion      :1432][INFO    ][14891] Starting a new job with PID 14891
2019-03-12 05:49:47,814 [salt.minion      :1711][INFO    ][14891] Returning information for job: 20190312054947756788
2019-03-12 05:50:10,151 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (443.855580091s left)
2019-03-12 05:50:17,796 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312055017784886
2019-03-12 05:50:17,817 [salt.minion      :1432][INFO    ][14948] Starting a new job with PID 14948
2019-03-12 05:50:17,843 [salt.minion      :1711][INFO    ][14948] Returning information for job: 20190312055017784886
2019-03-12 05:50:42,889 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (411.116533041s left)
2019-03-12 05:50:47,900 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312055047893332
2019-03-12 05:50:47,913 [salt.minion      :1432][INFO    ][15129] Starting a new job with PID 15129
2019-03-12 05:50:47,935 [salt.minion      :1711][INFO    ][15129] Returning information for job: 20190312055047893332
2019-03-12 05:51:15,654 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (378.352308989s left)
2019-03-12 05:51:17,981 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312055117973325
2019-03-12 05:51:17,994 [salt.minion      :1432][INFO    ][15203] Starting a new job with PID 15203
2019-03-12 05:51:18,015 [salt.minion      :1711][INFO    ][15203] Returning information for job: 20190312055117973325
2019-03-12 05:51:48,061 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312055148052160
2019-03-12 05:51:48,080 [salt.minion      :1432][INFO    ][15249] Starting a new job with PID 15249
2019-03-12 05:51:48,103 [salt.minion      :1711][INFO    ][15249] Returning information for job: 20190312055148052160
2019-03-12 05:51:48,341 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (345.664909124s left)
2019-03-12 05:52:18,218 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312055218206343
2019-03-12 05:52:18,238 [salt.minion      :1432][INFO    ][15301] Starting a new job with PID 15301
2019-03-12 05:52:18,268 [salt.minion      :1711][INFO    ][15301] Returning information for job: 20190312055218206343
2019-03-12 05:52:21,046 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (312.959652185s left)
2019-03-12 05:52:48,344 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312055248330027
2019-03-12 05:52:48,361 [salt.minion      :1432][INFO    ][15348] Starting a new job with PID 15348
2019-03-12 05:52:48,381 [salt.minion      :1711][INFO    ][15348] Returning information for job: 20190312055248330027
2019-03-12 05:52:53,708 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (280.298180103s left)
2019-03-12 05:53:18,538 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312055318524531
2019-03-12 05:53:18,561 [salt.minion      :1432][INFO    ][15405] Starting a new job with PID 15405
2019-03-12 05:53:18,586 [salt.minion      :1711][INFO    ][15405] Returning information for job: 20190312055318524531
2019-03-12 05:53:26,444 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (247.563521147s left)
2019-03-12 05:53:48,701 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312055348688358
2019-03-12 05:53:48,720 [salt.minion      :1432][INFO    ][15452] Starting a new job with PID 15452
2019-03-12 05:53:48,741 [salt.minion      :1711][INFO    ][15452] Returning information for job: 20190312055348688358
2019-03-12 05:53:59,192 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (214.813670158s left)
2019-03-12 05:54:18,745 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312055418730820
2019-03-12 05:54:18,766 [salt.minion      :1432][INFO    ][15509] Starting a new job with PID 15509
2019-03-12 05:54:18,791 [salt.minion      :1711][INFO    ][15509] Returning information for job: 20190312055418730820
2019-03-12 05:54:31,975 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (182.030670166s left)
2019-03-12 05:54:48,922 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312055448911765
2019-03-12 05:54:48,944 [salt.minion      :1432][INFO    ][15556] Starting a new job with PID 15556
2019-03-12 05:54:48,968 [salt.minion      :1711][INFO    ][15556] Returning information for job: 20190312055448911765
2019-03-12 05:55:04,739 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (149.26691103s left)
2019-03-12 05:55:18,985 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312055518973419
2019-03-12 05:55:19,005 [salt.minion      :1432][INFO    ][15617] Starting a new job with PID 15617
2019-03-12 05:55:19,030 [salt.minion      :1711][INFO    ][15617] Returning information for job: 20190312055518973419
2019-03-12 05:55:37,336 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (116.669781208s left)
2019-03-12 05:55:49,013 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312055548997443
2019-03-12 05:55:49,029 [salt.minion      :1432][INFO    ][15662] Starting a new job with PID 15662
2019-03-12 05:55:49,053 [salt.minion      :1711][INFO    ][15662] Returning information for job: 20190312055548997443
2019-03-12 05:56:09,991 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (84.0151081085s left)
2019-03-12 05:56:19,116 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312055619102735
2019-03-12 05:56:19,135 [salt.minion      :1432][INFO    ][15719] Starting a new job with PID 15719
2019-03-12 05:56:19,160 [salt.minion      :1711][INFO    ][15719] Returning information for job: 20190312055619102735
2019-03-12 05:56:42,674 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (51.3318440914s left)
2019-03-12 05:56:49,156 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312055649141515
2019-03-12 05:56:49,174 [salt.minion      :1432][INFO    ][15766] Starting a new job with PID 15766
2019-03-12 05:56:49,198 [salt.minion      :1711][INFO    ][15766] Returning information for job: 20190312055649141515
2019-03-12 05:57:15,372 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (18.6334819794s left)
2019-03-12 05:57:19,256 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312055719243084
2019-03-12 05:57:19,277 [salt.minion      :1432][INFO    ][15825] Starting a new job with PID 15825
2019-03-12 05:57:19,300 [salt.minion      :1711][INFO    ][15825] Returning information for job: 20190312055719243084
2019-03-12 05:57:48,152 [salt.state       :302 ][ERROR   ][9470] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-03-12 05:57:48,153 [salt.state       :1951][INFO    ][9470] Completed state [maas.wait_for_machine_status] at time 05:57:48.153357 duration_in_ms=2264159.591
2019-03-12 05:57:48,156 [salt.minion      :1711][INFO    ][9470] Returning information for job: 20190312051956189732
2019-03-12 05:57:59,005 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command pillar.get with jid 20190312055758993987
2019-03-12 05:57:59,025 [salt.minion      :1432][INFO    ][15901] Starting a new job with PID 15901
2019-03-12 05:57:59,034 [salt.minion      :1711][INFO    ][15901] Returning information for job: 20190312055758993987
2019-03-12 05:57:59,585 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command service.status with jid 20190312055759572501
2019-03-12 05:57:59,603 [salt.minion      :1432][INFO    ][15906] Starting a new job with PID 15906
2019-03-12 05:58:00,104 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][15906] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-03-12 05:58:00,139 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][15906] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-03-12 05:58:00,155 [salt.minion      :1711][INFO    ][15906] Returning information for job: 20190312055759572501
2019-03-12 05:58:00,692 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command state.apply with jid 20190312055800683542
2019-03-12 05:58:00,715 [salt.minion      :1432][INFO    ][15917] Starting a new job with PID 15917
2019-03-12 05:58:06,351 [salt.state       :915 ][INFO    ][15917] Loading fresh modules for state activity
2019-03-12 05:58:06,920 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15917] Executing command 'salt-minion --version' in directory '/root'
2019-03-12 05:58:07,197 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15917] Executing command 'salt-minion --version' in directory '/root'
2019-03-12 05:58:08,180 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15917] Executing command 'salt-minion --version' in directory '/root'
2019-03-12 05:58:08,456 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15917] Executing command 'salt-minion --version' in directory '/root'
2019-03-12 05:58:10,220 [salt.state       :1780][INFO    ][15917] Running state [salt-minion] at time 05:58:10.220081
2019-03-12 05:58:10,220 [salt.state       :1813][INFO    ][15917] Executing state pkg.installed for [salt-minion]
2019-03-12 05:58:10,221 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15917] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-03-12 05:58:10,321 [salt.state       :300 ][INFO    ][15917] All specified packages are already installed
2019-03-12 05:58:10,321 [salt.state       :1951][INFO    ][15917] Completed state [salt-minion] at time 05:58:10.321686 duration_in_ms=101.606
2019-03-12 05:58:10,322 [salt.state       :1780][INFO    ][15917] Running state [salt_minion_dependency_packages] at time 05:58:10.322026
2019-03-12 05:58:10,322 [salt.state       :1813][INFO    ][15917] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-03-12 05:58:10,333 [salt.state       :300 ][INFO    ][15917] All specified packages are already installed
2019-03-12 05:58:10,333 [salt.state       :1951][INFO    ][15917] Completed state [salt_minion_dependency_packages] at time 05:58:10.333476 duration_in_ms=11.45
2019-03-12 05:58:10,337 [salt.state       :1780][INFO    ][15917] Running state [/etc/salt/minion.d/minion.conf] at time 05:58:10.337824
2019-03-12 05:58:10,338 [salt.state       :1813][INFO    ][15917] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-03-12 05:58:10,604 [salt.state       :300 ][INFO    ][15917] File /etc/salt/minion.d/minion.conf is in the correct state
2019-03-12 05:58:10,604 [salt.state       :1951][INFO    ][15917] Completed state [/etc/salt/minion.d/minion.conf] at time 05:58:10.604784 duration_in_ms=266.959
2019-03-12 05:58:10,605 [salt.state       :1780][INFO    ][15917] Running state [python-netaddr] at time 05:58:10.605075
2019-03-12 05:58:10,605 [salt.state       :1813][INFO    ][15917] Executing state pkg.installed for [python-netaddr]
2019-03-12 05:58:10,615 [salt.state       :300 ][INFO    ][15917] All specified packages are already installed
2019-03-12 05:58:10,615 [salt.state       :1951][INFO    ][15917] Completed state [python-netaddr] at time 05:58:10.615227 duration_in_ms=10.152
2019-03-12 05:58:10,618 [salt.state       :1780][INFO    ][15917] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 05:58:10.618225
2019-03-12 05:58:10,618 [salt.state       :1813][INFO    ][15917] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-03-12 05:58:10,631 [salt.state       :300 ][INFO    ][15917] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-03-12 05:58:10,631 [salt.state       :1951][INFO    ][15917] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 05:58:10.631497 duration_in_ms=13.272
2019-03-12 05:58:10,632 [salt.state       :1780][INFO    ][15917] Running state [salt-minion] at time 05:58:10.632726
2019-03-12 05:58:10,633 [salt.state       :1813][INFO    ][15917] Executing state service.running for [salt-minion]
2019-03-12 05:58:10,633 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15917] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-03-12 05:58:10,673 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15917] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-03-12 05:58:10,691 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15917] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-03-12 05:58:10,706 [salt.state       :300 ][INFO    ][15917] The service salt-minion is already running
2019-03-12 05:58:10,708 [salt.state       :1951][INFO    ][15917] Completed state [salt-minion] at time 05:58:10.708486 duration_in_ms=75.759
2019-03-12 05:58:10,711 [salt.state       :1780][INFO    ][15917] Running state [/etc/salt/grains.d] at time 05:58:10.711379
2019-03-12 05:58:10,711 [salt.state       :1813][INFO    ][15917] Executing state file.directory for [/etc/salt/grains.d]
2019-03-12 05:58:10,713 [salt.state       :300 ][INFO    ][15917] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-03-12 05:58:10,713 [salt.state       :1951][INFO    ][15917] Completed state [/etc/salt/grains.d] at time 05:58:10.713645 duration_in_ms=2.265
2019-03-12 05:58:10,714 [salt.state       :1780][INFO    ][15917] Running state [/etc/salt/grains] at time 05:58:10.714727
2019-03-12 05:58:10,715 [salt.state       :1813][INFO    ][15917] Executing state file.managed for [/etc/salt/grains]
2019-03-12 05:58:10,715 [salt.state       :300 ][INFO    ][15917] File /etc/salt/grains exists with proper permissions. No changes made.
2019-03-12 05:58:10,716 [salt.state       :1951][INFO    ][15917] Completed state [/etc/salt/grains] at time 05:58:10.716283 duration_in_ms=1.555
2019-03-12 05:58:10,717 [salt.state       :1780][INFO    ][15917] Running state [/etc/salt/grains.d/placeholder] at time 05:58:10.717028
2019-03-12 05:58:10,717 [salt.state       :1813][INFO    ][15917] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-03-12 05:58:10,718 [salt.state       :300 ][INFO    ][15917] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-03-12 05:58:10,718 [salt.state       :1951][INFO    ][15917] Completed state [/etc/salt/grains.d/placeholder] at time 05:58:10.718552 duration_in_ms=1.524
2019-03-12 05:58:10,720 [salt.state       :1780][INFO    ][15917] Running state [/etc/salt/grains.d/sphinx] at time 05:58:10.720837
2019-03-12 05:58:10,721 [salt.state       :1813][INFO    ][15917] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-03-12 05:58:10,722 [salt.state       :300 ][INFO    ][15917] File /etc/salt/grains.d/sphinx is in the correct state
2019-03-12 05:58:10,722 [salt.state       :1951][INFO    ][15917] Completed state [/etc/salt/grains.d/sphinx] at time 05:58:10.722603 duration_in_ms=1.766
2019-03-12 05:58:10,724 [salt.state       :1780][INFO    ][15917] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 05:58:10.724882
2019-03-12 05:58:10,725 [salt.state       :1813][INFO    ][15917] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-03-12 05:58:10,725 [salt.state       :300 ][INFO    ][15917] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-03-12 05:58:10,725 [salt.state       :1951][INFO    ][15917] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 05:58:10.725680 duration_in_ms=0.798
2019-03-12 05:58:10,726 [salt.state       :1780][INFO    ][15917] Running state [/etc/salt/grains.d/dns_records] at time 05:58:10.726166
2019-03-12 05:58:10,726 [salt.state       :1813][INFO    ][15917] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-03-12 05:58:10,727 [salt.state       :300 ][INFO    ][15917] File /etc/salt/grains.d/dns_records is in the correct state
2019-03-12 05:58:10,727 [salt.state       :1951][INFO    ][15917] Completed state [/etc/salt/grains.d/dns_records] at time 05:58:10.727729 duration_in_ms=1.563
2019-03-12 05:58:10,728 [salt.state       :1780][INFO    ][15917] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 05:58:10.728656
2019-03-12 05:58:10,728 [salt.state       :1813][INFO    ][15917] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-03-12 05:58:10,729 [salt.state       :300 ][INFO    ][15917] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-03-12 05:58:10,729 [salt.state       :1951][INFO    ][15917] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 05:58:10.729446 duration_in_ms=0.79
2019-03-12 05:58:10,729 [salt.state       :1780][INFO    ][15917] Running state [/etc/salt/grains.d/salt] at time 05:58:10.729935
2019-03-12 05:58:10,730 [salt.state       :1813][INFO    ][15917] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-03-12 05:58:10,732 [salt.state       :300 ][INFO    ][15917] File /etc/salt/grains.d/salt is in the correct state
2019-03-12 05:58:10,732 [salt.state       :1951][INFO    ][15917] Completed state [/etc/salt/grains.d/salt] at time 05:58:10.732332 duration_in_ms=2.397
2019-03-12 05:58:10,733 [salt.state       :1780][INFO    ][15917] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 05:58:10.733280
2019-03-12 05:58:10,733 [salt.state       :1813][INFO    ][15917] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-03-12 05:58:10,733 [salt.state       :300 ][INFO    ][15917] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-03-12 05:58:10,734 [salt.state       :1951][INFO    ][15917] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 05:58:10.734065 duration_in_ms=0.785
2019-03-12 05:58:10,736 [salt.state       :1780][INFO    ][15917] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 05:58:10.735987
2019-03-12 05:58:10,736 [salt.state       :1813][INFO    ][15917] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-03-12 05:58:10,736 [salt.state       :300 ][INFO    ][15917] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-03-12 05:58:10,736 [salt.state       :1951][INFO    ][15917] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 05:58:10.736796 duration_in_ms=0.809
2019-03-12 05:58:10,737 [salt.state       :1780][INFO    ][15917] Running state [mine.update] at time 05:58:10.737461
2019-03-12 05:58:10,737 [salt.state       :1813][INFO    ][15917] Executing state module.wait for [mine.update]
2019-03-12 05:58:10,738 [salt.state       :300 ][INFO    ][15917] No changes made for mine.update
2019-03-12 05:58:10,738 [salt.state       :1951][INFO    ][15917] Completed state [mine.update] at time 05:58:10.738197 duration_in_ms=0.736
2019-03-12 05:58:10,738 [salt.state       :1780][INFO    ][15917] Running state [ca-certificates] at time 05:58:10.738446
2019-03-12 05:58:10,738 [salt.state       :1813][INFO    ][15917] Executing state pkg.installed for [ca-certificates]
2019-03-12 05:58:10,748 [salt.state       :300 ][INFO    ][15917] All specified packages are already installed
2019-03-12 05:58:10,748 [salt.state       :1951][INFO    ][15917] Completed state [ca-certificates] at time 05:58:10.748785 duration_in_ms=10.339
2019-03-12 05:58:10,749 [salt.state       :1780][INFO    ][15917] Running state [update-ca-certificates] at time 05:58:10.749490
2019-03-12 05:58:10,749 [salt.state       :1813][INFO    ][15917] Executing state cmd.wait for [update-ca-certificates]
2019-03-12 05:58:10,750 [salt.state       :300 ][INFO    ][15917] No changes made for update-ca-certificates
2019-03-12 05:58:10,750 [salt.state       :1951][INFO    ][15917] Completed state [update-ca-certificates] at time 05:58:10.750246 duration_in_ms=0.756
2019-03-12 05:58:10,750 [salt.state       :1780][INFO    ][15917] Running state [iptables] at time 05:58:10.750496
2019-03-12 05:58:10,750 [salt.state       :1813][INFO    ][15917] Executing state pkg.installed for [iptables]
2019-03-12 05:58:10,760 [salt.state       :300 ][INFO    ][15917] All specified packages are already installed
2019-03-12 05:58:10,760 [salt.state       :1951][INFO    ][15917] Completed state [iptables] at time 05:58:10.760355 duration_in_ms=9.859
2019-03-12 05:58:10,760 [salt.state       :1780][INFO    ][15917] Running state [iptables-persistent] at time 05:58:10.760615
2019-03-12 05:58:10,760 [salt.state       :1813][INFO    ][15917] Executing state pkg.installed for [iptables-persistent]
2019-03-12 05:58:10,770 [salt.state       :300 ][INFO    ][15917] All specified packages are already installed
2019-03-12 05:58:10,771 [salt.state       :1951][INFO    ][15917] Completed state [iptables-persistent] at time 05:58:10.771467 duration_in_ms=10.852
2019-03-12 05:58:10,772 [salt.state       :1780][INFO    ][15917] Running state [iptables_modules_v4_load] at time 05:58:10.772664
2019-03-12 05:58:10,772 [salt.state       :1813][INFO    ][15917] Executing state kmod.present for [iptables_modules_v4_load]
2019-03-12 05:58:10,773 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15917] Executing command 'lsmod' in directory '/root'
2019-03-12 05:58:10,794 [salt.state       :300 ][INFO    ][15917] Kernel modules iptable_filter, ip_tables are already present
2019-03-12 05:58:10,795 [salt.state       :1951][INFO    ][15917] Completed state [iptables_modules_v4_load] at time 05:58:10.795033 duration_in_ms=22.369
2019-03-12 05:58:10,795 [salt.state       :1780][INFO    ][15917] Running state [/etc/iptables/rules.v4] at time 05:58:10.795842
2019-03-12 05:58:10,796 [salt.state       :1813][INFO    ][15917] Executing state file.managed for [/etc/iptables/rules.v4]
2019-03-12 05:58:10,896 [salt.state       :300 ][INFO    ][15917] File /etc/iptables/rules.v4 is in the correct state
2019-03-12 05:58:10,897 [salt.state       :1951][INFO    ][15917] Completed state [/etc/iptables/rules.v4] at time 05:58:10.897194 duration_in_ms=101.351
2019-03-12 05:58:10,898 [salt.state       :1780][INFO    ][15917] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 05:58:10.898292
2019-03-12 05:58:10,898 [salt.state       :1813][INFO    ][15917] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-03-12 05:58:10,899 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15917] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-03-12 05:58:10,920 [salt.state       :300 ][INFO    ][15917] onlyif execution failed
2019-03-12 05:58:10,921 [salt.state       :1951][INFO    ][15917] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 05:58:10.921216 duration_in_ms=22.923
2019-03-12 05:58:10,926 [salt.state       :1780][INFO    ][15917] Running state [netfilter-persistent] at time 05:58:10.926045
2019-03-12 05:58:10,926 [salt.state       :1813][INFO    ][15917] Executing state service.running for [netfilter-persistent]
2019-03-12 05:58:10,927 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15917] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-03-12 05:58:10,949 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15917] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-03-12 05:58:10,967 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15917] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-03-12 05:58:10,985 [salt.state       :300 ][INFO    ][15917] The service netfilter-persistent is already running
2019-03-12 05:58:10,985 [salt.state       :1951][INFO    ][15917] Completed state [netfilter-persistent] at time 05:58:10.985507 duration_in_ms=59.462
2019-03-12 05:58:10,986 [salt.state       :1780][INFO    ][15917] Running state [iptables_extra.remove_stale_tables] at time 05:58:10.986727
2019-03-12 05:58:10,987 [salt.state       :1813][INFO    ][15917] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-03-12 05:58:10,988 [salt.state       :300 ][INFO    ][15917] No changes made for iptables_extra.remove_stale_tables
2019-03-12 05:58:10,988 [salt.state       :1951][INFO    ][15917] Completed state [iptables_extra.remove_stale_tables] at time 05:58:10.988482 duration_in_ms=1.755
2019-03-12 05:58:10,988 [salt.state       :1780][INFO    ][15917] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 05:58:10.988828
2019-03-12 05:58:10,989 [salt.state       :1813][INFO    ][15917] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-03-12 05:58:10,990 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15917] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-03-12 05:58:11,001 [salt.state       :300 ][INFO    ][15917] onlyif execution failed
2019-03-12 05:58:11,002 [salt.state       :1951][INFO    ][15917] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 05:58:11.001944 duration_in_ms=13.116
2019-03-12 05:58:11,002 [salt.state       :1780][INFO    ][15917] Running state [/etc/iptables/rules.v6] at time 05:58:11.002874
2019-03-12 05:58:11,003 [salt.state       :1813][INFO    ][15917] Executing state file.absent for [/etc/iptables/rules.v6]
2019-03-12 05:58:11,003 [salt.state       :300 ][INFO    ][15917] File /etc/iptables/rules.v6 is not present
2019-03-12 05:58:11,003 [salt.state       :1951][INFO    ][15917] Completed state [/etc/iptables/rules.v6] at time 05:58:11.003783 duration_in_ms=0.908
2019-03-12 05:58:11,004 [salt.state       :1780][INFO    ][15917] Running state [iptables_extra.flush_all] at time 05:58:11.004465
2019-03-12 05:58:11,004 [salt.state       :1813][INFO    ][15917] Executing state module.wait for [iptables_extra.flush_all]
2019-03-12 05:58:11,005 [salt.state       :300 ][INFO    ][15917] No changes made for iptables_extra.flush_all
2019-03-12 05:58:11,005 [salt.state       :1951][INFO    ][15917] Completed state [iptables_extra.flush_all] at time 05:58:11.005200 duration_in_ms=0.735
2019-03-12 05:58:11,010 [salt.minion      :1711][INFO    ][15917] Returning information for job: 20190312055800683542
2019-03-12 05:58:11,549 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command state.apply with jid 20190312055811538192
2019-03-12 05:58:11,566 [salt.minion      :1432][INFO    ][16004] Starting a new job with PID 16004
2019-03-12 05:58:12,270 [salt.state       :915 ][INFO    ][16004] Loading fresh modules for state activity
2019-03-12 05:58:13,113 [salt.state       :1780][INFO    ][16004] Running state [maas-rack-controller] at time 05:58:13.113512
2019-03-12 05:58:13,113 [salt.state       :1813][INFO    ][16004] Executing state pkg.installed for [maas-rack-controller]
2019-03-12 05:58:13,114 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16004] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-03-12 05:58:13,216 [salt.state       :300 ][INFO    ][16004] All specified packages are already installed
2019-03-12 05:58:13,217 [salt.state       :1951][INFO    ][16004] Completed state [maas-rack-controller] at time 05:58:13.217348 duration_in_ms=103.835
2019-03-12 05:58:13,217 [salt.state       :1780][INFO    ][16004] Running state [ipmitool] at time 05:58:13.217739
2019-03-12 05:58:13,218 [salt.state       :1813][INFO    ][16004] Executing state pkg.installed for [ipmitool]
2019-03-12 05:58:13,227 [salt.state       :300 ][INFO    ][16004] All specified packages are already installed
2019-03-12 05:58:13,227 [salt.state       :1951][INFO    ][16004] Completed state [ipmitool] at time 05:58:13.227650 duration_in_ms=9.911
2019-03-12 05:58:13,231 [salt.state       :1780][INFO    ][16004] Running state [/etc/maas/rackd.conf] at time 05:58:13.231453
2019-03-12 05:58:13,231 [salt.state       :1813][INFO    ][16004] Executing state file.line for [/etc/maas/rackd.conf]
2019-03-12 05:58:13,232 [salt.state       :300 ][INFO    ][16004] No changes needed to be made
2019-03-12 05:58:13,233 [salt.state       :1951][INFO    ][16004] Completed state [/etc/maas/rackd.conf] at time 05:58:13.233015 duration_in_ms=1.562
2019-03-12 05:58:13,233 [salt.state       :1780][INFO    ][16004] Running state [/etc/maas/rackd.conf] at time 05:58:13.233252
2019-03-12 05:58:13,233 [salt.state       :1813][INFO    ][16004] Executing state file.managed for [/etc/maas/rackd.conf]
2019-03-12 05:58:13,233 [salt.loaded.int.states.file:2298][WARNING ][16004] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-03-12 05:58:13,234 [salt.state       :300 ][INFO    ][16004] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-03-12 05:58:13,234 [salt.state       :1951][INFO    ][16004] Completed state [/etc/maas/rackd.conf] at time 05:58:13.234530 duration_in_ms=1.278
2019-03-12 05:58:13,235 [salt.state       :1780][INFO    ][16004] Running state [maas-rackd] at time 05:58:13.235551
2019-03-12 05:58:13,235 [salt.state       :1813][INFO    ][16004] Executing state service.running for [maas-rackd]
2019-03-12 05:58:13,236 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16004] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-03-12 05:58:13,270 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16004] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-03-12 05:58:13,286 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16004] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-03-12 05:58:13,302 [salt.state       :300 ][INFO    ][16004] The service maas-rackd is already running
2019-03-12 05:58:13,303 [salt.state       :1951][INFO    ][16004] Completed state [maas-rackd] at time 05:58:13.303501 duration_in_ms=67.949
2019-03-12 05:58:13,305 [salt.minion      :1711][INFO    ][16004] Returning information for job: 20190312055811538192
2019-03-12 05:58:13,860 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command state.apply with jid 20190312055813849216
2019-03-12 05:58:13,881 [salt.minion      :1432][INFO    ][16031] Starting a new job with PID 16031
2019-03-12 05:58:14,585 [salt.state       :915 ][INFO    ][16031] Loading fresh modules for state activity
2019-03-12 05:58:15,566 [salt.state       :1780][INFO    ][16031] Running state [maas-region-controller] at time 05:58:15.566420
2019-03-12 05:58:15,567 [salt.state       :1813][INFO    ][16031] Executing state pkg.installed for [maas-region-controller]
2019-03-12 05:58:15,568 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16031] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-03-12 05:58:15,672 [salt.state       :300 ][INFO    ][16031] All specified packages are already installed
2019-03-12 05:58:15,672 [salt.state       :1951][INFO    ][16031] Completed state [maas-region-controller] at time 05:58:15.672363 duration_in_ms=105.944
2019-03-12 05:58:15,672 [salt.state       :1780][INFO    ][16031] Running state [python-oauth] at time 05:58:15.672678
2019-03-12 05:58:15,672 [salt.state       :1813][INFO    ][16031] Executing state pkg.installed for [python-oauth]
2019-03-12 05:58:15,681 [salt.state       :300 ][INFO    ][16031] All specified packages are already installed
2019-03-12 05:58:15,681 [salt.state       :1951][INFO    ][16031] Completed state [python-oauth] at time 05:58:15.681596 duration_in_ms=8.918
2019-03-12 05:58:15,684 [salt.state       :1780][INFO    ][16031] Running state [/etc/maas/regiond.conf] at time 05:58:15.684410
2019-03-12 05:58:15,684 [salt.state       :1813][INFO    ][16031] Executing state file.replace for [/etc/maas/regiond.conf]
2019-03-12 05:58:15,689 [salt.state       :300 ][INFO    ][16031] No changes needed to be made
2019-03-12 05:58:15,689 [salt.state       :1951][INFO    ][16031] Completed state [/etc/maas/regiond.conf] at time 05:58:15.689771 duration_in_ms=5.361
2019-03-12 05:58:15,690 [salt.state       :1780][INFO    ][16031] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:58:15.690246
2019-03-12 05:58:15,690 [salt.state       :1813][INFO    ][16031] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-03-12 05:58:15,744 [salt.state       :300 ][INFO    ][16031] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-03-12 05:58:15,744 [salt.state       :1951][INFO    ][16031] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:58:15.744345 duration_in_ms=54.099
2019-03-12 05:58:15,744 [salt.state       :1780][INFO    ][16031] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:58:15.744882
2019-03-12 05:58:15,745 [salt.state       :1813][INFO    ][16031] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-03-12 05:58:15,751 [salt.state       :300 ][INFO    ][16031] No changes needed to be made
2019-03-12 05:58:15,752 [salt.state       :1951][INFO    ][16031] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:58:15.751988 duration_in_ms=7.106
2019-03-12 05:58:15,752 [salt.state       :1780][INFO    ][16031] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:58:15.752831
2019-03-12 05:58:15,753 [salt.state       :1813][INFO    ][16031] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-03-12 05:58:15,760 [salt.state       :300 ][INFO    ][16031] No changes needed to be made
2019-03-12 05:58:15,760 [salt.state       :1951][INFO    ][16031] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:58:15.760612 duration_in_ms=7.781
2019-03-12 05:58:15,761 [salt.state       :1780][INFO    ][16031] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:58:15.761092
2019-03-12 05:58:15,761 [salt.state       :1813][INFO    ][16031] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-03-12 05:58:15,785 [salt.state       :300 ][INFO    ][16031] No changes needed to be made
2019-03-12 05:58:15,785 [salt.state       :1951][INFO    ][16031] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:58:15.785359 duration_in_ms=24.267
2019-03-12 05:58:15,785 [salt.state       :1780][INFO    ][16031] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:58:15.785835
2019-03-12 05:58:15,786 [salt.state       :1813][INFO    ][16031] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-03-12 05:58:15,800 [salt.state       :300 ][INFO    ][16031] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-03-12 05:58:15,800 [salt.state       :1951][INFO    ][16031] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:58:15.800344 duration_in_ms=14.508
2019-03-12 05:58:15,801 [salt.state       :1780][INFO    ][16031] Running state [a2enmod headers] at time 05:58:15.801793
2019-03-12 05:58:15,802 [salt.state       :1813][INFO    ][16031] Executing state cmd.run for [a2enmod headers]
2019-03-12 05:58:15,802 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16031] Executing command 'a2enmod headers' in directory '/root'
2019-03-12 05:58:15,881 [salt.state       :300 ][INFO    ][16031] {'pid': 16051, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-03-12 05:58:15,882 [salt.state       :1951][INFO    ][16031] Completed state [a2enmod headers] at time 05:58:15.882593 duration_in_ms=80.799
2019-03-12 05:58:15,883 [salt.state       :1780][INFO    ][16031] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:58:15.883200
2019-03-12 05:58:15,883 [salt.state       :1813][INFO    ][16031] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-03-12 05:58:15,907 [salt.state       :300 ][INFO    ][16031] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-03-12 05:58:15,908 [salt.state       :1951][INFO    ][16031] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:58:15.908035 duration_in_ms=24.835
2019-03-12 05:58:15,908 [salt.state       :1780][INFO    ][16031] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:58:15.908907
2019-03-12 05:58:15,909 [salt.state       :1813][INFO    ][16031] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-03-12 05:58:15,965 [salt.state       :300 ][INFO    ][16031] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-03-12 05:58:15,965 [salt.state       :1951][INFO    ][16031] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:58:15.965882 duration_in_ms=56.975
2019-03-12 05:58:15,966 [salt.state       :1780][INFO    ][16031] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:58:15.966528
2019-03-12 05:58:15,967 [salt.state       :1813][INFO    ][16031] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-03-12 05:58:16,027 [salt.state       :300 ][INFO    ][16031] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-03-12 05:58:16,027 [salt.state       :1951][INFO    ][16031] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:58:16.027265 duration_in_ms=60.738
2019-03-12 05:58:16,027 [salt.state       :1780][INFO    ][16031] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:58:16.027793
2019-03-12 05:58:16,028 [salt.state       :1813][INFO    ][16031] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-03-12 05:58:16,110 [salt.state       :300 ][INFO    ][16031] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-03-12 05:58:16,110 [salt.state       :1951][INFO    ][16031] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:58:16.110386 duration_in_ms=82.593
2019-03-12 05:58:16,110 [salt.state       :1780][INFO    ][16031] Running state [/root/.pgpass] at time 05:58:16.110636
2019-03-12 05:58:16,111 [salt.state       :1813][INFO    ][16031] Executing state file.managed for [/root/.pgpass]
2019-03-12 05:58:16,159 [salt.state       :300 ][INFO    ][16031] File /root/.pgpass is in the correct state
2019-03-12 05:58:16,160 [salt.state       :1951][INFO    ][16031] Completed state [/root/.pgpass] at time 05:58:16.160060 duration_in_ms=49.423
2019-03-12 05:58:16,169 [salt.state       :1780][INFO    ][16031] Running state [maas-region syncdb --noinput] at time 05:58:16.169159
2019-03-12 05:58:16,169 [salt.state       :1813][INFO    ][16031] Executing state cmd.run for [maas-region syncdb --noinput]
2019-03-12 05:58:16,170 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16031] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-03-12 05:58:18,678 [salt.state       :300 ][INFO    ][16031] {'pid': 16064, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: piston3, maasserver, sites, auth, contenttypes, sessions, metadataserver\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-03-12 05:58:18,678 [salt.state       :1951][INFO    ][16031] Completed state [maas-region syncdb --noinput] at time 05:58:18.678839 duration_in_ms=2509.678
2019-03-12 05:58:18,679 [salt.state       :2022][WARNING ][16031] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-03-12 05:58:18,682 [salt.state       :1780][INFO    ][16031] Running state [maas-regiond] at time 05:58:18.682215
2019-03-12 05:58:18,682 [salt.state       :1813][INFO    ][16031] Executing state service.running for [maas-regiond]
2019-03-12 05:58:18,684 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16031] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-03-12 05:58:18,720 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16031] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-03-12 05:58:18,738 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16031] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-03-12 05:58:18,753 [salt.state       :300 ][INFO    ][16031] The service maas-regiond is already running
2019-03-12 05:58:18,753 [salt.state       :1951][INFO    ][16031] Completed state [maas-regiond] at time 05:58:18.753519 duration_in_ms=71.304
2019-03-12 05:58:18,756 [salt.state       :1780][INFO    ][16031] Running state [bind9] at time 05:58:18.756026
2019-03-12 05:58:18,756 [salt.state       :1813][INFO    ][16031] Executing state service.running for [bind9]
2019-03-12 05:58:18,757 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16031] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-03-12 05:58:18,779 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16031] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-03-12 05:58:18,797 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16031] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-03-12 05:58:18,813 [salt.state       :300 ][INFO    ][16031] The service bind9 is already running
2019-03-12 05:58:18,814 [salt.state       :1951][INFO    ][16031] Completed state [bind9] at time 05:58:18.814252 duration_in_ms=58.225
2019-03-12 05:58:18,816 [salt.state       :1780][INFO    ][16031] Running state [apache2] at time 05:58:18.816636
2019-03-12 05:58:18,817 [salt.state       :1813][INFO    ][16031] Executing state service.running for [apache2]
2019-03-12 05:58:18,818 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16031] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-03-12 05:58:18,837 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16031] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-03-12 05:58:18,852 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16031] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-03-12 05:58:18,876 [salt.state       :300 ][INFO    ][16031] The service apache2 is already running
2019-03-12 05:58:18,877 [salt.state       :1951][INFO    ][16031] Completed state [apache2] at time 05:58:18.877007 duration_in_ms=60.37
2019-03-12 05:58:18,881 [salt.state       :1780][INFO    ][16031] Running state [maasng.wait_for_http_code] at time 05:58:18.881365
2019-03-12 05:58:18,882 [salt.state       :1813][INFO    ][16031] Executing state module.run for [maasng.wait_for_http_code]
2019-03-12 05:58:18,882 [salt.utils.decorators:613 ][WARNING ][16031] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-12 05:58:19,016 [salt.state       :300 ][INFO    ][16031] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-03-12 05:58:19,016 [salt.state       :1951][INFO    ][16031] Completed state [maasng.wait_for_http_code] at time 05:58:19.016700 duration_in_ms=135.618
2019-03-12 05:58:19,018 [salt.state       :1780][INFO    ][16031] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:58:19.018039
2019-03-12 05:58:19,018 [salt.state       :1813][INFO    ][16031] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-03-12 05:58:19,019 [salt.state       :300 ][INFO    ][16031] /var/lib/maas/.setup_admin exists
2019-03-12 05:58:19,020 [salt.state       :1951][INFO    ][16031] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:58:19.020393 duration_in_ms=2.353
2019-03-12 05:58:19,022 [salt.state       :1780][INFO    ][16031] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:58:19.022022
2019-03-12 05:58:19,022 [salt.state       :1813][INFO    ][16031] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-12 05:58:19,025 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16031] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-12 05:58:20,712 [salt.state       :300 ][INFO    ][16031] {'pid': 16086, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-12 05:58:20,713 [salt.state       :1951][INFO    ][16031] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:58:20.713106 duration_in_ms=1691.082
2019-03-12 05:58:20,724 [salt.state       :1780][INFO    ][16031] Running state [maas_region_boot_source_resources_mirror] at time 05:58:20.723923
2019-03-12 05:58:20,724 [salt.state       :1813][INFO    ][16031] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-03-12 05:58:20,809 [salt.state       :300 ][INFO    ][16031] {'changes': {}}
2019-03-12 05:58:20,810 [salt.state       :1951][INFO    ][16031] Completed state [maas_region_boot_source_resources_mirror] at time 05:58:20.810160 duration_in_ms=86.237
2019-03-12 05:58:20,812 [salt.state       :1780][INFO    ][16031] Running state [maasng.boot_resources_import] at time 05:58:20.812523
2019-03-12 05:58:20,812 [salt.state       :1813][INFO    ][16031] Executing state module.run for [maasng.boot_resources_import]
2019-03-12 05:58:20,813 [salt.utils.decorators:613 ][WARNING ][16031] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-12 05:58:20,902 [salt.loaded.ext.module.maasng:1600][INFO    ][16031] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-03-12 05:58:25,954 [salt.loaded.ext.module.maasng:1600][INFO    ][16031] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-03-12 05:58:28,964 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312055828953007
2019-03-12 05:58:28,981 [salt.minion      :1432][INFO    ][16117] Starting a new job with PID 16117
2019-03-12 05:58:29,008 [salt.minion      :1711][INFO    ][16117] Returning information for job: 20190312055828953007
2019-03-12 05:58:31,039 [salt.state       :300 ][INFO    ][16031] {'ret': True}
2019-03-12 05:58:31,040 [salt.state       :1951][INFO    ][16031] Completed state [maasng.boot_resources_import] at time 05:58:31.040268 duration_in_ms=10227.744
2019-03-12 05:58:31,041 [salt.state       :1780][INFO    ][16031] Running state [maas_region_boot_sources_selection_xenial] at time 05:58:31.041322
2019-03-12 05:58:31,041 [salt.state       :1813][INFO    ][16031] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-03-12 05:58:31,197 [salt.state       :300 ][INFO    ][16031] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-03-12 05:58:31,197 [salt.state       :1951][INFO    ][16031] Completed state [maas_region_boot_sources_selection_xenial] at time 05:58:31.197425 duration_in_ms=156.103
2019-03-12 05:58:31,198 [salt.state       :1780][INFO    ][16031] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 05:58:31.198255
2019-03-12 05:58:31,198 [salt.state       :1813][INFO    ][16031] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-03-12 05:58:31,199 [salt.utils.decorators:613 ][WARNING ][16031] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-12 05:58:31,199 [salt.loaded.ext.module.maasng:1771][INFO    ][16031] boot-sources sync initiated for ALL Rack's
2019-03-12 05:58:32,151 [salt.state       :300 ][INFO    ][16031] {'ret': True}
2019-03-12 05:58:32,152 [salt.state       :1951][INFO    ][16031] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 05:58:32.152014 duration_in_ms=953.758
2019-03-12 05:58:32,153 [salt.state       :1780][INFO    ][16031] Running state [maas.process_maas_config] at time 05:58:32.153432
2019-03-12 05:58:32,153 [salt.state       :1813][INFO    ][16031] Executing state module.run for [maas.process_maas_config]
2019-03-12 05:58:32,154 [salt.utils.decorators:613 ][WARNING ][16031] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-12 05:58:32,154 [salt.loaded.ext.module.maas:92  ][INFO    ][16031] maasconfig name=enable_http_proxy value=True
2019-03-12 05:58:32,210 [salt.loaded.ext.module.maas:92  ][INFO    ][16031] maasconfig name=upstream_dns value=8.8.8.8
2019-03-12 05:58:32,261 [salt.loaded.ext.module.maas:92  ][INFO    ][16031] maasconfig name=commissioning_distro_series value=xenial
2019-03-12 05:58:32,321 [salt.loaded.ext.module.maas:92  ][INFO    ][16031] maasconfig name=default_osystem value=ubuntu
2019-03-12 05:58:32,377 [salt.loaded.ext.module.maas:92  ][INFO    ][16031] maasconfig name=active_discovery_interval value=600
2019-03-12 05:58:33,461 [salt.loaded.ext.module.maas:92  ][INFO    ][16031] maasconfig name=dnssec_validation value=no
2019-03-12 05:58:33,509 [salt.loaded.ext.module.maas:92  ][INFO    ][16031] maasconfig name=maas_name value=mas01
2019-03-12 05:58:33,551 [salt.loaded.ext.module.maas:92  ][INFO    ][16031] maasconfig name=network_discovery value=enabled
2019-03-12 05:58:33,642 [salt.loaded.ext.module.maas:92  ][INFO    ][16031] maasconfig name=enable_third_party_drivers value=True
2019-03-12 05:58:33,686 [salt.loaded.ext.module.maas:92  ][INFO    ][16031] maasconfig name=default_storage_layout value=lvm
2019-03-12 05:58:33,728 [salt.loaded.ext.module.maas:92  ][INFO    ][16031] maasconfig name=ntp_external_only value=True
2019-03-12 05:58:33,785 [salt.loaded.ext.module.maas:92  ][INFO    ][16031] maasconfig name=disk_erase_with_secure_erase value=False
2019-03-12 05:58:33,825 [salt.loaded.ext.module.maas:92  ][INFO    ][16031] maasconfig name=default_distro_series value=xenial
2019-03-12 05:58:33,876 [salt.loaded.ext.module.maas:92  ][INFO    ][16031] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-03-12 05:58:33,984 [salt.state       :300 ][INFO    ][16031] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-03-12 05:58:33,984 [salt.state       :1951][INFO    ][16031] Completed state [maas.process_maas_config] at time 05:58:33.984670 duration_in_ms=1831.238
2019-03-12 05:58:33,985 [salt.state       :1780][INFO    ][16031] Running state [pxe_admin] at time 05:58:33.985335
2019-03-12 05:58:33,985 [salt.state       :1813][INFO    ][16031] Executing state maasng.fabric_present for [pxe_admin]
2019-03-12 05:58:34,057 [salt.loaded.ext.module.maasng:945 ][INFO    ][16031] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'knwm4m', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-03-12 05:58:34,118 [salt.loaded.ext.module.maasng:1008][WARNING ][16031] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-03-12 05:58:34,121 [salt.loaded.ext.module.maasng:1011][WARNING ][16031] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-03-12 05:58:34,175 [salt.state       :300 ][INFO    ][16031] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-03-12 05:58:34,175 [salt.state       :1951][INFO    ][16031] Completed state [pxe_admin] at time 05:58:34.175842 duration_in_ms=190.507
2019-03-12 05:58:34,176 [salt.state       :1780][INFO    ][16031] Running state [vlan 0] at time 05:58:34.176206
2019-03-12 05:58:34,176 [salt.state       :1813][INFO    ][16031] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-03-12 05:58:34,225 [salt.loaded.ext.module.maasng:945 ][INFO    ][16031] [{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'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'fabric_id': 2, u'dhcp_on': False, 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'fabric_id': 1, u'dhcp_on': True, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'knwm4m', 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-03-12 05:58:34,315 [salt.loaded.ext.module.maasng:945 ][INFO    ][16031] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'knwm4m', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-03-12 05:58:34,550 [salt.loaded.ext.module.maasng:945 ][INFO    ][16031] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'knwm4m', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-03-12 05:58:34,642 [salt.state       :300 ][INFO    ][16031] {'new': 'Vlan untagged was updated'}
2019-03-12 05:58:34,643 [salt.state       :1951][INFO    ][16031] Completed state [vlan 0] at time 05:58:34.643782 duration_in_ms=467.575
2019-03-12 05:58:34,645 [salt.state       :1780][INFO    ][16031] Running state [192.168.11.0/24] at time 05:58:34.645233
2019-03-12 05:58:34,645 [salt.state       :1813][INFO    ][16031] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-03-12 05:58:34,812 [salt.loaded.ext.module.maasng:945 ][INFO    ][16031] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'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'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'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'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'knwm4m', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-03-12 05:58:34,813 [salt.loaded.ext.module.maasng:1235][WARNING ][16031] Ignoring parameter vlan:0
2019-03-12 05:58:34,884 [salt.state       :300 ][INFO    ][16031] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-03-12 05:58:34,885 [salt.state       :1951][INFO    ][16031] Completed state [192.168.11.0/24] at time 05:58:34.884948 duration_in_ms=239.714
2019-03-12 05:58:34,885 [salt.state       :1780][INFO    ][16031] Running state [maas_create_iprange_1] at time 05:58:34.885716
2019-03-12 05:58:34,886 [salt.state       :1813][INFO    ][16031] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-03-12 05:58:34,932 [salt.state       :300 ][INFO    ][16031] Iprange maas_create_iprange_1 already exist.
2019-03-12 05:58:34,932 [salt.state       :1951][INFO    ][16031] Completed state [maas_create_iprange_1] at time 05:58:34.932326 duration_in_ms=46.61
2019-03-12 05:58:34,932 [salt.state       :1780][INFO    ][16031] Running state [vlan 0] at time 05:58:34.932587
2019-03-12 05:58:34,932 [salt.state       :1813][INFO    ][16031] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-03-12 05:58:34,975 [salt.loaded.ext.module.maasng:945 ][INFO    ][16031] [{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'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'fabric_id': 2, u'dhcp_on': False, 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'fabric_id': 1, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'knwm4m', 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-03-12 05:58:35,057 [salt.loaded.ext.module.maasng:945 ][INFO    ][16031] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'knwm4m', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-03-12 05:58:35,266 [salt.loaded.ext.module.maasng:945 ][INFO    ][16031] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'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'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'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'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'knwm4m', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-03-12 05:58:35,360 [salt.state       :300 ][INFO    ][16031] {'new': 'Vlan untagged was updated'}
2019-03-12 05:58:35,360 [salt.state       :1951][INFO    ][16031] Completed state [vlan 0] at time 05:58:35.360769 duration_in_ms=428.182
2019-03-12 05:58:35,361 [salt.state       :1780][INFO    ][16031] Running state [opnfv] at time 05:58:35.361353
2019-03-12 05:58:35,361 [salt.state       :1813][INFO    ][16031] Executing state maasng.sshkey_present for [opnfv]
2019-03-12 05:58:35,409 [salt.loaded.ext.module.maasng:1903][INFO    ][16031] [{u'keysource': u'', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCwbsLV1UY13y2+2b7H9h6zOFMrcnumZvKOz6oqAXgttZjoKci01cqGRU8OMjob/9xeAOP2Cmu7HOBpRxda00X57yYx4UidSgEtlbOi7LLj2DuvJScix+rgPAd19aukdCiTuMmyLZQPMzVrdBxqzupJ5zqS3djSCllnQ2myEwswcSBn0Dpusgt3bnwdsCgI6B6ZgL9aR9DLnyMPMVroI6ZZH7aP7syIy1sPEsiJd3smWEULgeRElsuK+4I3YKG1Z1J2n80lmtR2gVD5tuKLFRRxnbjAqzyCEXQBTUVRc13SAFrH0b5bzFaqfxY1wdQPzP+dBSA+t25KcjnpTAAtL86H', u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/'}]
2019-03-12 05:58:35,409 [salt.state       :300 ][INFO    ][16031] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCwbsLV1UY13y2+2b7H9h6zOFMrcnumZvKOz6oqAXgttZjoKci01cqGRU8OMjob/9xeAOP2Cmu7HOBpRxda00X57yYx4UidSgEtlbOi7LLj2DuvJScix+rgPAd19aukdCiTuMmyLZQPMzVrdBxqzupJ5zqS3djSCllnQ2myEwswcSBn0Dpusgt3bnwdsCgI6B6ZgL9aR9DLnyMPMVroI6ZZH7aP7syIy1sPEsiJd3smWEULgeRElsuK+4I3YKG1Z1J2n80lmtR2gVD5tuKLFRRxnbjAqzyCEXQBTUVRc13SAFrH0b5bzFaqfxY1wdQPzP+dBSA+t25KcjnpTAAtL86H already exist for user opnfv.
2019-03-12 05:58:35,409 [salt.state       :1951][INFO    ][16031] Completed state [opnfv] at time 05:58:35.409901 duration_in_ms=48.547
2019-03-12 05:58:35,410 [salt.state       :1780][INFO    ][16031] Running state [maas.process_tags] at time 05:58:35.410494
2019-03-12 05:58:35,410 [salt.state       :1813][INFO    ][16031] Executing state module.run for [maas.process_tags]
2019-03-12 05:58:35,411 [salt.utils.decorators:613 ][WARNING ][16031] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-12 05:58:35,454 [salt.loaded.ext.module.maas:92  ][INFO    ][16031] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-03-12 05:58:35,507 [salt.state       :300 ][INFO    ][16031] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-03-12 05:58:35,507 [salt.state       :1951][INFO    ][16031] Completed state [maas.process_tags] at time 05:58:35.507929 duration_in_ms=97.434
2019-03-12 05:58:35,510 [salt.minion      :1711][INFO    ][16031] Returning information for job: 20190312055813849216
2019-03-12 05:58:36,079 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command state.apply with jid 20190312055836071622
2019-03-12 05:58:36,093 [salt.minion      :1432][INFO    ][16472] Starting a new job with PID 16472
2019-03-12 05:58:41,659 [salt.state       :915 ][INFO    ][16472] Loading fresh modules for state activity
2019-03-12 05:58:41,753 [salt.state       :1780][INFO    ][16472] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:58:41.753010
2019-03-12 05:58:41,753 [salt.state       :1813][INFO    ][16472] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-12 05:58:41,755 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16472] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-12 05:58:43,428 [salt.state       :300 ][INFO    ][16472] {'pid': 16496, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-12 05:58:43,429 [salt.state       :1951][INFO    ][16472] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:58:43.429841 duration_in_ms=1676.831
2019-03-12 05:58:43,433 [salt.state       :1780][INFO    ][16472] Running state [maas.process_machines] at time 05:58:43.433217
2019-03-12 05:58:43,433 [salt.state       :1813][INFO    ][16472] Executing state module.run for [maas.process_machines]
2019-03-12 05:58:43,435 [salt.utils.decorators:613 ][WARNING ][16472] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-12 05:58:44,172 [salt.loaded.ext.module.maas:412 ][WARNING ][16472] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-12 05:58:44,172 [salt.loaded.ext.module.maas:92  ][INFO    ][16472] 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=nhxwyc architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-12 05:58:45,281 [salt.loaded.ext.module.maas:412 ][WARNING ][16472] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-12 05:58:45,282 [salt.loaded.ext.module.maas:92  ][INFO    ][16472] 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=qyhmyg architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-12 05:58:46,408 [salt.loaded.ext.module.maas:412 ][WARNING ][16472] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-12 05:58:46,409 [salt.loaded.ext.module.maas:92  ][INFO    ][16472] 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=fm8fk8 architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-12 05:58:47,509 [salt.loaded.ext.module.maas:412 ][WARNING ][16472] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-12 05:58:47,510 [salt.loaded.ext.module.maas:92  ][INFO    ][16472] 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=reqpkf architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-12 05:58:48,586 [salt.loaded.ext.module.maas:412 ][WARNING ][16472] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-12 05:58:48,587 [salt.loaded.ext.module.maas:92  ][INFO    ][16472] 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=wdahmd architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-12 05:58:49,805 [salt.state       :300 ][INFO    ][16472] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-03-12 05:58:49,805 [salt.state       :1951][INFO    ][16472] Completed state [maas.process_machines] at time 05:58:49.805871 duration_in_ms=6372.654
2019-03-12 05:58:49,808 [salt.minion      :1711][INFO    ][16472] Returning information for job: 20190312055836071622
2019-03-12 05:59:22,728 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command state.apply with jid 20190312055922716908
2019-03-12 05:59:22,741 [salt.minion      :1432][INFO    ][16789] Starting a new job with PID 16789
2019-03-12 05:59:28,351 [salt.state       :915 ][INFO    ][16789] Loading fresh modules for state activity
2019-03-12 05:59:28,441 [salt.state       :1780][INFO    ][16789] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:59:28.441574
2019-03-12 05:59:28,441 [salt.state       :1813][INFO    ][16789] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-12 05:59:28,443 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16789] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-12 05:59:30,134 [salt.state       :300 ][INFO    ][16789] {'pid': 16799, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-12 05:59:30,136 [salt.state       :1951][INFO    ][16789] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:59:30.136437 duration_in_ms=1694.862
2019-03-12 05:59:30,139 [salt.state       :1780][INFO    ][16789] Running state [maas.wait_for_machine_status] at time 05:59:30.139635
2019-03-12 05:59:30,140 [salt.state       :1813][INFO    ][16789] Executing state module.run for [maas.wait_for_machine_status]
2019-03-12 05:59:30,141 [salt.utils.decorators:613 ][WARNING ][16789] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-12 05:59:32,782 [salt.state       :300 ][INFO    ][16789] {'ret': True}
2019-03-12 05:59:32,783 [salt.state       :1951][INFO    ][16789] Completed state [maas.wait_for_machine_status] at time 05:59:32.783015 duration_in_ms=2643.38
2019-03-12 05:59:32,786 [salt.minion      :1711][INFO    ][16789] Returning information for job: 20190312055922716908
2019-03-12 05:59:33,329 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command state.apply with jid 20190312055933316476
2019-03-12 05:59:33,348 [salt.minion      :1432][INFO    ][16813] Starting a new job with PID 16813
2019-03-12 05:59:34,038 [salt.state       :915 ][INFO    ][16813] Loading fresh modules for state activity
2019-03-12 05:59:34,171 [salt.state       :1780][INFO    ][16813] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:59:34.171311
2019-03-12 05:59:34,171 [salt.state       :1813][INFO    ][16813] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-12 05:59:34,173 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16813] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-12 05:59:35,849 [salt.state       :300 ][INFO    ][16813] {'pid': 16820, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-12 05:59:35,849 [salt.state       :1951][INFO    ][16813] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:59:35.849765 duration_in_ms=1678.45
2019-03-12 05:59:35,852 [salt.state       :1780][INFO    ][16813] Running state [maas_machines_storage_cmp002_lvm] at time 05:59:35.852346
2019-03-12 05:59:35,852 [salt.state       :1813][INFO    ][16813] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-03-12 05:59:36,504 [salt.state       :300 ][INFO    ][16813] Machine cmp002 is not in Ready state.
2019-03-12 05:59:36,504 [salt.state       :1951][INFO    ][16813] Completed state [maas_machines_storage_cmp002_lvm] at time 05:59:36.504474 duration_in_ms=652.127
2019-03-12 05:59:36,504 [salt.state       :1780][INFO    ][16813] Running state [maas_machines_storage_cmp001_lvm] at time 05:59:36.504817
2019-03-12 05:59:36,505 [salt.state       :1813][INFO    ][16813] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-03-12 05:59:37,041 [salt.state       :300 ][INFO    ][16813] Machine cmp001 is not in Ready state.
2019-03-12 05:59:37,041 [salt.state       :1951][INFO    ][16813] Completed state [maas_machines_storage_cmp001_lvm] at time 05:59:37.041600 duration_in_ms=536.782
2019-03-12 05:59:37,045 [salt.minion      :1711][INFO    ][16813] Returning information for job: 20190312055933316476
2019-03-12 05:59:37,590 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command state.apply with jid 20190312055937574451
2019-03-12 05:59:37,605 [salt.minion      :1432][INFO    ][16831] Starting a new job with PID 16831
2019-03-12 05:59:38,291 [salt.state       :915 ][INFO    ][16831] Loading fresh modules for state activity
2019-03-12 05:59:38,376 [salt.state       :1780][INFO    ][16831] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:59:38.376754
2019-03-12 05:59:38,377 [salt.state       :1813][INFO    ][16831] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-12 05:59:38,378 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16831] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-12 05:59:40,044 [salt.state       :300 ][INFO    ][16831] {'pid': 16838, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-12 05:59:40,045 [salt.state       :1951][INFO    ][16831] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:59:40.045064 duration_in_ms=1668.309
2019-03-12 05:59:40,049 [salt.state       :1780][INFO    ][16831] Running state [maas.deploy_machines] at time 05:59:40.049311
2019-03-12 05:59:40,049 [salt.state       :1813][INFO    ][16831] Executing state module.run for [maas.deploy_machines]
2019-03-12 05:59:40,051 [salt.utils.decorators:613 ][WARNING ][16831] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-12 05:59:40,601 [salt.loaded.ext.module.maas:684 ][INFO    ][16831] deploymachines hwe_kernel=hwe-16.04 system_id=wdahmd distro_series=xenial
2019-03-12 05:59:42,839 [salt.state       :300 ][INFO    ][16831] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-03-12 05:59:42,840 [salt.state       :1951][INFO    ][16831] Completed state [maas.deploy_machines] at time 05:59:42.839949 duration_in_ms=2790.637
2019-03-12 05:59:42,842 [salt.minion      :1711][INFO    ][16831] Returning information for job: 20190312055937574451
2019-03-12 05:59:43,390 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command state.apply with jid 20190312055943379736
2019-03-12 05:59:43,409 [salt.minion      :1432][INFO    ][16898] Starting a new job with PID 16898
2019-03-12 05:59:44,188 [salt.state       :915 ][INFO    ][16898] Loading fresh modules for state activity
2019-03-12 05:59:44,272 [salt.state       :1780][INFO    ][16898] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:59:44.272873
2019-03-12 05:59:44,273 [salt.state       :1813][INFO    ][16898] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-12 05:59:44,274 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16898] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-12 05:59:46,016 [salt.state       :300 ][INFO    ][16898] {'pid': 16918, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-12 05:59:46,017 [salt.state       :1951][INFO    ][16898] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:59:46.017208 duration_in_ms=1744.335
2019-03-12 05:59:46,019 [salt.state       :1780][INFO    ][16898] Running state [maas.wait_for_machine_status] at time 05:59:46.019805
2019-03-12 05:59:46,020 [salt.state       :1813][INFO    ][16898] Executing state module.run for [maas.wait_for_machine_status]
2019-03-12 05:59:46,020 [salt.utils.decorators:613 ][WARNING ][16898] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-12 05:59:48,761 [salt.loaded.ext.module.maas:1023][INFO    ][16898] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.26956892s left)
2019-03-12 05:59:58,469 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312055958460164
2019-03-12 05:59:58,482 [salt.minion      :1432][INFO    ][16981] Starting a new job with PID 16981
2019-03-12 05:59:58,501 [salt.minion      :1711][INFO    ][16981] Returning information for job: 20190312055958460164
2019-03-12 06:00:21,410 [salt.loaded.ext.module.maas:1023][INFO    ][16898] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.62136292s left)
2019-03-12 06:00:28,705 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312060028645800
2019-03-12 06:00:28,724 [salt.minion      :1432][INFO    ][17017] Starting a new job with PID 17017
2019-03-12 06:00:28,750 [salt.minion      :1711][INFO    ][17017] Returning information for job: 20190312060028645800
2019-03-12 06:00:54,086 [salt.loaded.ext.module.maas:1023][INFO    ][16898] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2181.94510388s left)
2019-03-12 06:00:58,778 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312060058766064
2019-03-12 06:00:58,798 [salt.minion      :1432][INFO    ][17099] Starting a new job with PID 17099
2019-03-12 06:00:58,822 [salt.minion      :1711][INFO    ][17099] Returning information for job: 20190312060058766064
2019-03-12 06:01:26,863 [salt.loaded.ext.module.maas:1023][INFO    ][16898] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2149.1679821s left)
2019-03-12 06:01:28,829 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312060128818188
2019-03-12 06:01:28,846 [salt.minion      :1432][INFO    ][17140] Starting a new job with PID 17140
2019-03-12 06:01:28,871 [salt.minion      :1711][INFO    ][17140] Returning information for job: 20190312060128818188
2019-03-12 06:01:58,891 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312060158881605
2019-03-12 06:01:58,905 [salt.minion      :1432][INFO    ][17213] Starting a new job with PID 17213
2019-03-12 06:01:58,930 [salt.minion      :1711][INFO    ][17213] Returning information for job: 20190312060158881605
2019-03-12 06:01:59,671 [salt.loaded.ext.module.maas:1023][INFO    ][16898] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2116.36002588s left)
2019-03-12 06:02:28,945 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312060228932242
2019-03-12 06:02:28,964 [salt.minion      :1432][INFO    ][17241] Starting a new job with PID 17241
2019-03-12 06:02:28,993 [salt.minion      :1711][INFO    ][17241] Returning information for job: 20190312060228932242
2019-03-12 06:02:32,318 [salt.loaded.ext.module.maas:1023][INFO    ][16898] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2083.71275806s left)
2019-03-12 06:02:59,036 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312060259023801
2019-03-12 06:02:59,057 [salt.minion      :1432][INFO    ][17314] Starting a new job with PID 17314
2019-03-12 06:02:59,081 [salt.minion      :1711][INFO    ][17314] Returning information for job: 20190312060259023801
2019-03-12 06:03:05,012 [salt.loaded.ext.module.maas:1023][INFO    ][16898] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2051.01851392s left)
2019-03-12 06:03:29,104 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312060329089260
2019-03-12 06:03:29,124 [salt.minion      :1432][INFO    ][17346] Starting a new job with PID 17346
2019-03-12 06:03:29,148 [salt.minion      :1711][INFO    ][17346] Returning information for job: 20190312060329089260
2019-03-12 06:03:37,778 [salt.loaded.ext.module.maas:1023][INFO    ][16898] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2018.253268s left)
2019-03-12 06:03:59,196 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312060359184529
2019-03-12 06:03:59,209 [salt.minion      :1432][INFO    ][17439] Starting a new job with PID 17439
2019-03-12 06:03:59,233 [salt.minion      :1711][INFO    ][17439] Returning information for job: 20190312060359184529
2019-03-12 06:04:10,547 [salt.loaded.ext.module.maas:1023][INFO    ][16898] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1985.48387098s left)
2019-03-12 06:04:29,237 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312060429229895
2019-03-12 06:04:29,256 [salt.minion      :1432][INFO    ][17502] Starting a new job with PID 17502
2019-03-12 06:04:29,281 [salt.minion      :1711][INFO    ][17502] Returning information for job: 20190312060429229895
2019-03-12 06:04:43,360 [salt.loaded.ext.module.maas:1023][INFO    ][16898] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1952.67110801s left)
2019-03-12 06:04:59,349 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312060459337258
2019-03-12 06:04:59,372 [salt.minion      :1432][INFO    ][17682] Starting a new job with PID 17682
2019-03-12 06:04:59,396 [salt.minion      :1711][INFO    ][17682] Returning information for job: 20190312060459337258
2019-03-12 06:05:16,194 [salt.loaded.ext.module.maas:1023][INFO    ][16898] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1919.83663011s left)
2019-03-12 06:05:29,432 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312060529424935
2019-03-12 06:05:29,448 [salt.minion      :1432][INFO    ][17714] Starting a new job with PID 17714
2019-03-12 06:05:29,469 [salt.minion      :1711][INFO    ][17714] Returning information for job: 20190312060529424935
2019-03-12 06:05:48,895 [salt.loaded.ext.module.maas:1023][INFO    ][16898] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1887.13585591s left)
2019-03-12 06:05:59,530 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312060559517326
2019-03-12 06:05:59,553 [salt.minion      :1432][INFO    ][17848] Starting a new job with PID 17848
2019-03-12 06:05:59,576 [salt.minion      :1711][INFO    ][17848] Returning information for job: 20190312060559517326
2019-03-12 06:06:21,712 [salt.loaded.ext.module.maas:1023][INFO    ][16898] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1854.3185339s left)
2019-03-12 06:06:29,637 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312060629623064
2019-03-12 06:06:29,660 [salt.minion      :1432][INFO    ][17880] Starting a new job with PID 17880
2019-03-12 06:06:29,682 [salt.minion      :1711][INFO    ][17880] Returning information for job: 20190312060629623064
2019-03-12 06:06:54,480 [salt.loaded.ext.module.maas:1023][INFO    ][16898] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1821.55118895s left)
2019-03-12 06:06:59,732 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312060659721865
2019-03-12 06:06:59,748 [salt.minion      :1432][INFO    ][18001] Starting a new job with PID 18001
2019-03-12 06:06:59,772 [salt.minion      :1711][INFO    ][18001] Returning information for job: 20190312060659721865
2019-03-12 06:07:27,263 [salt.loaded.ext.module.maas:1023][INFO    ][16898] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1788.768188s left)
2019-03-12 06:07:29,849 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312060729836274
2019-03-12 06:07:29,868 [salt.minion      :1432][INFO    ][18039] Starting a new job with PID 18039
2019-03-12 06:07:29,890 [salt.minion      :1711][INFO    ][18039] Returning information for job: 20190312060729836274
2019-03-12 06:07:59,948 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312060759941042
2019-03-12 06:07:59,963 [salt.minion      :1432][INFO    ][18177] Starting a new job with PID 18177
2019-03-12 06:07:59,985 [salt.minion      :1711][INFO    ][18177] Returning information for job: 20190312060759941042
2019-03-12 06:08:00,182 [salt.loaded.ext.module.maas:1023][INFO    ][16898] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1755.84886408s left)
2019-03-12 06:08:30,076 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312060830061020
2019-03-12 06:08:30,095 [salt.minion      :1432][INFO    ][18206] Starting a new job with PID 18206
2019-03-12 06:08:30,120 [salt.minion      :1711][INFO    ][18206] Returning information for job: 20190312060830061020
2019-03-12 06:08:33,036 [salt.loaded.ext.module.maas:1023][INFO    ][16898] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1722.99463201s left)
2019-03-12 06:09:00,201 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312060900188243
2019-03-12 06:09:00,223 [salt.minion      :1432][INFO    ][18278] Starting a new job with PID 18278
2019-03-12 06:09:00,248 [salt.minion      :1711][INFO    ][18278] Returning information for job: 20190312060900188243
2019-03-12 06:09:05,940 [salt.loaded.ext.module.maas:1023][INFO    ][16898] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1690.09087801s left)
2019-03-12 06:09:30,353 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312060930339414
2019-03-12 06:09:30,374 [salt.minion      :1432][INFO    ][18314] Starting a new job with PID 18314
2019-03-12 06:09:30,398 [salt.minion      :1711][INFO    ][18314] Returning information for job: 20190312060930339414
2019-03-12 06:09:38,677 [salt.loaded.ext.module.maas:1023][INFO    ][16898] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1657.35345101s left)
2019-03-12 06:10:00,478 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312061000463634
2019-03-12 06:10:00,499 [salt.minion      :1432][INFO    ][18384] Starting a new job with PID 18384
2019-03-12 06:10:00,523 [salt.minion      :1711][INFO    ][18384] Returning information for job: 20190312061000463634
2019-03-12 06:10:11,557 [salt.loaded.ext.module.maas:1023][INFO    ][16898] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1624.47392488s left)
2019-03-12 06:10:30,637 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312061030623945
2019-03-12 06:10:30,657 [salt.minion      :1432][INFO    ][18416] Starting a new job with PID 18416
2019-03-12 06:10:30,685 [salt.minion      :1711][INFO    ][18416] Returning information for job: 20190312061030623945
2019-03-12 06:10:44,448 [salt.loaded.ext.module.maas:1023][INFO    ][16898] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1591.58341408s left)
2019-03-12 06:11:00,790 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312061100776454
2019-03-12 06:11:00,811 [salt.minion      :1432][INFO    ][18498] Starting a new job with PID 18498
2019-03-12 06:11:00,834 [salt.minion      :1711][INFO    ][18498] Returning information for job: 20190312061100776454
2019-03-12 06:11:17,305 [salt.loaded.ext.module.maas:1023][INFO    ][16898] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1558.72614002s left)
2019-03-12 06:11:30,961 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command saltutil.find_job with jid 20190312061130948283
2019-03-12 06:11:30,979 [salt.minion      :1432][INFO    ][18559] Starting a new job with PID 18559
2019-03-12 06:11:31,003 [salt.minion      :1711][INFO    ][18559] Returning information for job: 20190312061130948283
2019-03-12 06:11:50,582 [salt.state       :300 ][INFO    ][16898] {'ret': True}
2019-03-12 06:11:50,584 [salt.state       :1951][INFO    ][16898] Completed state [maas.wait_for_machine_status] at time 06:11:50.584106 duration_in_ms=724564.299
2019-03-12 06:11:50,586 [salt.minion      :1711][INFO    ][16898] Returning information for job: 20190312055943379736
2019-03-12 06:12:40,456 [salt.utils.schedule:1377][INFO    ][7071] Running scheduled job: __mine_interval
2019-03-12 07:12:40,455 [salt.utils.schedule:1377][INFO    ][7071] Running scheduled job: __mine_interval
2019-03-12 07:38:50,730 [salt.minion      :1308][INFO    ][7071] User sudo_ubuntu Executing command cp.push_dir with jid 20190312073850720877
2019-03-12 07:38:50,748 [salt.minion      :1432][INFO    ][27785] Starting a new job with PID 27785
