2019-05-03 14:11:11,856 [salt.utils.decorators:613 ][WARNING ][2185] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-03 14:11:12,909 [salt.utils.decorators:613 ][WARNING ][2185] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-03 14:11:16,426 [salt.loaded.int.states.file:2298][WARNING ][2407] 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-05-03 14:11:38,728 [salt.state       :2022][WARNING ][2659] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-03 14:11:40,904 [salt.utils.decorators:613 ][WARNING ][2659] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-03 14:11:56,301 [salt.utils.decorators:613 ][WARNING ][2659] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-03 14:12:24,843 [salt.utils.decorators:613 ][WARNING ][2659] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-03 14:16:50,985 [salt.utils.decorators:613 ][WARNING ][2659] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-03 14:16:53,428 [salt.loaded.ext.module.maasng:1008][WARNING ][2659] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-05-03 14:16:53,429 [salt.loaded.ext.module.maasng:1011][WARNING ][2659] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-05-03 14:16:54,156 [salt.loaded.ext.module.maasng:1235][WARNING ][2659] Ignoring parameter vlan:0
2019-05-03 14:17:05,734 [salt.utils.decorators:613 ][WARNING ][9430] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-03 14:17:05,792 [salt.loaded.ext.module.maas:412 ][WARNING ][9430] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-03 14:17:07,280 [salt.loaded.ext.module.maas:412 ][WARNING ][9430] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-03 14:17:08,577 [salt.loaded.ext.module.maas:412 ][WARNING ][9430] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-03 14:17:09,981 [salt.loaded.ext.module.maas:412 ][WARNING ][9430] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-03 14:17:11,441 [salt.loaded.ext.module.maas:412 ][WARNING ][9430] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-03 14:17:18,157 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10047] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-03 14:17:18,199 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10047] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-05-03 14:17:18,240 [salt.utils.parsers:1051][WARNING ][278] Minion received a SIGTERM. Exiting.
2019-05-03 14:17:19,349 [salt.cli.daemons :293 ][INFO    ][10098] Setting up the Salt Minion "mas01.mcp-ovs-ha.local"
2019-05-03 14:17:19,517 [salt.cli.daemons :82  ][INFO    ][10098] Starting up the Salt Minion
2019-05-03 14:17:19,518 [salt.utils.event :1017][INFO    ][10098] Starting pull socket on /var/run/salt/minion/minion_event_501f9ec045_pull.ipc
2019-05-03 14:17:20,765 [salt.minion      :976 ][INFO    ][10098] Creating minion process manager
2019-05-03 14:17:22,934 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][10098] Executing command ['date', '+%z'] in directory '/root'
2019-05-03 14:17:22,967 [salt.utils.schedule:568 ][INFO    ][10098] Updating job settings for scheduled job: __mine_interval
2019-05-03 14:17:22,971 [salt.minion      :1108][INFO    ][10098] Added mine.update to scheduler
2019-05-03 14:17:22,978 [salt.minion      :1975][INFO    ][10098] Minion is starting as user 'root'
2019-05-03 14:17:23,000 [salt.minion      :2336][INFO    ][10098] Minion is ready to receive requests!
2019-05-03 14:17:54,701 [salt.minion      :1308][INFO    ][10098] User sudo_ubuntu Executing command state.apply with jid 20190503141754687140
2019-05-03 14:17:54,726 [salt.minion      :1432][INFO    ][10245] Starting a new job with PID 10245
2019-05-03 14:18:01,685 [salt.state       :915 ][INFO    ][10245] Loading fresh modules for state activity
2019-05-03 14:18:01,744 [salt.fileclient  :1219][INFO    ][10245] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-05-03 14:18:01,797 [salt.state       :1780][INFO    ][10245] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:18:01.797054
2019-05-03 14:18:01,797 [salt.state       :1813][INFO    ][10245] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-03 14:18:01,799 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10245] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-03 14:18:03,608 [salt.state       :300 ][INFO    ][10245] {'pid': 10257, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-03 14:18:03,609 [salt.state       :1951][INFO    ][10245] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:18:03.608920 duration_in_ms=1811.866
2019-05-03 14:18:03,611 [salt.state       :1780][INFO    ][10245] Running state [maas.wait_for_machine_status] at time 14:18:03.611818
2019-05-03 14:18:03,612 [salt.state       :1813][INFO    ][10245] Executing state module.run for [maas.wait_for_machine_status]
2019-05-03 14:18:03,612 [salt.utils.decorators:613 ][WARNING ][10245] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-03 14:18:04,362 [salt.loaded.ext.module.maas:1023][INFO    ][10245] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.26204205s left)
2019-05-03 14:18:09,765 [salt.minion      :1308][INFO    ][10098] User sudo_ubuntu Executing command saltutil.find_job with jid 20190503141809713572
2019-05-03 14:18:09,787 [salt.minion      :1432][INFO    ][10270] Starting a new job with PID 10270
2019-05-03 14:18:09,823 [salt.minion      :1711][INFO    ][10270] Returning information for job: 20190503141809713572
2019-05-03 14:18:35,210 [salt.loaded.ext.module.maas:1023][INFO    ][10245] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.41449404s left)
2019-05-03 14:18:39,885 [salt.minion      :1308][INFO    ][10098] User sudo_ubuntu Executing command saltutil.find_job with jid 20190503141839871435
2019-05-03 14:18:39,912 [salt.minion      :1432][INFO    ][10325] Starting a new job with PID 10325
2019-05-03 14:18:39,944 [salt.minion      :1711][INFO    ][10325] Returning information for job: 20190503141839871435
2019-05-03 14:19:06,077 [salt.loaded.ext.module.maas:1023][INFO    ][10245] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.54676604s left)
2019-05-03 14:19:10,005 [salt.minion      :1308][INFO    ][10098] User sudo_ubuntu Executing command saltutil.find_job with jid 20190503141909995084
2019-05-03 14:19:10,026 [salt.minion      :1432][INFO    ][10367] Starting a new job with PID 10367
2019-05-03 14:19:10,052 [salt.minion      :1711][INFO    ][10367] Returning information for job: 20190503141909995084
2019-05-03 14:19:37,024 [salt.loaded.ext.module.maas:1023][INFO    ][10245] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.59994316s left)
2019-05-03 14:19:40,064 [salt.minion      :1308][INFO    ][10098] User sudo_ubuntu Executing command saltutil.find_job with jid 20190503141940047696
2019-05-03 14:19:40,090 [salt.minion      :1432][INFO    ][10450] Starting a new job with PID 10450
2019-05-03 14:19:40,120 [salt.minion      :1711][INFO    ][10450] Returning information for job: 20190503141940047696
2019-05-03 14:20:08,097 [salt.loaded.ext.module.maas:1023][INFO    ][10245] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.52677798s left)
2019-05-03 14:20:10,156 [salt.minion      :1308][INFO    ][10098] User sudo_ubuntu Executing command saltutil.find_job with jid 20190503142010135289
2019-05-03 14:20:10,181 [salt.minion      :1432][INFO    ][10550] Starting a new job with PID 10550
2019-05-03 14:20:10,210 [salt.minion      :1711][INFO    ][10550] Returning information for job: 20190503142010135289
2019-05-03 14:20:39,259 [salt.loaded.ext.module.maas:1023][INFO    ][10245] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.36462617s left)
2019-05-03 14:20:40,272 [salt.minion      :1308][INFO    ][10098] User sudo_ubuntu Executing command saltutil.find_job with jid 20190503142040256779
2019-05-03 14:20:40,297 [salt.minion      :1432][INFO    ][10659] Starting a new job with PID 10659
2019-05-03 14:20:40,330 [salt.minion      :1711][INFO    ][10659] Returning information for job: 20190503142040256779
2019-05-03 14:21:10,409 [salt.minion      :1308][INFO    ][10098] User sudo_ubuntu Executing command saltutil.find_job with jid 20190503142110397640
2019-05-03 14:21:10,428 [salt.minion      :1432][INFO    ][10868] Starting a new job with PID 10868
2019-05-03 14:21:10,460 [salt.minion      :1711][INFO    ][10868] Returning information for job: 20190503142110397640
2019-05-03 14:21:10,571 [salt.loaded.ext.module.maas:1023][INFO    ][10245] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1313.05267119s left)
2019-05-03 14:21:40,493 [salt.minion      :1308][INFO    ][10098] User sudo_ubuntu Executing command saltutil.find_job with jid 20190503142140480324
2019-05-03 14:21:40,518 [salt.minion      :1432][INFO    ][11088] Starting a new job with PID 11088
2019-05-03 14:21:40,544 [salt.minion      :1711][INFO    ][11088] Returning information for job: 20190503142140480324
2019-05-03 14:21:42,094 [salt.loaded.ext.module.maas:1023][INFO    ][10245] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1281.53010011s left)
2019-05-03 14:22:10,627 [salt.minion      :1308][INFO    ][10098] User sudo_ubuntu Executing command saltutil.find_job with jid 20190503142210615136
2019-05-03 14:22:10,656 [salt.minion      :1432][INFO    ][11264] Starting a new job with PID 11264
2019-05-03 14:22:10,684 [salt.minion      :1711][INFO    ][11264] Returning information for job: 20190503142210615136
2019-05-03 14:22:14,124 [salt.loaded.ext.module.maas:1023][INFO    ][10245] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1249.49975014s left)
2019-05-03 14:22:40,786 [salt.minion      :1308][INFO    ][10098] User sudo_ubuntu Executing command saltutil.find_job with jid 20190503142240769805
2019-05-03 14:22:40,812 [salt.minion      :1432][INFO    ][11534] Starting a new job with PID 11534
2019-05-03 14:22:40,840 [salt.minion      :1711][INFO    ][11534] Returning information for job: 20190503142240769805
2019-05-03 14:22:46,760 [salt.loaded.ext.module.maas:1023][INFO    ][10245] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1216.86399817s left)
2019-05-03 14:23:10,944 [salt.minion      :1308][INFO    ][10098] User sudo_ubuntu Executing command saltutil.find_job with jid 20190503142310925223
2019-05-03 14:23:10,974 [salt.minion      :1432][INFO    ][11828] Starting a new job with PID 11828
2019-05-03 14:23:11,003 [salt.minion      :1711][INFO    ][11828] Returning information for job: 20190503142310925223
2019-05-03 14:23:19,055 [salt.loaded.ext.module.maas:1023][INFO    ][10245] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1184.56921816s left)
2019-05-03 14:23:41,096 [salt.minion      :1308][INFO    ][10098] User sudo_ubuntu Executing command saltutil.find_job with jid 20190503142341083342
2019-05-03 14:23:41,124 [salt.minion      :1432][INFO    ][12016] Starting a new job with PID 12016
2019-05-03 14:23:41,154 [salt.minion      :1711][INFO    ][12016] Returning information for job: 20190503142341083342
2019-05-03 14:23:51,916 [salt.state       :300 ][INFO    ][10245] {'ret': True}
2019-05-03 14:23:51,916 [salt.state       :1951][INFO    ][10245] Completed state [maas.wait_for_machine_status] at time 14:23:51.916811 duration_in_ms=348304.992
2019-05-03 14:23:51,919 [salt.minion      :1711][INFO    ][10245] Returning information for job: 20190503141754687140
2019-05-03 14:23:52,607 [salt.minion      :1308][INFO    ][10098] User sudo_ubuntu Executing command state.apply with jid 20190503142352588209
2019-05-03 14:23:52,636 [salt.minion      :1432][INFO    ][12062] Starting a new job with PID 12062
2019-05-03 14:23:59,686 [salt.state       :915 ][INFO    ][12062] Loading fresh modules for state activity
2019-05-03 14:23:59,755 [salt.fileclient  :1219][INFO    ][12062] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-05-03 14:23:59,874 [salt.state       :1780][INFO    ][12062] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:23:59.874130
2019-05-03 14:23:59,874 [salt.state       :1813][INFO    ][12062] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-03 14:23:59,876 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12062] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-03 14:24:01,667 [salt.state       :300 ][INFO    ][12062] {'pid': 12094, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-03 14:24:01,668 [salt.state       :1951][INFO    ][12062] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:24:01.668619 duration_in_ms=1794.488
2019-05-03 14:24:01,673 [salt.state       :1780][INFO    ][12062] Running state [maas_machines_storage_cmp002_lvm] at time 14:24:01.673284
2019-05-03 14:24:01,674 [salt.state       :1813][INFO    ][12062] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-03 14:24:02,991 [salt.loaded.ext.module.maasng:610 ][INFO    ][12062] yywr4b
2019-05-03 14:24:02,992 [salt.loaded.ext.module.maasng:626 ][INFO    ][12062] sda
2019-05-03 14:24:03,550 [salt.loaded.ext.module.maasng:361 ][INFO    ][12062] yywr4b
2019-05-03 14:24:03,641 [salt.loaded.ext.module.maasng:367 ][INFO    ][12062] [{u'available_size': 0, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/yywr4b/blockdevices/1/', u'used_size': 800106479616, u'partitions': [{u'uuid': u'286ac75a-42f5-4563-be10-626121265ae8', u'resource_uri': u'/MAAS/api/2.0/nodes/yywr4b/blockdevices/1/partition/1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'yywr4b', u'filesystem': {u'label': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'c4a12eee-7db2-4d7a-9bf8-ff3ad5081d73', u'mount_options': 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'tags': [u'ssd'], u'uuid': None, u'id': 1, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'yywr4b', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'type': u'physical', u'model': u'LOGICAL VOLUME', u'size': 800109715456}, {u'available_size': 0, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/yywr4b/blockdevices/3/', u'used_size': 800097042432, u'partitions': [], u'tags': [], u'uuid': u'400a4880-bb5c-45a0-8743-a1690df80734', u'id': 3, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'yywr4b', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'868e6492-f280-480f-9053-e67a4eff9d0d', u'mount_options': None}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'model': None, u'size': 800097042432}]
2019-05-03 14:24:03,642 [salt.loaded.ext.module.maasng:632 ][INFO    ][12062] vgroot
2019-05-03 14:24:03,642 [salt.loaded.ext.module.maasng:635 ][INFO    ][12062] lvroot
2019-05-03 14:24:03,643 [salt.loaded.ext.module.maasng:639 ][INFO    ][12062] 107374182400
2019-05-03 14:24:04,232 [salt.loaded.ext.module.maasng:645 ][INFO    ][12062] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'swap_size': None, u'memory_test_status': -1, u'disable_ipv4': False, u'cpu_count': 40, u'power_type': u'ipmi', u'hwe_kernel': u'', u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qcnpd6', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, 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': 18, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qcnpd6', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qcnpd6', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, 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'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'system_id': u'yywr4b', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/yywr4b/interfaces/4/'}, u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'testing_status': 2, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'de3bd673-b1d0-482b-a8b5-3c70884021fe', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'yywr4b', u'device_id': 1, u'filesystem': {u'uuid': u'6045f865-54ea-4fea-866c-35bc86e54d25', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/yywr4b/blockdevices/1/partition/6'}], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'yywr4b', 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'resource_uri': u'/MAAS/api/2.0/nodes/yywr4b/blockdevices/1/', u'used_size': 800106479616, u'id': 1, u'uuid': None}, u'blockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'de3bd673-b1d0-482b-a8b5-3c70884021fe', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'yywr4b', u'device_id': 1, u'filesystem': {u'uuid': u'6045f865-54ea-4fea-866c-35bc86e54d25', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/yywr4b/blockdevices/1/partition/6'}], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'yywr4b', 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'resource_uri': u'/MAAS/api/2.0/nodes/yywr4b/blockdevices/1/', u'used_size': 800106479616, u'id': 1, u'uuid': None}, {u'size': 107374182400, u'model': None, u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'yywr4b', u'partition_table_type': None, u'filesystem': {u'uuid': u'5809deea-c16b-4871-8be0-87254e364d81', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/yywr4b/blockdevices/11/', u'used_size': 107374182400, u'id': 11, u'uuid': u'2f107e9f-4a26-4a1c-a5c6-98f59699bad1'}], u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default'}, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'status_action': u'', u'system_id': u'yywr4b', 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'model': None, u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'yywr4b', u'partition_table_type': None, u'filesystem': {u'uuid': u'5809deea-c16b-4871-8be0-87254e364d81', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/yywr4b/blockdevices/11/', u'used_size': 107374182400, u'id': 11, u'uuid': u'2f107e9f-4a26-4a1c-a5c6-98f59699bad1'}], u'min_hwe_kernel': u'hwe-16.04', u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'power_state': u'off', u'physicalblockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'de3bd673-b1d0-482b-a8b5-3c70884021fe', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'yywr4b', u'device_id': 1, u'filesystem': {u'uuid': u'6045f865-54ea-4fea-866c-35bc86e54d25', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/yywr4b/blockdevices/1/partition/6'}], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'yywr4b', 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'resource_uri': u'/MAAS/api/2.0/nodes/yywr4b/blockdevices/1/', u'used_size': 800106479616, u'id': 1, u'uuid': None}], u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'yywr4b', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'memory_test_status_name': u'Unknown', u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qcnpd6', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, 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': 18, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qcnpd6', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qcnpd6', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, 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'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'system_id': u'yywr4b', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/yywr4b/interfaces/4/'}, {u'name': u'ens2f1', u'links': [{u'id': 19, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:12:49', u'parents': [], u'system_id': u'yywr4b', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/yywr4b/interfaces/13/'}, {u'name': u'ens2f0', u'links': [{u'id': 20, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:12:48', u'parents': [], u'system_id': u'yywr4b', u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/yywr4b/interfaces/14/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qcnpd6', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, 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': 21, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qcnpd6', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qcnpd6', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, 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'params': u'', u'mac_address': u'9c:b6:54:8a:10:1c', u'parents': [], u'system_id': u'yywr4b', u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/yywr4b/interfaces/15/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:07:51', u'parents': [], u'system_id': u'yywr4b', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/yywr4b/interfaces/11/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:07:50', u'parents': [], u'system_id': u'yywr4b', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/yywr4b/interfaces/12/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'bcaches': [], u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/yywr4b/'}
2019-05-03 14:24:04,235 [salt.state       :300 ][INFO    ][12062] {'new': {'storage_layout': 'lvm'}}
2019-05-03 14:24:04,236 [salt.state       :1951][INFO    ][12062] Completed state [maas_machines_storage_cmp002_lvm] at time 14:24:04.236084 duration_in_ms=2562.799
2019-05-03 14:24:04,236 [salt.state       :1780][INFO    ][12062] Running state [maas_machines_storage_cmp001_lvm] at time 14:24:04.236794
2019-05-03 14:24:04,237 [salt.state       :1813][INFO    ][12062] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-03 14:24:05,444 [salt.loaded.ext.module.maasng:610 ][INFO    ][12062] rapafx
2019-05-03 14:24:05,444 [salt.loaded.ext.module.maasng:626 ][INFO    ][12062] sda
2019-05-03 14:24:06,005 [salt.loaded.ext.module.maasng:361 ][INFO    ][12062] rapafx
2019-05-03 14:24:06,095 [salt.loaded.ext.module.maasng:367 ][INFO    ][12062] [{u'uuid': None, u'resource_uri': u'/MAAS/api/2.0/nodes/rapafx/blockdevices/2/', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 2, u'partitions': [{u'uuid': u'685f1ef8-0fe3-43b8-b92f-fd601d24ecbf', u'resource_uri': u'/MAAS/api/2.0/nodes/rapafx/blockdevices/2/partition/2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'rapafx', u'filesystem': {u'uuid': u'8465c949-1d71-41a6-9495-1a6341b3113e', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 2, u'size': 800101236736}], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'rapafx', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, {u'uuid': u'49e177e2-60d7-4477-9c5f-8d1095da96b3', u'resource_uri': u'/MAAS/api/2.0/nodes/rapafx/blockdevices/4/', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'id': 4, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'rapafx', u'partition_table_type': None, u'filesystem': {u'uuid': u'976bf38c-084d-42d0-a2c0-3108dcd29930', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'type': u'virtual', u'serial': None, u'size': 800097042432}]
2019-05-03 14:24:06,096 [salt.loaded.ext.module.maasng:632 ][INFO    ][12062] vgroot
2019-05-03 14:24:06,096 [salt.loaded.ext.module.maasng:635 ][INFO    ][12062] lvroot
2019-05-03 14:24:06,097 [salt.loaded.ext.module.maasng:639 ][INFO    ][12062] 107374182400
2019-05-03 14:24:06,696 [salt.loaded.ext.module.maasng:645 ][INFO    ][12062] {u'hwe_kernel': u'', u'status_action': u'', u'swap_size': None, u'disable_ipv4': False, u'cpu_count': 40, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qcnpd6', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, 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': 24, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qcnpd6', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qcnpd6', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, 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'parents': [], u'system_id': u'rapafx', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/rapafx/interfaces/5/'}, u'min_hwe_kernel': u'hwe-16.04', u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'uuid': None, u'resource_uri': u'/MAAS/api/2.0/nodes/rapafx/blockdevices/2/', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 2, u'partitions': [{u'uuid': u'20b68bf4-05b7-4c38-866a-b26060f9f1b2', u'resource_uri': u'/MAAS/api/2.0/nodes/rapafx/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'rapafx', u'filesystem': {u'uuid': u'a59ee41e-c530-4824-89f1-35ce3e53cf7f', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'size': 800101236736}], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'rapafx', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, u'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default'}, u'resource_uri': u'/MAAS/api/2.0/machines/rapafx/', u'current_commissioning_result_id': 4, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'rapafx', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'owner_data': {}, u'blockdevice_set': [{u'uuid': None, u'resource_uri': u'/MAAS/api/2.0/nodes/rapafx/blockdevices/2/', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 2, u'partitions': [{u'uuid': u'20b68bf4-05b7-4c38-866a-b26060f9f1b2', u'resource_uri': u'/MAAS/api/2.0/nodes/rapafx/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'rapafx', u'filesystem': {u'uuid': u'a59ee41e-c530-4824-89f1-35ce3e53cf7f', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'size': 800101236736}], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'rapafx', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, {u'uuid': u'559fa144-f8e4-4be0-8352-68e562d22412', u'resource_uri': u'/MAAS/api/2.0/nodes/rapafx/blockdevices/12/', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'id': 12, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'rapafx', u'partition_table_type': None, u'filesystem': {u'uuid': u'dddeffc2-4393-49dc-94ed-10e080eef1d5', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'type': u'virtual', u'serial': None, u'size': 107374182400}], u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'raids': [], u'physicalblockdevice_set': [{u'uuid': None, u'resource_uri': u'/MAAS/api/2.0/nodes/rapafx/blockdevices/2/', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 2, u'partitions': [{u'uuid': u'20b68bf4-05b7-4c38-866a-b26060f9f1b2', u'resource_uri': u'/MAAS/api/2.0/nodes/rapafx/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'rapafx', u'filesystem': {u'uuid': u'a59ee41e-c530-4824-89f1-35ce3e53cf7f', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'size': 800101236736}], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'rapafx', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}], u'ip_addresses': [u'192.168.11.39', u'192.168.11.44'], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'rapafx', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'memory_test_status_name': u'Unknown', u'current_testing_result_id': 5, u'cpu_test_status': -1, u'bcaches': [], u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'virtualblockdevice_set': [{u'uuid': u'559fa144-f8e4-4be0-8352-68e562d22412', u'resource_uri': u'/MAAS/api/2.0/nodes/rapafx/blockdevices/12/', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'id': 12, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'rapafx', u'partition_table_type': None, u'filesystem': {u'uuid': u'dddeffc2-4393-49dc-94ed-10e080eef1d5', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'type': u'virtual', u'serial': None, u'size': 107374182400}], u'commissioning_status': 2, u'architecture': u'amd64/generic', 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'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qcnpd6', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, 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': 24, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qcnpd6', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qcnpd6', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, 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'parents': [], u'system_id': u'rapafx', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/rapafx/interfaces/5/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d4', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'rapafx', u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/rapafx/interfaces/16/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d5', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'rapafx', u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/rapafx/interfaces/17/'}, {u'name': u'ens2f0', u'links': [{u'id': 25, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'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'fabric': u'fabric-0'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cc', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'rapafx', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/rapafx/interfaces/18/'}, {u'name': u'ens2f1', u'links': [{u'id': 26, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'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'fabric': u'fabric-0'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cd', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'rapafx', u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/rapafx/interfaces/19/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qcnpd6', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, 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': 27, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qcnpd6', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qcnpd6', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, 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'parents': [], u'system_id': u'rapafx', u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/rapafx/interfaces/20/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-05-03 14:24:06,700 [salt.state       :300 ][INFO    ][12062] {'new': {'storage_layout': 'lvm'}}
2019-05-03 14:24:06,701 [salt.state       :1951][INFO    ][12062] Completed state [maas_machines_storage_cmp001_lvm] at time 14:24:06.701374 duration_in_ms=2464.578
2019-05-03 14:24:06,706 [salt.minion      :1711][INFO    ][12062] Returning information for job: 20190503142352588209
2019-05-03 14:24:07,403 [salt.minion      :1308][INFO    ][10098] User sudo_ubuntu Executing command state.apply with jid 20190503142407384884
2019-05-03 14:24:07,428 [salt.minion      :1432][INFO    ][12120] Starting a new job with PID 12120
2019-05-03 14:24:08,548 [salt.state       :915 ][INFO    ][12120] Loading fresh modules for state activity
2019-05-03 14:24:08,610 [salt.fileclient  :1219][INFO    ][12120] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-05-03 14:24:08,659 [salt.state       :1780][INFO    ][12120] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:24:08.659675
2019-05-03 14:24:08,660 [salt.state       :1813][INFO    ][12120] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-03 14:24:08,661 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12120] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-03 14:24:10,459 [salt.state       :300 ][INFO    ][12120] {'pid': 12127, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-03 14:24:10,459 [salt.state       :1951][INFO    ][12120] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:24:10.459838 duration_in_ms=1800.163
2019-05-03 14:24:10,463 [salt.state       :1780][INFO    ][12120] Running state [maas.deploy_machines] at time 14:24:10.462986
2019-05-03 14:24:10,463 [salt.state       :1813][INFO    ][12120] Executing state module.run for [maas.deploy_machines]
2019-05-03 14:24:10,464 [salt.utils.decorators:613 ][WARNING ][12120] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-03 14:24:11,157 [salt.loaded.ext.module.maas:684 ][INFO    ][12120] deploymachines hwe_kernel=hwe-16.04 system_id=yywr4b distro_series=xenial
2019-05-03 14:24:13,665 [salt.loaded.ext.module.maas:684 ][INFO    ][12120] deploymachines hwe_kernel=hwe-16.04 system_id=rapafx distro_series=xenial
2019-05-03 14:24:16,129 [salt.loaded.ext.module.maas:684 ][INFO    ][12120] deploymachines hwe_kernel=hwe-16.04 system_id=g7p6fa distro_series=xenial
2019-05-03 14:24:18,609 [salt.loaded.ext.module.maas:684 ][INFO    ][12120] deploymachines hwe_kernel=hwe-16.04 system_id=r77adn distro_series=xenial
2019-05-03 14:24:21,091 [salt.loaded.ext.module.maas:684 ][INFO    ][12120] deploymachines hwe_kernel=hwe-16.04 system_id=4yapem distro_series=xenial
2019-05-03 14:24:22,510 [salt.minion      :1308][INFO    ][10098] User sudo_ubuntu Executing command saltutil.find_job with jid 20190503142422503339
2019-05-03 14:24:22,532 [salt.minion      :1432][INFO    ][12385] Starting a new job with PID 12385
2019-05-03 14:24:22,564 [salt.minion      :1711][INFO    ][12385] Returning information for job: 20190503142422503339
2019-05-03 14:24:23,496 [salt.state       :300 ][INFO    ][12120] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-05-03 14:24:23,497 [salt.state       :1951][INFO    ][12120] Completed state [maas.deploy_machines] at time 14:24:23.497314 duration_in_ms=13034.326
2019-05-03 14:24:23,503 [salt.minion      :1711][INFO    ][12120] Returning information for job: 20190503142407384884
2019-05-03 14:24:24,155 [salt.minion      :1308][INFO    ][10098] User sudo_ubuntu Executing command state.apply with jid 20190503142424142921
2019-05-03 14:24:24,179 [salt.minion      :1432][INFO    ][12403] Starting a new job with PID 12403
2019-05-03 14:24:31,400 [salt.state       :915 ][INFO    ][12403] Loading fresh modules for state activity
2019-05-03 14:24:31,462 [salt.fileclient  :1219][INFO    ][12403] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-05-03 14:24:31,526 [salt.state       :1780][INFO    ][12403] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:24:31.526759
2019-05-03 14:24:31,527 [salt.state       :1813][INFO    ][12403] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-03 14:24:31,531 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12403] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-03 14:24:33,357 [salt.state       :300 ][INFO    ][12403] {'pid': 12444, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-03 14:24:33,358 [salt.state       :1951][INFO    ][12403] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:24:33.358189 duration_in_ms=1831.429
2019-05-03 14:24:33,363 [salt.state       :1780][INFO    ][12403] Running state [maas.wait_for_machine_status] at time 14:24:33.363750
2019-05-03 14:24:33,364 [salt.state       :1813][INFO    ][12403] Executing state module.run for [maas.wait_for_machine_status]
2019-05-03 14:24:33,365 [salt.utils.decorators:613 ][WARNING ][12403] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-03 14:24:36,188 [salt.loaded.ext.module.maas:1023][INFO    ][12403] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.19524503s left)
2019-05-03 14:24:39,252 [salt.minion      :1308][INFO    ][10098] User sudo_ubuntu Executing command saltutil.find_job with jid 20190503142439242833
2019-05-03 14:24:39,276 [salt.minion      :1432][INFO    ][12468] Starting a new job with PID 12468
2019-05-03 14:24:39,302 [salt.minion      :1711][INFO    ][12468] Returning information for job: 20190503142439242833
2019-05-03 14:25:09,045 [salt.loaded.ext.module.maas:1023][INFO    ][12403] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.33777905s left)
2019-05-03 14:25:09,336 [salt.minion      :1308][INFO    ][10098] User sudo_ubuntu Executing command saltutil.find_job with jid 20190503142509325406
2019-05-03 14:25:09,365 [salt.minion      :1432][INFO    ][12512] Starting a new job with PID 12512
2019-05-03 14:25:09,395 [salt.minion      :1711][INFO    ][12512] Returning information for job: 20190503142509325406
2019-05-03 14:25:39,460 [salt.minion      :1308][INFO    ][10098] User sudo_ubuntu Executing command saltutil.find_job with jid 20190503142539448683
2019-05-03 14:25:39,479 [salt.minion      :1432][INFO    ][12564] Starting a new job with PID 12564
2019-05-03 14:25:39,512 [salt.minion      :1711][INFO    ][12564] Returning information for job: 20190503142539448683
2019-05-03 14:25:42,093 [salt.loaded.ext.module.maas:1023][INFO    ][12403] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.29028106s left)
2019-05-03 14:26:09,547 [salt.minion      :1308][INFO    ][10098] User sudo_ubuntu Executing command saltutil.find_job with jid 20190503142609535629
2019-05-03 14:26:09,575 [salt.minion      :1432][INFO    ][12605] Starting a new job with PID 12605
2019-05-03 14:26:09,601 [salt.minion      :1711][INFO    ][12605] Returning information for job: 20190503142609535629
2019-05-03 14:26:15,070 [salt.loaded.ext.module.maas:1023][INFO    ][12403] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2148.31338406s left)
2019-05-03 14:26:39,647 [salt.minion      :1308][INFO    ][10098] User sudo_ubuntu Executing command saltutil.find_job with jid 20190503142639636697
2019-05-03 14:26:39,672 [salt.minion      :1432][INFO    ][12663] Starting a new job with PID 12663
2019-05-03 14:26:39,704 [salt.minion      :1711][INFO    ][12663] Returning information for job: 20190503142639636697
2019-05-03 14:26:48,145 [salt.loaded.ext.module.maas:1023][INFO    ][12403] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2115.23811197s left)
2019-05-03 14:27:09,731 [salt.minion      :1308][INFO    ][10098] User sudo_ubuntu Executing command saltutil.find_job with jid 20190503142709715940
2019-05-03 14:27:09,757 [salt.minion      :1432][INFO    ][12732] Starting a new job with PID 12732
2019-05-03 14:27:09,786 [salt.minion      :1711][INFO    ][12732] Returning information for job: 20190503142709715940
2019-05-03 14:27:20,695 [salt.loaded.ext.module.maas:993 ][INFO    ][12403] Machine 4yapem mark broken
2019-05-03 14:27:21,154 [salt.loaded.ext.module.maas:996 ][INFO    ][12403] Machine 4yapem mark fixed
2019-05-03 14:27:22,143 [salt.loaded.ext.module.maas:163 ][ERROR   ][12403] Failed for object cmp002 reason Not in ready state
2019-05-03 14:27:22,144 [salt.loaded.ext.module.maas:163 ][ERROR   ][12403] Failed for object cmp001 reason Not in ready state
2019-05-03 14:27:22,144 [salt.loaded.ext.module.maas:163 ][ERROR   ][12403] Failed for object kvm01 reason Not in ready state
2019-05-03 14:27:22,144 [salt.loaded.ext.module.maas:163 ][ERROR   ][12403] Failed for object kvm03 reason Not in ready state
2019-05-03 14:27:22,144 [salt.loaded.ext.module.maas:684 ][INFO    ][12403] deploymachines hwe_kernel=hwe-16.04 system_id=4yapem distro_series=xenial
2019-05-03 14:27:24,661 [salt.state       :302 ][ERROR   ][12403] Module function maas.wait_for_machine_status threw an exception. Exception: {'updated': [], 'errors': {'cmp002': 'Not in ready state', 'cmp001': 'Not in ready state', 'kvm01': 'Not in ready state', 'kvm03': 'Not in ready state'}, 'success': ['kvm02']}
2019-05-03 14:27:24,664 [salt.state       :1951][INFO    ][12403] Completed state [maas.wait_for_machine_status] at time 14:27:24.664111 duration_in_ms=171300.36
2019-05-03 14:27:24,668 [salt.minion      :1711][INFO    ][12403] Returning information for job: 20190503142424142921
2019-05-03 14:27:35,520 [salt.minion      :1308][INFO    ][10098] User sudo_ubuntu Executing command pillar.get with jid 20190503142735507214
2019-05-03 14:27:35,549 [salt.minion      :1432][INFO    ][12902] Starting a new job with PID 12902
2019-05-03 14:27:35,561 [salt.minion      :1711][INFO    ][12902] Returning information for job: 20190503142735507214
2019-05-03 14:27:36,240 [salt.minion      :1308][INFO    ][10098] User sudo_ubuntu Executing command service.status with jid 20190503142736224972
2019-05-03 14:27:36,268 [salt.minion      :1432][INFO    ][12907] Starting a new job with PID 12907
2019-05-03 14:27:36,930 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12907] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-05-03 14:27:36,972 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12907] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-05-03 14:27:36,994 [salt.minion      :1711][INFO    ][12907] Returning information for job: 20190503142736224972
2019-05-03 14:27:37,652 [salt.minion      :1308][INFO    ][10098] User sudo_ubuntu Executing command state.apply with jid 20190503142737638180
2019-05-03 14:27:37,679 [salt.minion      :1432][INFO    ][12918] Starting a new job with PID 12918
2019-05-03 14:27:45,015 [salt.state       :915 ][INFO    ][12918] Loading fresh modules for state activity
2019-05-03 14:27:45,646 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12918] Executing command 'salt-minion --version' in directory '/root'
2019-05-03 14:27:46,027 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12918] Executing command 'salt-minion --version' in directory '/root'
2019-05-03 14:27:47,113 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12918] Executing command 'salt-minion --version' in directory '/root'
2019-05-03 14:27:47,445 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12918] Executing command 'salt-minion --version' in directory '/root'
2019-05-03 14:27:49,618 [salt.state       :1780][INFO    ][12918] Running state [salt-minion] at time 14:27:49.618544
2019-05-03 14:27:49,619 [salt.state       :1813][INFO    ][12918] Executing state pkg.installed for [salt-minion]
2019-05-03 14:27:49,619 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12918] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-03 14:27:49,729 [salt.state       :300 ][INFO    ][12918] All specified packages are already installed
2019-05-03 14:27:49,730 [salt.state       :1951][INFO    ][12918] Completed state [salt-minion] at time 14:27:49.730414 duration_in_ms=111.871
2019-05-03 14:27:49,730 [salt.state       :1780][INFO    ][12918] Running state [salt_minion_dependency_packages] at time 14:27:49.730773
2019-05-03 14:27:49,731 [salt.state       :1813][INFO    ][12918] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-05-03 14:27:49,746 [salt.state       :300 ][INFO    ][12918] All specified packages are already installed
2019-05-03 14:27:49,746 [salt.state       :1951][INFO    ][12918] Completed state [salt_minion_dependency_packages] at time 14:27:49.746617 duration_in_ms=15.843
2019-05-03 14:27:49,750 [salt.state       :1780][INFO    ][12918] Running state [/etc/salt/minion.d/minion.conf] at time 14:27:49.750042
2019-05-03 14:27:49,750 [salt.state       :1813][INFO    ][12918] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-05-03 14:27:50,055 [salt.state       :300 ][INFO    ][12918] File /etc/salt/minion.d/minion.conf is in the correct state
2019-05-03 14:27:50,055 [salt.state       :1951][INFO    ][12918] Completed state [/etc/salt/minion.d/minion.conf] at time 14:27:50.055824 duration_in_ms=305.782
2019-05-03 14:27:50,056 [salt.state       :1780][INFO    ][12918] Running state [python-netaddr] at time 14:27:50.056117
2019-05-03 14:27:50,056 [salt.state       :1813][INFO    ][12918] Executing state pkg.installed for [python-netaddr]
2019-05-03 14:27:50,065 [salt.state       :300 ][INFO    ][12918] All specified packages are already installed
2019-05-03 14:27:50,066 [salt.state       :1951][INFO    ][12918] Completed state [python-netaddr] at time 14:27:50.066883 duration_in_ms=10.766
2019-05-03 14:27:50,070 [salt.state       :1780][INFO    ][12918] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 14:27:50.069989
2019-05-03 14:27:50,070 [salt.state       :1813][INFO    ][12918] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-05-03 14:27:50,087 [salt.state       :300 ][INFO    ][12918] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-05-03 14:27:50,088 [salt.state       :1951][INFO    ][12918] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 14:27:50.087884 duration_in_ms=17.894
2019-05-03 14:27:50,091 [salt.state       :1780][INFO    ][12918] Running state [salt-minion] at time 14:27:50.091815
2019-05-03 14:27:50,092 [salt.state       :1813][INFO    ][12918] Executing state service.running for [salt-minion]
2019-05-03 14:27:50,092 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12918] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-03 14:27:50,133 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12918] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-05-03 14:27:50,156 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12918] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-05-03 14:27:50,182 [salt.state       :300 ][INFO    ][12918] The service salt-minion is already running
2019-05-03 14:27:50,182 [salt.state       :1951][INFO    ][12918] Completed state [salt-minion] at time 14:27:50.182519 duration_in_ms=90.703
2019-05-03 14:27:50,185 [salt.state       :1780][INFO    ][12918] Running state [/etc/salt/grains.d] at time 14:27:50.185289
2019-05-03 14:27:50,188 [salt.state       :1813][INFO    ][12918] Executing state file.directory for [/etc/salt/grains.d]
2019-05-03 14:27:50,189 [salt.state       :300 ][INFO    ][12918] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-05-03 14:27:50,189 [salt.state       :1951][INFO    ][12918] Completed state [/etc/salt/grains.d] at time 14:27:50.189874 duration_in_ms=4.584
2019-05-03 14:27:50,190 [salt.state       :1780][INFO    ][12918] Running state [/etc/salt/grains] at time 14:27:50.190824
2019-05-03 14:27:50,191 [salt.state       :1813][INFO    ][12918] Executing state file.managed for [/etc/salt/grains]
2019-05-03 14:27:50,192 [salt.state       :300 ][INFO    ][12918] File /etc/salt/grains exists with proper permissions. No changes made.
2019-05-03 14:27:50,192 [salt.state       :1951][INFO    ][12918] Completed state [/etc/salt/grains] at time 14:27:50.192346 duration_in_ms=1.521
2019-05-03 14:27:50,193 [salt.state       :1780][INFO    ][12918] Running state [/etc/salt/grains.d/placeholder] at time 14:27:50.193057
2019-05-03 14:27:50,193 [salt.state       :1813][INFO    ][12918] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-05-03 14:27:50,194 [salt.state       :300 ][INFO    ][12918] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-05-03 14:27:50,194 [salt.state       :1951][INFO    ][12918] Completed state [/etc/salt/grains.d/placeholder] at time 14:27:50.194527 duration_in_ms=1.47
2019-05-03 14:27:50,195 [salt.state       :1780][INFO    ][12918] Running state [/etc/salt/grains.d/sphinx] at time 14:27:50.195232
2019-05-03 14:27:50,195 [salt.state       :1813][INFO    ][12918] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-05-03 14:27:50,197 [salt.state       :300 ][INFO    ][12918] File /etc/salt/grains.d/sphinx is in the correct state
2019-05-03 14:27:50,199 [salt.state       :1951][INFO    ][12918] Completed state [/etc/salt/grains.d/sphinx] at time 14:27:50.197676 duration_in_ms=2.444
2019-05-03 14:27:50,202 [salt.state       :1780][INFO    ][12918] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 14:27:50.201990
2019-05-03 14:27:50,202 [salt.state       :1813][INFO    ][12918] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-05-03 14:27:50,202 [salt.state       :300 ][INFO    ][12918] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-05-03 14:27:50,202 [salt.state       :1951][INFO    ][12918] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 14:27:50.202844 duration_in_ms=0.854
2019-05-03 14:27:50,203 [salt.state       :1780][INFO    ][12918] Running state [/etc/salt/grains.d/dns_records] at time 14:27:50.203372
2019-05-03 14:27:50,203 [salt.state       :1813][INFO    ][12918] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-05-03 14:27:50,204 [salt.state       :300 ][INFO    ][12918] File /etc/salt/grains.d/dns_records is in the correct state
2019-05-03 14:27:50,204 [salt.state       :1951][INFO    ][12918] Completed state [/etc/salt/grains.d/dns_records] at time 14:27:50.204920 duration_in_ms=1.549
2019-05-03 14:27:50,206 [salt.state       :1780][INFO    ][12918] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 14:27:50.205945
2019-05-03 14:27:50,206 [salt.state       :1813][INFO    ][12918] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-05-03 14:27:50,206 [salt.state       :300 ][INFO    ][12918] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-05-03 14:27:50,206 [salt.state       :1951][INFO    ][12918] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 14:27:50.206759 duration_in_ms=0.815
2019-05-03 14:27:50,207 [salt.state       :1780][INFO    ][12918] Running state [/etc/salt/grains.d/salt] at time 14:27:50.207281
2019-05-03 14:27:50,207 [salt.state       :1813][INFO    ][12918] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-05-03 14:27:50,208 [salt.state       :300 ][INFO    ][12918] File /etc/salt/grains.d/salt is in the correct state
2019-05-03 14:27:50,208 [salt.state       :1951][INFO    ][12918] Completed state [/etc/salt/grains.d/salt] at time 14:27:50.208841 duration_in_ms=1.56
2019-05-03 14:27:50,211 [salt.state       :1780][INFO    ][12918] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 14:27:50.210949
2019-05-03 14:27:50,211 [salt.state       :1813][INFO    ][12918] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-05-03 14:27:50,211 [salt.state       :300 ][INFO    ][12918] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-05-03 14:27:50,212 [salt.state       :1951][INFO    ][12918] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 14:27:50.212082 duration_in_ms=1.133
2019-05-03 14:27:50,214 [salt.state       :1780][INFO    ][12918] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 14:27:50.214388
2019-05-03 14:27:50,214 [salt.state       :1813][INFO    ][12918] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-05-03 14:27:50,215 [salt.state       :300 ][INFO    ][12918] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-05-03 14:27:50,215 [salt.state       :1951][INFO    ][12918] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 14:27:50.215210 duration_in_ms=0.822
2019-05-03 14:27:50,215 [salt.state       :1780][INFO    ][12918] Running state [mine.update] at time 14:27:50.215899
2019-05-03 14:27:50,216 [salt.state       :1813][INFO    ][12918] Executing state module.wait for [mine.update]
2019-05-03 14:27:50,216 [salt.state       :300 ][INFO    ][12918] No changes made for mine.update
2019-05-03 14:27:50,216 [salt.state       :1951][INFO    ][12918] Completed state [mine.update] at time 14:27:50.216683 duration_in_ms=0.783
2019-05-03 14:27:50,217 [salt.state       :1780][INFO    ][12918] Running state [ca-certificates] at time 14:27:50.216954
2019-05-03 14:27:50,217 [salt.state       :1813][INFO    ][12918] Executing state pkg.installed for [ca-certificates]
2019-05-03 14:27:50,229 [salt.state       :300 ][INFO    ][12918] All specified packages are already installed
2019-05-03 14:27:50,229 [salt.state       :1951][INFO    ][12918] Completed state [ca-certificates] at time 14:27:50.229444 duration_in_ms=12.49
2019-05-03 14:27:50,230 [salt.state       :1780][INFO    ][12918] Running state [update-ca-certificates] at time 14:27:50.230854
2019-05-03 14:27:50,231 [salt.state       :1813][INFO    ][12918] Executing state cmd.wait for [update-ca-certificates]
2019-05-03 14:27:50,232 [salt.state       :300 ][INFO    ][12918] No changes made for update-ca-certificates
2019-05-03 14:27:50,232 [salt.state       :1951][INFO    ][12918] Completed state [update-ca-certificates] at time 14:27:50.232552 duration_in_ms=1.699
2019-05-03 14:27:50,233 [salt.state       :1780][INFO    ][12918] Running state [iptables] at time 14:27:50.233098
2019-05-03 14:27:50,233 [salt.state       :1813][INFO    ][12918] Executing state pkg.installed for [iptables]
2019-05-03 14:27:50,244 [salt.state       :300 ][INFO    ][12918] All specified packages are already installed
2019-05-03 14:27:50,244 [salt.state       :1951][INFO    ][12918] Completed state [iptables] at time 14:27:50.244894 duration_in_ms=11.796
2019-05-03 14:27:50,245 [salt.state       :1780][INFO    ][12918] Running state [iptables-persistent] at time 14:27:50.245174
2019-05-03 14:27:50,245 [salt.state       :1813][INFO    ][12918] Executing state pkg.installed for [iptables-persistent]
2019-05-03 14:27:50,255 [salt.state       :300 ][INFO    ][12918] All specified packages are already installed
2019-05-03 14:27:50,256 [salt.state       :1951][INFO    ][12918] Completed state [iptables-persistent] at time 14:27:50.256060 duration_in_ms=10.886
2019-05-03 14:27:50,257 [salt.state       :1780][INFO    ][12918] Running state [iptables_modules_v4_load] at time 14:27:50.257150
2019-05-03 14:27:50,257 [salt.state       :1813][INFO    ][12918] Executing state kmod.present for [iptables_modules_v4_load]
2019-05-03 14:27:50,258 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12918] Executing command 'lsmod' in directory '/root'
2019-05-03 14:27:50,286 [salt.state       :300 ][INFO    ][12918] Kernel modules iptable_filter, ip_tables are already present
2019-05-03 14:27:50,287 [salt.state       :1951][INFO    ][12918] Completed state [iptables_modules_v4_load] at time 14:27:50.287394 duration_in_ms=30.243
2019-05-03 14:27:50,288 [salt.state       :1780][INFO    ][12918] Running state [/etc/iptables/rules.v4] at time 14:27:50.288600
2019-05-03 14:27:50,289 [salt.state       :1813][INFO    ][12918] Executing state file.managed for [/etc/iptables/rules.v4]
2019-05-03 14:27:50,404 [salt.state       :300 ][INFO    ][12918] File /etc/iptables/rules.v4 is in the correct state
2019-05-03 14:27:50,404 [salt.state       :1951][INFO    ][12918] Completed state [/etc/iptables/rules.v4] at time 14:27:50.404701 duration_in_ms=116.101
2019-05-03 14:27:50,406 [salt.state       :1780][INFO    ][12918] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 14:27:50.406172
2019-05-03 14:27:50,406 [salt.state       :1813][INFO    ][12918] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-05-03 14:27:50,407 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12918] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-05-03 14:27:50,424 [salt.state       :300 ][INFO    ][12918] onlyif execution failed
2019-05-03 14:27:50,425 [salt.state       :1951][INFO    ][12918] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 14:27:50.425162 duration_in_ms=18.99
2019-05-03 14:27:50,430 [salt.state       :1780][INFO    ][12918] Running state [netfilter-persistent] at time 14:27:50.429689
2019-05-03 14:27:50,430 [salt.state       :1813][INFO    ][12918] Executing state service.running for [netfilter-persistent]
2019-05-03 14:27:50,432 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12918] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-05-03 14:27:50,460 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12918] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-05-03 14:27:50,484 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12918] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-05-03 14:27:50,507 [salt.state       :300 ][INFO    ][12918] The service netfilter-persistent is already running
2019-05-03 14:27:50,507 [salt.state       :1951][INFO    ][12918] Completed state [netfilter-persistent] at time 14:27:50.507664 duration_in_ms=77.975
2019-05-03 14:27:50,509 [salt.state       :1780][INFO    ][12918] Running state [iptables_extra.remove_stale_tables] at time 14:27:50.509241
2019-05-03 14:27:50,512 [salt.state       :1813][INFO    ][12918] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-05-03 14:27:50,512 [salt.state       :300 ][INFO    ][12918] No changes made for iptables_extra.remove_stale_tables
2019-05-03 14:27:50,513 [salt.state       :1951][INFO    ][12918] Completed state [iptables_extra.remove_stale_tables] at time 14:27:50.513177 duration_in_ms=3.936
2019-05-03 14:27:50,513 [salt.state       :1780][INFO    ][12918] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 14:27:50.513544
2019-05-03 14:27:50,514 [salt.state       :1813][INFO    ][12918] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-05-03 14:27:50,515 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12918] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-05-03 14:27:50,532 [salt.state       :300 ][INFO    ][12918] onlyif execution failed
2019-05-03 14:27:50,532 [salt.state       :1951][INFO    ][12918] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 14:27:50.532798 duration_in_ms=19.254
2019-05-03 14:27:50,537 [salt.state       :1780][INFO    ][12918] Running state [/etc/iptables/rules.v6] at time 14:27:50.536956
2019-05-03 14:27:50,538 [salt.state       :1813][INFO    ][12918] Executing state file.absent for [/etc/iptables/rules.v6]
2019-05-03 14:27:50,538 [salt.state       :300 ][INFO    ][12918] File /etc/iptables/rules.v6 is not present
2019-05-03 14:27:50,539 [salt.state       :1951][INFO    ][12918] Completed state [/etc/iptables/rules.v6] at time 14:27:50.539193 duration_in_ms=2.237
2019-05-03 14:27:50,540 [salt.state       :1780][INFO    ][12918] Running state [iptables_extra.flush_all] at time 14:27:50.540512
2019-05-03 14:27:50,541 [salt.state       :1813][INFO    ][12918] Executing state module.wait for [iptables_extra.flush_all]
2019-05-03 14:27:50,541 [salt.state       :300 ][INFO    ][12918] No changes made for iptables_extra.flush_all
2019-05-03 14:27:50,542 [salt.state       :1951][INFO    ][12918] Completed state [iptables_extra.flush_all] at time 14:27:50.542312 duration_in_ms=1.8
2019-05-03 14:27:50,548 [salt.minion      :1711][INFO    ][12918] Returning information for job: 20190503142737638180
2019-05-03 14:27:51,187 [salt.minion      :1308][INFO    ][10098] User sudo_ubuntu Executing command state.apply with jid 20190503142751175486
2019-05-03 14:27:51,214 [salt.minion      :1432][INFO    ][13084] Starting a new job with PID 13084
2019-05-03 14:27:52,313 [salt.state       :915 ][INFO    ][13084] Loading fresh modules for state activity
2019-05-03 14:27:53,525 [salt.state       :1780][INFO    ][13084] Running state [maas-rack-controller] at time 14:27:53.525189
2019-05-03 14:27:53,525 [salt.state       :1813][INFO    ][13084] Executing state pkg.installed for [maas-rack-controller]
2019-05-03 14:27:53,526 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13084] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-03 14:27:53,658 [salt.state       :300 ][INFO    ][13084] All specified packages are already installed
2019-05-03 14:27:53,658 [salt.state       :1951][INFO    ][13084] Completed state [maas-rack-controller] at time 14:27:53.658717 duration_in_ms=133.529
2019-05-03 14:27:53,659 [salt.state       :1780][INFO    ][13084] Running state [ipmitool] at time 14:27:53.659093
2019-05-03 14:27:53,659 [salt.state       :1813][INFO    ][13084] Executing state pkg.installed for [ipmitool]
2019-05-03 14:27:53,669 [salt.state       :300 ][INFO    ][13084] All specified packages are already installed
2019-05-03 14:27:53,669 [salt.state       :1951][INFO    ][13084] Completed state [ipmitool] at time 14:27:53.669469 duration_in_ms=10.375
2019-05-03 14:27:53,672 [salt.state       :1780][INFO    ][13084] Running state [/etc/maas/rackd.conf] at time 14:27:53.672633
2019-05-03 14:27:53,672 [salt.state       :1813][INFO    ][13084] Executing state file.line for [/etc/maas/rackd.conf]
2019-05-03 14:27:53,674 [salt.state       :300 ][INFO    ][13084] No changes needed to be made
2019-05-03 14:27:53,674 [salt.state       :1951][INFO    ][13084] Completed state [/etc/maas/rackd.conf] at time 14:27:53.674390 duration_in_ms=1.757
2019-05-03 14:27:53,674 [salt.state       :1780][INFO    ][13084] Running state [/etc/maas/rackd.conf] at time 14:27:53.674661
2019-05-03 14:27:53,674 [salt.state       :1813][INFO    ][13084] Executing state file.managed for [/etc/maas/rackd.conf]
2019-05-03 14:27:53,675 [salt.loaded.int.states.file:2298][WARNING ][13084] 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-05-03 14:27:53,675 [salt.state       :300 ][INFO    ][13084] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-05-03 14:27:53,676 [salt.state       :1951][INFO    ][13084] Completed state [/etc/maas/rackd.conf] at time 14:27:53.676081 duration_in_ms=1.42
2019-05-03 14:27:53,677 [salt.state       :1780][INFO    ][13084] Running state [maas-rackd] at time 14:27:53.677112
2019-05-03 14:27:53,677 [salt.state       :1813][INFO    ][13084] Executing state service.running for [maas-rackd]
2019-05-03 14:27:53,679 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13084] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-05-03 14:27:53,719 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13084] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-05-03 14:27:53,744 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13084] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-05-03 14:27:53,767 [salt.state       :300 ][INFO    ][13084] The service maas-rackd is already running
2019-05-03 14:27:53,768 [salt.state       :1951][INFO    ][13084] Completed state [maas-rackd] at time 14:27:53.768522 duration_in_ms=91.409
2019-05-03 14:27:53,770 [salt.minion      :1711][INFO    ][13084] Returning information for job: 20190503142751175486
2019-05-03 14:27:54,443 [salt.minion      :1308][INFO    ][10098] User sudo_ubuntu Executing command state.apply with jid 20190503142754431165
2019-05-03 14:27:54,468 [salt.minion      :1432][INFO    ][13110] Starting a new job with PID 13110
2019-05-03 14:27:55,605 [salt.state       :915 ][INFO    ][13110] Loading fresh modules for state activity
2019-05-03 14:27:56,982 [salt.state       :1780][INFO    ][13110] Running state [maas-region-controller] at time 14:27:56.982364
2019-05-03 14:27:56,982 [salt.state       :1813][INFO    ][13110] Executing state pkg.installed for [maas-region-controller]
2019-05-03 14:27:56,983 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13110] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-03 14:27:57,097 [salt.state       :300 ][INFO    ][13110] All specified packages are already installed
2019-05-03 14:27:57,097 [salt.state       :1951][INFO    ][13110] Completed state [maas-region-controller] at time 14:27:57.097401 duration_in_ms=115.038
2019-05-03 14:27:57,098 [salt.state       :1780][INFO    ][13110] Running state [python-oauth] at time 14:27:57.098683
2019-05-03 14:27:57,099 [salt.state       :1813][INFO    ][13110] Executing state pkg.installed for [python-oauth]
2019-05-03 14:27:57,107 [salt.state       :300 ][INFO    ][13110] All specified packages are already installed
2019-05-03 14:27:57,107 [salt.state       :1951][INFO    ][13110] Completed state [python-oauth] at time 14:27:57.107836 duration_in_ms=9.154
2019-05-03 14:27:57,111 [salt.state       :1780][INFO    ][13110] Running state [/etc/maas/regiond.conf] at time 14:27:57.111638
2019-05-03 14:27:57,111 [salt.state       :1813][INFO    ][13110] Executing state file.replace for [/etc/maas/regiond.conf]
2019-05-03 14:27:57,116 [salt.state       :300 ][INFO    ][13110] No changes needed to be made
2019-05-03 14:27:57,117 [salt.state       :1951][INFO    ][13110] Completed state [/etc/maas/regiond.conf] at time 14:27:57.117032 duration_in_ms=5.394
2019-05-03 14:27:57,117 [salt.state       :1780][INFO    ][13110] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 14:27:57.117532
2019-05-03 14:27:57,117 [salt.state       :1813][INFO    ][13110] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-05-03 14:27:57,186 [salt.state       :300 ][INFO    ][13110] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-05-03 14:27:57,186 [salt.state       :1951][INFO    ][13110] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 14:27:57.186463 duration_in_ms=68.932
2019-05-03 14:27:57,187 [salt.state       :1780][INFO    ][13110] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 14:27:57.186976
2019-05-03 14:27:57,187 [salt.state       :1813][INFO    ][13110] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-03 14:27:57,191 [salt.state       :300 ][INFO    ][13110] No changes needed to be made
2019-05-03 14:27:57,191 [salt.state       :1951][INFO    ][13110] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 14:27:57.191579 duration_in_ms=4.603
2019-05-03 14:27:57,192 [salt.state       :1780][INFO    ][13110] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 14:27:57.192079
2019-05-03 14:27:57,192 [salt.state       :1813][INFO    ][13110] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-03 14:27:57,197 [salt.state       :300 ][INFO    ][13110] No changes needed to be made
2019-05-03 14:27:57,197 [salt.state       :1951][INFO    ][13110] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 14:27:57.197274 duration_in_ms=5.195
2019-05-03 14:27:57,197 [salt.state       :1780][INFO    ][13110] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 14:27:57.197813
2019-05-03 14:27:57,198 [salt.state       :1813][INFO    ][13110] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-05-03 14:27:57,233 [salt.state       :300 ][INFO    ][13110] No changes needed to be made
2019-05-03 14:27:57,233 [salt.state       :1951][INFO    ][13110] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 14:27:57.233409 duration_in_ms=35.595
2019-05-03 14:27:57,234 [salt.state       :1780][INFO    ][13110] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 14:27:57.234606
2019-05-03 14:27:57,234 [salt.state       :1813][INFO    ][13110] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-05-03 14:27:57,256 [salt.state       :300 ][INFO    ][13110] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-05-03 14:27:57,256 [salt.state       :1951][INFO    ][13110] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 14:27:57.256898 duration_in_ms=22.291
2019-05-03 14:27:57,259 [salt.state       :1780][INFO    ][13110] Running state [a2enmod headers] at time 14:27:57.259712
2019-05-03 14:27:57,260 [salt.state       :1813][INFO    ][13110] Executing state cmd.run for [a2enmod headers]
2019-05-03 14:27:57,261 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13110] Executing command 'a2enmod headers' in directory '/root'
2019-05-03 14:27:57,332 [salt.state       :300 ][INFO    ][13110] {'pid': 13136, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-05-03 14:27:57,333 [salt.state       :1951][INFO    ][13110] Completed state [a2enmod headers] at time 14:27:57.333048 duration_in_ms=73.337
2019-05-03 14:27:57,333 [salt.state       :1780][INFO    ][13110] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 14:27:57.333680
2019-05-03 14:27:57,334 [salt.state       :1813][INFO    ][13110] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-05-03 14:27:57,360 [salt.state       :300 ][INFO    ][13110] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-05-03 14:27:57,360 [salt.state       :1951][INFO    ][13110] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 14:27:57.360753 duration_in_ms=27.073
2019-05-03 14:27:57,364 [salt.state       :1780][INFO    ][13110] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 14:27:57.364940
2019-05-03 14:27:57,365 [salt.state       :1813][INFO    ][13110] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-05-03 14:27:57,421 [salt.state       :300 ][INFO    ][13110] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-05-03 14:27:57,421 [salt.state       :1951][INFO    ][13110] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 14:27:57.421426 duration_in_ms=56.486
2019-05-03 14:27:57,425 [salt.state       :1780][INFO    ][13110] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 14:27:57.425040
2019-05-03 14:27:57,425 [salt.state       :1813][INFO    ][13110] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-05-03 14:27:57,479 [salt.state       :300 ][INFO    ][13110] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-05-03 14:27:57,479 [salt.state       :1951][INFO    ][13110] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 14:27:57.479793 duration_in_ms=54.753
2019-05-03 14:27:57,480 [salt.state       :1780][INFO    ][13110] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 14:27:57.480411
2019-05-03 14:27:57,480 [salt.state       :1813][INFO    ][13110] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-05-03 14:27:57,569 [salt.state       :300 ][INFO    ][13110] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-05-03 14:27:57,569 [salt.state       :1951][INFO    ][13110] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 14:27:57.569394 duration_in_ms=88.982
2019-05-03 14:27:57,570 [salt.state       :1780][INFO    ][13110] Running state [/root/.pgpass] at time 14:27:57.570136
2019-05-03 14:27:57,570 [salt.state       :1813][INFO    ][13110] Executing state file.managed for [/root/.pgpass]
2019-05-03 14:27:57,618 [salt.state       :300 ][INFO    ][13110] File /root/.pgpass is in the correct state
2019-05-03 14:27:57,618 [salt.state       :1951][INFO    ][13110] Completed state [/root/.pgpass] at time 14:27:57.618600 duration_in_ms=48.464
2019-05-03 14:27:57,623 [salt.state       :1780][INFO    ][13110] Running state [maas-region syncdb --noinput] at time 14:27:57.623732
2019-05-03 14:27:57,624 [salt.state       :1813][INFO    ][13110] Executing state cmd.run for [maas-region syncdb --noinput]
2019-05-03 14:27:57,624 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13110] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-05-03 14:28:00,265 [salt.state       :300 ][INFO    ][13110] {'pid': 13149, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: sites, auth, metadataserver, sessions, piston3, contenttypes, maasserver\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-05-03 14:28:00,268 [salt.state       :1951][INFO    ][13110] Completed state [maas-region syncdb --noinput] at time 14:28:00.267877 duration_in_ms=2644.144
2019-05-03 14:28:00,268 [salt.state       :2022][WARNING ][13110] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-03 14:28:00,271 [salt.state       :1780][INFO    ][13110] Running state [maas-regiond] at time 14:28:00.271452
2019-05-03 14:28:00,272 [salt.state       :1813][INFO    ][13110] Executing state service.running for [maas-regiond]
2019-05-03 14:28:00,273 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13110] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-05-03 14:28:00,322 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13110] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-05-03 14:28:00,340 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13110] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-05-03 14:28:00,356 [salt.state       :300 ][INFO    ][13110] The service maas-regiond is already running
2019-05-03 14:28:00,357 [salt.state       :1951][INFO    ][13110] Completed state [maas-regiond] at time 14:28:00.356982 duration_in_ms=85.529
2019-05-03 14:28:00,359 [salt.state       :1780][INFO    ][13110] Running state [bind9] at time 14:28:00.359895
2019-05-03 14:28:00,360 [salt.state       :1813][INFO    ][13110] Executing state service.running for [bind9]
2019-05-03 14:28:00,361 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13110] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-05-03 14:28:00,385 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13110] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-05-03 14:28:00,405 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13110] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-05-03 14:28:00,424 [salt.state       :300 ][INFO    ][13110] The service bind9 is already running
2019-05-03 14:28:00,424 [salt.state       :1951][INFO    ][13110] Completed state [bind9] at time 14:28:00.424679 duration_in_ms=64.783
2019-05-03 14:28:00,427 [salt.state       :1780][INFO    ][13110] Running state [apache2] at time 14:28:00.427847
2019-05-03 14:28:00,428 [salt.state       :1813][INFO    ][13110] Executing state service.running for [apache2]
2019-05-03 14:28:00,429 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13110] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-05-03 14:28:00,452 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13110] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-05-03 14:28:00,469 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13110] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-05-03 14:28:00,492 [salt.state       :300 ][INFO    ][13110] The service apache2 is already running
2019-05-03 14:28:00,492 [salt.state       :1951][INFO    ][13110] Completed state [apache2] at time 14:28:00.492867 duration_in_ms=65.019
2019-05-03 14:28:00,497 [salt.state       :1780][INFO    ][13110] Running state [maasng.wait_for_http_code] at time 14:28:00.497072
2019-05-03 14:28:00,497 [salt.state       :1813][INFO    ][13110] Executing state module.run for [maasng.wait_for_http_code]
2019-05-03 14:28:00,498 [salt.utils.decorators:613 ][WARNING ][13110] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-03 14:28:00,504 [salt.state       :300 ][INFO    ][13110] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-05-03 14:28:00,505 [salt.state       :1951][INFO    ][13110] Completed state [maasng.wait_for_http_code] at time 14:28:00.505006 duration_in_ms=7.935
2019-05-03 14:28:00,509 [salt.state       :1780][INFO    ][13110] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 14:28:00.509525
2019-05-03 14:28:00,509 [salt.state       :1813][INFO    ][13110] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-05-03 14:28:00,510 [salt.state       :300 ][INFO    ][13110] /var/lib/maas/.setup_admin exists
2019-05-03 14:28:00,510 [salt.state       :1951][INFO    ][13110] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 14:28:00.510497 duration_in_ms=0.972
2019-05-03 14:28:00,511 [salt.state       :1780][INFO    ][13110] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:28:00.511199
2019-05-03 14:28:00,511 [salt.state       :1813][INFO    ][13110] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-03 14:28:00,512 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13110] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-03 14:28:02,295 [salt.state       :300 ][INFO    ][13110] {'pid': 13168, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-03 14:28:02,296 [salt.state       :1951][INFO    ][13110] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:28:02.296601 duration_in_ms=1785.4
2019-05-03 14:28:02,305 [salt.state       :1780][INFO    ][13110] Running state [maas_region_boot_source_resources_mirror] at time 14:28:02.305516
2019-05-03 14:28:02,307 [salt.state       :1813][INFO    ][13110] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-05-03 14:28:02,401 [salt.state       :300 ][INFO    ][13110] {'changes': {}}
2019-05-03 14:28:02,403 [salt.state       :1951][INFO    ][13110] Completed state [maas_region_boot_source_resources_mirror] at time 14:28:02.401674 duration_in_ms=96.159
2019-05-03 14:28:02,404 [salt.state       :1780][INFO    ][13110] Running state [maasng.boot_resources_import] at time 14:28:02.404820
2019-05-03 14:28:02,405 [salt.state       :1813][INFO    ][13110] Executing state module.run for [maasng.boot_resources_import]
2019-05-03 14:28:02,406 [salt.utils.decorators:613 ][WARNING ][13110] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-03 14:28:02,495 [salt.loaded.ext.module.maasng:1600][INFO    ][13110] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-05-03 14:28:07,548 [salt.loaded.ext.module.maasng:1600][INFO    ][13110] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-05-03 14:28:09,548 [salt.minion      :1308][INFO    ][10098] User sudo_ubuntu Executing command saltutil.find_job with jid 20190503142809535360
2019-05-03 14:28:09,577 [salt.minion      :1432][INFO    ][13200] Starting a new job with PID 13200
2019-05-03 14:28:09,609 [salt.minion      :1711][INFO    ][13200] Returning information for job: 20190503142809535360
2019-05-03 14:28:12,657 [salt.state       :300 ][INFO    ][13110] {'ret': True}
2019-05-03 14:28:12,657 [salt.state       :1951][INFO    ][13110] Completed state [maasng.boot_resources_import] at time 14:28:12.657685 duration_in_ms=10252.864
2019-05-03 14:28:12,659 [salt.state       :1780][INFO    ][13110] Running state [maas_region_boot_sources_selection_xenial] at time 14:28:12.659105
2019-05-03 14:28:12,659 [salt.state       :1813][INFO    ][13110] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-05-03 14:28:12,864 [salt.state       :300 ][INFO    ][13110] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-05-03 14:28:12,865 [salt.state       :1951][INFO    ][13110] Completed state [maas_region_boot_sources_selection_xenial] at time 14:28:12.864928 duration_in_ms=205.823
2019-05-03 14:28:12,868 [salt.state       :1780][INFO    ][13110] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 14:28:12.868888
2019-05-03 14:28:12,869 [salt.state       :1813][INFO    ][13110] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-05-03 14:28:12,870 [salt.utils.decorators:613 ][WARNING ][13110] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-03 14:28:12,870 [salt.loaded.ext.module.maasng:1771][INFO    ][13110] boot-sources sync initiated for ALL Rack's
2019-05-03 14:28:14,061 [salt.state       :300 ][INFO    ][13110] {'ret': True}
2019-05-03 14:28:14,061 [salt.state       :1951][INFO    ][13110] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 14:28:14.061542 duration_in_ms=1192.654
2019-05-03 14:28:14,063 [salt.state       :1780][INFO    ][13110] Running state [maas.process_maas_config] at time 14:28:14.063783
2019-05-03 14:28:14,064 [salt.state       :1813][INFO    ][13110] Executing state module.run for [maas.process_maas_config]
2019-05-03 14:28:14,064 [salt.utils.decorators:613 ][WARNING ][13110] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-03 14:28:14,065 [salt.loaded.ext.module.maas:92  ][INFO    ][13110] maasconfig name=enable_http_proxy value=True
2019-05-03 14:28:14,122 [salt.loaded.ext.module.maas:92  ][INFO    ][13110] maasconfig name=upstream_dns value=8.8.8.8
2019-05-03 14:28:14,170 [salt.loaded.ext.module.maas:92  ][INFO    ][13110] maasconfig name=commissioning_distro_series value=xenial
2019-05-03 14:28:14,229 [salt.loaded.ext.module.maas:92  ][INFO    ][13110] maasconfig name=default_osystem value=ubuntu
2019-05-03 14:28:14,292 [salt.loaded.ext.module.maas:92  ][INFO    ][13110] maasconfig name=active_discovery_interval value=600
2019-05-03 14:28:14,345 [salt.loaded.ext.module.maas:92  ][INFO    ][13110] maasconfig name=dnssec_validation value=no
2019-05-03 14:28:14,400 [salt.loaded.ext.module.maas:92  ][INFO    ][13110] maasconfig name=maas_name value=mas01
2019-05-03 14:28:14,455 [salt.loaded.ext.module.maas:92  ][INFO    ][13110] maasconfig name=network_discovery value=enabled
2019-05-03 14:28:14,564 [salt.loaded.ext.module.maas:92  ][INFO    ][13110] maasconfig name=enable_third_party_drivers value=True
2019-05-03 14:28:14,611 [salt.loaded.ext.module.maas:92  ][INFO    ][13110] maasconfig name=default_storage_layout value=lvm
2019-05-03 14:28:14,660 [salt.loaded.ext.module.maas:92  ][INFO    ][13110] maasconfig name=ntp_external_only value=True
2019-05-03 14:28:15,725 [salt.loaded.ext.module.maas:92  ][INFO    ][13110] maasconfig name=disk_erase_with_secure_erase value=False
2019-05-03 14:28:15,767 [salt.loaded.ext.module.maas:92  ][INFO    ][13110] maasconfig name=default_distro_series value=xenial
2019-05-03 14:28:15,836 [salt.loaded.ext.module.maas:92  ][INFO    ][13110] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-05-03 14:28:16,005 [salt.state       :300 ][INFO    ][13110] {'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-05-03 14:28:16,006 [salt.state       :1951][INFO    ][13110] Completed state [maas.process_maas_config] at time 14:28:16.006273 duration_in_ms=1942.483
2019-05-03 14:28:16,007 [salt.state       :1780][INFO    ][13110] Running state [pxe_admin] at time 14:28:16.007486
2019-05-03 14:28:16,008 [salt.state       :1813][INFO    ][13110] Executing state maasng.fabric_present for [pxe_admin]
2019-05-03 14:28:16,071 [salt.loaded.ext.module.maasng:945 ][INFO    ][13110] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'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'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'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'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'qcnpd6', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-03 14:28:16,131 [salt.loaded.ext.module.maasng:1008][WARNING ][13110] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-05-03 14:28:16,131 [salt.loaded.ext.module.maasng:1011][WARNING ][13110] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-05-03 14:28:16,200 [salt.state       :300 ][INFO    ][13110] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-05-03 14:28:16,201 [salt.state       :1951][INFO    ][13110] Completed state [pxe_admin] at time 14:28:16.201233 duration_in_ms=193.746
2019-05-03 14:28:16,201 [salt.state       :1780][INFO    ][13110] Running state [vlan 0] at time 14:28:16.201585
2019-05-03 14:28:16,202 [salt.state       :1813][INFO    ][13110] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-03 14:28:16,270 [salt.loaded.ext.module.maasng:945 ][INFO    ][13110] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'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'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'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'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'qcnpd6', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-03 14:28:16,378 [salt.loaded.ext.module.maasng:945 ][INFO    ][13110] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qcnpd6', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-03 14:28:16,616 [salt.loaded.ext.module.maasng:945 ][INFO    ][13110] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'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'fabric': u'fabric-0'}], u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, 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'fabric': u'fabric-2'}], u'class_type': None, u'name': u'fabric-2', u'id': 2}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qcnpd6', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': u'', u'name': u'pxe_admin', u'id': 1}]
2019-05-03 14:28:16,692 [salt.state       :300 ][INFO    ][13110] {'new': 'Vlan untagged was updated'}
2019-05-03 14:28:16,693 [salt.state       :1951][INFO    ][13110] Completed state [vlan 0] at time 14:28:16.693036 duration_in_ms=491.45
2019-05-03 14:28:16,696 [salt.state       :1780][INFO    ][13110] Running state [192.168.11.0/24] at time 14:28:16.696372
2019-05-03 14:28:16,696 [salt.state       :1813][INFO    ][13110] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-05-03 14:28:16,889 [salt.loaded.ext.module.maasng:945 ][INFO    ][13110] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'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'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'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'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/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'primary_rack': u'qcnpd6', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-03 14:28:16,890 [salt.loaded.ext.module.maasng:1235][WARNING ][13110] Ignoring parameter vlan:0
2019-05-03 14:28:16,975 [salt.state       :300 ][INFO    ][13110] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-05-03 14:28:16,975 [salt.state       :1951][INFO    ][13110] Completed state [192.168.11.0/24] at time 14:28:16.975679 duration_in_ms=279.306
2019-05-03 14:28:16,977 [salt.state       :1780][INFO    ][13110] Running state [maas_create_iprange_1] at time 14:28:16.977129
2019-05-03 14:28:16,977 [salt.state       :1813][INFO    ][13110] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-05-03 14:28:17,064 [salt.state       :300 ][INFO    ][13110] Iprange maas_create_iprange_1 already exist.
2019-05-03 14:28:17,064 [salt.state       :1951][INFO    ][13110] Completed state [maas_create_iprange_1] at time 14:28:17.064426 duration_in_ms=87.296
2019-05-03 14:28:17,064 [salt.state       :1780][INFO    ][13110] Running state [vlan 0] at time 14:28:17.064730
2019-05-03 14:28:17,065 [salt.state       :1813][INFO    ][13110] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-03 14:28:17,118 [salt.loaded.ext.module.maasng:945 ][INFO    ][13110] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'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'fabric': u'fabric-0'}], u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, 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'fabric': u'fabric-2'}], u'class_type': None, u'name': u'fabric-2', u'id': 2}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qcnpd6', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': u'', u'name': u'pxe_admin', u'id': 1}]
2019-05-03 14:28:17,240 [salt.loaded.ext.module.maasng:945 ][INFO    ][13110] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], 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'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], 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'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'qcnpd6', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-03 14:28:17,497 [salt.loaded.ext.module.maasng:945 ][INFO    ][13110] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qcnpd6', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-03 14:28:17,605 [salt.state       :300 ][INFO    ][13110] {'new': 'Vlan untagged was updated'}
2019-05-03 14:28:17,605 [salt.state       :1951][INFO    ][13110] Completed state [vlan 0] at time 14:28:17.605520 duration_in_ms=540.789
2019-05-03 14:28:17,610 [salt.state       :1780][INFO    ][13110] Running state [opnfv] at time 14:28:17.610695
2019-05-03 14:28:17,611 [salt.state       :1813][INFO    ][13110] Executing state maasng.sshkey_present for [opnfv]
2019-05-03 14:28:17,659 [salt.loaded.ext.module.maasng:1903][INFO    ][13110] [{u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b', u'keysource': u''}]
2019-05-03 14:28:17,660 [salt.state       :300 ][INFO    ][13110] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-05-03 14:28:17,660 [salt.state       :1951][INFO    ][13110] Completed state [opnfv] at time 14:28:17.660639 duration_in_ms=49.943
2019-05-03 14:28:17,664 [salt.minion      :1711][INFO    ][13110] Returning information for job: 20190503142754431165
2019-05-03 14:28:18,376 [salt.minion      :1308][INFO    ][10098] User sudo_ubuntu Executing command state.apply with jid 20190503142818362516
2019-05-03 14:28:18,394 [salt.minion      :1432][INFO    ][13635] Starting a new job with PID 13635
2019-05-03 14:28:25,675 [salt.state       :915 ][INFO    ][13635] Loading fresh modules for state activity
2019-05-03 14:28:25,772 [salt.state       :1780][INFO    ][13635] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:28:25.772375
2019-05-03 14:28:25,772 [salt.state       :1813][INFO    ][13635] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-03 14:28:25,774 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13635] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-03 14:28:27,636 [salt.state       :300 ][INFO    ][13635] {'pid': 13682, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-03 14:28:27,638 [salt.state       :1951][INFO    ][13635] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:28:27.637609 duration_in_ms=1865.233
2019-05-03 14:28:27,640 [salt.state       :1780][INFO    ][13635] Running state [maas.process_machines] at time 14:28:27.640804
2019-05-03 14:28:27,641 [salt.state       :1813][INFO    ][13635] Executing state module.run for [maas.process_machines]
2019-05-03 14:28:27,643 [salt.utils.decorators:613 ][WARNING ][13635] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-03 14:28:28,242 [salt.loaded.ext.module.maas:412 ][WARNING ][13635] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-03 14:28:28,243 [salt.loaded.ext.module.maas:92  ][INFO    ][13635] 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=yywr4b architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-03 14:28:29,460 [salt.loaded.ext.module.maas:412 ][WARNING ][13635] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-03 14:28:29,460 [salt.loaded.ext.module.maas:92  ][INFO    ][13635] 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=rapafx architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-03 14:28:30,560 [salt.loaded.ext.module.maas:412 ][WARNING ][13635] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-03 14:28:30,560 [salt.loaded.ext.module.maas:92  ][INFO    ][13635] 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=g7p6fa architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-03 14:28:31,771 [salt.loaded.ext.module.maas:412 ][WARNING ][13635] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-03 14:28:31,772 [salt.loaded.ext.module.maas:92  ][INFO    ][13635] 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=r77adn architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-03 14:28:32,928 [salt.loaded.ext.module.maas:412 ][WARNING ][13635] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-03 14:28:32,929 [salt.loaded.ext.module.maas:92  ][INFO    ][13635] 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=4yapem architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-03 14:28:33,437 [salt.minion      :1308][INFO    ][10098] User sudo_ubuntu Executing command saltutil.find_job with jid 20190503142833426681
2019-05-03 14:28:33,462 [salt.minion      :1432][INFO    ][13912] Starting a new job with PID 13912
2019-05-03 14:28:33,490 [salt.minion      :1711][INFO    ][13912] Returning information for job: 20190503142833426681
2019-05-03 14:28:34,291 [salt.state       :300 ][INFO    ][13635] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-05-03 14:28:34,291 [salt.state       :1951][INFO    ][13635] Completed state [maas.process_machines] at time 14:28:34.291531 duration_in_ms=6650.725
2019-05-03 14:28:34,295 [salt.minion      :1711][INFO    ][13635] Returning information for job: 20190503142818362516
2019-05-03 14:29:05,581 [salt.minion      :1308][INFO    ][10098] User sudo_ubuntu Executing command state.apply with jid 20190503142905573195
2019-05-03 14:29:05,612 [salt.minion      :1432][INFO    ][14171] Starting a new job with PID 14171
2019-05-03 14:29:13,537 [salt.state       :915 ][INFO    ][14171] Loading fresh modules for state activity
2019-05-03 14:29:13,643 [salt.state       :1780][INFO    ][14171] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:29:13.642862
2019-05-03 14:29:13,643 [salt.state       :1813][INFO    ][14171] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-03 14:29:13,647 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14171] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-03 14:29:15,442 [salt.state       :300 ][INFO    ][14171] {'pid': 14276, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-03 14:29:15,443 [salt.state       :1951][INFO    ][14171] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:29:15.443764 duration_in_ms=1800.902
2019-05-03 14:29:15,448 [salt.state       :1780][INFO    ][14171] Running state [maas.wait_for_machine_status] at time 14:29:15.447991
2019-05-03 14:29:15,448 [salt.state       :1813][INFO    ][14171] Executing state module.run for [maas.wait_for_machine_status]
2019-05-03 14:29:15,449 [salt.utils.decorators:613 ][WARNING ][14171] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-03 14:29:18,365 [salt.loaded.ext.module.maas:1023][INFO    ][14171] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1497.09571099s left)
2019-05-03 14:29:20,625 [salt.minion      :1308][INFO    ][10098] User sudo_ubuntu Executing command saltutil.find_job with jid 20190503142920605085
2019-05-03 14:29:20,656 [salt.minion      :1432][INFO    ][14287] Starting a new job with PID 14287
2019-05-03 14:29:20,687 [salt.minion      :1711][INFO    ][14287] Returning information for job: 20190503142920605085
2019-05-03 14:29:50,768 [salt.minion      :1308][INFO    ][10098] User sudo_ubuntu Executing command saltutil.find_job with jid 20190503142950754761
2019-05-03 14:29:50,793 [salt.minion      :1432][INFO    ][14568] Starting a new job with PID 14568
2019-05-03 14:29:50,826 [salt.minion      :1711][INFO    ][14568] Returning information for job: 20190503142950754761
2019-05-03 14:29:51,596 [salt.loaded.ext.module.maas:1023][INFO    ][14171] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1463.86460614s left)
2019-05-03 14:30:20,903 [salt.minion      :1308][INFO    ][10098] User sudo_ubuntu Executing command saltutil.find_job with jid 20190503143020895192
2019-05-03 14:30:20,926 [salt.minion      :1432][INFO    ][14678] Starting a new job with PID 14678
2019-05-03 14:30:20,961 [salt.minion      :1711][INFO    ][14678] Returning information for job: 20190503143020895192
2019-05-03 14:30:24,581 [salt.loaded.ext.module.maas:1023][INFO    ][14171] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1430.87985611s left)
2019-05-03 14:30:51,060 [salt.minion      :1308][INFO    ][10098] User sudo_ubuntu Executing command saltutil.find_job with jid 20190503143051046602
2019-05-03 14:30:51,090 [salt.minion      :1432][INFO    ][14982] Starting a new job with PID 14982
2019-05-03 14:30:51,125 [salt.minion      :1711][INFO    ][14982] Returning information for job: 20190503143051046602
2019-05-03 14:30:57,636 [salt.loaded.ext.module.maas:1023][INFO    ][14171] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1397.82481217s left)
2019-05-03 14:31:21,186 [salt.minion      :1308][INFO    ][10098] User sudo_ubuntu Executing command saltutil.find_job with jid 20190503143121166574
2019-05-03 14:31:21,205 [salt.minion      :1432][INFO    ][15096] Starting a new job with PID 15096
2019-05-03 14:31:21,238 [salt.minion      :1711][INFO    ][15096] Returning information for job: 20190503143121166574
2019-05-03 14:31:30,660 [salt.loaded.ext.module.maas:1023][INFO    ][14171] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1364.80089307s left)
2019-05-03 14:31:51,332 [salt.minion      :1308][INFO    ][10098] User sudo_ubuntu Executing command saltutil.find_job with jid 20190503143151319375
2019-05-03 14:31:51,359 [salt.minion      :1432][INFO    ][15391] Starting a new job with PID 15391
2019-05-03 14:31:51,389 [salt.minion      :1711][INFO    ][15391] Returning information for job: 20190503143151319375
2019-05-03 14:32:03,716 [salt.loaded.ext.module.maas:1023][INFO    ][14171] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1331.74546909s left)
2019-05-03 14:32:21,469 [salt.minion      :1308][INFO    ][10098] User sudo_ubuntu Executing command saltutil.find_job with jid 20190503143221456144
2019-05-03 14:32:21,497 [salt.minion      :1432][INFO    ][15527] Starting a new job with PID 15527
2019-05-03 14:32:21,524 [salt.minion      :1711][INFO    ][15527] Returning information for job: 20190503143221456144
2019-05-03 14:32:36,515 [salt.loaded.ext.module.maas:1023][INFO    ][14171] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1298.94580507s left)
2019-05-03 14:32:51,619 [salt.minion      :1308][INFO    ][10098] User sudo_ubuntu Executing command saltutil.find_job with jid 20190503143251606420
2019-05-03 14:32:51,645 [salt.minion      :1432][INFO    ][15931] Starting a new job with PID 15931
2019-05-03 14:32:51,672 [salt.minion      :1711][INFO    ][15931] Returning information for job: 20190503143251606420
2019-05-03 14:33:09,539 [salt.loaded.ext.module.maas:1023][INFO    ][14171] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1265.92216015s left)
2019-05-03 14:33:21,738 [salt.minion      :1308][INFO    ][10098] User sudo_ubuntu Executing command saltutil.find_job with jid 20190503143321723236
2019-05-03 14:33:21,764 [salt.minion      :1432][INFO    ][16027] Starting a new job with PID 16027
2019-05-03 14:33:21,795 [salt.minion      :1711][INFO    ][16027] Returning information for job: 20190503143321723236
2019-05-03 14:33:42,933 [salt.loaded.ext.module.maas:1023][INFO    ][14171] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1232.52832508s left)
2019-05-03 14:33:51,882 [salt.minion      :1308][INFO    ][10098] User sudo_ubuntu Executing command saltutil.find_job with jid 20190503143351865591
2019-05-03 14:33:51,909 [salt.minion      :1432][INFO    ][16177] Starting a new job with PID 16177
2019-05-03 14:33:51,942 [salt.minion      :1711][INFO    ][16177] Returning information for job: 20190503143351865591
2019-05-03 14:34:15,896 [salt.loaded.ext.module.maas:1023][INFO    ][14171] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1199.56506801s left)
2019-05-03 14:34:21,945 [salt.minion      :1308][INFO    ][10098] User sudo_ubuntu Executing command saltutil.find_job with jid 20190503143421929657
2019-05-03 14:34:21,972 [salt.minion      :1432][INFO    ][16264] Starting a new job with PID 16264
2019-05-03 14:34:22,003 [salt.minion      :1711][INFO    ][16264] Returning information for job: 20190503143421929657
2019-05-03 14:34:48,773 [salt.loaded.ext.module.maas:1023][INFO    ][14171] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1166.68791199s left)
2019-05-03 14:34:52,099 [salt.minion      :1308][INFO    ][10098] User sudo_ubuntu Executing command saltutil.find_job with jid 20190503143452084790
2019-05-03 14:34:52,128 [salt.minion      :1432][INFO    ][16541] Starting a new job with PID 16541
2019-05-03 14:34:52,160 [salt.minion      :1711][INFO    ][16541] Returning information for job: 20190503143452084790
2019-05-03 14:35:21,660 [salt.loaded.ext.module.maas:1023][INFO    ][14171] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1133.80136418s left)
2019-05-03 14:35:22,255 [salt.minion      :1308][INFO    ][10098] User sudo_ubuntu Executing command saltutil.find_job with jid 20190503143522239340
2019-05-03 14:35:22,280 [salt.minion      :1432][INFO    ][16639] Starting a new job with PID 16639
2019-05-03 14:35:22,308 [salt.minion      :1711][INFO    ][16639] Returning information for job: 20190503143522239340
2019-05-03 14:35:52,390 [salt.minion      :1308][INFO    ][10098] User sudo_ubuntu Executing command saltutil.find_job with jid 20190503143552383675
2019-05-03 14:35:52,414 [salt.minion      :1432][INFO    ][16760] Starting a new job with PID 16760
2019-05-03 14:35:52,446 [salt.minion      :1711][INFO    ][16760] Returning information for job: 20190503143552383675
2019-05-03 14:35:54,679 [salt.loaded.ext.module.maas:1023][INFO    ][14171] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1100.78161907s left)
2019-05-03 14:36:22,542 [salt.minion      :1308][INFO    ][10098] User sudo_ubuntu Executing command saltutil.find_job with jid 20190503143622526490
2019-05-03 14:36:22,570 [salt.minion      :1432][INFO    ][16782] Starting a new job with PID 16782
2019-05-03 14:36:22,595 [salt.minion      :1711][INFO    ][16782] Returning information for job: 20190503143622526490
2019-05-03 14:36:27,888 [salt.loaded.ext.module.maas:1023][INFO    ][14171] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1067.57311916s left)
2019-05-03 14:36:52,724 [salt.minion      :1308][INFO    ][10098] User sudo_ubuntu Executing command saltutil.find_job with jid 20190503143652704166
2019-05-03 14:36:52,752 [salt.minion      :1432][INFO    ][16915] Starting a new job with PID 16915
2019-05-03 14:36:52,784 [salt.minion      :1711][INFO    ][16915] Returning information for job: 20190503143652704166
2019-05-03 14:37:00,976 [salt.loaded.ext.module.maas:1023][INFO    ][14171] Waiting status:Ready|Deployed for machines:['kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1034.48533511s left)
2019-05-03 14:37:22,891 [salt.minion      :1308][INFO    ][10098] User sudo_ubuntu Executing command saltutil.find_job with jid 20190503143722880850
2019-05-03 14:37:22,919 [salt.minion      :1432][INFO    ][17018] Starting a new job with PID 17018
2019-05-03 14:37:22,951 [salt.minion      :1711][INFO    ][17018] Returning information for job: 20190503143722880850
2019-05-03 14:37:33,802 [salt.loaded.ext.module.maas:1023][INFO    ][14171] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1001.65925598s left)
2019-05-03 14:37:53,104 [salt.minion      :1308][INFO    ][10098] User sudo_ubuntu Executing command saltutil.find_job with jid 20190503143753089572
2019-05-03 14:37:53,130 [salt.minion      :1432][INFO    ][17271] Starting a new job with PID 17271
2019-05-03 14:37:53,157 [salt.minion      :1711][INFO    ][17271] Returning information for job: 20190503143753089572
2019-05-03 14:38:06,653 [salt.loaded.ext.module.maas:1023][INFO    ][14171] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (968.808171034s left)
2019-05-03 14:38:23,268 [salt.minion      :1308][INFO    ][10098] User sudo_ubuntu Executing command saltutil.find_job with jid 20190503143823255553
2019-05-03 14:38:23,298 [salt.minion      :1432][INFO    ][17320] Starting a new job with PID 17320
2019-05-03 14:38:23,328 [salt.minion      :1711][INFO    ][17320] Returning information for job: 20190503143823255553
2019-05-03 14:38:39,658 [salt.loaded.ext.module.maas:1023][INFO    ][14171] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (935.803035021s left)
2019-05-03 14:38:53,458 [salt.minion      :1308][INFO    ][10098] User sudo_ubuntu Executing command saltutil.find_job with jid 20190503143853446475
2019-05-03 14:38:53,484 [salt.minion      :1432][INFO    ][17404] Starting a new job with PID 17404
2019-05-03 14:38:53,514 [salt.minion      :1711][INFO    ][17404] Returning information for job: 20190503143853446475
2019-05-03 14:39:12,581 [salt.loaded.ext.module.maas:1023][INFO    ][14171] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (902.880377054s left)
2019-05-03 14:39:23,638 [salt.minion      :1308][INFO    ][10098] User sudo_ubuntu Executing command saltutil.find_job with jid 20190503143923627266
2019-05-03 14:39:23,664 [salt.minion      :1432][INFO    ][17450] Starting a new job with PID 17450
2019-05-03 14:39:23,698 [salt.minion      :1711][INFO    ][17450] Returning information for job: 20190503143923627266
2019-05-03 14:39:46,107 [salt.state       :300 ][INFO    ][14171] {'ret': True}
2019-05-03 14:39:46,108 [salt.state       :1951][INFO    ][14171] Completed state [maas.wait_for_machine_status] at time 14:39:46.108467 duration_in_ms=630660.471
2019-05-03 14:39:46,113 [salt.minion      :1711][INFO    ][14171] Returning information for job: 20190503142905573195
2019-05-03 14:39:46,795 [salt.minion      :1308][INFO    ][10098] User sudo_ubuntu Executing command state.apply with jid 20190503143946783895
2019-05-03 14:39:46,819 [salt.minion      :1432][INFO    ][17635] Starting a new job with PID 17635
2019-05-03 14:39:54,083 [salt.state       :915 ][INFO    ][17635] Loading fresh modules for state activity
2019-05-03 14:39:54,246 [salt.state       :1780][INFO    ][17635] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:39:54.246240
2019-05-03 14:39:54,246 [salt.state       :1813][INFO    ][17635] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-03 14:39:54,248 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17635] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-03 14:39:56,103 [salt.state       :300 ][INFO    ][17635] {'pid': 17651, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-03 14:39:56,104 [salt.state       :1951][INFO    ][17635] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:39:56.104246 duration_in_ms=1858.005
2019-05-03 14:39:56,108 [salt.state       :1780][INFO    ][17635] Running state [maas_machines_storage_cmp002_lvm] at time 14:39:56.108125
2019-05-03 14:39:56,108 [salt.state       :1813][INFO    ][17635] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-03 14:39:56,697 [salt.state       :300 ][INFO    ][17635] Machine cmp002 is not in Ready state.
2019-05-03 14:39:56,698 [salt.state       :1951][INFO    ][17635] Completed state [maas_machines_storage_cmp002_lvm] at time 14:39:56.698042 duration_in_ms=589.916
2019-05-03 14:39:56,698 [salt.state       :1780][INFO    ][17635] Running state [maas_machines_storage_cmp001_lvm] at time 14:39:56.698740
2019-05-03 14:39:56,699 [salt.state       :1813][INFO    ][17635] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-03 14:39:57,271 [salt.state       :300 ][INFO    ][17635] Machine cmp001 is not in Ready state.
2019-05-03 14:39:57,271 [salt.state       :1951][INFO    ][17635] Completed state [maas_machines_storage_cmp001_lvm] at time 14:39:57.271620 duration_in_ms=572.879
2019-05-03 14:39:57,275 [salt.minion      :1711][INFO    ][17635] Returning information for job: 20190503143946783895
2019-05-03 14:39:57,941 [salt.minion      :1308][INFO    ][10098] User sudo_ubuntu Executing command state.apply with jid 20190503143957924075
2019-05-03 14:39:57,969 [salt.minion      :1432][INFO    ][17667] Starting a new job with PID 17667
2019-05-03 14:39:59,033 [salt.state       :915 ][INFO    ][17667] Loading fresh modules for state activity
2019-05-03 14:39:59,139 [salt.state       :1780][INFO    ][17667] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:39:59.139793
2019-05-03 14:39:59,140 [salt.state       :1813][INFO    ][17667] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-03 14:39:59,143 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17667] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-03 14:40:00,951 [salt.state       :300 ][INFO    ][17667] {'pid': 17674, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-03 14:40:00,952 [salt.state       :1951][INFO    ][17667] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:40:00.952393 duration_in_ms=1812.602
2019-05-03 14:40:00,956 [salt.state       :1780][INFO    ][17667] Running state [maas.deploy_machines] at time 14:40:00.955897
2019-05-03 14:40:00,956 [salt.state       :1813][INFO    ][17667] Executing state module.run for [maas.deploy_machines]
2019-05-03 14:40:00,957 [salt.utils.decorators:613 ][WARNING ][17667] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-03 14:40:01,537 [salt.state       :300 ][INFO    ][17667] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-05-03 14:40:01,540 [salt.state       :1951][INFO    ][17667] Completed state [maas.deploy_machines] at time 14:40:01.540783 duration_in_ms=584.885
2019-05-03 14:40:01,545 [salt.minion      :1711][INFO    ][17667] Returning information for job: 20190503143957924075
2019-05-03 14:40:02,202 [salt.minion      :1308][INFO    ][10098] User sudo_ubuntu Executing command state.apply with jid 20190503144002191208
2019-05-03 14:40:02,229 [salt.minion      :1432][INFO    ][17683] Starting a new job with PID 17683
2019-05-03 14:40:03,304 [salt.state       :915 ][INFO    ][17683] Loading fresh modules for state activity
2019-05-03 14:40:03,423 [salt.state       :1780][INFO    ][17683] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:40:03.423734
2019-05-03 14:40:03,424 [salt.state       :1813][INFO    ][17683] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-03 14:40:03,426 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17683] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-03 14:40:05,227 [salt.state       :300 ][INFO    ][17683] {'pid': 17690, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-03 14:40:05,228 [salt.state       :1951][INFO    ][17683] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:40:05.228493 duration_in_ms=1804.757
2019-05-03 14:40:05,230 [salt.state       :1780][INFO    ][17683] Running state [maas.wait_for_machine_status] at time 14:40:05.230832
2019-05-03 14:40:05,231 [salt.state       :1813][INFO    ][17683] Executing state module.run for [maas.wait_for_machine_status]
2019-05-03 14:40:05,231 [salt.utils.decorators:613 ][WARNING ][17683] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-03 14:40:08,019 [salt.state       :300 ][INFO    ][17683] {'ret': True}
2019-05-03 14:40:08,019 [salt.state       :1951][INFO    ][17683] Completed state [maas.wait_for_machine_status] at time 14:40:08.019464 duration_in_ms=2788.632
2019-05-03 14:40:08,023 [salt.minion      :1711][INFO    ][17683] Returning information for job: 20190503144002191208
2019-05-03 15:17:24,004 [salt.utils.schedule:1377][INFO    ][10098] Running scheduled job: __mine_interval
2019-05-03 16:02:07,665 [salt.minion      :1308][INFO    ][10098] User sudo_ubuntu Executing command cp.push_dir with jid 20190503160207650532
2019-05-03 16:02:07,689 [salt.minion      :1432][INFO    ][26127] Starting a new job with PID 26127
