2019-03-08 05:22:42,621 [salt.utils.decorators:613 ][WARNING ][1970] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-08 05:22:43,446 [salt.utils.decorators:613 ][WARNING ][1970] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-08 05:22:46,057 [salt.loaded.int.states.file:2298][WARNING ][2529] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-03-08 05:23:17,633 [salt.state       :2022][WARNING ][3145] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-03-08 05:23:20,757 [salt.utils.decorators:613 ][WARNING ][3145] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-08 05:23:32,648 [salt.utils.decorators:613 ][WARNING ][3145] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-08 05:23:59,474 [salt.utils.decorators:613 ][WARNING ][3145] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-08 05:24:00,349 [salt.utils.decorators:613 ][WARNING ][3145] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-08 05:24:02,546 [salt.loaded.ext.module.maasng:1008][WARNING ][3145] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-03-08 05:24:02,546 [salt.loaded.ext.module.maasng:1011][WARNING ][3145] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-03-08 05:24:03,365 [salt.loaded.ext.module.maasng:1235][WARNING ][3145] Ignoring parameter vlan:0
2019-03-08 05:24:04,075 [salt.utils.decorators:613 ][WARNING ][3145] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-08 05:24:06,588 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5740] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-03-08 05:24:06,609 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5740] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-03-08 05:24:06,644 [salt.utils.parsers:1051][WARNING ][383] Minion received a SIGTERM. Exiting.
2019-03-08 05:24:07,655 [salt.cli.daemons :293 ][INFO    ][5815] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-03-08 05:24:07,782 [salt.cli.daemons :82  ][INFO    ][5815] Starting up the Salt Minion
2019-03-08 05:24:07,783 [salt.utils.event :1017][INFO    ][5815] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-03-08 05:24:08,840 [salt.minion      :976 ][INFO    ][5815] Creating minion process manager
2019-03-08 05:24:10,493 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][5815] Executing command ['date', '+%z'] in directory '/root'
2019-03-08 05:24:10,517 [salt.utils.schedule:568 ][INFO    ][5815] Updating job settings for scheduled job: __mine_interval
2019-03-08 05:24:10,520 [salt.minion      :1108][INFO    ][5815] Added mine.update to scheduler
2019-03-08 05:24:10,525 [salt.minion      :1975][INFO    ][5815] Minion is starting as user 'root'
2019-03-08 05:24:10,537 [salt.minion      :2336][INFO    ][5815] Minion is ready to receive requests!
2019-03-08 05:24:12,878 [salt.utils.decorators:613 ][WARNING ][5749] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-08 05:24:12,933 [salt.loaded.ext.module.maas:412 ][WARNING ][5749] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-08 05:24:14,382 [salt.loaded.ext.module.maas:412 ][WARNING ][5749] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-08 05:24:15,669 [salt.loaded.ext.module.maas:412 ][WARNING ][5749] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-08 05:24:16,774 [salt.loaded.ext.module.maas:412 ][WARNING ][5749] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-08 05:24:17,864 [salt.loaded.ext.module.maas:412 ][WARNING ][5749] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-08 05:24:50,034 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command state.apply with jid 20190308052450018292
2019-03-08 05:24:50,051 [salt.minion      :1432][INFO    ][6265] Starting a new job with PID 6265
2019-03-08 05:24:55,620 [salt.state       :915 ][INFO    ][6265] Loading fresh modules for state activity
2019-03-08 05:24:55,675 [salt.fileclient  :1219][INFO    ][6265] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-03-08 05:24:55,715 [salt.state       :1780][INFO    ][6265] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:24:55.715295
2019-03-08 05:24:55,715 [salt.state       :1813][INFO    ][6265] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-08 05:24:55,717 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6265] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-08 05:24:57,404 [salt.state       :300 ][INFO    ][6265] {'pid': 6277, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-08 05:24:57,405 [salt.state       :1951][INFO    ][6265] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:24:57.405252 duration_in_ms=1689.958
2019-03-08 05:24:57,408 [salt.state       :1780][INFO    ][6265] Running state [maas.wait_for_machine_status] at time 05:24:57.408158
2019-03-08 05:24:57,408 [salt.state       :1813][INFO    ][6265] Executing state module.run for [maas.wait_for_machine_status]
2019-03-08 05:24:57,408 [salt.utils.decorators:613 ][WARNING ][6265] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-08 05:24:58,550 [salt.loaded.ext.module.maas:1023][INFO    ][6265] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1498.86786914s left)
2019-03-08 05:25:05,124 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308052505112847
2019-03-08 05:25:05,146 [salt.minion      :1432][INFO    ][6289] Starting a new job with PID 6289
2019-03-08 05:25:05,172 [salt.minion      :1711][INFO    ][6289] Returning information for job: 20190308052505112847
2019-03-08 05:25:29,215 [salt.loaded.ext.module.maas:1023][INFO    ][6265] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.2036581s left)
2019-03-08 05:25:35,340 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308052535330643
2019-03-08 05:25:35,365 [salt.minion      :1432][INFO    ][6393] Starting a new job with PID 6393
2019-03-08 05:25:35,384 [salt.minion      :1711][INFO    ][6393] Returning information for job: 20190308052535330643
2019-03-08 05:26:00,050 [salt.loaded.ext.module.maas:1023][INFO    ][6265] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.36825395s left)
2019-03-08 05:26:05,531 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308052605520243
2019-03-08 05:26:05,552 [salt.minion      :1432][INFO    ][6417] Starting a new job with PID 6417
2019-03-08 05:26:05,573 [salt.minion      :1711][INFO    ][6417] Returning information for job: 20190308052605520243
2019-03-08 05:26:30,856 [salt.loaded.ext.module.maas:1023][INFO    ][6265] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.56182694s left)
2019-03-08 05:26:35,585 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308052635577005
2019-03-08 05:26:35,598 [salt.minion      :1432][INFO    ][6493] Starting a new job with PID 6493
2019-03-08 05:26:35,615 [salt.minion      :1711][INFO    ][6493] Returning information for job: 20190308052635577005
2019-03-08 05:27:01,968 [salt.loaded.ext.module.maas:1023][INFO    ][6265] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.45037103s left)
2019-03-08 05:27:05,611 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308052705602316
2019-03-08 05:27:05,630 [salt.minion      :1432][INFO    ][6544] Starting a new job with PID 6544
2019-03-08 05:27:05,653 [salt.minion      :1711][INFO    ][6544] Returning information for job: 20190308052705602316
2019-03-08 05:27:33,002 [salt.loaded.ext.module.maas:1023][INFO    ][6265] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.41579795s left)
2019-03-08 05:27:35,690 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308052735679918
2019-03-08 05:27:35,709 [salt.minion      :1432][INFO    ][6734] Starting a new job with PID 6734
2019-03-08 05:27:35,730 [salt.minion      :1711][INFO    ][6734] Returning information for job: 20190308052735679918
2019-03-08 05:28:04,124 [salt.loaded.ext.module.maas:1023][INFO    ][6265] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1313.29458809s left)
2019-03-08 05:28:05,765 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308052805746347
2019-03-08 05:28:05,783 [salt.minion      :1432][INFO    ][6850] Starting a new job with PID 6850
2019-03-08 05:28:05,806 [salt.minion      :1711][INFO    ][6850] Returning information for job: 20190308052805746347
2019-03-08 05:28:35,332 [salt.loaded.ext.module.maas:1023][INFO    ][6265] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1282.08577394s left)
2019-03-08 05:28:35,882 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308052835866506
2019-03-08 05:28:35,925 [salt.minion      :1432][INFO    ][7149] Starting a new job with PID 7149
2019-03-08 05:28:35,958 [salt.minion      :1711][INFO    ][7149] Returning information for job: 20190308052835866506
2019-03-08 05:29:05,974 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308052905962642
2019-03-08 05:29:05,995 [salt.minion      :1432][INFO    ][7281] Starting a new job with PID 7281
2019-03-08 05:29:06,020 [salt.minion      :1711][INFO    ][7281] Returning information for job: 20190308052905962642
2019-03-08 05:29:07,194 [salt.loaded.ext.module.maas:1023][INFO    ][6265] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1250.22402906s left)
2019-03-08 05:29:36,066 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308052936050323
2019-03-08 05:29:36,085 [salt.minion      :1432][INFO    ][7601] Starting a new job with PID 7601
2019-03-08 05:29:36,108 [salt.minion      :1711][INFO    ][7601] Returning information for job: 20190308052936050323
2019-03-08 05:29:39,142 [salt.loaded.ext.module.maas:1023][INFO    ][6265] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1218.27585697s left)
2019-03-08 05:30:06,162 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308053006145578
2019-03-08 05:30:06,181 [salt.minion      :1432][INFO    ][7824] Starting a new job with PID 7824
2019-03-08 05:30:06,200 [salt.minion      :1711][INFO    ][7824] Returning information for job: 20190308053006145578
2019-03-08 05:30:11,270 [salt.loaded.ext.module.maas:1023][INFO    ][6265] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1186.14800715s left)
2019-03-08 05:30:36,381 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308053036373148
2019-03-08 05:30:36,409 [salt.minion      :1432][INFO    ][8022] Starting a new job with PID 8022
2019-03-08 05:30:36,429 [salt.minion      :1711][INFO    ][8022] Returning information for job: 20190308053036373148
2019-03-08 05:30:43,441 [salt.loaded.ext.module.maas:1023][INFO    ][6265] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1153.97681499s left)
2019-03-08 05:31:06,601 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308053106593218
2019-03-08 05:31:06,624 [salt.minion      :1432][INFO    ][8166] Starting a new job with PID 8166
2019-03-08 05:31:06,644 [salt.minion      :1711][INFO    ][8166] Returning information for job: 20190308053106593218
2019-03-08 05:31:16,213 [salt.state       :300 ][INFO    ][6265] {'ret': True}
2019-03-08 05:31:16,213 [salt.state       :1951][INFO    ][6265] Completed state [maas.wait_for_machine_status] at time 05:31:16.213486 duration_in_ms=378805.327
2019-03-08 05:31:16,218 [salt.minion      :1711][INFO    ][6265] Returning information for job: 20190308052450018292
2019-03-08 05:31:16,773 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command state.apply with jid 20190308053116760672
2019-03-08 05:31:16,788 [salt.minion      :1432][INFO    ][8205] Starting a new job with PID 8205
2019-03-08 05:31:22,734 [salt.state       :915 ][INFO    ][8205] Loading fresh modules for state activity
2019-03-08 05:31:22,785 [salt.fileclient  :1219][INFO    ][8205] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-03-08 05:31:22,886 [salt.state       :1780][INFO    ][8205] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:31:22.886294
2019-03-08 05:31:22,886 [salt.state       :1813][INFO    ][8205] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-08 05:31:22,890 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8205] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-08 05:31:24,640 [salt.state       :300 ][INFO    ][8205] {'pid': 8300, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-08 05:31:24,641 [salt.state       :1951][INFO    ][8205] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:31:24.641312 duration_in_ms=1755.019
2019-03-08 05:31:24,643 [salt.state       :1780][INFO    ][8205] Running state [maas_machines_storage_cmp002_lvm] at time 05:31:24.643396
2019-03-08 05:31:24,643 [salt.state       :1813][INFO    ][8205] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-03-08 05:31:25,764 [salt.loaded.ext.module.maasng:610 ][INFO    ][8205] 6s638m
2019-03-08 05:31:25,764 [salt.loaded.ext.module.maasng:626 ][INFO    ][8205] sda
2019-03-08 05:31:26,322 [salt.loaded.ext.module.maasng:361 ][INFO    ][8205] 6s638m
2019-03-08 05:31:26,429 [salt.loaded.ext.module.maasng:367 ][INFO    ][8205] [{u'model': u'LOGICAL VOLUME', u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/6s638m/blockdevices/1/', u'used_size': 800106479616, u'partitions': [{u'uuid': u'b08bc780-f5c7-4d76-ac13-da8ae05e73dd', u'resource_uri': u'/MAAS/api/2.0/nodes/6s638m/blockdevices/1/partition/1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6s638m', u'filesystem': {u'uuid': u'b604e1c6-1920-45f4-9b10-4700f59be594', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 1, u'size': 800101236736}], u'tags': [u'ssd'], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'6s638m', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'type': u'physical', u'id': 1, u'size': 800109715456}, {u'model': None, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/6s638m/blockdevices/3/', u'used_size': 800097042432, u'partitions': [], u'tags': [], u'uuid': u'176f97b9-3008-4753-91cd-8b89a91f7207', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'6s638m', u'partition_table_type': None, u'filesystem': {u'uuid': u'e96cd50d-c9de-495c-9b79-45890e8c48cd', 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'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 3, u'size': 800097042432}]
2019-03-08 05:31:26,429 [salt.loaded.ext.module.maasng:632 ][INFO    ][8205] vgroot
2019-03-08 05:31:26,429 [salt.loaded.ext.module.maasng:635 ][INFO    ][8205] lvroot
2019-03-08 05:31:26,429 [salt.loaded.ext.module.maasng:639 ][INFO    ][8205] 107374182400
2019-03-08 05:31:27,024 [salt.loaded.ext.module.maasng:645 ][INFO    ][8205] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'name': u'untagged', u'primary_rack': u'ntd34q', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 19}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'name': u'untagged', u'primary_rack': u'ntd34q', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'name': u'untagged', u'primary_rack': u'ntd34q', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'6s638m', u'params': u'', u'parents': [], u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/6s638m/interfaces/4/'}, u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'status_action': u'', u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'description': u''}, u'resource_uri': u'/MAAS/api/2.0/machines/6s638m/', u'disable_ipv4': False, u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'6s638m', 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'size': 800109715456, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'uuid': u'20135bb2-cdbb-4bad-ad69-85db2a4baef5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6s638m', u'device_id': 1, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'7d478dbe-ebdf-4d2e-9994-19df83fd2aeb', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/6s638m/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'size': 800101236736}], u'id': 1, u'name': u'sda', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'6s638m', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/6s638m/blockdevices/1/'}, {u'size': 107374182400, u'uuid': u'4e939601-46ab-4357-b362-5f67858918cd', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'partitions': [], u'id': 11, u'name': u'vgroot-lvroot', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'6s638m', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'8a6f667c-0366-4b9b-85b6-272a19c7420d', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/6s638m/blockdevices/11/'}], u'status': 4, u'storage_test_status': 2, u'cpu_count': 40, u'raids': [], u'physicalblockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'uuid': None, u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'20135bb2-cdbb-4bad-ad69-85db2a4baef5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6s638m', u'device_id': 1, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'7d478dbe-ebdf-4d2e-9994-19df83fd2aeb', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/6s638m/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'size': 800101236736}], u'id': 1, u'name': u'sda', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'6s638m', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'used_size': 800106479616, u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/6s638m/blockdevices/1/'}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'6s638m', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'uuid': None, u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'20135bb2-cdbb-4bad-ad69-85db2a4baef5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6s638m', u'device_id': 1, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'7d478dbe-ebdf-4d2e-9994-19df83fd2aeb', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/6s638m/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'size': 800101236736}], u'id': 1, u'name': u'sda', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'6s638m', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'used_size': 800106479616, u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/6s638m/blockdevices/1/'}, u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'name': u'untagged', u'primary_rack': u'ntd34q', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 19}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'name': u'untagged', u'primary_rack': u'ntd34q', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'name': u'untagged', u'primary_rack': u'ntd34q', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'6s638m', u'params': u'', u'parents': [], u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/6s638m/interfaces/4/'}, {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'mac_address': u'38:ea:a7:8f:07:51', u'system_id': u'6s638m', u'params': u'', u'parents': [], u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/6s638m/interfaces/15/'}, {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'mac_address': u'38:ea:a7:8f:07:50', u'system_id': u'6s638m', u'params': u'', u'parents': [], u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/6s638m/interfaces/11/'}, {u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 20}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:48', u'system_id': u'6s638m', u'params': u'', u'parents': [], u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/6s638m/interfaces/14/'}, {u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 21}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:49', u'system_id': u'6s638m', u'params': u'', u'parents': [], u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/6s638m/interfaces/12/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'name': u'untagged', u'primary_rack': u'ntd34q', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'mode': u'link_up', u'id': 22}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'name': u'untagged', u'primary_rack': u'ntd34q', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'name': u'untagged', u'primary_rack': u'ntd34q', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.40'}], u'mac_address': u'9c:b6:54:8a:10:1c', u'system_id': u'6s638m', u'params': u'', u'parents': [], u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/6s638m/interfaces/13/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'bcaches': [], u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'uuid': u'4e939601-46ab-4357-b362-5f67858918cd', u'tags': [], u'type': u'virtual', u'partitions': [], u'id': 11, u'name': u'vgroot-lvroot', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'6s638m', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'8a6f667c-0366-4b9b-85b6-272a19c7420d', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'used_for': u'ext4 formatted filesystem mounted at /', u'resource_uri': u'/MAAS/api/2.0/nodes/6s638m/blockdevices/11/'}], u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'commissioning_status_name': u'Passed', u'current_commissioning_result_id': 2, u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'node_type_name': u'Machine'}
2019-03-08 05:31:27,025 [salt.state       :300 ][INFO    ][8205] {'new': {'storage_layout': 'lvm'}}
2019-03-08 05:31:27,025 [salt.state       :1951][INFO    ][8205] Completed state [maas_machines_storage_cmp002_lvm] at time 05:31:27.025739 duration_in_ms=2382.342
2019-03-08 05:31:27,026 [salt.state       :1780][INFO    ][8205] Running state [maas_machines_storage_cmp001_lvm] at time 05:31:27.026076
2019-03-08 05:31:27,026 [salt.state       :1813][INFO    ][8205] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-03-08 05:31:28,114 [salt.loaded.ext.module.maasng:610 ][INFO    ][8205] fttfy3
2019-03-08 05:31:28,114 [salt.loaded.ext.module.maasng:626 ][INFO    ][8205] sda
2019-03-08 05:31:28,677 [salt.loaded.ext.module.maasng:361 ][INFO    ][8205] fttfy3
2019-03-08 05:31:28,769 [salt.loaded.ext.module.maasng:367 ][INFO    ][8205] [{u'model': u'LOGICAL VOLUME', u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/fttfy3/blockdevices/2/', u'used_size': 800106479616, u'partitions': [{u'uuid': u'a6d4b7e7-99cf-4523-ac6e-0ef29fa97e8f', u'resource_uri': u'/MAAS/api/2.0/nodes/fttfy3/blockdevices/2/partition/2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'fttfy3', u'filesystem': {u'uuid': u'ef9bc2ad-e93b-49dc-b1d0-d062bb8e20e6', 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'tags': [u'ssd'], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'fttfy3', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'type': u'physical', u'id': 2, u'size': 800109715456}, {u'model': None, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/fttfy3/blockdevices/4/', u'used_size': 800097042432, u'partitions': [], u'tags': [], u'uuid': u'e55fd4e6-f378-461f-a65b-f3f5fca0f83e', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'fttfy3', u'partition_table_type': None, u'filesystem': {u'uuid': u'bf2f2deb-3f5b-4f6e-8044-8bca8de40592', 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'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 4, u'size': 800097042432}]
2019-03-08 05:31:28,770 [salt.loaded.ext.module.maasng:632 ][INFO    ][8205] vgroot
2019-03-08 05:31:28,770 [salt.loaded.ext.module.maasng:635 ][INFO    ][8205] lvroot
2019-03-08 05:31:28,770 [salt.loaded.ext.module.maasng:639 ][INFO    ][8205] 107374182400
2019-03-08 05:31:29,385 [salt.loaded.ext.module.maasng:645 ][INFO    ][8205] {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'testing_status_name': u'Passed', u'ip_addresses': [u'192.168.11.39', u'192.168.11.44'], u'cpu_count': 40, u'power_type': u'ipmi', u'hwe_kernel': u'', u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ntd34q', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'id': 25, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ntd34q', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ntd34q', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'system_id': u'fttfy3', u'mac_address': u'9c:b6:54:8a:95:a0', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/fttfy3/interfaces/5/'}, u'fqdn': u'cmp001.maas', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'zone': {u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default', u'description': u''}, u'resource_uri': u'/MAAS/api/2.0/machines/fttfy3/', 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'fttfy3', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'disable_ipv4': False, u'blockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/fttfy3/blockdevices/2/', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'840ddd0b-62b1-439e-9abf-f2636e2eeb90', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'fttfy3', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'b632becb-a25d-4bb4-86cb-cca678386273', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/fttfy3/blockdevices/2/partition/7'}], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'fttfy3', 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'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, {u'resource_uri': u'/MAAS/api/2.0/nodes/fttfy3/blockdevices/12/', u'uuid': u'9e0e00cf-d2ee-4145-93a0-4a7f854c99c7', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'fttfy3', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'dee6b949-04d6-493e-8026-3fff6d5f74b7', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 12, u'serial': None, u'size': 107374182400}], u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'fttfy3', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'resource_uri': u'/MAAS/api/2.0/nodes/fttfy3/blockdevices/2/', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'840ddd0b-62b1-439e-9abf-f2636e2eeb90', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'fttfy3', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'b632becb-a25d-4bb4-86cb-cca678386273', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/fttfy3/blockdevices/2/partition/7'}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'fttfy3', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, u'current_testing_result_id': 5, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/fttfy3/blockdevices/2/', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'840ddd0b-62b1-439e-9abf-f2636e2eeb90', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'fttfy3', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'b632becb-a25d-4bb4-86cb-cca678386273', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/fttfy3/blockdevices/2/partition/7'}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'fttfy3', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}], u'netboot': True, u'osystem': u'', u'node_type': 0, u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/fttfy3/blockdevices/12/', u'uuid': u'9e0e00cf-d2ee-4145-93a0-4a7f854c99c7', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'dee6b949-04d6-493e-8026-3fff6d5f74b7', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'fttfy3', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 12, u'serial': None, u'size': 107374182400}], u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ntd34q', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'id': 25, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ntd34q', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ntd34q', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'system_id': u'fttfy3', u'mac_address': u'9c:b6:54:8a:95:a0', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/fttfy3/interfaces/5/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'id': 16, u'discovered': None, u'parents': [], u'system_id': u'fttfy3', u'mac_address': u'38:ea:a7:8f:1f:d4', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/fttfy3/interfaces/16/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'id': 20, u'discovered': None, u'parents': [], u'system_id': u'fttfy3', u'mac_address': u'38:ea:a7:8f:1f:d5', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/fttfy3/interfaces/20/'}, {u'name': u'ens2f1', u'links': [{u'id': 26, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'id': 17, u'discovered': None, u'parents': [], u'system_id': u'fttfy3', u'mac_address': u'38:ea:a7:8f:52:cd', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/fttfy3/interfaces/17/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ntd34q', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 27, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ntd34q', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'id': 18, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ntd34q', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.44'}], u'parents': [], u'system_id': u'fttfy3', u'mac_address': u'9c:b6:54:8a:95:a4', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/fttfy3/interfaces/18/'}, {u'name': u'ens2f0', u'links': [{u'id': 28, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'id': 19, u'discovered': None, u'parents': [], u'system_id': u'fttfy3', u'mac_address': u'38:ea:a7:8f:52:cc', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/fttfy3/interfaces/19/'}], u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2019-03-08 05:31:29,388 [salt.state       :300 ][INFO    ][8205] {'new': {'storage_layout': 'lvm'}}
2019-03-08 05:31:29,388 [salt.state       :1951][INFO    ][8205] Completed state [maas_machines_storage_cmp001_lvm] at time 05:31:29.388781 duration_in_ms=2362.703
2019-03-08 05:31:29,393 [salt.minion      :1711][INFO    ][8205] Returning information for job: 20190308053116760672
2019-03-08 05:31:29,934 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command state.apply with jid 20190308053129920772
2019-03-08 05:31:29,951 [salt.minion      :1432][INFO    ][8330] Starting a new job with PID 8330
2019-03-08 05:31:30,653 [salt.state       :915 ][INFO    ][8330] Loading fresh modules for state activity
2019-03-08 05:31:30,704 [salt.fileclient  :1219][INFO    ][8330] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-03-08 05:31:30,750 [salt.state       :1780][INFO    ][8330] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:31:30.750500
2019-03-08 05:31:30,750 [salt.state       :1813][INFO    ][8330] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-08 05:31:30,752 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8330] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-08 05:31:32,455 [salt.state       :300 ][INFO    ][8330] {'pid': 8337, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-08 05:31:32,456 [salt.state       :1951][INFO    ][8330] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:31:32.456440 duration_in_ms=1705.94
2019-03-08 05:31:32,457 [salt.state       :1780][INFO    ][8330] Running state [maas.deploy_machines] at time 05:31:32.457932
2019-03-08 05:31:32,458 [salt.state       :1813][INFO    ][8330] Executing state module.run for [maas.deploy_machines]
2019-03-08 05:31:32,458 [salt.utils.decorators:613 ][WARNING ][8330] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-08 05:31:33,032 [salt.loaded.ext.module.maas:684 ][INFO    ][8330] deploymachines hwe_kernel=hwe-16.04 system_id=6s638m distro_series=xenial
2019-03-08 05:31:35,369 [salt.loaded.ext.module.maas:684 ][INFO    ][8330] deploymachines hwe_kernel=hwe-16.04 system_id=fttfy3 distro_series=xenial
2019-03-08 05:31:37,810 [salt.loaded.ext.module.maas:684 ][INFO    ][8330] deploymachines hwe_kernel=hwe-16.04 system_id=qhxygp distro_series=xenial
2019-03-08 05:31:40,085 [salt.loaded.ext.module.maas:684 ][INFO    ][8330] deploymachines hwe_kernel=hwe-16.04 system_id=n4p7kb distro_series=xenial
2019-03-08 05:31:42,403 [salt.loaded.ext.module.maas:684 ][INFO    ][8330] deploymachines hwe_kernel=hwe-16.04 system_id=4qcpsg distro_series=xenial
2019-03-08 05:31:44,683 [salt.state       :300 ][INFO    ][8330] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-03-08 05:31:44,684 [salt.state       :1951][INFO    ][8330] Completed state [maas.deploy_machines] at time 05:31:44.684032 duration_in_ms=12226.099
2019-03-08 05:31:44,686 [salt.minion      :1711][INFO    ][8330] Returning information for job: 20190308053129920772
2019-03-08 05:31:45,237 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command state.apply with jid 20190308053145222678
2019-03-08 05:31:45,259 [salt.minion      :1432][INFO    ][8611] Starting a new job with PID 8611
2019-03-08 05:31:50,893 [salt.state       :915 ][INFO    ][8611] Loading fresh modules for state activity
2019-03-08 05:31:50,956 [salt.fileclient  :1219][INFO    ][8611] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-03-08 05:31:51,000 [salt.state       :1780][INFO    ][8611] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:31:51.000834
2019-03-08 05:31:51,001 [salt.state       :1813][INFO    ][8611] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-08 05:31:51,002 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8611] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-08 05:31:52,704 [salt.state       :300 ][INFO    ][8611] {'pid': 8632, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-08 05:31:52,705 [salt.state       :1951][INFO    ][8611] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:31:52.705305 duration_in_ms=1704.47
2019-03-08 05:31:52,708 [salt.state       :1780][INFO    ][8611] Running state [maas.wait_for_machine_status] at time 05:31:52.708710
2019-03-08 05:31:52,709 [salt.state       :1813][INFO    ][8611] Executing state module.run for [maas.wait_for_machine_status]
2019-03-08 05:31:52,709 [salt.utils.decorators:613 ][WARNING ][8611] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-08 05:31:55,569 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.14739299s left)
2019-03-08 05:32:00,299 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308053200285574
2019-03-08 05:32:00,314 [salt.minion      :1432][INFO    ][8651] Starting a new job with PID 8651
2019-03-08 05:32:00,336 [salt.minion      :1711][INFO    ][8651] Returning information for job: 20190308053200285574
2019-03-08 05:32:28,409 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.30731511s left)
2019-03-08 05:32:30,343 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308053230331064
2019-03-08 05:32:30,361 [salt.minion      :1432][INFO    ][8729] Starting a new job with PID 8729
2019-03-08 05:32:30,383 [salt.minion      :1711][INFO    ][8729] Returning information for job: 20190308053230331064
2019-03-08 05:33:00,397 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308053300388645
2019-03-08 05:33:00,409 [salt.minion      :1432][INFO    ][8753] Starting a new job with PID 8753
2019-03-08 05:33:00,431 [salt.minion      :1711][INFO    ][8753] Returning information for job: 20190308053300388645
2019-03-08 05:33:01,237 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.47934008s left)
2019-03-08 05:33:30,438 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308053330422435
2019-03-08 05:33:30,456 [salt.minion      :1432][INFO    ][8842] Starting a new job with PID 8842
2019-03-08 05:33:30,477 [salt.minion      :1711][INFO    ][8842] Returning information for job: 20190308053330422435
2019-03-08 05:33:34,105 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2148.61209106s left)
2019-03-08 05:34:00,491 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308053400480520
2019-03-08 05:34:00,510 [salt.minion      :1432][INFO    ][8869] Starting a new job with PID 8869
2019-03-08 05:34:00,529 [salt.minion      :1711][INFO    ][8869] Returning information for job: 20190308053400480520
2019-03-08 05:34:06,904 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2115.81298804s left)
2019-03-08 05:34:30,535 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308053430522352
2019-03-08 05:34:30,559 [salt.minion      :1432][INFO    ][8965] Starting a new job with PID 8965
2019-03-08 05:34:30,578 [salt.minion      :1711][INFO    ][8965] Returning information for job: 20190308053430522352
2019-03-08 05:34:39,550 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2083.16652608s left)
2019-03-08 05:35:00,588 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308053500578172
2019-03-08 05:35:00,607 [salt.minion      :1432][INFO    ][9048] Starting a new job with PID 9048
2019-03-08 05:35:00,629 [salt.minion      :1711][INFO    ][9048] Returning information for job: 20190308053500578172
2019-03-08 05:35:12,290 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2050.42674804s left)
2019-03-08 05:35:30,801 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308053530792805
2019-03-08 05:35:30,824 [salt.minion      :1432][INFO    ][9325] Starting a new job with PID 9325
2019-03-08 05:35:30,847 [salt.minion      :1711][INFO    ][9325] Returning information for job: 20190308053530792805
2019-03-08 05:35:45,233 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2017.48336196s left)
2019-03-08 05:36:01,008 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308053600999744
2019-03-08 05:36:01,020 [salt.minion      :1432][INFO    ][9439] Starting a new job with PID 9439
2019-03-08 05:36:01,043 [salt.minion      :1711][INFO    ][9439] Returning information for job: 20190308053600999744
2019-03-08 05:36:18,236 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1984.48093915s left)
2019-03-08 05:36:31,069 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308053631058786
2019-03-08 05:36:31,089 [salt.minion      :1432][INFO    ][9702] Starting a new job with PID 9702
2019-03-08 05:36:31,115 [salt.minion      :1711][INFO    ][9702] Returning information for job: 20190308053631058786
2019-03-08 05:36:51,348 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1951.36889195s left)
2019-03-08 05:37:01,181 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308053701172428
2019-03-08 05:37:01,200 [salt.minion      :1432][INFO    ][9812] Starting a new job with PID 9812
2019-03-08 05:37:01,219 [salt.minion      :1711][INFO    ][9812] Returning information for job: 20190308053701172428
2019-03-08 05:37:25,332 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1917.38509703s left)
2019-03-08 05:37:31,334 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308053731327281
2019-03-08 05:37:31,346 [salt.minion      :1432][INFO    ][10203] Starting a new job with PID 10203
2019-03-08 05:37:31,367 [salt.minion      :1711][INFO    ][10203] Returning information for job: 20190308053731327281
2019-03-08 05:37:58,093 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1884.62391496s left)
2019-03-08 05:38:01,421 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308053801404558
2019-03-08 05:38:01,440 [salt.minion      :1432][INFO    ][10325] Starting a new job with PID 10325
2019-03-08 05:38:01,461 [salt.minion      :1711][INFO    ][10325] Returning information for job: 20190308053801404558
2019-03-08 05:38:31,039 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1851.67840505s left)
2019-03-08 05:38:31,544 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308053831531159
2019-03-08 05:38:31,565 [salt.minion      :1432][INFO    ][10568] Starting a new job with PID 10568
2019-03-08 05:38:31,584 [salt.minion      :1711][INFO    ][10568] Returning information for job: 20190308053831531159
2019-03-08 05:39:01,645 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308053901632754
2019-03-08 05:39:01,665 [salt.minion      :1432][INFO    ][10646] Starting a new job with PID 10646
2019-03-08 05:39:01,684 [salt.minion      :1711][INFO    ][10646] Returning information for job: 20190308053901632754
2019-03-08 05:39:03,811 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1818.90637112s left)
2019-03-08 05:39:31,781 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308053931766701
2019-03-08 05:39:31,800 [salt.minion      :1432][INFO    ][10843] Starting a new job with PID 10843
2019-03-08 05:39:31,822 [salt.minion      :1711][INFO    ][10843] Returning information for job: 20190308053931766701
2019-03-08 05:39:36,653 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1786.06404614s left)
2019-03-08 05:40:01,892 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308054001879861
2019-03-08 05:40:01,909 [salt.minion      :1432][INFO    ][10939] Starting a new job with PID 10939
2019-03-08 05:40:01,927 [salt.minion      :1711][INFO    ][10939] Returning information for job: 20190308054001879861
2019-03-08 05:40:09,587 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1753.12981606s left)
2019-03-08 05:40:32,053 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308054032045711
2019-03-08 05:40:32,066 [salt.minion      :1432][INFO    ][11111] Starting a new job with PID 11111
2019-03-08 05:40:32,087 [salt.minion      :1711][INFO    ][11111] Returning information for job: 20190308054032045711
2019-03-08 05:40:42,468 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1720.2491951s left)
2019-03-08 05:41:02,159 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308054102150031
2019-03-08 05:41:02,177 [salt.minion      :1432][INFO    ][11168] Starting a new job with PID 11168
2019-03-08 05:41:02,197 [salt.minion      :1711][INFO    ][11168] Returning information for job: 20190308054102150031
2019-03-08 05:41:15,309 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1687.40746498s left)
2019-03-08 05:41:32,274 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308054132260019
2019-03-08 05:41:32,297 [salt.minion      :1432][INFO    ][11394] Starting a new job with PID 11394
2019-03-08 05:41:32,318 [salt.minion      :1711][INFO    ][11394] Returning information for job: 20190308054132260019
2019-03-08 05:41:48,065 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1654.65206909s left)
2019-03-08 05:42:02,409 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308054202397982
2019-03-08 05:42:02,423 [salt.minion      :1432][INFO    ][11446] Starting a new job with PID 11446
2019-03-08 05:42:02,448 [salt.minion      :1711][INFO    ][11446] Returning information for job: 20190308054202397982
2019-03-08 05:42:20,858 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1621.85880494s left)
2019-03-08 05:42:32,576 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308054232563123
2019-03-08 05:42:32,594 [salt.minion      :1432][INFO    ][11564] Starting a new job with PID 11564
2019-03-08 05:42:32,620 [salt.minion      :1711][INFO    ][11564] Returning information for job: 20190308054232563123
2019-03-08 05:42:53,791 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1588.9263761s left)
2019-03-08 05:43:02,714 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308054302701176
2019-03-08 05:43:02,738 [salt.minion      :1432][INFO    ][11588] Starting a new job with PID 11588
2019-03-08 05:43:02,760 [salt.minion      :1711][INFO    ][11588] Returning information for job: 20190308054302701176
2019-03-08 05:43:26,669 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1556.0476141s left)
2019-03-08 05:43:32,936 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308054332921556
2019-03-08 05:43:32,960 [salt.minion      :1432][INFO    ][11844] Starting a new job with PID 11844
2019-03-08 05:43:32,982 [salt.minion      :1711][INFO    ][11844] Returning information for job: 20190308054332921556
2019-03-08 05:43:59,614 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1523.10312295s left)
2019-03-08 05:44:03,017 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308054403009086
2019-03-08 05:44:03,032 [salt.minion      :1432][INFO    ][11946] Starting a new job with PID 11946
2019-03-08 05:44:03,052 [salt.minion      :1711][INFO    ][11946] Returning information for job: 20190308054403009086
2019-03-08 05:44:32,657 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1490.06021309s left)
2019-03-08 05:44:33,044 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308054433032323
2019-03-08 05:44:33,065 [salt.minion      :1432][INFO    ][12222] Starting a new job with PID 12222
2019-03-08 05:44:33,088 [salt.minion      :1711][INFO    ][12222] Returning information for job: 20190308054433032323
2019-03-08 05:45:03,237 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308054503230009
2019-03-08 05:45:03,253 [salt.minion      :1432][INFO    ][12273] Starting a new job with PID 12273
2019-03-08 05:45:03,281 [salt.minion      :1711][INFO    ][12273] Returning information for job: 20190308054503230009
2019-03-08 05:45:05,560 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1457.15647006s left)
2019-03-08 05:45:33,417 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308054533405228
2019-03-08 05:45:33,439 [salt.minion      :1432][INFO    ][12346] Starting a new job with PID 12346
2019-03-08 05:45:33,461 [salt.minion      :1711][INFO    ][12346] Returning information for job: 20190308054533405228
2019-03-08 05:45:38,372 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1424.3451221s left)
2019-03-08 05:46:03,637 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308054603625072
2019-03-08 05:46:03,660 [salt.minion      :1432][INFO    ][12370] Starting a new job with PID 12370
2019-03-08 05:46:03,681 [salt.minion      :1711][INFO    ][12370] Returning information for job: 20190308054603625072
2019-03-08 05:46:11,161 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1391.55591607s left)
2019-03-08 05:46:33,833 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308054633822820
2019-03-08 05:46:33,850 [salt.minion      :1432][INFO    ][12448] Starting a new job with PID 12448
2019-03-08 05:46:33,875 [salt.minion      :1711][INFO    ][12448] Returning information for job: 20190308054633822820
2019-03-08 05:46:43,865 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1358.85185003s left)
2019-03-08 05:47:03,853 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308054703843257
2019-03-08 05:47:03,872 [salt.minion      :1432][INFO    ][12477] Starting a new job with PID 12477
2019-03-08 05:47:03,895 [salt.minion      :1711][INFO    ][12477] Returning information for job: 20190308054703843257
2019-03-08 05:47:16,638 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1326.07853603s left)
2019-03-08 05:47:34,074 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308054734061120
2019-03-08 05:47:34,093 [salt.minion      :1432][INFO    ][12555] Starting a new job with PID 12555
2019-03-08 05:47:34,115 [salt.minion      :1711][INFO    ][12555] Returning information for job: 20190308054734061120
2019-03-08 05:47:49,478 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1293.23905802s left)
2019-03-08 05:48:04,110 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308054804102396
2019-03-08 05:48:04,135 [salt.minion      :1432][INFO    ][12580] Starting a new job with PID 12580
2019-03-08 05:48:04,161 [salt.minion      :1711][INFO    ][12580] Returning information for job: 20190308054804102396
2019-03-08 05:48:22,379 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1260.33750105s left)
2019-03-08 05:48:34,142 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308054834130532
2019-03-08 05:48:34,162 [salt.minion      :1432][INFO    ][12660] Starting a new job with PID 12660
2019-03-08 05:48:34,188 [salt.minion      :1711][INFO    ][12660] Returning information for job: 20190308054834130532
2019-03-08 05:48:54,997 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1227.72005606s left)
2019-03-08 05:49:04,200 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308054904185872
2019-03-08 05:49:04,220 [salt.minion      :1432][INFO    ][12692] Starting a new job with PID 12692
2019-03-08 05:49:04,243 [salt.minion      :1711][INFO    ][12692] Returning information for job: 20190308054904185872
2019-03-08 05:49:27,703 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1195.01345706s left)
2019-03-08 05:49:34,264 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308054934256636
2019-03-08 05:49:34,274 [salt.minion      :1432][INFO    ][12770] Starting a new job with PID 12770
2019-03-08 05:49:34,295 [salt.minion      :1711][INFO    ][12770] Returning information for job: 20190308054934256636
2019-03-08 05:50:00,437 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1162.27970505s left)
2019-03-08 05:50:04,340 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308055004329237
2019-03-08 05:50:04,355 [salt.minion      :1432][INFO    ][12797] Starting a new job with PID 12797
2019-03-08 05:50:04,380 [salt.minion      :1711][INFO    ][12797] Returning information for job: 20190308055004329237
2019-03-08 05:50:33,184 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1129.53266096s left)
2019-03-08 05:50:34,441 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308055034430051
2019-03-08 05:50:34,460 [salt.minion      :1432][INFO    ][12875] Starting a new job with PID 12875
2019-03-08 05:50:34,482 [salt.minion      :1711][INFO    ][12875] Returning information for job: 20190308055034430051
2019-03-08 05:51:04,520 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308055104509948
2019-03-08 05:51:04,535 [salt.minion      :1432][INFO    ][12898] Starting a new job with PID 12898
2019-03-08 05:51:04,557 [salt.minion      :1711][INFO    ][12898] Returning information for job: 20190308055104509948
2019-03-08 05:51:05,995 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1096.72154403s left)
2019-03-08 05:51:34,651 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308055134643189
2019-03-08 05:51:34,668 [salt.minion      :1432][INFO    ][12971] Starting a new job with PID 12971
2019-03-08 05:51:34,692 [salt.minion      :1711][INFO    ][12971] Returning information for job: 20190308055134643189
2019-03-08 05:51:38,804 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1063.91279006s left)
2019-03-08 05:52:04,757 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308055204743379
2019-03-08 05:52:04,781 [salt.minion      :1432][INFO    ][12996] Starting a new job with PID 12996
2019-03-08 05:52:04,804 [salt.minion      :1711][INFO    ][12996] Returning information for job: 20190308055204743379
2019-03-08 05:52:11,466 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1031.25068903s left)
2019-03-08 05:52:34,925 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308055234911558
2019-03-08 05:52:34,942 [salt.minion      :1432][INFO    ][13074] Starting a new job with PID 13074
2019-03-08 05:52:34,965 [salt.minion      :1711][INFO    ][13074] Returning information for job: 20190308055234911558
2019-03-08 05:52:44,015 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (998.701478958s left)
2019-03-08 05:53:05,060 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308055305045687
2019-03-08 05:53:05,081 [salt.minion      :1432][INFO    ][13101] Starting a new job with PID 13101
2019-03-08 05:53:05,104 [salt.minion      :1711][INFO    ][13101] Returning information for job: 20190308055305045687
2019-03-08 05:53:16,734 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (965.982810974s left)
2019-03-08 05:53:35,250 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308055335239289
2019-03-08 05:53:35,268 [salt.minion      :1432][INFO    ][13200] Starting a new job with PID 13200
2019-03-08 05:53:35,291 [salt.minion      :1711][INFO    ][13200] Returning information for job: 20190308055335239289
2019-03-08 05:53:49,388 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (933.328741074s left)
2019-03-08 05:54:05,405 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308055405390761
2019-03-08 05:54:05,423 [salt.minion      :1432][INFO    ][13229] Starting a new job with PID 13229
2019-03-08 05:54:05,444 [salt.minion      :1711][INFO    ][13229] Returning information for job: 20190308055405390761
2019-03-08 05:54:22,364 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (900.352514029s left)
2019-03-08 05:54:35,430 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308055435416201
2019-03-08 05:54:35,455 [salt.minion      :1432][INFO    ][13305] Starting a new job with PID 13305
2019-03-08 05:54:35,478 [salt.minion      :1711][INFO    ][13305] Returning information for job: 20190308055435416201
2019-03-08 05:54:55,133 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (867.584172964s left)
2019-03-08 05:55:05,640 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308055505631400
2019-03-08 05:55:05,660 [salt.minion      :1432][INFO    ][13330] Starting a new job with PID 13330
2019-03-08 05:55:05,683 [salt.minion      :1711][INFO    ][13330] Returning information for job: 20190308055505631400
2019-03-08 05:55:27,760 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (834.95631814s left)
2019-03-08 05:55:35,674 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308055535661724
2019-03-08 05:55:35,697 [salt.minion      :1432][INFO    ][13408] Starting a new job with PID 13408
2019-03-08 05:55:35,721 [salt.minion      :1711][INFO    ][13408] Returning information for job: 20190308055535661724
2019-03-08 05:56:00,533 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (802.184102058s left)
2019-03-08 05:56:05,698 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308055605691057
2019-03-08 05:56:05,712 [salt.minion      :1432][INFO    ][13433] Starting a new job with PID 13433
2019-03-08 05:56:05,733 [salt.minion      :1711][INFO    ][13433] Returning information for job: 20190308055605691057
2019-03-08 05:56:33,196 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (769.520411968s left)
2019-03-08 05:56:35,720 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308055635709897
2019-03-08 05:56:35,744 [salt.minion      :1432][INFO    ][13511] Starting a new job with PID 13511
2019-03-08 05:56:35,767 [salt.minion      :1711][INFO    ][13511] Returning information for job: 20190308055635709897
2019-03-08 05:57:05,825 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308055705814228
2019-03-08 05:57:05,842 [salt.minion      :1432][INFO    ][13538] Starting a new job with PID 13538
2019-03-08 05:57:05,863 [salt.minion      :1711][INFO    ][13538] Returning information for job: 20190308055705814228
2019-03-08 05:57:05,886 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (736.830471992s left)
2019-03-08 05:57:35,876 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308055735864166
2019-03-08 05:57:35,898 [salt.minion      :1432][INFO    ][13611] Starting a new job with PID 13611
2019-03-08 05:57:35,922 [salt.minion      :1711][INFO    ][13611] Returning information for job: 20190308055735864166
2019-03-08 05:57:38,564 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (704.153222084s left)
2019-03-08 05:58:06,005 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308055805995948
2019-03-08 05:58:06,022 [salt.minion      :1432][INFO    ][13638] Starting a new job with PID 13638
2019-03-08 05:58:06,045 [salt.minion      :1711][INFO    ][13638] Returning information for job: 20190308055805995948
2019-03-08 05:58:11,333 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (671.383577108s left)
2019-03-08 05:58:36,084 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308055836074030
2019-03-08 05:58:36,102 [salt.minion      :1432][INFO    ][13715] Starting a new job with PID 13715
2019-03-08 05:58:36,128 [salt.minion      :1711][INFO    ][13715] Returning information for job: 20190308055836074030
2019-03-08 05:58:44,008 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (638.708353043s left)
2019-03-08 05:59:06,233 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308055906220364
2019-03-08 05:59:06,257 [salt.minion      :1432][INFO    ][13742] Starting a new job with PID 13742
2019-03-08 05:59:06,277 [salt.minion      :1711][INFO    ][13742] Returning information for job: 20190308055906220364
2019-03-08 05:59:16,874 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (605.84286499s left)
2019-03-08 05:59:36,382 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308055936368410
2019-03-08 05:59:36,406 [salt.minion      :1432][INFO    ][13820] Starting a new job with PID 13820
2019-03-08 05:59:36,429 [salt.minion      :1711][INFO    ][13820] Returning information for job: 20190308055936368410
2019-03-08 05:59:49,679 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (573.037717104s left)
2019-03-08 06:00:06,593 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308060006578937
2019-03-08 06:00:06,612 [salt.minion      :1432][INFO    ][13845] Starting a new job with PID 13845
2019-03-08 06:00:06,634 [salt.minion      :1711][INFO    ][13845] Returning information for job: 20190308060006578937
2019-03-08 06:00:22,367 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (540.349539995s left)
2019-03-08 06:00:36,712 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308060036703773
2019-03-08 06:00:36,731 [salt.minion      :1432][INFO    ][13921] Starting a new job with PID 13921
2019-03-08 06:00:36,756 [salt.minion      :1711][INFO    ][13921] Returning information for job: 20190308060036703773
2019-03-08 06:00:55,024 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (507.692444086s left)
2019-03-08 06:01:06,753 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308060106745619
2019-03-08 06:01:06,765 [salt.minion      :1432][INFO    ][13950] Starting a new job with PID 13950
2019-03-08 06:01:06,786 [salt.minion      :1711][INFO    ][13950] Returning information for job: 20190308060106745619
2019-03-08 06:01:27,670 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (475.046667099s left)
2019-03-08 06:01:36,916 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308060136902280
2019-03-08 06:01:36,935 [salt.minion      :1432][INFO    ][14026] Starting a new job with PID 14026
2019-03-08 06:01:36,971 [salt.minion      :1711][INFO    ][14026] Returning information for job: 20190308060136902280
2019-03-08 06:02:00,434 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (442.282781124s left)
2019-03-08 06:02:07,001 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308060206987989
2019-03-08 06:02:07,020 [salt.minion      :1432][INFO    ][14051] Starting a new job with PID 14051
2019-03-08 06:02:07,049 [salt.minion      :1711][INFO    ][14051] Returning information for job: 20190308060206987989
2019-03-08 06:02:33,318 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (409.398733139s left)
2019-03-08 06:02:37,060 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308060237052349
2019-03-08 06:02:37,073 [salt.minion      :1432][INFO    ][14131] Starting a new job with PID 14131
2019-03-08 06:02:37,093 [salt.minion      :1711][INFO    ][14131] Returning information for job: 20190308060237052349
2019-03-08 06:03:05,948 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (376.768674135s left)
2019-03-08 06:03:07,136 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308060307124973
2019-03-08 06:03:07,153 [salt.minion      :1432][INFO    ][14156] Starting a new job with PID 14156
2019-03-08 06:03:07,178 [salt.minion      :1711][INFO    ][14156] Returning information for job: 20190308060307124973
2019-03-08 06:03:37,289 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308060337279540
2019-03-08 06:03:37,305 [salt.minion      :1432][INFO    ][14378] Starting a new job with PID 14378
2019-03-08 06:03:37,328 [salt.minion      :1711][INFO    ][14378] Returning information for job: 20190308060337279540
2019-03-08 06:03:38,746 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (343.971253157s left)
2019-03-08 06:04:07,400 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308060407384551
2019-03-08 06:04:07,421 [salt.minion      :1432][INFO    ][14401] Starting a new job with PID 14401
2019-03-08 06:04:07,446 [salt.minion      :1711][INFO    ][14401] Returning information for job: 20190308060407384551
2019-03-08 06:04:11,350 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (311.366791964s left)
2019-03-08 06:04:37,557 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308060437548647
2019-03-08 06:04:37,574 [salt.minion      :1432][INFO    ][14477] Starting a new job with PID 14477
2019-03-08 06:04:37,605 [salt.minion      :1711][INFO    ][14477] Returning information for job: 20190308060437548647
2019-03-08 06:04:44,138 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (278.579144001s left)
2019-03-08 06:05:07,695 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308060507685183
2019-03-08 06:05:07,715 [salt.minion      :1432][INFO    ][14517] Starting a new job with PID 14517
2019-03-08 06:05:07,745 [salt.minion      :1711][INFO    ][14517] Returning information for job: 20190308060507685183
2019-03-08 06:05:16,850 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (245.866660118s left)
2019-03-08 06:05:37,904 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308060537893651
2019-03-08 06:05:37,925 [salt.minion      :1432][INFO    ][14589] Starting a new job with PID 14589
2019-03-08 06:05:37,953 [salt.minion      :1711][INFO    ][14589] Returning information for job: 20190308060537893651
2019-03-08 06:05:49,592 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (213.124392986s left)
2019-03-08 06:06:08,092 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308060608080096
2019-03-08 06:06:08,110 [salt.minion      :1432][INFO    ][14623] Starting a new job with PID 14623
2019-03-08 06:06:08,141 [salt.minion      :1711][INFO    ][14623] Returning information for job: 20190308060608080096
2019-03-08 06:06:22,297 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (180.41951108s left)
2019-03-08 06:06:38,122 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308060638114771
2019-03-08 06:06:38,142 [salt.minion      :1432][INFO    ][14692] Starting a new job with PID 14692
2019-03-08 06:06:38,172 [salt.minion      :1711][INFO    ][14692] Returning information for job: 20190308060638114771
2019-03-08 06:06:54,958 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (147.758453131s left)
2019-03-08 06:07:08,305 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308060708294210
2019-03-08 06:07:08,324 [salt.minion      :1432][INFO    ][14728] Starting a new job with PID 14728
2019-03-08 06:07:08,354 [salt.minion      :1711][INFO    ][14728] Returning information for job: 20190308060708294210
2019-03-08 06:07:27,657 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (115.059580088s left)
2019-03-08 06:07:38,385 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308060738371374
2019-03-08 06:07:38,407 [salt.minion      :1432][INFO    ][14795] Starting a new job with PID 14795
2019-03-08 06:07:38,436 [salt.minion      :1711][INFO    ][14795] Returning information for job: 20190308060738371374
2019-03-08 06:08:00,361 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (82.3556330204s left)
2019-03-08 06:08:08,411 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308060808399299
2019-03-08 06:08:08,431 [salt.minion      :1432][INFO    ][14829] Starting a new job with PID 14829
2019-03-08 06:08:08,461 [salt.minion      :1711][INFO    ][14829] Returning information for job: 20190308060808399299
2019-03-08 06:08:33,285 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (49.4318029881s left)
2019-03-08 06:08:38,496 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308060838486084
2019-03-08 06:08:38,515 [salt.minion      :1432][INFO    ][14903] Starting a new job with PID 14903
2019-03-08 06:08:38,545 [salt.minion      :1711][INFO    ][14903] Returning information for job: 20190308060838486084
2019-03-08 06:09:05,962 [salt.loaded.ext.module.maas:1023][INFO    ][8611] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (16.7546651363s left)
2019-03-08 06:09:08,645 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308060908636530
2019-03-08 06:09:08,658 [salt.minion      :1432][INFO    ][14939] Starting a new job with PID 14939
2019-03-08 06:09:08,685 [salt.minion      :1711][INFO    ][14939] Returning information for job: 20190308060908636530
2019-03-08 06:09:38,591 [salt.state       :302 ][ERROR   ][8611] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-03-08 06:09:38,591 [salt.state       :1951][INFO    ][8611] Completed state [maas.wait_for_machine_status] at time 06:09:38.591521 duration_in_ms=2265882.805
2019-03-08 06:09:38,598 [salt.minion      :1711][INFO    ][8611] Returning information for job: 20190308053145222678
2019-03-08 06:09:49,764 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command pillar.get with jid 20190308060949753864
2019-03-08 06:09:49,784 [salt.minion      :1432][INFO    ][15015] Starting a new job with PID 15015
2019-03-08 06:09:49,794 [salt.minion      :1711][INFO    ][15015] Returning information for job: 20190308060949753864
2019-03-08 06:09:50,479 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command service.status with jid 20190308060950468443
2019-03-08 06:09:50,496 [salt.minion      :1432][INFO    ][15020] Starting a new job with PID 15020
2019-03-08 06:09:51,282 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][15020] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-03-08 06:09:51,322 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][15020] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-03-08 06:09:51,337 [salt.minion      :1711][INFO    ][15020] Returning information for job: 20190308060950468443
2019-03-08 06:09:52,010 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command state.apply with jid 20190308060951999687
2019-03-08 06:09:52,029 [salt.minion      :1432][INFO    ][15035] Starting a new job with PID 15035
2019-03-08 06:09:57,761 [salt.state       :915 ][INFO    ][15035] Loading fresh modules for state activity
2019-03-08 06:09:58,339 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15035] Executing command 'salt-minion --version' in directory '/root'
2019-03-08 06:09:58,688 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15035] Executing command 'salt-minion --version' in directory '/root'
2019-03-08 06:09:59,717 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15035] Executing command 'salt-minion --version' in directory '/root'
2019-03-08 06:10:00,046 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15035] Executing command 'salt-minion --version' in directory '/root'
2019-03-08 06:10:02,205 [salt.state       :1780][INFO    ][15035] Running state [salt-minion] at time 06:10:02.205874
2019-03-08 06:10:02,206 [salt.state       :1813][INFO    ][15035] Executing state pkg.installed for [salt-minion]
2019-03-08 06:10:02,207 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15035] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-03-08 06:10:02,316 [salt.state       :300 ][INFO    ][15035] All specified packages are already installed
2019-03-08 06:10:02,316 [salt.state       :1951][INFO    ][15035] Completed state [salt-minion] at time 06:10:02.316759 duration_in_ms=110.886
2019-03-08 06:10:02,317 [salt.state       :1780][INFO    ][15035] Running state [salt_minion_dependency_packages] at time 06:10:02.317128
2019-03-08 06:10:02,317 [salt.state       :1813][INFO    ][15035] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-03-08 06:10:02,327 [salt.state       :300 ][INFO    ][15035] All specified packages are already installed
2019-03-08 06:10:02,327 [salt.state       :1951][INFO    ][15035] Completed state [salt_minion_dependency_packages] at time 06:10:02.327191 duration_in_ms=10.063
2019-03-08 06:10:02,330 [salt.state       :1780][INFO    ][15035] Running state [/etc/salt/minion.d/minion.conf] at time 06:10:02.330232
2019-03-08 06:10:02,330 [salt.state       :1813][INFO    ][15035] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-03-08 06:10:02,607 [salt.state       :300 ][INFO    ][15035] File /etc/salt/minion.d/minion.conf is in the correct state
2019-03-08 06:10:02,607 [salt.state       :1951][INFO    ][15035] Completed state [/etc/salt/minion.d/minion.conf] at time 06:10:02.607556 duration_in_ms=277.324
2019-03-08 06:10:02,607 [salt.state       :1780][INFO    ][15035] Running state [python-netaddr] at time 06:10:02.607854
2019-03-08 06:10:02,608 [salt.state       :1813][INFO    ][15035] Executing state pkg.installed for [python-netaddr]
2019-03-08 06:10:02,617 [salt.state       :300 ][INFO    ][15035] All specified packages are already installed
2019-03-08 06:10:02,617 [salt.state       :1951][INFO    ][15035] Completed state [python-netaddr] at time 06:10:02.617466 duration_in_ms=9.612
2019-03-08 06:10:02,621 [salt.state       :1780][INFO    ][15035] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 06:10:02.620964
2019-03-08 06:10:02,621 [salt.state       :1813][INFO    ][15035] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-03-08 06:10:02,636 [salt.state       :300 ][INFO    ][15035] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-03-08 06:10:02,636 [salt.state       :1951][INFO    ][15035] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 06:10:02.636553 duration_in_ms=15.588
2019-03-08 06:10:02,638 [salt.state       :1780][INFO    ][15035] Running state [salt-minion] at time 06:10:02.638094
2019-03-08 06:10:02,638 [salt.state       :1813][INFO    ][15035] Executing state service.running for [salt-minion]
2019-03-08 06:10:02,639 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15035] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-03-08 06:10:02,677 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15035] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-03-08 06:10:02,695 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15035] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-03-08 06:10:02,712 [salt.state       :300 ][INFO    ][15035] The service salt-minion is already running
2019-03-08 06:10:02,712 [salt.state       :1951][INFO    ][15035] Completed state [salt-minion] at time 06:10:02.712462 duration_in_ms=74.368
2019-03-08 06:10:02,714 [salt.state       :1780][INFO    ][15035] Running state [/etc/salt/grains.d] at time 06:10:02.714650
2019-03-08 06:10:02,716 [salt.state       :1813][INFO    ][15035] Executing state file.directory for [/etc/salt/grains.d]
2019-03-08 06:10:02,718 [salt.state       :300 ][INFO    ][15035] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-03-08 06:10:02,718 [salt.state       :1951][INFO    ][15035] Completed state [/etc/salt/grains.d] at time 06:10:02.718488 duration_in_ms=3.838
2019-03-08 06:10:02,719 [salt.state       :1780][INFO    ][15035] Running state [/etc/salt/grains] at time 06:10:02.719572
2019-03-08 06:10:02,720 [salt.state       :1813][INFO    ][15035] Executing state file.managed for [/etc/salt/grains]
2019-03-08 06:10:02,720 [salt.state       :300 ][INFO    ][15035] File /etc/salt/grains exists with proper permissions. No changes made.
2019-03-08 06:10:02,721 [salt.state       :1951][INFO    ][15035] Completed state [/etc/salt/grains] at time 06:10:02.721146 duration_in_ms=1.573
2019-03-08 06:10:02,721 [salt.state       :1780][INFO    ][15035] Running state [/etc/salt/grains.d/placeholder] at time 06:10:02.721870
2019-03-08 06:10:02,722 [salt.state       :1813][INFO    ][15035] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-03-08 06:10:02,723 [salt.state       :300 ][INFO    ][15035] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-03-08 06:10:02,723 [salt.state       :1951][INFO    ][15035] Completed state [/etc/salt/grains.d/placeholder] at time 06:10:02.723732 duration_in_ms=1.862
2019-03-08 06:10:02,724 [salt.state       :1780][INFO    ][15035] Running state [/etc/salt/grains.d/sphinx] at time 06:10:02.724460
2019-03-08 06:10:02,724 [salt.state       :1813][INFO    ][15035] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-03-08 06:10:02,726 [salt.state       :300 ][INFO    ][15035] File /etc/salt/grains.d/sphinx is in the correct state
2019-03-08 06:10:02,728 [salt.state       :1951][INFO    ][15035] Completed state [/etc/salt/grains.d/sphinx] at time 06:10:02.728592 duration_in_ms=4.133
2019-03-08 06:10:02,730 [salt.state       :1780][INFO    ][15035] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 06:10:02.730625
2019-03-08 06:10:02,731 [salt.state       :1813][INFO    ][15035] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-03-08 06:10:02,731 [salt.state       :300 ][INFO    ][15035] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-03-08 06:10:02,731 [salt.state       :1951][INFO    ][15035] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 06:10:02.731652 duration_in_ms=1.027
2019-03-08 06:10:02,732 [salt.state       :1780][INFO    ][15035] Running state [/etc/salt/grains.d/dns_records] at time 06:10:02.732162
2019-03-08 06:10:02,732 [salt.state       :1813][INFO    ][15035] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-03-08 06:10:02,733 [salt.state       :300 ][INFO    ][15035] File /etc/salt/grains.d/dns_records is in the correct state
2019-03-08 06:10:02,733 [salt.state       :1951][INFO    ][15035] Completed state [/etc/salt/grains.d/dns_records] at time 06:10:02.733715 duration_in_ms=1.553
2019-03-08 06:10:02,734 [salt.state       :1780][INFO    ][15035] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 06:10:02.734658
2019-03-08 06:10:02,734 [salt.state       :1813][INFO    ][15035] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-03-08 06:10:02,735 [salt.state       :300 ][INFO    ][15035] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-03-08 06:10:02,735 [salt.state       :1951][INFO    ][15035] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 06:10:02.735490 duration_in_ms=0.833
2019-03-08 06:10:02,736 [salt.state       :1780][INFO    ][15035] Running state [/etc/salt/grains.d/salt] at time 06:10:02.735995
2019-03-08 06:10:02,736 [salt.state       :1813][INFO    ][15035] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-03-08 06:10:02,737 [salt.state       :300 ][INFO    ][15035] File /etc/salt/grains.d/salt is in the correct state
2019-03-08 06:10:02,737 [salt.state       :1951][INFO    ][15035] Completed state [/etc/salt/grains.d/salt] at time 06:10:02.737546 duration_in_ms=1.551
2019-03-08 06:10:02,738 [salt.state       :1780][INFO    ][15035] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 06:10:02.738443
2019-03-08 06:10:02,738 [salt.state       :1813][INFO    ][15035] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-03-08 06:10:02,740 [salt.state       :300 ][INFO    ][15035] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-03-08 06:10:02,740 [salt.state       :1951][INFO    ][15035] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 06:10:02.740385 duration_in_ms=1.941
2019-03-08 06:10:02,742 [salt.state       :1780][INFO    ][15035] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 06:10:02.742315
2019-03-08 06:10:02,742 [salt.state       :1813][INFO    ][15035] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-03-08 06:10:02,742 [salt.state       :300 ][INFO    ][15035] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-03-08 06:10:02,743 [salt.state       :1951][INFO    ][15035] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 06:10:02.743164 duration_in_ms=0.849
2019-03-08 06:10:02,743 [salt.state       :1780][INFO    ][15035] Running state [mine.update] at time 06:10:02.743851
2019-03-08 06:10:02,744 [salt.state       :1813][INFO    ][15035] Executing state module.wait for [mine.update]
2019-03-08 06:10:02,744 [salt.state       :300 ][INFO    ][15035] No changes made for mine.update
2019-03-08 06:10:02,744 [salt.state       :1951][INFO    ][15035] Completed state [mine.update] at time 06:10:02.744625 duration_in_ms=0.773
2019-03-08 06:10:02,744 [salt.state       :1780][INFO    ][15035] Running state [ca-certificates] at time 06:10:02.744895
2019-03-08 06:10:02,745 [salt.state       :1813][INFO    ][15035] Executing state pkg.installed for [ca-certificates]
2019-03-08 06:10:02,755 [salt.state       :300 ][INFO    ][15035] All specified packages are already installed
2019-03-08 06:10:02,756 [salt.state       :1951][INFO    ][15035] Completed state [ca-certificates] at time 06:10:02.756121 duration_in_ms=11.225
2019-03-08 06:10:02,756 [salt.state       :1780][INFO    ][15035] Running state [update-ca-certificates] at time 06:10:02.756830
2019-03-08 06:10:02,757 [salt.state       :1813][INFO    ][15035] Executing state cmd.wait for [update-ca-certificates]
2019-03-08 06:10:02,757 [salt.state       :300 ][INFO    ][15035] No changes made for update-ca-certificates
2019-03-08 06:10:02,757 [salt.state       :1951][INFO    ][15035] Completed state [update-ca-certificates] at time 06:10:02.757631 duration_in_ms=0.801
2019-03-08 06:10:02,757 [salt.state       :1780][INFO    ][15035] Running state [iptables] at time 06:10:02.757902
2019-03-08 06:10:02,758 [salt.state       :1813][INFO    ][15035] Executing state pkg.installed for [iptables]
2019-03-08 06:10:02,768 [salt.state       :300 ][INFO    ][15035] All specified packages are already installed
2019-03-08 06:10:02,768 [salt.state       :1951][INFO    ][15035] Completed state [iptables] at time 06:10:02.768600 duration_in_ms=10.698
2019-03-08 06:10:02,768 [salt.state       :1780][INFO    ][15035] Running state [iptables-persistent] at time 06:10:02.768875
2019-03-08 06:10:02,769 [salt.state       :1813][INFO    ][15035] Executing state pkg.installed for [iptables-persistent]
2019-03-08 06:10:02,778 [salt.state       :300 ][INFO    ][15035] All specified packages are already installed
2019-03-08 06:10:02,778 [salt.state       :1951][INFO    ][15035] Completed state [iptables-persistent] at time 06:10:02.778627 duration_in_ms=9.752
2019-03-08 06:10:02,779 [salt.state       :1780][INFO    ][15035] Running state [iptables_modules_v4_load] at time 06:10:02.779873
2019-03-08 06:10:02,780 [salt.state       :1813][INFO    ][15035] Executing state kmod.present for [iptables_modules_v4_load]
2019-03-08 06:10:02,780 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15035] Executing command 'lsmod' in directory '/root'
2019-03-08 06:10:02,801 [salt.state       :300 ][INFO    ][15035] Kernel modules iptable_filter, ip_tables are already present
2019-03-08 06:10:02,802 [salt.state       :1951][INFO    ][15035] Completed state [iptables_modules_v4_load] at time 06:10:02.802413 duration_in_ms=22.539
2019-03-08 06:10:02,803 [salt.state       :1780][INFO    ][15035] Running state [/etc/iptables/rules.v4] at time 06:10:02.803409
2019-03-08 06:10:02,804 [salt.state       :1813][INFO    ][15035] Executing state file.managed for [/etc/iptables/rules.v4]
2019-03-08 06:10:02,903 [salt.state       :300 ][INFO    ][15035] File /etc/iptables/rules.v4 is in the correct state
2019-03-08 06:10:02,904 [salt.state       :1951][INFO    ][15035] Completed state [/etc/iptables/rules.v4] at time 06:10:02.904037 duration_in_ms=100.628
2019-03-08 06:10:02,905 [salt.state       :1780][INFO    ][15035] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 06:10:02.905149
2019-03-08 06:10:02,905 [salt.state       :1813][INFO    ][15035] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-03-08 06:10:02,906 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15035] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-03-08 06:10:02,926 [salt.state       :300 ][INFO    ][15035] onlyif execution failed
2019-03-08 06:10:02,927 [salt.state       :1951][INFO    ][15035] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 06:10:02.926816 duration_in_ms=21.666
2019-03-08 06:10:02,929 [salt.state       :1780][INFO    ][15035] Running state [netfilter-persistent] at time 06:10:02.929359
2019-03-08 06:10:02,930 [salt.state       :1813][INFO    ][15035] Executing state service.running for [netfilter-persistent]
2019-03-08 06:10:02,933 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15035] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-03-08 06:10:02,953 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15035] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-03-08 06:10:02,974 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15035] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-03-08 06:10:02,994 [salt.state       :300 ][INFO    ][15035] The service netfilter-persistent is already running
2019-03-08 06:10:02,994 [salt.state       :1951][INFO    ][15035] Completed state [netfilter-persistent] at time 06:10:02.994649 duration_in_ms=65.29
2019-03-08 06:10:02,996 [salt.state       :1780][INFO    ][15035] Running state [iptables_extra.remove_stale_tables] at time 06:10:02.996217
2019-03-08 06:10:02,996 [salt.state       :1813][INFO    ][15035] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-03-08 06:10:02,997 [salt.state       :300 ][INFO    ][15035] No changes made for iptables_extra.remove_stale_tables
2019-03-08 06:10:02,997 [salt.state       :1951][INFO    ][15035] Completed state [iptables_extra.remove_stale_tables] at time 06:10:02.997754 duration_in_ms=1.538
2019-03-08 06:10:02,998 [salt.state       :1780][INFO    ][15035] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 06:10:02.998237
2019-03-08 06:10:02,998 [salt.state       :1813][INFO    ][15035] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-03-08 06:10:02,999 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15035] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-03-08 06:10:03,018 [salt.state       :300 ][INFO    ][15035] onlyif execution failed
2019-03-08 06:10:03,019 [salt.state       :1951][INFO    ][15035] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 06:10:03.019008 duration_in_ms=20.772
2019-03-08 06:10:03,020 [salt.state       :1780][INFO    ][15035] Running state [/etc/iptables/rules.v6] at time 06:10:03.020655
2019-03-08 06:10:03,021 [salt.state       :1813][INFO    ][15035] Executing state file.absent for [/etc/iptables/rules.v6]
2019-03-08 06:10:03,022 [salt.state       :300 ][INFO    ][15035] File /etc/iptables/rules.v6 is not present
2019-03-08 06:10:03,022 [salt.state       :1951][INFO    ][15035] Completed state [/etc/iptables/rules.v6] at time 06:10:03.022389 duration_in_ms=1.734
2019-03-08 06:10:03,023 [salt.state       :1780][INFO    ][15035] Running state [iptables_extra.flush_all] at time 06:10:03.023629
2019-03-08 06:10:03,024 [salt.state       :1813][INFO    ][15035] Executing state module.wait for [iptables_extra.flush_all]
2019-03-08 06:10:03,024 [salt.state       :300 ][INFO    ][15035] No changes made for iptables_extra.flush_all
2019-03-08 06:10:03,025 [salt.state       :1951][INFO    ][15035] Completed state [iptables_extra.flush_all] at time 06:10:03.025053 duration_in_ms=1.425
2019-03-08 06:10:03,030 [salt.minion      :1711][INFO    ][15035] Returning information for job: 20190308060951999687
2019-03-08 06:10:03,709 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command state.apply with jid 20190308061003700111
2019-03-08 06:10:03,728 [salt.minion      :1432][INFO    ][15112] Starting a new job with PID 15112
2019-03-08 06:10:04,459 [salt.state       :915 ][INFO    ][15112] Loading fresh modules for state activity
2019-03-08 06:10:05,666 [salt.state       :1780][INFO    ][15112] Running state [maas-rack-controller] at time 06:10:05.666148
2019-03-08 06:10:05,666 [salt.state       :1813][INFO    ][15112] Executing state pkg.installed for [maas-rack-controller]
2019-03-08 06:10:05,667 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15112] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-03-08 06:10:05,781 [salt.state       :300 ][INFO    ][15112] All specified packages are already installed
2019-03-08 06:10:05,781 [salt.state       :1951][INFO    ][15112] Completed state [maas-rack-controller] at time 06:10:05.781659 duration_in_ms=115.512
2019-03-08 06:10:05,782 [salt.state       :1780][INFO    ][15112] Running state [ipmitool] at time 06:10:05.782011
2019-03-08 06:10:05,782 [salt.state       :1813][INFO    ][15112] Executing state pkg.installed for [ipmitool]
2019-03-08 06:10:05,791 [salt.state       :300 ][INFO    ][15112] All specified packages are already installed
2019-03-08 06:10:05,791 [salt.state       :1951][INFO    ][15112] Completed state [ipmitool] at time 06:10:05.791728 duration_in_ms=9.718
2019-03-08 06:10:05,794 [salt.state       :1780][INFO    ][15112] Running state [/etc/maas/rackd.conf] at time 06:10:05.794590
2019-03-08 06:10:05,795 [salt.state       :1813][INFO    ][15112] Executing state file.line for [/etc/maas/rackd.conf]
2019-03-08 06:10:05,796 [salt.state       :300 ][INFO    ][15112] No changes needed to be made
2019-03-08 06:10:05,796 [salt.state       :1951][INFO    ][15112] Completed state [/etc/maas/rackd.conf] at time 06:10:05.796614 duration_in_ms=2.024
2019-03-08 06:10:05,796 [salt.state       :1780][INFO    ][15112] Running state [/etc/maas/rackd.conf] at time 06:10:05.796894
2019-03-08 06:10:05,797 [salt.state       :1813][INFO    ][15112] Executing state file.managed for [/etc/maas/rackd.conf]
2019-03-08 06:10:05,797 [salt.loaded.int.states.file:2298][WARNING ][15112] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-03-08 06:10:05,798 [salt.state       :300 ][INFO    ][15112] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-03-08 06:10:05,798 [salt.state       :1951][INFO    ][15112] Completed state [/etc/maas/rackd.conf] at time 06:10:05.798347 duration_in_ms=1.453
2019-03-08 06:10:05,799 [salt.state       :1780][INFO    ][15112] Running state [maas-rackd] at time 06:10:05.799684
2019-03-08 06:10:05,800 [salt.state       :1813][INFO    ][15112] Executing state service.running for [maas-rackd]
2019-03-08 06:10:05,800 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15112] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-03-08 06:10:05,837 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15112] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-03-08 06:10:05,851 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15112] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-03-08 06:10:05,870 [salt.state       :300 ][INFO    ][15112] The service maas-rackd is already running
2019-03-08 06:10:05,872 [salt.state       :1951][INFO    ][15112] Completed state [maas-rackd] at time 06:10:05.870757 duration_in_ms=71.072
2019-03-08 06:10:05,875 [salt.minion      :1711][INFO    ][15112] Returning information for job: 20190308061003700111
2019-03-08 06:10:06,564 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command state.apply with jid 20190308061006556386
2019-03-08 06:10:06,586 [salt.minion      :1432][INFO    ][15135] Starting a new job with PID 15135
2019-03-08 06:10:07,288 [salt.state       :915 ][INFO    ][15135] Loading fresh modules for state activity
2019-03-08 06:10:08,649 [salt.state       :1780][INFO    ][15135] Running state [maas-region-controller] at time 06:10:08.649742
2019-03-08 06:10:08,650 [salt.state       :1813][INFO    ][15135] Executing state pkg.installed for [maas-region-controller]
2019-03-08 06:10:08,650 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15135] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-03-08 06:10:08,757 [salt.state       :300 ][INFO    ][15135] All specified packages are already installed
2019-03-08 06:10:08,757 [salt.state       :1951][INFO    ][15135] Completed state [maas-region-controller] at time 06:10:08.757881 duration_in_ms=108.139
2019-03-08 06:10:08,758 [salt.state       :1780][INFO    ][15135] Running state [python-oauth] at time 06:10:08.758235
2019-03-08 06:10:08,758 [salt.state       :1813][INFO    ][15135] Executing state pkg.installed for [python-oauth]
2019-03-08 06:10:08,768 [salt.state       :300 ][INFO    ][15135] All specified packages are already installed
2019-03-08 06:10:08,768 [salt.state       :1951][INFO    ][15135] Completed state [python-oauth] at time 06:10:08.768561 duration_in_ms=10.326
2019-03-08 06:10:08,771 [salt.state       :1780][INFO    ][15135] Running state [/etc/maas/regiond.conf] at time 06:10:08.771611
2019-03-08 06:10:08,771 [salt.state       :1813][INFO    ][15135] Executing state file.replace for [/etc/maas/regiond.conf]
2019-03-08 06:10:08,776 [salt.state       :300 ][INFO    ][15135] No changes needed to be made
2019-03-08 06:10:08,777 [salt.state       :1951][INFO    ][15135] Completed state [/etc/maas/regiond.conf] at time 06:10:08.777179 duration_in_ms=5.568
2019-03-08 06:10:08,777 [salt.state       :1780][INFO    ][15135] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 06:10:08.777691
2019-03-08 06:10:08,778 [salt.state       :1813][INFO    ][15135] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-03-08 06:10:08,841 [salt.state       :300 ][INFO    ][15135] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-03-08 06:10:08,841 [salt.state       :1951][INFO    ][15135] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 06:10:08.841550 duration_in_ms=63.858
2019-03-08 06:10:08,842 [salt.state       :1780][INFO    ][15135] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:10:08.842106
2019-03-08 06:10:08,842 [salt.state       :1813][INFO    ][15135] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-03-08 06:10:08,846 [salt.state       :300 ][INFO    ][15135] No changes needed to be made
2019-03-08 06:10:08,847 [salt.state       :1951][INFO    ][15135] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:10:08.847446 duration_in_ms=5.341
2019-03-08 06:10:08,848 [salt.state       :1780][INFO    ][15135] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:10:08.847966
2019-03-08 06:10:08,848 [salt.state       :1813][INFO    ][15135] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-03-08 06:10:08,852 [salt.state       :300 ][INFO    ][15135] No changes needed to be made
2019-03-08 06:10:08,852 [salt.state       :1951][INFO    ][15135] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:10:08.852575 duration_in_ms=4.609
2019-03-08 06:10:08,853 [salt.state       :1780][INFO    ][15135] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 06:10:08.853088
2019-03-08 06:10:08,853 [salt.state       :1813][INFO    ][15135] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-03-08 06:10:08,877 [salt.state       :300 ][INFO    ][15135] No changes needed to be made
2019-03-08 06:10:08,878 [salt.state       :1951][INFO    ][15135] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 06:10:08.878114 duration_in_ms=25.026
2019-03-08 06:10:08,878 [salt.state       :1780][INFO    ][15135] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 06:10:08.878633
2019-03-08 06:10:08,879 [salt.state       :1813][INFO    ][15135] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-03-08 06:10:08,896 [salt.state       :300 ][INFO    ][15135] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-03-08 06:10:08,897 [salt.state       :1951][INFO    ][15135] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 06:10:08.896948 duration_in_ms=18.314
2019-03-08 06:10:08,898 [salt.state       :1780][INFO    ][15135] Running state [a2enmod headers] at time 06:10:08.898355
2019-03-08 06:10:08,898 [salt.state       :1813][INFO    ][15135] Executing state cmd.run for [a2enmod headers]
2019-03-08 06:10:08,899 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15135] Executing command 'a2enmod headers' in directory '/root'
2019-03-08 06:10:08,969 [salt.state       :300 ][INFO    ][15135] {'pid': 15167, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-03-08 06:10:08,969 [salt.state       :1951][INFO    ][15135] Completed state [a2enmod headers] at time 06:10:08.969740 duration_in_ms=71.384
2019-03-08 06:10:08,970 [salt.state       :1780][INFO    ][15135] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 06:10:08.970206
2019-03-08 06:10:08,970 [salt.state       :1813][INFO    ][15135] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-03-08 06:10:08,993 [salt.state       :300 ][INFO    ][15135] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-03-08 06:10:08,994 [salt.state       :1951][INFO    ][15135] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 06:10:08.993949 duration_in_ms=23.743
2019-03-08 06:10:08,994 [salt.state       :1780][INFO    ][15135] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 06:10:08.994635
2019-03-08 06:10:08,995 [salt.state       :1813][INFO    ][15135] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-03-08 06:10:09,052 [salt.state       :300 ][INFO    ][15135] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-03-08 06:10:09,052 [salt.state       :1951][INFO    ][15135] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 06:10:09.052896 duration_in_ms=58.262
2019-03-08 06:10:09,053 [salt.state       :1780][INFO    ][15135] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 06:10:09.053423
2019-03-08 06:10:09,053 [salt.state       :1813][INFO    ][15135] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-03-08 06:10:09,114 [salt.state       :300 ][INFO    ][15135] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-03-08 06:10:09,115 [salt.state       :1951][INFO    ][15135] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 06:10:09.115208 duration_in_ms=61.784
2019-03-08 06:10:09,115 [salt.state       :1780][INFO    ][15135] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 06:10:09.115768
2019-03-08 06:10:09,116 [salt.state       :1813][INFO    ][15135] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-03-08 06:10:09,199 [salt.state       :300 ][INFO    ][15135] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-03-08 06:10:09,199 [salt.state       :1951][INFO    ][15135] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 06:10:09.199669 duration_in_ms=83.901
2019-03-08 06:10:09,200 [salt.state       :1780][INFO    ][15135] Running state [/root/.pgpass] at time 06:10:09.199995
2019-03-08 06:10:09,200 [salt.state       :1813][INFO    ][15135] Executing state file.managed for [/root/.pgpass]
2019-03-08 06:10:09,238 [salt.state       :300 ][INFO    ][15135] File /root/.pgpass is in the correct state
2019-03-08 06:10:09,239 [salt.state       :1951][INFO    ][15135] Completed state [/root/.pgpass] at time 06:10:09.238826 duration_in_ms=38.83
2019-03-08 06:10:09,245 [salt.state       :1780][INFO    ][15135] Running state [maas-region syncdb --noinput] at time 06:10:09.245609
2019-03-08 06:10:09,245 [salt.state       :1813][INFO    ][15135] Executing state cmd.run for [maas-region syncdb --noinput]
2019-03-08 06:10:09,246 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15135] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-03-08 06:10:11,846 [salt.state       :300 ][INFO    ][15135] {'pid': 15180, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: sites, contenttypes, maasserver, sessions, piston3, auth, metadataserver\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-03-08 06:10:11,848 [salt.state       :1951][INFO    ][15135] Completed state [maas-region syncdb --noinput] at time 06:10:11.848375 duration_in_ms=2602.765
2019-03-08 06:10:11,848 [salt.state       :2022][WARNING ][15135] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-03-08 06:10:11,853 [salt.state       :1780][INFO    ][15135] Running state [maas-regiond] at time 06:10:11.852887
2019-03-08 06:10:11,853 [salt.state       :1813][INFO    ][15135] Executing state service.running for [maas-regiond]
2019-03-08 06:10:11,855 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15135] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-03-08 06:10:11,898 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15135] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-03-08 06:10:11,915 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15135] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-03-08 06:10:11,936 [salt.state       :300 ][INFO    ][15135] The service maas-regiond is already running
2019-03-08 06:10:11,936 [salt.state       :1951][INFO    ][15135] Completed state [maas-regiond] at time 06:10:11.936718 duration_in_ms=83.831
2019-03-08 06:10:11,940 [salt.state       :1780][INFO    ][15135] Running state [bind9] at time 06:10:11.940041
2019-03-08 06:10:11,940 [salt.state       :1813][INFO    ][15135] Executing state service.running for [bind9]
2019-03-08 06:10:11,942 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15135] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-03-08 06:10:11,962 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15135] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-03-08 06:10:11,982 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15135] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-03-08 06:10:12,000 [salt.state       :300 ][INFO    ][15135] The service bind9 is already running
2019-03-08 06:10:12,000 [salt.state       :1951][INFO    ][15135] Completed state [bind9] at time 06:10:12.000701 duration_in_ms=60.661
2019-03-08 06:10:12,005 [salt.state       :1780][INFO    ][15135] Running state [apache2] at time 06:10:12.004949
2019-03-08 06:10:12,005 [salt.state       :1813][INFO    ][15135] Executing state service.running for [apache2]
2019-03-08 06:10:12,006 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15135] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-03-08 06:10:12,029 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15135] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-03-08 06:10:12,046 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15135] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-03-08 06:10:12,070 [salt.state       :300 ][INFO    ][15135] The service apache2 is already running
2019-03-08 06:10:12,071 [salt.state       :1951][INFO    ][15135] Completed state [apache2] at time 06:10:12.071139 duration_in_ms=66.19
2019-03-08 06:10:12,072 [salt.state       :1780][INFO    ][15135] Running state [maasng.wait_for_http_code] at time 06:10:12.072869
2019-03-08 06:10:12,073 [salt.state       :1813][INFO    ][15135] Executing state module.run for [maasng.wait_for_http_code]
2019-03-08 06:10:12,074 [salt.utils.decorators:613 ][WARNING ][15135] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-08 06:10:12,200 [salt.state       :300 ][INFO    ][15135] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-03-08 06:10:12,200 [salt.state       :1951][INFO    ][15135] Completed state [maasng.wait_for_http_code] at time 06:10:12.200799 duration_in_ms=127.929
2019-03-08 06:10:12,202 [salt.state       :1780][INFO    ][15135] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 06:10:12.202171
2019-03-08 06:10:12,202 [salt.state       :1813][INFO    ][15135] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-03-08 06:10:12,203 [salt.state       :300 ][INFO    ][15135] /var/lib/maas/.setup_admin exists
2019-03-08 06:10:12,204 [salt.state       :1951][INFO    ][15135] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 06:10:12.203911 duration_in_ms=1.741
2019-03-08 06:10:12,205 [salt.state       :1780][INFO    ][15135] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:10:12.205231
2019-03-08 06:10:12,205 [salt.state       :1813][INFO    ][15135] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-08 06:10:12,208 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15135] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-08 06:10:14,044 [salt.state       :300 ][INFO    ][15135] {'pid': 15201, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-08 06:10:14,044 [salt.state       :1951][INFO    ][15135] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:10:14.044845 duration_in_ms=1839.615
2019-03-08 06:10:14,050 [salt.state       :1780][INFO    ][15135] Running state [maas_region_boot_source_resources_mirror] at time 06:10:14.050092
2019-03-08 06:10:14,050 [salt.state       :1813][INFO    ][15135] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-03-08 06:10:14,112 [salt.state       :300 ][INFO    ][15135] {'changes': {}}
2019-03-08 06:10:14,113 [salt.state       :1951][INFO    ][15135] Completed state [maas_region_boot_source_resources_mirror] at time 06:10:14.113058 duration_in_ms=62.965
2019-03-08 06:10:14,113 [salt.state       :1780][INFO    ][15135] Running state [maasng.boot_resources_import] at time 06:10:14.113744
2019-03-08 06:10:14,114 [salt.state       :1813][INFO    ][15135] Executing state module.run for [maasng.boot_resources_import]
2019-03-08 06:10:14,114 [salt.utils.decorators:613 ][WARNING ][15135] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-08 06:10:14,180 [salt.loaded.ext.module.maasng:1600][INFO    ][15135] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-03-08 06:10:19,232 [salt.loaded.ext.module.maasng:1600][INFO    ][15135] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-03-08 06:10:21,657 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308061021648475
2019-03-08 06:10:21,673 [salt.minion      :1432][INFO    ][15392] Starting a new job with PID 15392
2019-03-08 06:10:21,705 [salt.minion      :1711][INFO    ][15392] Returning information for job: 20190308061021648475
2019-03-08 06:10:24,320 [salt.state       :300 ][INFO    ][15135] {'ret': True}
2019-03-08 06:10:24,320 [salt.state       :1951][INFO    ][15135] Completed state [maasng.boot_resources_import] at time 06:10:24.320846 duration_in_ms=10207.101
2019-03-08 06:10:24,322 [salt.state       :1780][INFO    ][15135] Running state [maas_region_boot_sources_selection_xenial] at time 06:10:24.322078
2019-03-08 06:10:24,322 [salt.state       :1813][INFO    ][15135] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-03-08 06:10:24,487 [salt.state       :300 ][INFO    ][15135] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-03-08 06:10:24,487 [salt.state       :1951][INFO    ][15135] Completed state [maas_region_boot_sources_selection_xenial] at time 06:10:24.487824 duration_in_ms=165.746
2019-03-08 06:10:24,489 [salt.state       :1780][INFO    ][15135] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 06:10:24.489118
2019-03-08 06:10:24,489 [salt.state       :1813][INFO    ][15135] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-03-08 06:10:24,490 [salt.utils.decorators:613 ][WARNING ][15135] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-08 06:10:24,490 [salt.loaded.ext.module.maasng:1771][INFO    ][15135] boot-sources sync initiated for ALL Rack's
2019-03-08 06:10:25,460 [salt.state       :300 ][INFO    ][15135] {'ret': True}
2019-03-08 06:10:25,460 [salt.state       :1951][INFO    ][15135] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 06:10:25.460535 duration_in_ms=971.416
2019-03-08 06:10:25,462 [salt.state       :1780][INFO    ][15135] Running state [maas.process_maas_config] at time 06:10:25.462584
2019-03-08 06:10:25,464 [salt.state       :1813][INFO    ][15135] Executing state module.run for [maas.process_maas_config]
2019-03-08 06:10:25,464 [salt.utils.decorators:613 ][WARNING ][15135] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-08 06:10:25,465 [salt.loaded.ext.module.maas:92  ][INFO    ][15135] maasconfig name=enable_http_proxy value=True
2019-03-08 06:10:25,517 [salt.loaded.ext.module.maas:92  ][INFO    ][15135] maasconfig name=upstream_dns value=8.8.8.8
2019-03-08 06:10:26,781 [salt.loaded.ext.module.maas:92  ][INFO    ][15135] maasconfig name=commissioning_distro_series value=xenial
2019-03-08 06:10:26,836 [salt.loaded.ext.module.maas:92  ][INFO    ][15135] maasconfig name=default_osystem value=ubuntu
2019-03-08 06:10:26,884 [salt.loaded.ext.module.maas:92  ][INFO    ][15135] maasconfig name=active_discovery_interval value=600
2019-03-08 06:10:26,941 [salt.loaded.ext.module.maas:92  ][INFO    ][15135] maasconfig name=dnssec_validation value=no
2019-03-08 06:10:26,984 [salt.loaded.ext.module.maas:92  ][INFO    ][15135] maasconfig name=maas_name value=mas01
2019-03-08 06:10:27,029 [salt.loaded.ext.module.maas:92  ][INFO    ][15135] maasconfig name=network_discovery value=enabled
2019-03-08 06:10:27,115 [salt.loaded.ext.module.maas:92  ][INFO    ][15135] maasconfig name=enable_third_party_drivers value=True
2019-03-08 06:10:27,164 [salt.loaded.ext.module.maas:92  ][INFO    ][15135] maasconfig name=default_storage_layout value=lvm
2019-03-08 06:10:27,205 [salt.loaded.ext.module.maas:92  ][INFO    ][15135] maasconfig name=ntp_external_only value=True
2019-03-08 06:10:27,248 [salt.loaded.ext.module.maas:92  ][INFO    ][15135] maasconfig name=disk_erase_with_secure_erase value=False
2019-03-08 06:10:27,295 [salt.loaded.ext.module.maas:92  ][INFO    ][15135] maasconfig name=default_distro_series value=xenial
2019-03-08 06:10:27,354 [salt.loaded.ext.module.maas:92  ][INFO    ][15135] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-03-08 06:10:27,469 [salt.state       :300 ][INFO    ][15135] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-03-08 06:10:27,469 [salt.state       :1951][INFO    ][15135] Completed state [maas.process_maas_config] at time 06:10:27.469371 duration_in_ms=2006.787
2019-03-08 06:10:27,470 [salt.state       :1780][INFO    ][15135] Running state [pxe_admin] at time 06:10:27.470048
2019-03-08 06:10:27,470 [salt.state       :1813][INFO    ][15135] Executing state maasng.fabric_present for [pxe_admin]
2019-03-08 06:10:27,520 [salt.loaded.ext.module.maasng:945 ][INFO    ][15135] [{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'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ntd34q', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-03-08 06:10:27,573 [salt.loaded.ext.module.maasng:1008][WARNING ][15135] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-03-08 06:10:27,574 [salt.loaded.ext.module.maasng:1011][WARNING ][15135] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-03-08 06:10:27,681 [salt.state       :300 ][INFO    ][15135] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-03-08 06:10:27,681 [salt.state       :1951][INFO    ][15135] Completed state [pxe_admin] at time 06:10:27.681690 duration_in_ms=211.642
2019-03-08 06:10:27,682 [salt.state       :1780][INFO    ][15135] Running state [vlan 0] at time 06:10:27.682033
2019-03-08 06:10:27,682 [salt.state       :1813][INFO    ][15135] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-03-08 06:10:27,736 [salt.loaded.ext.module.maasng:945 ][INFO    ][15135] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ntd34q', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-03-08 06:10:27,837 [salt.loaded.ext.module.maasng:945 ][INFO    ][15135] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ntd34q', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-03-08 06:10:28,052 [salt.loaded.ext.module.maasng:945 ][INFO    ][15135] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'name': u'untagged', u'primary_rack': u'ntd34q', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-03-08 06:10:28,115 [salt.state       :300 ][INFO    ][15135] {'new': 'Vlan untagged was updated'}
2019-03-08 06:10:28,116 [salt.state       :1951][INFO    ][15135] Completed state [vlan 0] at time 06:10:28.116246 duration_in_ms=434.21
2019-03-08 06:10:28,117 [salt.state       :1780][INFO    ][15135] Running state [192.168.11.0/24] at time 06:10:28.117791
2019-03-08 06:10:28,118 [salt.state       :1813][INFO    ][15135] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-03-08 06:10:28,291 [salt.loaded.ext.module.maasng:945 ][INFO    ][15135] [{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'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'ntd34q', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-03-08 06:10:28,292 [salt.loaded.ext.module.maasng:1235][WARNING ][15135] Ignoring parameter vlan:0
2019-03-08 06:10:28,358 [salt.state       :300 ][INFO    ][15135] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-03-08 06:10:28,361 [salt.state       :1951][INFO    ][15135] Completed state [192.168.11.0/24] at time 06:10:28.361305 duration_in_ms=243.515
2019-03-08 06:10:28,362 [salt.state       :1780][INFO    ][15135] Running state [maas_create_iprange_1] at time 06:10:28.362149
2019-03-08 06:10:28,362 [salt.state       :1813][INFO    ][15135] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-03-08 06:10:28,417 [salt.state       :300 ][INFO    ][15135] Iprange maas_create_iprange_1 already exist.
2019-03-08 06:10:28,417 [salt.state       :1951][INFO    ][15135] Completed state [maas_create_iprange_1] at time 06:10:28.417606 duration_in_ms=55.456
2019-03-08 06:10:28,418 [salt.state       :1780][INFO    ][15135] Running state [vlan 0] at time 06:10:28.417953
2019-03-08 06:10:28,418 [salt.state       :1813][INFO    ][15135] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-03-08 06:10:28,464 [salt.loaded.ext.module.maasng:945 ][INFO    ][15135] [{u'class_type': None, 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'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'id': 0}, {u'class_type': None, 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'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'fabric-2', u'id': 2}, {u'class_type': u'', 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'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'ntd34q', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'id': 1}]
2019-03-08 06:10:28,571 [salt.loaded.ext.module.maasng:945 ][INFO    ][15135] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ntd34q', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-03-08 06:10:28,964 [salt.loaded.ext.module.maasng:945 ][INFO    ][15135] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'name': u'untagged', u'primary_rack': u'ntd34q', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-03-08 06:10:29,066 [salt.state       :300 ][INFO    ][15135] {'new': 'Vlan untagged was updated'}
2019-03-08 06:10:29,066 [salt.state       :1951][INFO    ][15135] Completed state [vlan 0] at time 06:10:29.066443 duration_in_ms=648.489
2019-03-08 06:10:29,068 [salt.state       :1780][INFO    ][15135] Running state [opnfv] at time 06:10:29.068077
2019-03-08 06:10:29,068 [salt.state       :1813][INFO    ][15135] Executing state maasng.sshkey_present for [opnfv]
2019-03-08 06:10:29,104 [salt.loaded.ext.module.maasng:1903][INFO    ][15135] [{u'keysource': u'', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCwbsLV1UY13y2+2b7H9h6zOFMrcnumZvKOz6oqAXgttZjoKci01cqGRU8OMjob/9xeAOP2Cmu7HOBpRxda00X57yYx4UidSgEtlbOi7LLj2DuvJScix+rgPAd19aukdCiTuMmyLZQPMzVrdBxqzupJ5zqS3djSCllnQ2myEwswcSBn0Dpusgt3bnwdsCgI6B6ZgL9aR9DLnyMPMVroI6ZZH7aP7syIy1sPEsiJd3smWEULgeRElsuK+4I3YKG1Z1J2n80lmtR2gVD5tuKLFRRxnbjAqzyCEXQBTUVRc13SAFrH0b5bzFaqfxY1wdQPzP+dBSA+t25KcjnpTAAtL86H', u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/'}]
2019-03-08 06:10:29,104 [salt.state       :300 ][INFO    ][15135] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCwbsLV1UY13y2+2b7H9h6zOFMrcnumZvKOz6oqAXgttZjoKci01cqGRU8OMjob/9xeAOP2Cmu7HOBpRxda00X57yYx4UidSgEtlbOi7LLj2DuvJScix+rgPAd19aukdCiTuMmyLZQPMzVrdBxqzupJ5zqS3djSCllnQ2myEwswcSBn0Dpusgt3bnwdsCgI6B6ZgL9aR9DLnyMPMVroI6ZZH7aP7syIy1sPEsiJd3smWEULgeRElsuK+4I3YKG1Z1J2n80lmtR2gVD5tuKLFRRxnbjAqzyCEXQBTUVRc13SAFrH0b5bzFaqfxY1wdQPzP+dBSA+t25KcjnpTAAtL86H already exist for user opnfv.
2019-03-08 06:10:29,105 [salt.state       :1951][INFO    ][15135] Completed state [opnfv] at time 06:10:29.105088 duration_in_ms=37.011
2019-03-08 06:10:29,105 [salt.state       :1780][INFO    ][15135] Running state [maas.process_tags] at time 06:10:29.105825
2019-03-08 06:10:29,106 [salt.state       :1813][INFO    ][15135] Executing state module.run for [maas.process_tags]
2019-03-08 06:10:29,106 [salt.utils.decorators:613 ][WARNING ][15135] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-08 06:10:29,144 [salt.loaded.ext.module.maas:92  ][INFO    ][15135] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-03-08 06:10:29,195 [salt.state       :300 ][INFO    ][15135] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-03-08 06:10:29,196 [salt.state       :1951][INFO    ][15135] Completed state [maas.process_tags] at time 06:10:29.196051 duration_in_ms=90.224
2019-03-08 06:10:29,202 [salt.minion      :1711][INFO    ][15135] Returning information for job: 20190308061006556386
2019-03-08 06:10:29,925 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command state.apply with jid 20190308061029917289
2019-03-08 06:10:29,938 [salt.minion      :1432][INFO    ][15635] Starting a new job with PID 15635
2019-03-08 06:10:35,565 [salt.state       :915 ][INFO    ][15635] Loading fresh modules for state activity
2019-03-08 06:10:35,663 [salt.state       :1780][INFO    ][15635] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:10:35.663752
2019-03-08 06:10:35,664 [salt.state       :1813][INFO    ][15635] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-08 06:10:35,665 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15635] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-08 06:10:37,501 [salt.state       :300 ][INFO    ][15635] {'pid': 15658, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-08 06:10:37,503 [salt.state       :1951][INFO    ][15635] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:10:37.502842 duration_in_ms=1839.09
2019-03-08 06:10:37,506 [salt.state       :1780][INFO    ][15635] Running state [maas.process_machines] at time 06:10:37.506652
2019-03-08 06:10:37,507 [salt.state       :1813][INFO    ][15635] Executing state module.run for [maas.process_machines]
2019-03-08 06:10:37,508 [salt.utils.decorators:613 ][WARNING ][15635] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-08 06:10:38,087 [salt.loaded.ext.module.maas:412 ][WARNING ][15635] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-08 06:10:38,088 [salt.loaded.ext.module.maas:92  ][INFO    ][15635] 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=6s638m architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-08 06:10:39,216 [salt.loaded.ext.module.maas:412 ][WARNING ][15635] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-08 06:10:39,217 [salt.loaded.ext.module.maas:92  ][INFO    ][15635] 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=fttfy3 architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-08 06:10:40,336 [salt.loaded.ext.module.maas:412 ][WARNING ][15635] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-08 06:10:40,336 [salt.loaded.ext.module.maas:92  ][INFO    ][15635] 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=qhxygp architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-08 06:10:41,537 [salt.loaded.ext.module.maas:412 ][WARNING ][15635] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-08 06:10:41,537 [salt.loaded.ext.module.maas:92  ][INFO    ][15635] 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=n4p7kb architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-08 06:10:42,653 [salt.loaded.ext.module.maas:412 ][WARNING ][15635] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-08 06:10:42,654 [salt.loaded.ext.module.maas:92  ][INFO    ][15635] 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=4qcpsg architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-08 06:10:43,722 [salt.state       :300 ][INFO    ][15635] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-03-08 06:10:43,722 [salt.state       :1951][INFO    ][15635] Completed state [maas.process_machines] at time 06:10:43.722548 duration_in_ms=6215.896
2019-03-08 06:10:43,726 [salt.minion      :1711][INFO    ][15635] Returning information for job: 20190308061029917289
2019-03-08 06:11:17,562 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command state.apply with jid 20190308061117550725
2019-03-08 06:11:17,577 [salt.minion      :1432][INFO    ][15902] Starting a new job with PID 15902
2019-03-08 06:11:23,368 [salt.state       :915 ][INFO    ][15902] Loading fresh modules for state activity
2019-03-08 06:11:23,453 [salt.state       :1780][INFO    ][15902] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:11:23.453436
2019-03-08 06:11:23,453 [salt.state       :1813][INFO    ][15902] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-08 06:11:23,457 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15902] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-08 06:11:25,265 [salt.state       :300 ][INFO    ][15902] {'pid': 15952, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-08 06:11:25,266 [salt.state       :1951][INFO    ][15902] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:11:25.266039 duration_in_ms=1812.602
2019-03-08 06:11:25,270 [salt.state       :1780][INFO    ][15902] Running state [maas.wait_for_machine_status] at time 06:11:25.270501
2019-03-08 06:11:25,271 [salt.state       :1813][INFO    ][15902] Executing state module.run for [maas.wait_for_machine_status]
2019-03-08 06:11:25,271 [salt.utils.decorators:613 ][WARNING ][15902] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-08 06:11:27,918 [salt.state       :300 ][INFO    ][15902] {'ret': True}
2019-03-08 06:11:27,919 [salt.state       :1951][INFO    ][15902] Completed state [maas.wait_for_machine_status] at time 06:11:27.919270 duration_in_ms=2648.77
2019-03-08 06:11:27,921 [salt.minion      :1711][INFO    ][15902] Returning information for job: 20190308061117550725
2019-03-08 06:11:28,688 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command state.apply with jid 20190308061128634825
2019-03-08 06:11:28,706 [salt.minion      :1432][INFO    ][15979] Starting a new job with PID 15979
2019-03-08 06:11:29,470 [salt.state       :915 ][INFO    ][15979] Loading fresh modules for state activity
2019-03-08 06:11:29,612 [salt.state       :1780][INFO    ][15979] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:11:29.612636
2019-03-08 06:11:29,613 [salt.state       :1813][INFO    ][15979] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-08 06:11:29,614 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15979] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-08 06:11:31,434 [salt.state       :300 ][INFO    ][15979] {'pid': 15986, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-08 06:11:31,435 [salt.state       :1951][INFO    ][15979] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:11:31.435666 duration_in_ms=1823.031
2019-03-08 06:11:31,439 [salt.state       :1780][INFO    ][15979] Running state [maas_machines_storage_cmp002_lvm] at time 06:11:31.439828
2019-03-08 06:11:31,440 [salt.state       :1813][INFO    ][15979] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-03-08 06:11:31,991 [salt.state       :300 ][INFO    ][15979] Machine cmp002 is not in Ready state.
2019-03-08 06:11:31,992 [salt.state       :1951][INFO    ][15979] Completed state [maas_machines_storage_cmp002_lvm] at time 06:11:31.992212 duration_in_ms=552.383
2019-03-08 06:11:31,992 [salt.state       :1780][INFO    ][15979] Running state [maas_machines_storage_cmp001_lvm] at time 06:11:31.992644
2019-03-08 06:11:31,993 [salt.state       :1813][INFO    ][15979] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-03-08 06:11:32,509 [salt.state       :300 ][INFO    ][15979] Machine cmp001 is not in Ready state.
2019-03-08 06:11:32,510 [salt.state       :1951][INFO    ][15979] Completed state [maas_machines_storage_cmp001_lvm] at time 06:11:32.510062 duration_in_ms=517.416
2019-03-08 06:11:32,514 [salt.minion      :1711][INFO    ][15979] Returning information for job: 20190308061128634825
2019-03-08 06:11:33,188 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command state.apply with jid 20190308061133173495
2019-03-08 06:11:33,210 [salt.minion      :1432][INFO    ][15996] Starting a new job with PID 15996
2019-03-08 06:11:33,919 [salt.state       :915 ][INFO    ][15996] Loading fresh modules for state activity
2019-03-08 06:11:34,013 [salt.state       :1780][INFO    ][15996] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:11:34.013329
2019-03-08 06:11:34,013 [salt.state       :1813][INFO    ][15996] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-08 06:11:34,015 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15996] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-08 06:11:35,852 [salt.state       :300 ][INFO    ][15996] {'pid': 16003, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-08 06:11:35,854 [salt.state       :1951][INFO    ][15996] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:11:35.853857 duration_in_ms=1840.528
2019-03-08 06:11:35,857 [salt.state       :1780][INFO    ][15996] Running state [maas.deploy_machines] at time 06:11:35.857222
2019-03-08 06:11:35,857 [salt.state       :1813][INFO    ][15996] Executing state module.run for [maas.deploy_machines]
2019-03-08 06:11:35,860 [salt.utils.decorators:613 ][WARNING ][15996] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-08 06:11:36,396 [salt.loaded.ext.module.maas:684 ][INFO    ][15996] deploymachines hwe_kernel=hwe-16.04 system_id=4qcpsg distro_series=xenial
2019-03-08 06:11:38,701 [salt.state       :300 ][INFO    ][15996] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-03-08 06:11:38,701 [salt.state       :1951][INFO    ][15996] Completed state [maas.deploy_machines] at time 06:11:38.701623 duration_in_ms=2844.402
2019-03-08 06:11:38,707 [salt.minion      :1711][INFO    ][15996] Returning information for job: 20190308061133173495
2019-03-08 06:11:39,398 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command state.apply with jid 20190308061139386658
2019-03-08 06:11:39,420 [salt.minion      :1432][INFO    ][16067] Starting a new job with PID 16067
2019-03-08 06:11:43,394 [salt.state       :915 ][INFO    ][16067] Loading fresh modules for state activity
2019-03-08 06:11:43,482 [salt.state       :1780][INFO    ][16067] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:11:43.482657
2019-03-08 06:11:43,483 [salt.state       :1813][INFO    ][16067] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-08 06:11:43,485 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16067] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-08 06:11:45,317 [salt.state       :300 ][INFO    ][16067] {'pid': 16077, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-08 06:11:45,319 [salt.state       :1951][INFO    ][16067] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:11:45.318656 duration_in_ms=1835.999
2019-03-08 06:11:45,322 [salt.state       :1780][INFO    ][16067] Running state [maas.wait_for_machine_status] at time 06:11:45.322479
2019-03-08 06:11:45,323 [salt.state       :1813][INFO    ][16067] Executing state module.run for [maas.wait_for_machine_status]
2019-03-08 06:11:45,323 [salt.utils.decorators:613 ][WARNING ][16067] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-08 06:11:48,017 [salt.loaded.ext.module.maas:1023][INFO    ][16067] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.31901908s left)
2019-03-08 06:11:54,468 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308061154453270
2019-03-08 06:11:54,487 [salt.minion      :1432][INFO    ][16099] Starting a new job with PID 16099
2019-03-08 06:11:54,516 [salt.minion      :1711][INFO    ][16099] Returning information for job: 20190308061154453270
2019-03-08 06:12:20,670 [salt.loaded.ext.module.maas:1023][INFO    ][16067] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.6660409s left)
2019-03-08 06:12:24,522 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308061224514445
2019-03-08 06:12:24,537 [salt.minion      :1432][INFO    ][16164] Starting a new job with PID 16164
2019-03-08 06:12:24,566 [salt.minion      :1711][INFO    ][16164] Returning information for job: 20190308061224514445
2019-03-08 06:12:53,337 [salt.loaded.ext.module.maas:1023][INFO    ][16067] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2181.99943304s left)
2019-03-08 06:12:54,570 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308061254557305
2019-03-08 06:12:54,588 [salt.minion      :1432][INFO    ][16200] Starting a new job with PID 16200
2019-03-08 06:12:54,619 [salt.minion      :1711][INFO    ][16200] Returning information for job: 20190308061254557305
2019-03-08 06:13:24,632 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308061324623896
2019-03-08 06:13:24,648 [salt.minion      :1432][INFO    ][16281] Starting a new job with PID 16281
2019-03-08 06:13:24,679 [salt.minion      :1711][INFO    ][16281] Returning information for job: 20190308061324623896
2019-03-08 06:13:26,106 [salt.loaded.ext.module.maas:1023][INFO    ][16067] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2149.23015904s left)
2019-03-08 06:13:54,680 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308061354669998
2019-03-08 06:13:54,703 [salt.minion      :1432][INFO    ][16324] Starting a new job with PID 16324
2019-03-08 06:13:54,732 [salt.minion      :1711][INFO    ][16324] Returning information for job: 20190308061354669998
2019-03-08 06:13:58,982 [salt.loaded.ext.module.maas:1023][INFO    ][16067] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2116.3542881s left)
2019-03-08 06:14:24,752 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308061424740721
2019-03-08 06:14:24,769 [salt.minion      :1432][INFO    ][16391] Starting a new job with PID 16391
2019-03-08 06:14:24,801 [salt.minion      :1711][INFO    ][16391] Returning information for job: 20190308061424740721
2019-03-08 06:14:31,666 [salt.loaded.ext.module.maas:1023][INFO    ][16067] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2083.66994405s left)
2019-03-08 06:14:54,812 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308061454797933
2019-03-08 06:14:54,835 [salt.minion      :1432][INFO    ][16427] Starting a new job with PID 16427
2019-03-08 06:14:54,864 [salt.minion      :1711][INFO    ][16427] Returning information for job: 20190308061454797933
2019-03-08 06:15:04,428 [salt.loaded.ext.module.maas:1023][INFO    ][16067] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2050.90764093s left)
2019-03-08 06:15:24,894 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308061524882353
2019-03-08 06:15:24,919 [salt.minion      :1432][INFO    ][16494] Starting a new job with PID 16494
2019-03-08 06:15:24,950 [salt.minion      :1711][INFO    ][16494] Returning information for job: 20190308061524882353
2019-03-08 06:15:37,217 [salt.loaded.ext.module.maas:1023][INFO    ][16067] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2018.11953592s left)
2019-03-08 06:15:54,969 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308061554961133
2019-03-08 06:15:54,983 [salt.minion      :1432][INFO    ][16553] Starting a new job with PID 16553
2019-03-08 06:15:55,014 [salt.minion      :1711][INFO    ][16553] Returning information for job: 20190308061554961133
2019-03-08 06:16:09,934 [salt.loaded.ext.module.maas:1023][INFO    ][16067] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1985.40211391s left)
2019-03-08 06:16:25,033 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308061625022381
2019-03-08 06:16:25,053 [salt.minion      :1432][INFO    ][16625] Starting a new job with PID 16625
2019-03-08 06:16:25,081 [salt.minion      :1711][INFO    ][16625] Returning information for job: 20190308061625022381
2019-03-08 06:16:42,715 [salt.loaded.ext.module.maas:1023][INFO    ][16067] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1952.62169695s left)
2019-03-08 06:16:55,093 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308061655077404
2019-03-08 06:16:55,113 [salt.minion      :1432][INFO    ][16711] Starting a new job with PID 16711
2019-03-08 06:16:55,145 [salt.minion      :1711][INFO    ][16711] Returning information for job: 20190308061655077404
2019-03-08 06:17:15,536 [salt.loaded.ext.module.maas:1023][INFO    ][16067] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1919.80014491s left)
2019-03-08 06:17:25,211 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308061725197523
2019-03-08 06:17:25,232 [salt.minion      :1432][INFO    ][16888] Starting a new job with PID 16888
2019-03-08 06:17:25,260 [salt.minion      :1711][INFO    ][16888] Returning information for job: 20190308061725197523
2019-03-08 06:17:48,346 [salt.loaded.ext.module.maas:1023][INFO    ][16067] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1886.98972201s left)
2019-03-08 06:17:55,301 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308061755293062
2019-03-08 06:17:55,316 [salt.minion      :1432][INFO    ][16954] Starting a new job with PID 16954
2019-03-08 06:17:55,345 [salt.minion      :1711][INFO    ][16954] Returning information for job: 20190308061755293062
2019-03-08 06:18:21,285 [salt.loaded.ext.module.maas:1023][INFO    ][16067] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1854.05110908s left)
2019-03-08 06:18:25,409 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308061825393931
2019-03-08 06:18:25,428 [salt.minion      :1432][INFO    ][17035] Starting a new job with PID 17035
2019-03-08 06:18:25,460 [salt.minion      :1711][INFO    ][17035] Returning information for job: 20190308061825393931
2019-03-08 06:18:54,036 [salt.loaded.ext.module.maas:1023][INFO    ][16067] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1821.30037189s left)
2019-03-08 06:18:55,531 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308061855520054
2019-03-08 06:18:55,547 [salt.minion      :1432][INFO    ][17084] Starting a new job with PID 17084
2019-03-08 06:18:55,580 [salt.minion      :1711][INFO    ][17084] Returning information for job: 20190308061855520054
2019-03-08 06:19:25,637 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308061925629286
2019-03-08 06:19:25,655 [salt.minion      :1432][INFO    ][17226] Starting a new job with PID 17226
2019-03-08 06:19:25,685 [salt.minion      :1711][INFO    ][17226] Returning information for job: 20190308061925629286
2019-03-08 06:19:26,854 [salt.loaded.ext.module.maas:1023][INFO    ][16067] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1788.48190999s left)
2019-03-08 06:19:55,770 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308061955758538
2019-03-08 06:19:55,796 [salt.minion      :1432][INFO    ][17285] Starting a new job with PID 17285
2019-03-08 06:19:55,825 [salt.minion      :1711][INFO    ][17285] Returning information for job: 20190308061955758538
2019-03-08 06:19:59,550 [salt.loaded.ext.module.maas:1023][INFO    ][16067] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1755.78649092s left)
2019-03-08 06:20:25,896 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308062025884667
2019-03-08 06:20:25,918 [salt.minion      :1432][INFO    ][17353] Starting a new job with PID 17353
2019-03-08 06:20:25,945 [salt.minion      :1711][INFO    ][17353] Returning information for job: 20190308062025884667
2019-03-08 06:20:32,390 [salt.loaded.ext.module.maas:1023][INFO    ][16067] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1722.94586802s left)
2019-03-08 06:20:56,028 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308062056018011
2019-03-08 06:20:56,048 [salt.minion      :1432][INFO    ][17389] Starting a new job with PID 17389
2019-03-08 06:20:56,077 [salt.minion      :1711][INFO    ][17389] Returning information for job: 20190308062056018011
2019-03-08 06:21:05,237 [salt.loaded.ext.module.maas:1023][INFO    ][16067] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1690.09955192s left)
2019-03-08 06:21:26,154 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308062126140228
2019-03-08 06:21:26,179 [salt.minion      :1432][INFO    ][17456] Starting a new job with PID 17456
2019-03-08 06:21:26,208 [salt.minion      :1711][INFO    ][17456] Returning information for job: 20190308062126140228
2019-03-08 06:21:38,185 [salt.loaded.ext.module.maas:1023][INFO    ][16067] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1657.15137291s left)
2019-03-08 06:21:56,316 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308062156306124
2019-03-08 06:21:56,336 [salt.minion      :1432][INFO    ][17492] Starting a new job with PID 17492
2019-03-08 06:21:56,366 [salt.minion      :1711][INFO    ][17492] Returning information for job: 20190308062156306124
2019-03-08 06:22:10,973 [salt.loaded.ext.module.maas:1023][INFO    ][16067] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1624.36309791s left)
2019-03-08 06:22:26,453 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308062226443332
2019-03-08 06:22:26,474 [salt.minion      :1432][INFO    ][17560] Starting a new job with PID 17560
2019-03-08 06:22:26,505 [salt.minion      :1711][INFO    ][17560] Returning information for job: 20190308062226443332
2019-03-08 06:22:43,713 [salt.loaded.ext.module.maas:1023][INFO    ][16067] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1591.62338209s left)
2019-03-08 06:22:56,617 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308062256606187
2019-03-08 06:22:56,638 [salt.minion      :1432][INFO    ][17600] Starting a new job with PID 17600
2019-03-08 06:22:56,668 [salt.minion      :1711][INFO    ][17600] Returning information for job: 20190308062256606187
2019-03-08 06:23:16,585 [salt.loaded.ext.module.maas:1023][INFO    ][16067] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1558.75122905s left)
2019-03-08 06:23:26,778 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command saltutil.find_job with jid 20190308062326764767
2019-03-08 06:23:26,796 [salt.minion      :1432][INFO    ][17720] Starting a new job with PID 17720
2019-03-08 06:23:26,828 [salt.minion      :1711][INFO    ][17720] Returning information for job: 20190308062326764767
2019-03-08 06:23:49,466 [salt.state       :300 ][INFO    ][16067] {'ret': True}
2019-03-08 06:23:49,469 [salt.state       :1951][INFO    ][16067] Completed state [maas.wait_for_machine_status] at time 06:23:49.468841 duration_in_ms=724146.36
2019-03-08 06:23:49,473 [salt.minion      :1711][INFO    ][16067] Returning information for job: 20190308061139386658
2019-03-08 06:24:11,539 [salt.utils.schedule:1377][INFO    ][5815] Running scheduled job: __mine_interval
2019-03-08 07:24:11,539 [salt.utils.schedule:1377][INFO    ][5815] Running scheduled job: __mine_interval
2019-03-08 08:09:43,026 [salt.minion      :1308][INFO    ][5815] User sudo_ubuntu Executing command cp.push_dir with jid 20190308080943015415
2019-03-08 08:09:43,052 [salt.minion      :1432][INFO    ][29407] Starting a new job with PID 29407
