2019-07-12 20:07:06,528 [salt.utils.decorators:613 ][WARNING ][2359] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-12 20:07:07,487 [salt.utils.decorators:613 ][WARNING ][2359] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-12 20:07:10,393 [salt.loaded.int.states.file:2298][WARNING ][2586] 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-07-12 20:07:33,945 [salt.state       :2022][WARNING ][2974] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-07-12 20:07:37,110 [salt.utils.decorators:613 ][WARNING ][2974] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-12 20:07:50,077 [salt.utils.decorators:613 ][WARNING ][2974] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-12 20:08:15,823 [salt.utils.decorators:613 ][WARNING ][2974] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-12 20:08:16,842 [salt.utils.decorators:613 ][WARNING ][2974] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-12 20:08:19,484 [salt.loaded.ext.module.maasng:1008][WARNING ][2974] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-07-12 20:08:19,485 [salt.loaded.ext.module.maasng:1011][WARNING ][2974] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-07-12 20:08:20,182 [salt.loaded.ext.module.maasng:1235][WARNING ][2974] Ignoring parameter vlan:0
2019-07-12 20:08:21,034 [salt.utils.decorators:613 ][WARNING ][2974] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-12 20:08:29,952 [salt.utils.decorators:613 ][WARNING ][5317] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-12 20:08:30,006 [salt.loaded.ext.module.maas:412 ][WARNING ][5317] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-12 20:08:31,498 [salt.loaded.ext.module.maas:412 ][WARNING ][5317] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-12 20:08:32,845 [salt.loaded.ext.module.maas:412 ][WARNING ][5317] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-12 20:08:34,002 [salt.loaded.ext.module.maas:412 ][WARNING ][5317] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-12 20:08:37,800 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5801] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-07-12 20:08:37,831 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5801] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-07-12 20:08:37,882 [salt.utils.parsers:1051][WARNING ][378] Minion received a SIGTERM. Exiting.
2019-07-12 20:08:38,903 [salt.cli.daemons :293 ][INFO    ][5884] Setting up the Salt Minion "mas01.mcp-fdio-noha.local"
2019-07-12 20:08:39,025 [salt.cli.daemons :82  ][INFO    ][5884] Starting up the Salt Minion
2019-07-12 20:08:39,025 [salt.utils.event :1017][INFO    ][5884] Starting pull socket on /var/run/salt/minion/minion_event_38d774b16c_pull.ipc
2019-07-12 20:08:40,259 [salt.minion      :976 ][INFO    ][5884] Creating minion process manager
2019-07-12 20:08:42,210 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][5884] Executing command ['date', '+%z'] in directory '/root'
2019-07-12 20:08:42,236 [salt.utils.schedule:568 ][INFO    ][5884] Updating job settings for scheduled job: __mine_interval
2019-07-12 20:08:42,238 [salt.minion      :1108][INFO    ][5884] Added mine.update to scheduler
2019-07-12 20:08:42,246 [salt.minion      :1975][INFO    ][5884] Minion is starting as user 'root'
2019-07-12 20:08:42,272 [salt.minion      :2336][INFO    ][5884] Minion is ready to receive requests!
2019-07-12 20:09:06,139 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command state.apply with jid 20190712200906124706
2019-07-12 20:09:06,166 [salt.minion      :1432][INFO    ][5989] Starting a new job with PID 5989
2019-07-12 20:09:12,053 [salt.state       :915 ][INFO    ][5989] Loading fresh modules for state activity
2019-07-12 20:09:12,114 [salt.fileclient  :1219][INFO    ][5989] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-07-12 20:09:12,155 [salt.state       :1780][INFO    ][5989] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:09:12.155292
2019-07-12 20:09:12,155 [salt.state       :1813][INFO    ][5989] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-12 20:09:12,157 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5989] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-12 20:09:13,893 [salt.state       :300 ][INFO    ][5989] {'pid': 6001, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-12 20:09:13,895 [salt.state       :1951][INFO    ][5989] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:09:13.895149 duration_in_ms=1739.856
2019-07-12 20:09:13,898 [salt.state       :1780][INFO    ][5989] Running state [maas.wait_for_machine_status] at time 20:09:13.898438
2019-07-12 20:09:13,899 [salt.state       :1813][INFO    ][5989] Executing state module.run for [maas.wait_for_machine_status]
2019-07-12 20:09:13,899 [salt.utils.decorators:613 ][WARNING ][5989] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-12 20:09:14,950 [salt.loaded.ext.module.maas:1023][INFO    ][5989] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1498.95852709s left)
2019-07-12 20:09:21,239 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712200921228499
2019-07-12 20:09:21,269 [salt.minion      :1432][INFO    ][6012] Starting a new job with PID 6012
2019-07-12 20:09:21,288 [salt.minion      :1711][INFO    ][6012] Returning information for job: 20190712200921228499
2019-07-12 20:09:45,547 [salt.loaded.ext.module.maas:1023][INFO    ][5989] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1468.36233497s left)
2019-07-12 20:09:51,343 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712200951332999
2019-07-12 20:09:51,369 [salt.minion      :1432][INFO    ][6084] Starting a new job with PID 6084
2019-07-12 20:09:51,392 [salt.minion      :1711][INFO    ][6084] Returning information for job: 20190712200951332999
2019-07-12 20:10:16,196 [salt.loaded.ext.module.maas:1023][INFO    ][5989] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1437.71319914s left)
2019-07-12 20:10:21,419 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712201021405418
2019-07-12 20:10:21,447 [salt.minion      :1432][INFO    ][6107] Starting a new job with PID 6107
2019-07-12 20:10:21,471 [salt.minion      :1711][INFO    ][6107] Returning information for job: 20190712201021405418
2019-07-12 20:10:46,846 [salt.loaded.ext.module.maas:1023][INFO    ][5989] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1407.06280994s left)
2019-07-12 20:10:51,497 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712201051487127
2019-07-12 20:10:51,526 [salt.minion      :1432][INFO    ][6156] Starting a new job with PID 6156
2019-07-12 20:10:51,546 [salt.minion      :1711][INFO    ][6156] Returning information for job: 20190712201051487127
2019-07-12 20:11:17,650 [salt.loaded.ext.module.maas:1023][INFO    ][5989] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1376.25866914s left)
2019-07-12 20:11:21,559 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712201121547476
2019-07-12 20:11:21,582 [salt.minion      :1432][INFO    ][6208] Starting a new job with PID 6208
2019-07-12 20:11:21,606 [salt.minion      :1711][INFO    ][6208] Returning information for job: 20190712201121547476
2019-07-12 20:11:48,512 [salt.loaded.ext.module.maas:1023][INFO    ][5989] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1345.39679098s left)
2019-07-12 20:11:51,629 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712201151615206
2019-07-12 20:11:51,656 [salt.minion      :1432][INFO    ][6362] Starting a new job with PID 6362
2019-07-12 20:11:51,680 [salt.minion      :1711][INFO    ][6362] Returning information for job: 20190712201151615206
2019-07-12 20:12:19,422 [salt.loaded.ext.module.maas:1023][INFO    ][5989] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1314.48666406s left)
2019-07-12 20:12:21,728 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712201221711558
2019-07-12 20:12:21,759 [salt.minion      :1432][INFO    ][6464] Starting a new job with PID 6464
2019-07-12 20:12:21,781 [salt.minion      :1711][INFO    ][6464] Returning information for job: 20190712201221711558
2019-07-12 20:12:50,394 [salt.loaded.ext.module.maas:1023][INFO    ][5989] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1283.51526213s left)
2019-07-12 20:12:51,832 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712201251820058
2019-07-12 20:12:51,856 [salt.minion      :1432][INFO    ][6751] Starting a new job with PID 6751
2019-07-12 20:12:51,875 [salt.minion      :1711][INFO    ][6751] Returning information for job: 20190712201251820058
2019-07-12 20:13:21,476 [salt.loaded.ext.module.maas:1023][INFO    ][5989] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1252.43334007s left)
2019-07-12 20:13:21,897 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712201321883832
2019-07-12 20:13:21,923 [salt.minion      :1432][INFO    ][6824] Starting a new job with PID 6824
2019-07-12 20:13:21,955 [salt.minion      :1711][INFO    ][6824] Returning information for job: 20190712201321883832
2019-07-12 20:13:52,057 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712201352047400
2019-07-12 20:13:52,078 [salt.minion      :1432][INFO    ][7111] Starting a new job with PID 7111
2019-07-12 20:13:52,099 [salt.minion      :1711][INFO    ][7111] Returning information for job: 20190712201352047400
2019-07-12 20:13:53,120 [salt.loaded.ext.module.maas:1023][INFO    ][5989] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1220.78866601s left)
2019-07-12 20:14:22,172 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712201422156962
2019-07-12 20:14:22,196 [salt.minion      :1432][INFO    ][7274] Starting a new job with PID 7274
2019-07-12 20:14:22,219 [salt.minion      :1711][INFO    ][7274] Returning information for job: 20190712201422156962
2019-07-12 20:14:24,714 [salt.loaded.ext.module.maas:1023][INFO    ][5989] Waiting status:Ready|Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:1500s (1189.19469094s left)
2019-07-12 20:14:52,280 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712201452267979
2019-07-12 20:14:52,305 [salt.minion      :1432][INFO    ][7371] Starting a new job with PID 7371
2019-07-12 20:14:52,326 [salt.minion      :1711][INFO    ][7371] Returning information for job: 20190712201452267979
2019-07-12 20:14:56,307 [salt.loaded.ext.module.maas:1023][INFO    ][5989] Waiting status:Ready|Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:1500s (1157.60175395s left)
2019-07-12 20:15:22,405 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712201522389042
2019-07-12 20:15:22,432 [salt.minion      :1432][INFO    ][7477] Starting a new job with PID 7477
2019-07-12 20:15:22,455 [salt.minion      :1711][INFO    ][7477] Returning information for job: 20190712201522389042
2019-07-12 20:15:28,272 [salt.state       :300 ][INFO    ][5989] {'ret': True}
2019-07-12 20:15:28,273 [salt.state       :1951][INFO    ][5989] Completed state [maas.wait_for_machine_status] at time 20:15:28.273012 duration_in_ms=374374.572
2019-07-12 20:15:28,279 [salt.minion      :1711][INFO    ][5989] Returning information for job: 20190712200906124706
2019-07-12 20:15:28,841 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command state.apply with jid 20190712201528827068
2019-07-12 20:15:28,864 [salt.minion      :1432][INFO    ][7508] Starting a new job with PID 7508
2019-07-12 20:15:34,734 [salt.state       :915 ][INFO    ][7508] Loading fresh modules for state activity
2019-07-12 20:15:34,791 [salt.fileclient  :1219][INFO    ][7508] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-07-12 20:15:34,888 [salt.state       :1780][INFO    ][7508] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:15:34.888549
2019-07-12 20:15:34,888 [salt.state       :1813][INFO    ][7508] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-12 20:15:34,890 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7508] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-12 20:15:36,699 [salt.state       :300 ][INFO    ][7508] {'pid': 7516, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-12 20:15:36,700 [salt.state       :1951][INFO    ][7508] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:15:36.700451 duration_in_ms=1811.901
2019-07-12 20:15:36,704 [salt.state       :1780][INFO    ][7508] Running state [maas_machines_storage_cmp002_lvm] at time 20:15:36.704539
2019-07-12 20:15:36,705 [salt.state       :1813][INFO    ][7508] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-07-12 20:15:37,902 [salt.loaded.ext.module.maasng:610 ][INFO    ][7508] wascd3
2019-07-12 20:15:37,902 [salt.loaded.ext.module.maasng:626 ][INFO    ][7508] sda
2019-07-12 20:15:38,399 [salt.loaded.ext.module.maasng:361 ][INFO    ][7508] wascd3
2019-07-12 20:15:38,489 [salt.loaded.ext.module.maasng:367 ][INFO    ][7508] [{u'size': 800109715456, u'block_size': 4096, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/wascd3/blockdevices/1/', u'used_size': 800106479616, u'tags': [u'ssd'], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'wascd3', 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'path': u'/dev/disk/by-dname/sda', u'type': u'physical', u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'size': 800101236736, u'uuid': u'8e7881f6-a67d-42b0-a84a-7064d01f6363', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'wascd3', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'd0907142-2beb-4f56-b8f4-3f1a8e56b54c', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/wascd3/blockdevices/1/partition/1', u'type': u'partition', u'id': 1, u'device_id': 1}]}, {u'size': 800097042432, u'block_size': 4096, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/wascd3/blockdevices/3/', u'used_size': 800097042432, u'tags': [], u'uuid': u'62c42a94-632f-48c1-ba18-0f4746c1d290', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'wascd3', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'50817704-bd34-4331-83cb-4a48e96c29ed', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'model': None, u'path': u'/dev/disk/by-dname/lvroot', u'type': u'virtual', u'id': 3, u'serial': None, u'partitions': []}]
2019-07-12 20:15:38,490 [salt.loaded.ext.module.maasng:632 ][INFO    ][7508] vgroot
2019-07-12 20:15:38,491 [salt.loaded.ext.module.maasng:635 ][INFO    ][7508] lvroot
2019-07-12 20:15:38,491 [salt.loaded.ext.module.maasng:639 ][INFO    ][7508] 107374182400
2019-07-12 20:15:39,116 [salt.loaded.ext.module.maasng:645 ][INFO    ][7508] {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'cpu_count': 40, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', 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'a3ythp', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'id': 16, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', 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'a3ythp', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', 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'a3ythp', 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'wascd3', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/wascd3/interfaces/5/'}, u'min_hwe_kernel': u'hwe-16.04', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'fqdn': u'cmp002.maas', u'zone': {u'description': u'', u'id': 1, u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/wascd3/', u'current_commissioning_result_id': 4, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'wascd3', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'block_size': 4096, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/wascd3/blockdevices/9/', u'used_size': 107374182400, u'tags': [], u'uuid': u'c58e8096-43ca-4c86-8abe-2c27db0fab56', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'wascd3', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'b0e52599-a363-4b0d-9eb0-e87e7d876c53', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'model': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'type': u'virtual', u'id': 9, u'serial': None, u'partitions': []}], u'blockdevice_set': [{u'size': 800109715456, u'block_size': 4096, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/wascd3/blockdevices/1/', u'used_size': 800106479616, u'tags': [u'ssd'], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'wascd3', 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'path': u'/dev/disk/by-dname/sda', u'type': u'physical', u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'size': 800101236736, u'uuid': u'aae00895-6058-4bbc-a78f-d393b7224805', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'wascd3', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'30e247c1-8f8b-489d-8b34-f76177e2192a', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/wascd3/blockdevices/1/partition/5', u'type': u'partition', u'id': 5, u'device_id': 1}]}, {u'size': 107374182400, u'block_size': 4096, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/wascd3/blockdevices/9/', u'used_size': 107374182400, u'tags': [], u'uuid': u'c58e8096-43ca-4c86-8abe-2c27db0fab56', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'wascd3', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'b0e52599-a363-4b0d-9eb0-e87e7d876c53', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'model': None, u'path': u'/dev/disk/by-dname/lvroot', u'type': u'virtual', u'id': 9, u'serial': None, u'partitions': []}], u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'power_state': u'off', u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'wascd3', u'id': 5}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'commissioning_status_name': u'Passed', u'current_testing_result_id': 5, u'cpu_test_status': -1, u'bcaches': [], u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 800109715456, u'block_size': 4096, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/wascd3/blockdevices/1/', u'used_size': 800106479616, u'tags': [u'ssd'], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'wascd3', 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'path': u'/dev/disk/by-dname/sda', u'type': u'physical', u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'size': 800101236736, u'uuid': u'aae00895-6058-4bbc-a78f-d393b7224805', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'wascd3', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'30e247c1-8f8b-489d-8b34-f76177e2192a', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/wascd3/blockdevices/1/partition/5', u'type': u'partition', u'id': 5, u'device_id': 1}]}], u'disable_ipv4': False, u'netboot': True, u'osystem': u'', u'node_type': 0, u'memory_test_status_name': u'Unknown', u'commissioning_status': 2, u'architecture': u'amd64/generic', 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'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', 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'a3ythp', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'id': 16, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', 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'a3ythp', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', 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'a3ythp', 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'wascd3', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/wascd3/interfaces/5/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:51', u'system_id': u'wascd3', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 10, u'resource_uri': u'/MAAS/api/2.0/nodes/wascd3/interfaces/10/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:50', u'system_id': u'wascd3', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/wascd3/interfaces/11/'}, {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'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', 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'a3ythp', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 17, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', 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'a3ythp', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', 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'a3ythp', 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'wascd3', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/wascd3/interfaces/12/'}, {u'name': u'ens2f0', u'links': [{u'id': 18, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', 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'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:48', u'system_id': u'wascd3', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/wascd3/interfaces/13/'}, {u'name': u'ens2f1', u'links': [{u'id': 19, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', 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'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:49', u'system_id': u'wascd3', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/wascd3/interfaces/14/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'boot_disk': {u'size': 800109715456, u'block_size': 4096, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/wascd3/blockdevices/1/', u'used_size': 800106479616, u'tags': [u'ssd'], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'wascd3', 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'path': u'/dev/disk/by-dname/sda', u'type': u'physical', u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'size': 800101236736, u'uuid': u'aae00895-6058-4bbc-a78f-d393b7224805', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'wascd3', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'30e247c1-8f8b-489d-8b34-f76177e2192a', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/wascd3/blockdevices/1/partition/5', u'type': u'partition', u'id': 5, u'device_id': 1}]}}
2019-07-12 20:15:39,119 [salt.state       :300 ][INFO    ][7508] {'new': {'storage_layout': 'lvm'}}
2019-07-12 20:15:39,119 [salt.state       :1951][INFO    ][7508] Completed state [maas_machines_storage_cmp002_lvm] at time 20:15:39.119499 duration_in_ms=2414.959
2019-07-12 20:15:39,120 [salt.state       :1780][INFO    ][7508] Running state [maas_machines_storage_cmp001_lvm] at time 20:15:39.120188
2019-07-12 20:15:39,120 [salt.state       :1813][INFO    ][7508] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-07-12 20:15:40,216 [salt.loaded.ext.module.maasng:610 ][INFO    ][7508] pnfnkf
2019-07-12 20:15:40,217 [salt.loaded.ext.module.maasng:626 ][INFO    ][7508] sda
2019-07-12 20:15:40,698 [salt.loaded.ext.module.maasng:361 ][INFO    ][7508] pnfnkf
2019-07-12 20:15:40,792 [salt.loaded.ext.module.maasng:367 ][INFO    ][7508] [{u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/pnfnkf/blockdevices/2/', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'216443ea-59ce-4140-a7b5-869bd93edbed', u'resource_uri': u'/MAAS/api/2.0/nodes/pnfnkf/blockdevices/2/partition/2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'pnfnkf', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'718d3cc5-b72d-4f03-9036-db11a9006f50', 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'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'pnfnkf', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'size': 800109715456}, {u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/pnfnkf/blockdevices/4/', u'uuid': u'a746cd2c-67be-4f43-8791-6e4864b3ef7c', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'2a9cdba8-69d0-4a18-a2fc-bef96554bb1a', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'pnfnkf', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'used_size': 800097042432, u'id': 4, u'size': 800097042432}]
2019-07-12 20:15:40,793 [salt.loaded.ext.module.maasng:632 ][INFO    ][7508] vgroot
2019-07-12 20:15:40,793 [salt.loaded.ext.module.maasng:635 ][INFO    ][7508] lvroot
2019-07-12 20:15:40,793 [salt.loaded.ext.module.maasng:639 ][INFO    ][7508] 107374182400
2019-07-12 20:15:41,431 [salt.loaded.ext.module.maasng:645 ][INFO    ][7508] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'ip_addresses': [u'192.168.11.39', u'192.168.11.43'], u'storage_test_status_name': u'Passed', u'owner_data': {}, u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'status_action': u'', u'blockdevice_set': [{u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/pnfnkf/blockdevices/2/', u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'type': u'physical', u'name': u'sda', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'pnfnkf', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'used_for': u'MBR partitioned with 1 partition', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'size': 800101236736, u'uuid': u'6b0ff027-910a-47e7-a932-a4e236972f3a', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'pnfnkf', u'filesystem': {u'mount_options': None, u'uuid': u'b0b76ae8-c2ca-4a2e-ba7b-cadd7c2feb07', u'mount_point': None, u'label': 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': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/pnfnkf/blockdevices/2/partition/6'}]}, {u'size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/pnfnkf/blockdevices/10/', u'available_size': 0, u'uuid': u'659f951b-29f8-4c15-b2d8-1336173d815d', u'tags': [], u'type': u'virtual', u'name': u'vgroot-lvroot', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'pnfnkf', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'a80b1ed3-3cf4-4ca9-afc8-35cec914322a', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, u'id_path': None, u'used_for': u'ext4 formatted filesystem mounted at /', u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 10, u'serial': None, u'partitions': []}], u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'description': u''}, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'pnfnkf', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/pnfnkf/blockdevices/10/', u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'uuid': u'659f951b-29f8-4c15-b2d8-1336173d815d', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'pnfnkf', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'a80b1ed3-3cf4-4ca9-afc8-35cec914322a', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 10, u'partitions': []}], u'architecture': u'amd64/generic', u'status': 4, u'bcaches': [], u'cpu_count': 40, u'power_state': u'off', u'power_type': u'ipmi', u'physicalblockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/pnfnkf/blockdevices/2/', u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'pnfnkf', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'type': u'physical', u'id': 2, u'partitions': [{u'size': 800101236736, u'uuid': u'6b0ff027-910a-47e7-a932-a4e236972f3a', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'pnfnkf', u'filesystem': {u'mount_options': None, u'uuid': u'b0b76ae8-c2ca-4a2e-ba7b-cadd7c2feb07', u'mount_point': None, u'label': 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': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/pnfnkf/blockdevices/2/partition/6'}]}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'pnfnkf', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 6, u'commissioning_status_name': u'Passed', u'current_testing_result_id': 7, u'cpu_test_status': -1, u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'disable_ipv4': False, u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', 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'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'a3ythp', 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'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': 22, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'a3ythp', 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'enabled': True, u'effective_mtu': 1500, u'id': 6, 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'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'a3ythp', 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'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'system_id': u'pnfnkf', u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/pnfnkf/interfaces/6/'}, u'boot_disk': {u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/pnfnkf/blockdevices/2/', u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'pnfnkf', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'type': u'physical', u'id': 2, u'partitions': [{u'size': 800101236736, u'uuid': u'6b0ff027-910a-47e7-a932-a4e236972f3a', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'pnfnkf', u'filesystem': {u'mount_options': None, u'uuid': u'b0b76ae8-c2ca-4a2e-ba7b-cadd7c2feb07', u'mount_point': None, u'label': 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': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/pnfnkf/blockdevices/2/partition/6'}]}, u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'a3ythp', 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'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': 22, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'a3ythp', 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'enabled': True, u'effective_mtu': 1500, u'id': 6, 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'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'a3ythp', 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'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'system_id': u'pnfnkf', u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/pnfnkf/interfaces/6/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'id': 15, u'discovered': None, u'system_id': u'pnfnkf', u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d5', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/pnfnkf/interfaces/15/'}, {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'system_id': u'pnfnkf', u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d4', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/pnfnkf/interfaces/16/'}, {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'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'a3ythp', 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'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': 23, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'a3ythp', 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'enabled': True, u'effective_mtu': 1500, u'id': 17, 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'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'a3ythp', 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'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.43'}], u'system_id': u'pnfnkf', u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a4', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/pnfnkf/interfaces/17/'}, {u'name': u'ens2f0', u'links': [{u'id': 24, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 18, u'discovered': None, u'system_id': u'pnfnkf', u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cc', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/pnfnkf/interfaces/18/'}, {u'name': u'ens2f1', u'links': [{u'id': 25, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 19, u'discovered': None, u'system_id': u'pnfnkf', u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cd', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/pnfnkf/interfaces/19/'}], u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/pnfnkf/', u'distro_series': u'', u'memory_test_status': -1}
2019-07-12 20:15:41,435 [salt.state       :300 ][INFO    ][7508] {'new': {'storage_layout': 'lvm'}}
2019-07-12 20:15:41,435 [salt.state       :1951][INFO    ][7508] Completed state [maas_machines_storage_cmp001_lvm] at time 20:15:41.435863 duration_in_ms=2315.674
2019-07-12 20:15:41,442 [salt.minion      :1711][INFO    ][7508] Returning information for job: 20190712201528827068
2019-07-12 20:15:42,011 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command state.apply with jid 20190712201541997092
2019-07-12 20:15:42,039 [salt.minion      :1432][INFO    ][7570] Starting a new job with PID 7570
2019-07-12 20:15:42,898 [salt.state       :915 ][INFO    ][7570] Loading fresh modules for state activity
2019-07-12 20:15:42,956 [salt.fileclient  :1219][INFO    ][7570] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-07-12 20:15:43,010 [salt.state       :1780][INFO    ][7570] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:15:43.010682
2019-07-12 20:15:43,011 [salt.state       :1813][INFO    ][7570] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-12 20:15:43,013 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7570] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-12 20:15:44,780 [salt.state       :300 ][INFO    ][7570] {'pid': 7577, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-12 20:15:44,782 [salt.state       :1951][INFO    ][7570] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:15:44.782218 duration_in_ms=1771.536
2019-07-12 20:15:44,785 [salt.state       :1780][INFO    ][7570] Running state [maas.deploy_machines] at time 20:15:44.785484
2019-07-12 20:15:44,787 [salt.state       :1813][INFO    ][7570] Executing state module.run for [maas.deploy_machines]
2019-07-12 20:15:44,787 [salt.utils.decorators:613 ][WARNING ][7570] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-12 20:15:45,400 [salt.loaded.ext.module.maas:684 ][INFO    ][7570] deploymachines hwe_kernel=hwe-16.04 system_id=k4tggq distro_series=xenial
2019-07-12 20:15:48,073 [salt.loaded.ext.module.maas:684 ][INFO    ][7570] deploymachines hwe_kernel=hwe-16.04 system_id=wascd3 distro_series=xenial
2019-07-12 20:15:50,629 [salt.loaded.ext.module.maas:684 ][INFO    ][7570] deploymachines hwe_kernel=hwe-16.04 system_id=pnfnkf distro_series=xenial
2019-07-12 20:15:53,169 [salt.loaded.ext.module.maas:684 ][INFO    ][7570] deploymachines hwe_kernel=hwe-16.04 system_id=k7ab78 distro_series=xenial
2019-07-12 20:15:55,693 [salt.state       :300 ][INFO    ][7570] {'ret': {'updated': [], 'errors': {}, 'success': ['gtw01', 'cmp002', 'cmp001', 'ctl01']}}
2019-07-12 20:15:55,696 [salt.state       :1951][INFO    ][7570] Completed state [maas.deploy_machines] at time 20:15:55.696390 duration_in_ms=10910.905
2019-07-12 20:15:55,700 [salt.minion      :1711][INFO    ][7570] Returning information for job: 20190712201541997092
2019-07-12 20:15:56,276 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command state.apply with jid 20190712201556263949
2019-07-12 20:15:56,302 [salt.minion      :1432][INFO    ][7798] Starting a new job with PID 7798
2019-07-12 20:16:02,248 [salt.state       :915 ][INFO    ][7798] Loading fresh modules for state activity
2019-07-12 20:16:02,311 [salt.fileclient  :1219][INFO    ][7798] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-07-12 20:16:02,361 [salt.state       :1780][INFO    ][7798] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:16:02.361105
2019-07-12 20:16:02,361 [salt.state       :1813][INFO    ][7798] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-12 20:16:02,363 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7798] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-12 20:16:04,063 [salt.state       :300 ][INFO    ][7798] {'pid': 7812, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-12 20:16:04,064 [salt.state       :1951][INFO    ][7798] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:16:04.064029 duration_in_ms=1702.923
2019-07-12 20:16:04,067 [salt.state       :1780][INFO    ][7798] Running state [maas.wait_for_machine_status] at time 20:16:04.067158
2019-07-12 20:16:04,067 [salt.state       :1813][INFO    ][7798] Executing state module.run for [maas.wait_for_machine_status]
2019-07-12 20:16:04,068 [salt.utils.decorators:613 ][WARNING ][7798] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-12 20:16:06,034 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2248.04490995s left)
2019-07-12 20:16:11,324 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712201611309047
2019-07-12 20:16:11,353 [salt.minion      :1432][INFO    ][7833] Starting a new job with PID 7833
2019-07-12 20:16:11,375 [salt.minion      :1711][INFO    ][7833] Returning information for job: 20190712201611309047
2019-07-12 20:16:38,253 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2215.82575011s left)
2019-07-12 20:16:41,385 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712201641367865
2019-07-12 20:16:41,411 [salt.minion      :1432][INFO    ][7887] Starting a new job with PID 7887
2019-07-12 20:16:41,432 [salt.minion      :1711][INFO    ][7887] Returning information for job: 20190712201641367865
2019-07-12 20:17:10,192 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2183.88689804s left)
2019-07-12 20:17:11,475 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712201711455801
2019-07-12 20:17:11,505 [salt.minion      :1432][INFO    ][7916] Starting a new job with PID 7916
2019-07-12 20:17:11,531 [salt.minion      :1711][INFO    ][7916] Returning information for job: 20190712201711455801
2019-07-12 20:17:41,564 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712201741548823
2019-07-12 20:17:41,587 [salt.minion      :1432][INFO    ][7981] Starting a new job with PID 7981
2019-07-12 20:17:41,610 [salt.minion      :1711][INFO    ][7981] Returning information for job: 20190712201741548823
2019-07-12 20:17:42,382 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2151.69622993s left)
2019-07-12 20:18:11,644 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712201811627175
2019-07-12 20:18:11,673 [salt.minion      :1432][INFO    ][8012] Starting a new job with PID 8012
2019-07-12 20:18:11,699 [salt.minion      :1711][INFO    ][8012] Returning information for job: 20190712201811627175
2019-07-12 20:18:14,332 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2119.74657011s left)
2019-07-12 20:18:41,715 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712201841698193
2019-07-12 20:18:41,742 [salt.minion      :1432][INFO    ][8083] Starting a new job with PID 8083
2019-07-12 20:18:41,766 [salt.minion      :1711][INFO    ][8083] Returning information for job: 20190712201841698193
2019-07-12 20:18:46,323 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2087.75516701s left)
2019-07-12 20:19:11,788 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712201911772137
2019-07-12 20:19:11,816 [salt.minion      :1432][INFO    ][8167] Starting a new job with PID 8167
2019-07-12 20:19:11,841 [salt.minion      :1711][INFO    ][8167] Returning information for job: 20190712201911772137
2019-07-12 20:19:18,429 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2055.64972997s left)
2019-07-12 20:19:41,902 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712201941886430
2019-07-12 20:19:41,928 [salt.minion      :1432][INFO    ][8392] Starting a new job with PID 8392
2019-07-12 20:19:41,951 [salt.minion      :1711][INFO    ][8392] Returning information for job: 20190712201941886430
2019-07-12 20:19:50,483 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2023.59600401s left)
2019-07-12 20:20:11,984 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712202011975378
2019-07-12 20:20:12,007 [salt.minion      :1432][INFO    ][8494] Starting a new job with PID 8494
2019-07-12 20:20:12,030 [salt.minion      :1711][INFO    ][8494] Returning information for job: 20190712202011975378
2019-07-12 20:20:22,647 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1991.43264604s left)
2019-07-12 20:20:42,067 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712202042056735
2019-07-12 20:20:42,095 [salt.minion      :1432][INFO    ][8640] Starting a new job with PID 8640
2019-07-12 20:20:42,115 [salt.minion      :1711][INFO    ][8640] Returning information for job: 20190712202042056735
2019-07-12 20:20:54,743 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1959.335706s left)
2019-07-12 20:21:12,164 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712202112148318
2019-07-12 20:21:12,192 [salt.minion      :1432][INFO    ][8730] Starting a new job with PID 8730
2019-07-12 20:21:12,211 [salt.minion      :1711][INFO    ][8730] Returning information for job: 20190712202112148318
2019-07-12 20:21:26,925 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1927.15339708s left)
2019-07-12 20:21:42,296 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712202142281673
2019-07-12 20:21:42,319 [salt.minion      :1432][INFO    ][8974] Starting a new job with PID 8974
2019-07-12 20:21:42,341 [salt.minion      :1711][INFO    ][8974] Returning information for job: 20190712202142281673
2019-07-12 20:21:58,977 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1895.10146403s left)
2019-07-12 20:22:12,425 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712202212404215
2019-07-12 20:22:12,455 [salt.minion      :1432][INFO    ][9099] Starting a new job with PID 9099
2019-07-12 20:22:12,477 [salt.minion      :1711][INFO    ][9099] Returning information for job: 20190712202212404215
2019-07-12 20:22:31,161 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1862.91779399s left)
2019-07-12 20:22:42,565 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712202242551172
2019-07-12 20:22:42,592 [salt.minion      :1432][INFO    ][9376] Starting a new job with PID 9376
2019-07-12 20:22:42,616 [salt.minion      :1711][INFO    ][9376] Returning information for job: 20190712202242551172
2019-07-12 20:23:03,116 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1830.96248603s left)
2019-07-12 20:23:12,715 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712202312697432
2019-07-12 20:23:12,742 [salt.minion      :1432][INFO    ][9484] Starting a new job with PID 9484
2019-07-12 20:23:12,765 [salt.minion      :1711][INFO    ][9484] Returning information for job: 20190712202312697432
2019-07-12 20:23:35,132 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1798.94610095s left)
2019-07-12 20:23:42,869 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712202342858390
2019-07-12 20:23:42,895 [salt.minion      :1432][INFO    ][9660] Starting a new job with PID 9660
2019-07-12 20:23:42,917 [salt.minion      :1711][INFO    ][9660] Returning information for job: 20190712202342858390
2019-07-12 20:24:07,355 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1766.72382092s left)
2019-07-12 20:24:13,009 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712202412995290
2019-07-12 20:24:13,035 [salt.minion      :1432][INFO    ][9715] Starting a new job with PID 9715
2019-07-12 20:24:13,057 [salt.minion      :1711][INFO    ][9715] Returning information for job: 20190712202412995290
2019-07-12 20:24:39,727 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1734.35124993s left)
2019-07-12 20:24:43,172 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712202443156316
2019-07-12 20:24:43,200 [salt.minion      :1432][INFO    ][9861] Starting a new job with PID 9861
2019-07-12 20:24:43,222 [salt.minion      :1711][INFO    ][9861] Returning information for job: 20190712202443156316
2019-07-12 20:25:11,836 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1702.24228406s left)
2019-07-12 20:25:13,331 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712202513320642
2019-07-12 20:25:13,361 [salt.minion      :1432][INFO    ][9919] Starting a new job with PID 9919
2019-07-12 20:25:13,383 [salt.minion      :1711][INFO    ][9919] Returning information for job: 20190712202513320642
2019-07-12 20:25:43,450 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712202543437470
2019-07-12 20:25:43,473 [salt.minion      :1432][INFO    ][10027] Starting a new job with PID 10027
2019-07-12 20:25:43,497 [salt.minion      :1711][INFO    ][10027] Returning information for job: 20190712202543437470
2019-07-12 20:25:44,109 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1669.96935701s left)
2019-07-12 20:26:13,623 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712202613606435
2019-07-12 20:26:13,648 [salt.minion      :1432][INFO    ][10139] Starting a new job with PID 10139
2019-07-12 20:26:13,671 [salt.minion      :1711][INFO    ][10139] Returning information for job: 20190712202613606435
2019-07-12 20:26:16,183 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:2250s (1637.89611292s left)
2019-07-12 20:26:43,819 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712202643803024
2019-07-12 20:26:43,846 [salt.minion      :1432][INFO    ][10324] Starting a new job with PID 10324
2019-07-12 20:26:43,870 [salt.minion      :1711][INFO    ][10324] Returning information for job: 20190712202643803024
2019-07-12 20:26:48,361 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:2250s (1605.71753693s left)
2019-07-12 20:27:13,989 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712202713981524
2019-07-12 20:27:14,012 [salt.minion      :1432][INFO    ][10367] Starting a new job with PID 10367
2019-07-12 20:27:14,037 [salt.minion      :1711][INFO    ][10367] Returning information for job: 20190712202713981524
2019-07-12 20:27:20,341 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:2250s (1573.73738003s left)
2019-07-12 20:27:44,192 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712202744176468
2019-07-12 20:27:44,220 [salt.minion      :1432][INFO    ][10560] Starting a new job with PID 10560
2019-07-12 20:27:44,246 [salt.minion      :1711][INFO    ][10560] Returning information for job: 20190712202744176468
2019-07-12 20:27:52,337 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:2250s (1541.74127793s left)
2019-07-12 20:28:14,407 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712202814391296
2019-07-12 20:28:14,435 [salt.minion      :1432][INFO    ][10591] Starting a new job with PID 10591
2019-07-12 20:28:14,463 [salt.minion      :1711][INFO    ][10591] Returning information for job: 20190712202814391296
2019-07-12 20:28:24,332 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:2250s (1509.74653912s left)
2019-07-12 20:28:44,608 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712202844589576
2019-07-12 20:28:44,637 [salt.minion      :1432][INFO    ][10661] Starting a new job with PID 10661
2019-07-12 20:28:44,658 [salt.minion      :1711][INFO    ][10661] Returning information for job: 20190712202844589576
2019-07-12 20:28:56,279 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1477.79959011s left)
2019-07-12 20:29:14,838 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712202914819544
2019-07-12 20:29:14,866 [salt.minion      :1432][INFO    ][10747] Starting a new job with PID 10747
2019-07-12 20:29:14,888 [salt.minion      :1711][INFO    ][10747] Returning information for job: 20190712202914819544
2019-07-12 20:29:28,371 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1445.7094779s left)
2019-07-12 20:29:45,059 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712202945044747
2019-07-12 20:29:45,089 [salt.minion      :1432][INFO    ][10866] Starting a new job with PID 10866
2019-07-12 20:29:45,119 [salt.minion      :1711][INFO    ][10866] Returning information for job: 20190712202945044747
2019-07-12 20:30:00,381 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1413.69810009s left)
2019-07-12 20:30:15,094 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712203015080113
2019-07-12 20:30:15,124 [salt.minion      :1432][INFO    ][10892] Starting a new job with PID 10892
2019-07-12 20:30:15,147 [salt.minion      :1711][INFO    ][10892] Returning information for job: 20190712203015080113
2019-07-12 20:30:32,466 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1381.61257005s left)
2019-07-12 20:30:45,130 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712203045113986
2019-07-12 20:30:45,160 [salt.minion      :1432][INFO    ][10944] Starting a new job with PID 10944
2019-07-12 20:30:45,181 [salt.minion      :1711][INFO    ][10944] Returning information for job: 20190712203045113986
2019-07-12 20:31:04,438 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1349.6405139s left)
2019-07-12 20:31:15,190 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712203115175129
2019-07-12 20:31:15,221 [salt.minion      :1432][INFO    ][10969] Starting a new job with PID 10969
2019-07-12 20:31:15,247 [salt.minion      :1711][INFO    ][10969] Returning information for job: 20190712203115175129
2019-07-12 20:31:36,396 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1317.68255901s left)
2019-07-12 20:31:45,261 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712203145249990
2019-07-12 20:31:45,289 [salt.minion      :1432][INFO    ][11027] Starting a new job with PID 11027
2019-07-12 20:31:45,308 [salt.minion      :1711][INFO    ][11027] Returning information for job: 20190712203145249990
2019-07-12 20:32:08,302 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1285.776227s left)
2019-07-12 20:32:15,325 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712203215317969
2019-07-12 20:32:15,351 [salt.minion      :1432][INFO    ][11051] Starting a new job with PID 11051
2019-07-12 20:32:15,376 [salt.minion      :1711][INFO    ][11051] Returning information for job: 20190712203215317969
2019-07-12 20:32:40,071 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1254.00936604s left)
2019-07-12 20:32:45,400 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712203245383571
2019-07-12 20:32:45,429 [salt.minion      :1432][INFO    ][11102] Starting a new job with PID 11102
2019-07-12 20:32:45,454 [salt.minion      :1711][INFO    ][11102] Returning information for job: 20190712203245383571
2019-07-12 20:33:11,998 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1222.08056092s left)
2019-07-12 20:33:15,491 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712203315482244
2019-07-12 20:33:15,515 [salt.minion      :1432][INFO    ][11131] Starting a new job with PID 11131
2019-07-12 20:33:15,539 [salt.minion      :1711][INFO    ][11131] Returning information for job: 20190712203315482244
2019-07-12 20:33:43,987 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1190.09116197s left)
2019-07-12 20:33:45,588 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712203345580194
2019-07-12 20:33:45,616 [salt.minion      :1432][INFO    ][11184] Starting a new job with PID 11184
2019-07-12 20:33:45,639 [salt.minion      :1711][INFO    ][11184] Returning information for job: 20190712203345580194
2019-07-12 20:34:15,700 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712203415685602
2019-07-12 20:34:15,720 [salt.minion      :1432][INFO    ][11207] Starting a new job with PID 11207
2019-07-12 20:34:15,744 [salt.minion      :1711][INFO    ][11207] Returning information for job: 20190712203415685602
2019-07-12 20:34:15,863 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1158.21519899s left)
2019-07-12 20:34:45,819 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712203445807037
2019-07-12 20:34:45,848 [salt.minion      :1432][INFO    ][11255] Starting a new job with PID 11255
2019-07-12 20:34:45,871 [salt.minion      :1711][INFO    ][11255] Returning information for job: 20190712203445807037
2019-07-12 20:34:47,925 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1126.15355706s left)
2019-07-12 20:35:15,956 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712203515943185
2019-07-12 20:35:15,983 [salt.minion      :1432][INFO    ][11280] Starting a new job with PID 11280
2019-07-12 20:35:16,005 [salt.minion      :1711][INFO    ][11280] Returning information for job: 20190712203515943185
2019-07-12 20:35:19,953 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1094.12589312s left)
2019-07-12 20:35:46,112 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712203546100839
2019-07-12 20:35:46,141 [salt.minion      :1432][INFO    ][11329] Starting a new job with PID 11329
2019-07-12 20:35:46,163 [salt.minion      :1711][INFO    ][11329] Returning information for job: 20190712203546100839
2019-07-12 20:35:51,820 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1062.25870299s left)
2019-07-12 20:36:16,289 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712203616273106
2019-07-12 20:36:16,311 [salt.minion      :1432][INFO    ][11353] Starting a new job with PID 11353
2019-07-12 20:36:16,335 [salt.minion      :1711][INFO    ][11353] Returning information for job: 20190712203616273106
2019-07-12 20:36:23,775 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1030.30343509s left)
2019-07-12 20:36:46,485 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712203646468596
2019-07-12 20:36:46,517 [salt.minion      :1432][INFO    ][11404] Starting a new job with PID 11404
2019-07-12 20:36:46,540 [salt.minion      :1711][INFO    ][11404] Returning information for job: 20190712203646468596
2019-07-12 20:36:55,858 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (998.220762014s left)
2019-07-12 20:37:16,697 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712203716682656
2019-07-12 20:37:16,726 [salt.minion      :1432][INFO    ][11428] Starting a new job with PID 11428
2019-07-12 20:37:16,752 [salt.minion      :1711][INFO    ][11428] Returning information for job: 20190712203716682656
2019-07-12 20:37:27,933 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (966.145419121s left)
2019-07-12 20:37:46,907 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712203746891238
2019-07-12 20:37:46,936 [salt.minion      :1432][INFO    ][11495] Starting a new job with PID 11495
2019-07-12 20:37:46,961 [salt.minion      :1711][INFO    ][11495] Returning information for job: 20190712203746891238
2019-07-12 20:37:59,845 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (934.234106064s left)
2019-07-12 20:38:16,945 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712203816932559
2019-07-12 20:38:16,972 [salt.minion      :1432][INFO    ][11523] Starting a new job with PID 11523
2019-07-12 20:38:16,999 [salt.minion      :1711][INFO    ][11523] Returning information for job: 20190712203816932559
2019-07-12 20:38:31,757 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (902.322037935s left)
2019-07-12 20:38:46,997 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712203846980318
2019-07-12 20:38:47,026 [salt.minion      :1432][INFO    ][11576] Starting a new job with PID 11576
2019-07-12 20:38:47,051 [salt.minion      :1711][INFO    ][11576] Returning information for job: 20190712203846980318
2019-07-12 20:39:03,643 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (870.435983896s left)
2019-07-12 20:39:17,069 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712203917056981
2019-07-12 20:39:17,093 [salt.minion      :1432][INFO    ][11602] Starting a new job with PID 11602
2019-07-12 20:39:17,119 [salt.minion      :1711][INFO    ][11602] Returning information for job: 20190712203917056981
2019-07-12 20:39:35,543 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (838.535506964s left)
2019-07-12 20:39:47,172 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712203947156073
2019-07-12 20:39:47,200 [salt.minion      :1432][INFO    ][11650] Starting a new job with PID 11650
2019-07-12 20:39:47,226 [salt.minion      :1711][INFO    ][11650] Returning information for job: 20190712203947156073
2019-07-12 20:40:07,593 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (806.485909939s left)
2019-07-12 20:40:17,273 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712204017257399
2019-07-12 20:40:17,302 [salt.minion      :1432][INFO    ][11673] Starting a new job with PID 11673
2019-07-12 20:40:17,327 [salt.minion      :1711][INFO    ][11673] Returning information for job: 20190712204017257399
2019-07-12 20:40:39,628 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (774.450182915s left)
2019-07-12 20:40:47,358 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712204047346826
2019-07-12 20:40:47,386 [salt.minion      :1432][INFO    ][11723] Starting a new job with PID 11723
2019-07-12 20:40:47,411 [salt.minion      :1711][INFO    ][11723] Returning information for job: 20190712204047346826
2019-07-12 20:41:11,451 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (742.627232075s left)
2019-07-12 20:41:17,492 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712204117482492
2019-07-12 20:41:17,517 [salt.minion      :1432][INFO    ][11748] Starting a new job with PID 11748
2019-07-12 20:41:17,539 [salt.minion      :1711][INFO    ][11748] Returning information for job: 20190712204117482492
2019-07-12 20:41:43,308 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (710.770704985s left)
2019-07-12 20:41:47,635 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712204147618990
2019-07-12 20:41:47,662 [salt.minion      :1432][INFO    ][11799] Starting a new job with PID 11799
2019-07-12 20:41:47,685 [salt.minion      :1711][INFO    ][11799] Returning information for job: 20190712204147618990
2019-07-12 20:42:15,345 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (678.733664989s left)
2019-07-12 20:42:17,808 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712204217792941
2019-07-12 20:42:17,832 [salt.minion      :1432][INFO    ][11823] Starting a new job with PID 11823
2019-07-12 20:42:17,857 [salt.minion      :1711][INFO    ][11823] Returning information for job: 20190712204217792941
2019-07-12 20:42:47,375 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (646.703824043s left)
2019-07-12 20:42:47,976 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712204247962356
2019-07-12 20:42:48,006 [salt.minion      :1432][INFO    ][11875] Starting a new job with PID 11875
2019-07-12 20:42:48,031 [salt.minion      :1711][INFO    ][11875] Returning information for job: 20190712204247962356
2019-07-12 20:43:18,204 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712204318188682
2019-07-12 20:43:18,227 [salt.minion      :1432][INFO    ][11899] Starting a new job with PID 11899
2019-07-12 20:43:18,252 [salt.minion      :1711][INFO    ][11899] Returning information for job: 20190712204318188682
2019-07-12 20:43:19,305 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (614.773528099s left)
2019-07-12 20:43:48,228 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712204348208752
2019-07-12 20:43:48,258 [salt.minion      :1432][INFO    ][11952] Starting a new job with PID 11952
2019-07-12 20:43:48,288 [salt.minion      :1711][INFO    ][11952] Returning information for job: 20190712204348208752
2019-07-12 20:43:51,247 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (582.831219912s left)
2019-07-12 20:44:18,281 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712204418264721
2019-07-12 20:44:18,312 [salt.minion      :1432][INFO    ][11978] Starting a new job with PID 11978
2019-07-12 20:44:18,333 [salt.minion      :1711][INFO    ][11978] Returning information for job: 20190712204418264721
2019-07-12 20:44:23,214 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (550.864998102s left)
2019-07-12 20:44:48,516 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712204448497768
2019-07-12 20:44:48,547 [salt.minion      :1432][INFO    ][12028] Starting a new job with PID 12028
2019-07-12 20:44:48,574 [salt.minion      :1711][INFO    ][12028] Returning information for job: 20190712204448497768
2019-07-12 20:44:55,211 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (518.867543936s left)
2019-07-12 20:45:18,610 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712204518593530
2019-07-12 20:45:18,636 [salt.minion      :1432][INFO    ][12052] Starting a new job with PID 12052
2019-07-12 20:45:18,661 [salt.minion      :1711][INFO    ][12052] Returning information for job: 20190712204518593530
2019-07-12 20:45:27,244 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (486.835016012s left)
2019-07-12 20:45:48,685 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712204548677526
2019-07-12 20:45:48,705 [salt.minion      :1432][INFO    ][12103] Starting a new job with PID 12103
2019-07-12 20:45:48,734 [salt.minion      :1711][INFO    ][12103] Returning information for job: 20190712204548677526
2019-07-12 20:45:59,136 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (454.942254066s left)
2019-07-12 20:46:18,787 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712204618771084
2019-07-12 20:46:18,813 [salt.minion      :1432][INFO    ][12129] Starting a new job with PID 12129
2019-07-12 20:46:18,838 [salt.minion      :1711][INFO    ][12129] Returning information for job: 20190712204618771084
2019-07-12 20:46:31,158 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (422.921214104s left)
2019-07-12 20:46:48,917 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712204648901697
2019-07-12 20:46:48,946 [salt.minion      :1432][INFO    ][12184] Starting a new job with PID 12184
2019-07-12 20:46:48,968 [salt.minion      :1711][INFO    ][12184] Returning information for job: 20190712204648901697
2019-07-12 20:47:03,048 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (391.030218124s left)
2019-07-12 20:47:19,075 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712204719063161
2019-07-12 20:47:19,104 [salt.minion      :1432][INFO    ][12212] Starting a new job with PID 12212
2019-07-12 20:47:19,126 [salt.minion      :1711][INFO    ][12212] Returning information for job: 20190712204719063161
2019-07-12 20:47:35,086 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (358.992451906s left)
2019-07-12 20:47:49,277 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712204749261261
2019-07-12 20:47:49,306 [salt.minion      :1432][INFO    ][12410] Starting a new job with PID 12410
2019-07-12 20:47:49,336 [salt.minion      :1711][INFO    ][12410] Returning information for job: 20190712204749261261
2019-07-12 20:48:07,192 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (326.886682987s left)
2019-07-12 20:48:19,484 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712204819471374
2019-07-12 20:48:19,512 [salt.minion      :1432][INFO    ][12438] Starting a new job with PID 12438
2019-07-12 20:48:19,541 [salt.minion      :1711][INFO    ][12438] Returning information for job: 20190712204819471374
2019-07-12 20:48:39,156 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (294.922934055s left)
2019-07-12 20:48:49,512 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712204849494979
2019-07-12 20:48:49,540 [salt.minion      :1432][INFO    ][12492] Starting a new job with PID 12492
2019-07-12 20:48:49,565 [salt.minion      :1711][INFO    ][12492] Returning information for job: 20190712204849494979
2019-07-12 20:49:10,995 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (263.083698988s left)
2019-07-12 20:49:19,724 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712204919709263
2019-07-12 20:49:19,750 [salt.minion      :1432][INFO    ][12516] Starting a new job with PID 12516
2019-07-12 20:49:19,774 [salt.minion      :1711][INFO    ][12516] Returning information for job: 20190712204919709263
2019-07-12 20:49:42,907 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (231.171232939s left)
2019-07-12 20:49:49,771 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712204949755868
2019-07-12 20:49:49,800 [salt.minion      :1432][INFO    ][12568] Starting a new job with PID 12568
2019-07-12 20:49:49,822 [salt.minion      :1711][INFO    ][12568] Returning information for job: 20190712204949755868
2019-07-12 20:50:14,847 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (199.231715918s left)
2019-07-12 20:50:20,000 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712205019984885
2019-07-12 20:50:20,025 [salt.minion      :1432][INFO    ][12591] Starting a new job with PID 12591
2019-07-12 20:50:20,051 [salt.minion      :1711][INFO    ][12591] Returning information for job: 20190712205019984885
2019-07-12 20:50:46,869 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (167.20937705s left)
2019-07-12 20:50:50,115 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712205050100149
2019-07-12 20:50:50,143 [salt.minion      :1432][INFO    ][12639] Starting a new job with PID 12639
2019-07-12 20:50:50,172 [salt.minion      :1711][INFO    ][12639] Returning information for job: 20190712205050100149
2019-07-12 20:51:18,640 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (135.438483953s left)
2019-07-12 20:51:20,207 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712205120190626
2019-07-12 20:51:20,238 [salt.minion      :1432][INFO    ][12666] Starting a new job with PID 12666
2019-07-12 20:51:20,261 [salt.minion      :1711][INFO    ][12666] Returning information for job: 20190712205120190626
2019-07-12 20:51:50,351 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712205150344006
2019-07-12 20:51:50,377 [salt.minion      :1432][INFO    ][12718] Starting a new job with PID 12718
2019-07-12 20:51:50,401 [salt.minion      :1711][INFO    ][12718] Returning information for job: 20190712205150344006
2019-07-12 20:51:50,731 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (103.347253084s left)
2019-07-12 20:52:20,483 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712205220472926
2019-07-12 20:52:20,504 [salt.minion      :1432][INFO    ][12738] Starting a new job with PID 12738
2019-07-12 20:52:20,530 [salt.minion      :1711][INFO    ][12738] Returning information for job: 20190712205220472926
2019-07-12 20:52:22,585 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (71.4937980175s left)
2019-07-12 20:52:50,586 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712205250575520
2019-07-12 20:52:50,611 [salt.minion      :1432][INFO    ][12790] Starting a new job with PID 12790
2019-07-12 20:52:50,637 [salt.minion      :1711][INFO    ][12790] Returning information for job: 20190712205250575520
2019-07-12 20:52:54,471 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (39.6071989536s left)
2019-07-12 20:53:20,784 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712205320771064
2019-07-12 20:53:20,812 [salt.minion      :1432][INFO    ][12814] Starting a new job with PID 12814
2019-07-12 20:53:20,844 [salt.minion      :1711][INFO    ][12814] Returning information for job: 20190712205320771064
2019-07-12 20:53:26,278 [salt.loaded.ext.module.maas:1023][INFO    ][7798] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (7.80069589615s left)
2019-07-12 20:53:50,974 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712205350960544
2019-07-12 20:53:51,005 [salt.minion      :1432][INFO    ][12863] Starting a new job with PID 12863
2019-07-12 20:53:51,028 [salt.minion      :1711][INFO    ][12863] Returning information for job: 20190712205350960544
2019-07-12 20:53:58,168 [salt.state       :302 ][ERROR   ][7798] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['gtw01']not in Deployed state
2019-07-12 20:53:58,169 [salt.state       :1951][INFO    ][7798] Completed state [maas.wait_for_machine_status] at time 20:53:58.168965 duration_in_ms=2274101.803
2019-07-12 20:53:58,176 [salt.minion      :1711][INFO    ][7798] Returning information for job: 20190712201556263949
2019-07-12 20:54:08,983 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command pillar.get with jid 20190712205408967927
2019-07-12 20:54:09,010 [salt.minion      :1432][INFO    ][12886] Starting a new job with PID 12886
2019-07-12 20:54:09,018 [salt.minion      :1711][INFO    ][12886] Returning information for job: 20190712205408967927
2019-07-12 20:54:09,585 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command service.status with jid 20190712205409575309
2019-07-12 20:54:09,615 [salt.minion      :1432][INFO    ][12891] Starting a new job with PID 12891
2019-07-12 20:54:10,157 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12891] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-07-12 20:54:10,199 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12891] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-07-12 20:54:10,220 [salt.minion      :1711][INFO    ][12891] Returning information for job: 20190712205409575309
2019-07-12 20:54:10,787 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command state.apply with jid 20190712205410775579
2019-07-12 20:54:10,816 [salt.minion      :1432][INFO    ][12902] Starting a new job with PID 12902
2019-07-12 20:54:16,733 [salt.state       :915 ][INFO    ][12902] Loading fresh modules for state activity
2019-07-12 20:54:17,310 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12902] Executing command 'salt-minion --version' in directory '/root'
2019-07-12 20:54:17,611 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12902] Executing command 'salt-minion --version' in directory '/root'
2019-07-12 20:54:18,572 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12902] Executing command 'salt-minion --version' in directory '/root'
2019-07-12 20:54:18,863 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12902] Executing command 'salt-minion --version' in directory '/root'
2019-07-12 20:54:20,675 [salt.state       :1780][INFO    ][12902] Running state [salt-minion] at time 20:54:20.675343
2019-07-12 20:54:20,675 [salt.state       :1813][INFO    ][12902] Executing state pkg.installed for [salt-minion]
2019-07-12 20:54:20,676 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12902] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-07-12 20:54:20,792 [salt.state       :300 ][INFO    ][12902] All specified packages are already installed
2019-07-12 20:54:20,793 [salt.state       :1951][INFO    ][12902] Completed state [salt-minion] at time 20:54:20.792995 duration_in_ms=117.652
2019-07-12 20:54:20,793 [salt.state       :1780][INFO    ][12902] Running state [salt_minion_dependency_packages] at time 20:54:20.793351
2019-07-12 20:54:20,793 [salt.state       :1813][INFO    ][12902] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-07-12 20:54:20,803 [salt.state       :300 ][INFO    ][12902] All specified packages are already installed
2019-07-12 20:54:20,803 [salt.state       :1951][INFO    ][12902] Completed state [salt_minion_dependency_packages] at time 20:54:20.803649 duration_in_ms=10.298
2019-07-12 20:54:20,807 [salt.state       :1780][INFO    ][12902] Running state [/etc/salt/minion.d/minion.conf] at time 20:54:20.807076
2019-07-12 20:54:20,807 [salt.state       :1813][INFO    ][12902] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-07-12 20:54:21,082 [salt.state       :300 ][INFO    ][12902] File /etc/salt/minion.d/minion.conf is in the correct state
2019-07-12 20:54:21,082 [salt.state       :1951][INFO    ][12902] Completed state [/etc/salt/minion.d/minion.conf] at time 20:54:21.082781 duration_in_ms=275.705
2019-07-12 20:54:21,085 [salt.state       :1780][INFO    ][12902] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 20:54:21.085841
2019-07-12 20:54:21,086 [salt.state       :1813][INFO    ][12902] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-07-12 20:54:21,100 [salt.state       :300 ][INFO    ][12902] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-07-12 20:54:21,101 [salt.state       :1951][INFO    ][12902] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 20:54:21.100986 duration_in_ms=15.145
2019-07-12 20:54:21,103 [salt.state       :1780][INFO    ][12902] Running state [salt-minion] at time 20:54:21.103140
2019-07-12 20:54:21,103 [salt.state       :1813][INFO    ][12902] Executing state service.running for [salt-minion]
2019-07-12 20:54:21,104 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12902] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-07-12 20:54:21,149 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12902] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-07-12 20:54:21,173 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12902] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-07-12 20:54:21,196 [salt.state       :300 ][INFO    ][12902] The service salt-minion is already running
2019-07-12 20:54:21,197 [salt.state       :1951][INFO    ][12902] Completed state [salt-minion] at time 20:54:21.197143 duration_in_ms=94.002
2019-07-12 20:54:21,200 [salt.state       :1780][INFO    ][12902] Running state [/etc/salt/grains.d] at time 20:54:21.200092
2019-07-12 20:54:21,200 [salt.state       :1813][INFO    ][12902] Executing state file.directory for [/etc/salt/grains.d]
2019-07-12 20:54:21,202 [salt.state       :300 ][INFO    ][12902] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-07-12 20:54:21,202 [salt.state       :1951][INFO    ][12902] Completed state [/etc/salt/grains.d] at time 20:54:21.202593 duration_in_ms=2.501
2019-07-12 20:54:21,203 [salt.state       :1780][INFO    ][12902] Running state [/etc/salt/grains] at time 20:54:21.203749
2019-07-12 20:54:21,204 [salt.state       :1813][INFO    ][12902] Executing state file.managed for [/etc/salt/grains]
2019-07-12 20:54:21,205 [salt.state       :300 ][INFO    ][12902] File /etc/salt/grains exists with proper permissions. No changes made.
2019-07-12 20:54:21,205 [salt.state       :1951][INFO    ][12902] Completed state [/etc/salt/grains] at time 20:54:21.205428 duration_in_ms=1.678
2019-07-12 20:54:21,208 [salt.state       :1780][INFO    ][12902] Running state [/etc/salt/grains.d/placeholder] at time 20:54:21.208059
2019-07-12 20:54:21,208 [salt.state       :1813][INFO    ][12902] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-07-12 20:54:21,208 [salt.state       :300 ][INFO    ][12902] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-07-12 20:54:21,209 [salt.state       :1951][INFO    ][12902] Completed state [/etc/salt/grains.d/placeholder] at time 20:54:21.208973 duration_in_ms=0.913
2019-07-12 20:54:21,209 [salt.state       :1780][INFO    ][12902] Running state [/etc/salt/grains.d/sphinx] at time 20:54:21.209431
2019-07-12 20:54:21,209 [salt.state       :1813][INFO    ][12902] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-07-12 20:54:21,211 [salt.state       :300 ][INFO    ][12902] File /etc/salt/grains.d/sphinx is in the correct state
2019-07-12 20:54:21,211 [salt.state       :1951][INFO    ][12902] Completed state [/etc/salt/grains.d/sphinx] at time 20:54:21.211306 duration_in_ms=1.876
2019-07-12 20:54:21,213 [salt.state       :1780][INFO    ][12902] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 20:54:21.213367
2019-07-12 20:54:21,213 [salt.state       :1813][INFO    ][12902] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-07-12 20:54:21,213 [salt.state       :300 ][INFO    ][12902] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-07-12 20:54:21,214 [salt.state       :1951][INFO    ][12902] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 20:54:21.214142 duration_in_ms=0.775
2019-07-12 20:54:21,214 [salt.state       :1780][INFO    ][12902] Running state [/etc/salt/grains.d/dns_records] at time 20:54:21.214620
2019-07-12 20:54:21,214 [salt.state       :1813][INFO    ][12902] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-07-12 20:54:21,215 [salt.state       :300 ][INFO    ][12902] File /etc/salt/grains.d/dns_records is in the correct state
2019-07-12 20:54:21,215 [salt.state       :1951][INFO    ][12902] Completed state [/etc/salt/grains.d/dns_records] at time 20:54:21.215917 duration_in_ms=1.296
2019-07-12 20:54:21,216 [salt.state       :1780][INFO    ][12902] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 20:54:21.216828
2019-07-12 20:54:21,217 [salt.state       :1813][INFO    ][12902] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-07-12 20:54:21,217 [salt.state       :300 ][INFO    ][12902] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-07-12 20:54:21,217 [salt.state       :1951][INFO    ][12902] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 20:54:21.217560 duration_in_ms=0.732
2019-07-12 20:54:21,219 [salt.state       :1780][INFO    ][12902] Running state [/etc/salt/grains.d/salt] at time 20:54:21.219726
2019-07-12 20:54:21,220 [salt.state       :1813][INFO    ][12902] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-07-12 20:54:21,220 [salt.state       :300 ][INFO    ][12902] File /etc/salt/grains.d/salt is in the correct state
2019-07-12 20:54:21,221 [salt.state       :1951][INFO    ][12902] Completed state [/etc/salt/grains.d/salt] at time 20:54:21.221038 duration_in_ms=1.312
2019-07-12 20:54:21,221 [salt.state       :1780][INFO    ][12902] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 20:54:21.221911
2019-07-12 20:54:21,222 [salt.state       :1813][INFO    ][12902] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-07-12 20:54:21,222 [salt.state       :300 ][INFO    ][12902] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-07-12 20:54:21,222 [salt.state       :1951][INFO    ][12902] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 20:54:21.222654 duration_in_ms=0.743
2019-07-12 20:54:21,224 [salt.state       :1780][INFO    ][12902] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 20:54:21.224526
2019-07-12 20:54:21,224 [salt.state       :1813][INFO    ][12902] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-07-12 20:54:21,225 [salt.state       :300 ][INFO    ][12902] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-07-12 20:54:21,225 [salt.state       :1951][INFO    ][12902] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 20:54:21.225290 duration_in_ms=0.764
2019-07-12 20:54:21,226 [salt.state       :1780][INFO    ][12902] Running state [mine.update] at time 20:54:21.225970
2019-07-12 20:54:21,226 [salt.state       :1813][INFO    ][12902] Executing state module.wait for [mine.update]
2019-07-12 20:54:21,226 [salt.state       :300 ][INFO    ][12902] No changes made for mine.update
2019-07-12 20:54:21,226 [salt.state       :1951][INFO    ][12902] Completed state [mine.update] at time 20:54:21.226663 duration_in_ms=0.693
2019-07-12 20:54:21,226 [salt.state       :1780][INFO    ][12902] Running state [ca-certificates] at time 20:54:21.226894
2019-07-12 20:54:21,227 [salt.state       :1813][INFO    ][12902] Executing state pkg.installed for [ca-certificates]
2019-07-12 20:54:21,237 [salt.state       :300 ][INFO    ][12902] All specified packages are already installed
2019-07-12 20:54:21,237 [salt.state       :1951][INFO    ][12902] Completed state [ca-certificates] at time 20:54:21.237223 duration_in_ms=10.328
2019-07-12 20:54:21,238 [salt.state       :1780][INFO    ][12902] Running state [update-ca-certificates] at time 20:54:21.238133
2019-07-12 20:54:21,238 [salt.state       :1813][INFO    ][12902] Executing state cmd.wait for [update-ca-certificates]
2019-07-12 20:54:21,238 [salt.state       :300 ][INFO    ][12902] No changes made for update-ca-certificates
2019-07-12 20:54:21,238 [salt.state       :1951][INFO    ][12902] Completed state [update-ca-certificates] at time 20:54:21.238855 duration_in_ms=0.722
2019-07-12 20:54:21,239 [salt.state       :1780][INFO    ][12902] Running state [iptables] at time 20:54:21.239092
2019-07-12 20:54:21,239 [salt.state       :1813][INFO    ][12902] Executing state pkg.installed for [iptables]
2019-07-12 20:54:21,248 [salt.state       :300 ][INFO    ][12902] All specified packages are already installed
2019-07-12 20:54:21,248 [salt.state       :1951][INFO    ][12902] Completed state [iptables] at time 20:54:21.248414 duration_in_ms=9.321
2019-07-12 20:54:21,248 [salt.state       :1780][INFO    ][12902] Running state [iptables-persistent] at time 20:54:21.248653
2019-07-12 20:54:21,248 [salt.state       :1813][INFO    ][12902] Executing state pkg.installed for [iptables-persistent]
2019-07-12 20:54:21,257 [salt.state       :300 ][INFO    ][12902] All specified packages are already installed
2019-07-12 20:54:21,258 [salt.state       :1951][INFO    ][12902] Completed state [iptables-persistent] at time 20:54:21.258400 duration_in_ms=9.746
2019-07-12 20:54:21,259 [salt.state       :1780][INFO    ][12902] Running state [iptables_modules_v4_load] at time 20:54:21.259360
2019-07-12 20:54:21,259 [salt.state       :1813][INFO    ][12902] Executing state kmod.present for [iptables_modules_v4_load]
2019-07-12 20:54:21,260 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12902] Executing command 'lsmod' in directory '/root'
2019-07-12 20:54:21,283 [salt.state       :300 ][INFO    ][12902] Kernel modules iptable_filter, ip_tables are already present
2019-07-12 20:54:21,284 [salt.state       :1951][INFO    ][12902] Completed state [iptables_modules_v4_load] at time 20:54:21.284171 duration_in_ms=24.81
2019-07-12 20:54:21,285 [salt.state       :1780][INFO    ][12902] Running state [/etc/iptables/rules.v4] at time 20:54:21.285326
2019-07-12 20:54:21,285 [salt.state       :1813][INFO    ][12902] Executing state file.managed for [/etc/iptables/rules.v4]
2019-07-12 20:54:21,398 [salt.state       :300 ][INFO    ][12902] File /etc/iptables/rules.v4 is in the correct state
2019-07-12 20:54:21,398 [salt.state       :1951][INFO    ][12902] Completed state [/etc/iptables/rules.v4] at time 20:54:21.398578 duration_in_ms=113.253
2019-07-12 20:54:21,399 [salt.state       :1780][INFO    ][12902] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 20:54:21.399654
2019-07-12 20:54:21,399 [salt.state       :1813][INFO    ][12902] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-07-12 20:54:21,400 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12902] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-07-12 20:54:21,420 [salt.state       :300 ][INFO    ][12902] onlyif execution failed
2019-07-12 20:54:21,421 [salt.state       :1951][INFO    ][12902] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 20:54:21.421170 duration_in_ms=21.516
2019-07-12 20:54:21,424 [salt.state       :1780][INFO    ][12902] Running state [netfilter-persistent] at time 20:54:21.424832
2019-07-12 20:54:21,425 [salt.state       :1813][INFO    ][12902] Executing state service.running for [netfilter-persistent]
2019-07-12 20:54:21,426 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12902] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-07-12 20:54:21,450 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12902] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-07-12 20:54:21,472 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12902] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-07-12 20:54:21,493 [salt.state       :300 ][INFO    ][12902] The service netfilter-persistent is already running
2019-07-12 20:54:21,495 [salt.state       :1951][INFO    ][12902] Completed state [netfilter-persistent] at time 20:54:21.495653 duration_in_ms=70.821
2019-07-12 20:54:21,497 [salt.state       :1780][INFO    ][12902] Running state [iptables_extra.remove_stale_tables] at time 20:54:21.497462
2019-07-12 20:54:21,498 [salt.state       :1813][INFO    ][12902] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-07-12 20:54:21,498 [salt.state       :300 ][INFO    ][12902] No changes made for iptables_extra.remove_stale_tables
2019-07-12 20:54:21,499 [salt.state       :1951][INFO    ][12902] Completed state [iptables_extra.remove_stale_tables] at time 20:54:21.499287 duration_in_ms=1.825
2019-07-12 20:54:21,499 [salt.state       :1780][INFO    ][12902] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 20:54:21.499779
2019-07-12 20:54:21,500 [salt.state       :1813][INFO    ][12902] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-07-12 20:54:21,501 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12902] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-07-12 20:54:21,523 [salt.state       :300 ][INFO    ][12902] onlyif execution failed
2019-07-12 20:54:21,523 [salt.state       :1951][INFO    ][12902] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 20:54:21.523682 duration_in_ms=23.902
2019-07-12 20:54:21,525 [salt.state       :1780][INFO    ][12902] Running state [/etc/iptables/rules.v6] at time 20:54:21.525369
2019-07-12 20:54:21,525 [salt.state       :1813][INFO    ][12902] Executing state file.absent for [/etc/iptables/rules.v6]
2019-07-12 20:54:21,526 [salt.state       :300 ][INFO    ][12902] File /etc/iptables/rules.v6 is not present
2019-07-12 20:54:21,527 [salt.state       :1951][INFO    ][12902] Completed state [/etc/iptables/rules.v6] at time 20:54:21.527017 duration_in_ms=1.647
2019-07-12 20:54:21,528 [salt.state       :1780][INFO    ][12902] Running state [iptables_extra.flush_all] at time 20:54:21.528276
2019-07-12 20:54:21,528 [salt.state       :1813][INFO    ][12902] Executing state module.wait for [iptables_extra.flush_all]
2019-07-12 20:54:21,529 [salt.state       :300 ][INFO    ][12902] No changes made for iptables_extra.flush_all
2019-07-12 20:54:21,532 [salt.state       :1951][INFO    ][12902] Completed state [iptables_extra.flush_all] at time 20:54:21.529615 duration_in_ms=1.339
2019-07-12 20:54:21,535 [salt.minion      :1711][INFO    ][12902] Returning information for job: 20190712205410775579
2019-07-12 20:54:22,083 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command state.apply with jid 20190712205422069587
2019-07-12 20:54:22,110 [salt.minion      :1432][INFO    ][12978] Starting a new job with PID 12978
2019-07-12 20:54:23,027 [salt.state       :915 ][INFO    ][12978] Loading fresh modules for state activity
2019-07-12 20:54:23,985 [salt.state       :1780][INFO    ][12978] Running state [maas-rack-controller] at time 20:54:23.985507
2019-07-12 20:54:23,985 [salt.state       :1813][INFO    ][12978] Executing state pkg.installed for [maas-rack-controller]
2019-07-12 20:54:23,986 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12978] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-07-12 20:54:24,103 [salt.state       :300 ][INFO    ][12978] All specified packages are already installed
2019-07-12 20:54:24,104 [salt.state       :1951][INFO    ][12978] Completed state [maas-rack-controller] at time 20:54:24.104155 duration_in_ms=118.648
2019-07-12 20:54:24,104 [salt.state       :1780][INFO    ][12978] Running state [ipmitool] at time 20:54:24.104472
2019-07-12 20:54:24,104 [salt.state       :1813][INFO    ][12978] Executing state pkg.installed for [ipmitool]
2019-07-12 20:54:24,114 [salt.state       :300 ][INFO    ][12978] All specified packages are already installed
2019-07-12 20:54:24,114 [salt.state       :1951][INFO    ][12978] Completed state [ipmitool] at time 20:54:24.114331 duration_in_ms=9.86
2019-07-12 20:54:24,117 [salt.state       :1780][INFO    ][12978] Running state [/etc/maas/rackd.conf] at time 20:54:24.117413
2019-07-12 20:54:24,117 [salt.state       :1813][INFO    ][12978] Executing state file.line for [/etc/maas/rackd.conf]
2019-07-12 20:54:24,118 [salt.state       :300 ][INFO    ][12978] No changes needed to be made
2019-07-12 20:54:24,118 [salt.state       :1951][INFO    ][12978] Completed state [/etc/maas/rackd.conf] at time 20:54:24.118767 duration_in_ms=1.354
2019-07-12 20:54:24,119 [salt.state       :1780][INFO    ][12978] Running state [/etc/maas/rackd.conf] at time 20:54:24.119024
2019-07-12 20:54:24,119 [salt.state       :1813][INFO    ][12978] Executing state file.managed for [/etc/maas/rackd.conf]
2019-07-12 20:54:24,119 [salt.loaded.int.states.file:2298][WARNING ][12978] 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-07-12 20:54:24,120 [salt.state       :300 ][INFO    ][12978] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-07-12 20:54:24,120 [salt.state       :1951][INFO    ][12978] Completed state [/etc/maas/rackd.conf] at time 20:54:24.120522 duration_in_ms=1.498
2019-07-12 20:54:24,121 [salt.state       :1780][INFO    ][12978] Running state [maas-rackd] at time 20:54:24.121505
2019-07-12 20:54:24,122 [salt.state       :1813][INFO    ][12978] Executing state service.running for [maas-rackd]
2019-07-12 20:54:24,123 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12978] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-07-12 20:54:24,156 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12978] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-07-12 20:54:24,179 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12978] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-07-12 20:54:24,202 [salt.state       :300 ][INFO    ][12978] The service maas-rackd is already running
2019-07-12 20:54:24,203 [salt.state       :1951][INFO    ][12978] Completed state [maas-rackd] at time 20:54:24.203582 duration_in_ms=82.076
2019-07-12 20:54:24,207 [salt.minion      :1711][INFO    ][12978] Returning information for job: 20190712205422069587
2019-07-12 20:54:24,780 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command state.apply with jid 20190712205424771092
2019-07-12 20:54:24,801 [salt.minion      :1432][INFO    ][13014] Starting a new job with PID 13014
2019-07-12 20:54:25,728 [salt.state       :915 ][INFO    ][13014] Loading fresh modules for state activity
2019-07-12 20:54:26,783 [salt.state       :1780][INFO    ][13014] Running state [maas-region-controller] at time 20:54:26.783328
2019-07-12 20:54:26,783 [salt.state       :1813][INFO    ][13014] Executing state pkg.installed for [maas-region-controller]
2019-07-12 20:54:26,784 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13014] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-07-12 20:54:26,898 [salt.state       :300 ][INFO    ][13014] All specified packages are already installed
2019-07-12 20:54:26,898 [salt.state       :1951][INFO    ][13014] Completed state [maas-region-controller] at time 20:54:26.898566 duration_in_ms=115.238
2019-07-12 20:54:26,899 [salt.state       :1780][INFO    ][13014] Running state [python-oauth] at time 20:54:26.899184
2019-07-12 20:54:26,900 [salt.state       :1813][INFO    ][13014] Executing state pkg.installed for [python-oauth]
2019-07-12 20:54:26,919 [salt.state       :300 ][INFO    ][13014] All specified packages are already installed
2019-07-12 20:54:26,919 [salt.state       :1951][INFO    ][13014] Completed state [python-oauth] at time 20:54:26.919604 duration_in_ms=20.421
2019-07-12 20:54:26,923 [salt.state       :1780][INFO    ][13014] Running state [/etc/maas/regiond.conf] at time 20:54:26.923028
2019-07-12 20:54:26,923 [salt.state       :1813][INFO    ][13014] Executing state file.replace for [/etc/maas/regiond.conf]
2019-07-12 20:54:26,928 [salt.state       :300 ][INFO    ][13014] No changes needed to be made
2019-07-12 20:54:26,928 [salt.state       :1951][INFO    ][13014] Completed state [/etc/maas/regiond.conf] at time 20:54:26.928258 duration_in_ms=5.23
2019-07-12 20:54:26,928 [salt.state       :1780][INFO    ][13014] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 20:54:26.928766
2019-07-12 20:54:26,929 [salt.state       :1813][INFO    ][13014] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-07-12 20:54:26,991 [salt.state       :300 ][INFO    ][13014] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-07-12 20:54:26,991 [salt.state       :1951][INFO    ][13014] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 20:54:26.991933 duration_in_ms=63.167
2019-07-12 20:54:26,992 [salt.state       :1780][INFO    ][13014] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 20:54:26.992450
2019-07-12 20:54:26,992 [salt.state       :1813][INFO    ][13014] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-07-12 20:54:26,997 [salt.state       :300 ][INFO    ][13014] No changes needed to be made
2019-07-12 20:54:26,997 [salt.state       :1951][INFO    ][13014] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 20:54:26.997205 duration_in_ms=4.755
2019-07-12 20:54:26,998 [salt.state       :1780][INFO    ][13014] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 20:54:26.997683
2019-07-12 20:54:26,998 [salt.state       :1813][INFO    ][13014] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-07-12 20:54:27,001 [salt.state       :300 ][INFO    ][13014] No changes needed to be made
2019-07-12 20:54:27,002 [salt.state       :1951][INFO    ][13014] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 20:54:27.002519 duration_in_ms=4.835
2019-07-12 20:54:27,003 [salt.state       :1780][INFO    ][13014] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 20:54:27.002992
2019-07-12 20:54:27,003 [salt.state       :1813][INFO    ][13014] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-07-12 20:54:27,034 [salt.state       :300 ][INFO    ][13014] No changes needed to be made
2019-07-12 20:54:27,034 [salt.state       :1951][INFO    ][13014] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 20:54:27.034353 duration_in_ms=31.361
2019-07-12 20:54:27,034 [salt.state       :1780][INFO    ][13014] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 20:54:27.034887
2019-07-12 20:54:27,035 [salt.state       :1813][INFO    ][13014] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-07-12 20:54:27,052 [salt.state       :300 ][INFO    ][13014] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-07-12 20:54:27,053 [salt.state       :1951][INFO    ][13014] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 20:54:27.053252 duration_in_ms=18.363
2019-07-12 20:54:27,055 [salt.state       :1780][INFO    ][13014] Running state [a2enmod headers] at time 20:54:27.055571
2019-07-12 20:54:27,055 [salt.state       :1813][INFO    ][13014] Executing state cmd.run for [a2enmod headers]
2019-07-12 20:54:27,056 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13014] Executing command 'a2enmod headers' in directory '/root'
2019-07-12 20:54:27,125 [salt.state       :300 ][INFO    ][13014] {'pid': 13033, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-07-12 20:54:27,126 [salt.state       :1951][INFO    ][13014] Completed state [a2enmod headers] at time 20:54:27.126129 duration_in_ms=70.558
2019-07-12 20:54:27,126 [salt.state       :1780][INFO    ][13014] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 20:54:27.126659
2019-07-12 20:54:27,127 [salt.state       :1813][INFO    ][13014] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-07-12 20:54:27,151 [salt.state       :300 ][INFO    ][13014] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-07-12 20:54:27,151 [salt.state       :1951][INFO    ][13014] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 20:54:27.151402 duration_in_ms=24.744
2019-07-12 20:54:27,152 [salt.state       :1780][INFO    ][13014] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 20:54:27.152095
2019-07-12 20:54:27,152 [salt.state       :1813][INFO    ][13014] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-07-12 20:54:27,211 [salt.state       :300 ][INFO    ][13014] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-07-12 20:54:27,212 [salt.state       :1951][INFO    ][13014] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 20:54:27.212039 duration_in_ms=59.944
2019-07-12 20:54:27,212 [salt.state       :1780][INFO    ][13014] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 20:54:27.212553
2019-07-12 20:54:27,212 [salt.state       :1813][INFO    ][13014] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-07-12 20:54:27,269 [salt.state       :300 ][INFO    ][13014] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-07-12 20:54:27,270 [salt.state       :1951][INFO    ][13014] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 20:54:27.270414 duration_in_ms=57.86
2019-07-12 20:54:27,271 [salt.state       :1780][INFO    ][13014] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 20:54:27.271407
2019-07-12 20:54:27,271 [salt.state       :1813][INFO    ][13014] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-07-12 20:54:27,348 [salt.state       :300 ][INFO    ][13014] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-07-12 20:54:27,348 [salt.state       :1951][INFO    ][13014] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 20:54:27.348379 duration_in_ms=76.972
2019-07-12 20:54:27,348 [salt.state       :1780][INFO    ][13014] Running state [/root/.pgpass] at time 20:54:27.348635
2019-07-12 20:54:27,348 [salt.state       :1813][INFO    ][13014] Executing state file.managed for [/root/.pgpass]
2019-07-12 20:54:27,394 [salt.state       :300 ][INFO    ][13014] File /root/.pgpass is in the correct state
2019-07-12 20:54:27,394 [salt.state       :1951][INFO    ][13014] Completed state [/root/.pgpass] at time 20:54:27.394755 duration_in_ms=46.12
2019-07-12 20:54:27,400 [salt.state       :1780][INFO    ][13014] Running state [maas-region syncdb --noinput] at time 20:54:27.400534
2019-07-12 20:54:27,400 [salt.state       :1813][INFO    ][13014] Executing state cmd.run for [maas-region syncdb --noinput]
2019-07-12 20:54:27,401 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13014] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-07-12 20:54:29,878 [salt.state       :300 ][INFO    ][13014] {'pid': 13046, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: piston3, maasserver, contenttypes, metadataserver, sessions, auth, sites\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-07-12 20:54:29,879 [salt.state       :1951][INFO    ][13014] Completed state [maas-region syncdb --noinput] at time 20:54:29.879453 duration_in_ms=2478.917
2019-07-12 20:54:29,880 [salt.state       :2022][WARNING ][13014] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-07-12 20:54:29,884 [salt.state       :1780][INFO    ][13014] Running state [maas-regiond] at time 20:54:29.884804
2019-07-12 20:54:29,885 [salt.state       :1813][INFO    ][13014] Executing state service.running for [maas-regiond]
2019-07-12 20:54:29,887 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13014] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-07-12 20:54:29,933 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13014] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-07-12 20:54:29,955 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13014] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-07-12 20:54:29,976 [salt.state       :300 ][INFO    ][13014] The service maas-regiond is already running
2019-07-12 20:54:29,976 [salt.state       :1951][INFO    ][13014] Completed state [maas-regiond] at time 20:54:29.976677 duration_in_ms=91.873
2019-07-12 20:54:29,981 [salt.state       :1780][INFO    ][13014] Running state [bind9] at time 20:54:29.981505
2019-07-12 20:54:29,982 [salt.state       :1813][INFO    ][13014] Executing state service.running for [bind9]
2019-07-12 20:54:29,983 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13014] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-07-12 20:54:30,007 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13014] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-07-12 20:54:30,029 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13014] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-07-12 20:54:30,053 [salt.state       :300 ][INFO    ][13014] The service bind9 is already running
2019-07-12 20:54:30,053 [salt.state       :1951][INFO    ][13014] Completed state [bind9] at time 20:54:30.053570 duration_in_ms=72.065
2019-07-12 20:54:30,057 [salt.state       :1780][INFO    ][13014] Running state [apache2] at time 20:54:30.057477
2019-07-12 20:54:30,058 [salt.state       :1813][INFO    ][13014] Executing state service.running for [apache2]
2019-07-12 20:54:30,059 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13014] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-07-12 20:54:30,084 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13014] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-07-12 20:54:30,104 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13014] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-07-12 20:54:30,131 [salt.state       :300 ][INFO    ][13014] The service apache2 is already running
2019-07-12 20:54:30,132 [salt.state       :1951][INFO    ][13014] Completed state [apache2] at time 20:54:30.132014 duration_in_ms=74.536
2019-07-12 20:54:30,136 [salt.state       :1780][INFO    ][13014] Running state [maasng.wait_for_http_code] at time 20:54:30.136588
2019-07-12 20:54:30,137 [salt.state       :1813][INFO    ][13014] Executing state module.run for [maasng.wait_for_http_code]
2019-07-12 20:54:30,137 [salt.utils.decorators:613 ][WARNING ][13014] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-12 20:54:30,272 [salt.state       :300 ][INFO    ][13014] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-07-12 20:54:30,272 [salt.state       :1951][INFO    ][13014] Completed state [maasng.wait_for_http_code] at time 20:54:30.272708 duration_in_ms=136.119
2019-07-12 20:54:30,274 [salt.state       :1780][INFO    ][13014] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 20:54:30.274388
2019-07-12 20:54:30,274 [salt.state       :1813][INFO    ][13014] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-07-12 20:54:30,275 [salt.state       :300 ][INFO    ][13014] /var/lib/maas/.setup_admin exists
2019-07-12 20:54:30,275 [salt.state       :1951][INFO    ][13014] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 20:54:30.275655 duration_in_ms=1.267
2019-07-12 20:54:30,276 [salt.state       :1780][INFO    ][13014] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:54:30.276750
2019-07-12 20:54:30,277 [salt.state       :1813][INFO    ][13014] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-12 20:54:30,279 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13014] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-12 20:54:31,972 [salt.state       :300 ][INFO    ][13014] {'pid': 13067, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-12 20:54:31,973 [salt.state       :1951][INFO    ][13014] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:54:31.973093 duration_in_ms=1696.343
2019-07-12 20:54:31,979 [salt.state       :1780][INFO    ][13014] Running state [maas_region_boot_source_resources_mirror] at time 20:54:31.979090
2019-07-12 20:54:31,979 [salt.state       :1813][INFO    ][13014] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-07-12 20:54:32,068 [salt.state       :300 ][INFO    ][13014] {'changes': {}}
2019-07-12 20:54:32,068 [salt.state       :1951][INFO    ][13014] Completed state [maas_region_boot_source_resources_mirror] at time 20:54:32.068422 duration_in_ms=89.332
2019-07-12 20:54:32,069 [salt.state       :1780][INFO    ][13014] Running state [maasng.boot_resources_import] at time 20:54:32.069075
2019-07-12 20:54:32,069 [salt.state       :1813][INFO    ][13014] Executing state module.run for [maasng.boot_resources_import]
2019-07-12 20:54:32,069 [salt.utils.decorators:613 ][WARNING ][13014] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-12 20:54:32,143 [salt.loaded.ext.module.maasng:1600][INFO    ][13014] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-07-12 20:54:37,180 [salt.loaded.ext.module.maasng:1600][INFO    ][13014] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-07-12 20:54:39,907 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712205439895646
2019-07-12 20:54:39,934 [salt.minion      :1432][INFO    ][13248] Starting a new job with PID 13248
2019-07-12 20:54:39,957 [salt.minion      :1711][INFO    ][13248] Returning information for job: 20190712205439895646
2019-07-12 20:54:42,284 [salt.state       :300 ][INFO    ][13014] {'ret': True}
2019-07-12 20:54:42,284 [salt.state       :1951][INFO    ][13014] Completed state [maasng.boot_resources_import] at time 20:54:42.284364 duration_in_ms=10215.288
2019-07-12 20:54:42,285 [salt.state       :1780][INFO    ][13014] Running state [maas_region_boot_sources_selection_xenial] at time 20:54:42.285550
2019-07-12 20:54:42,286 [salt.state       :1813][INFO    ][13014] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-07-12 20:54:42,468 [salt.state       :300 ][INFO    ][13014] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-07-12 20:54:42,469 [salt.state       :1951][INFO    ][13014] Completed state [maas_region_boot_sources_selection_xenial] at time 20:54:42.469039 duration_in_ms=183.488
2019-07-12 20:54:42,472 [salt.state       :1780][INFO    ][13014] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 20:54:42.471920
2019-07-12 20:54:42,472 [salt.state       :1813][INFO    ][13014] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-07-12 20:54:42,472 [salt.utils.decorators:613 ][WARNING ][13014] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-12 20:54:42,473 [salt.loaded.ext.module.maasng:1771][INFO    ][13014] boot-sources sync initiated for ALL Rack's
2019-07-12 20:54:43,539 [salt.state       :300 ][INFO    ][13014] {'ret': True}
2019-07-12 20:54:43,540 [salt.state       :1951][INFO    ][13014] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 20:54:43.540048 duration_in_ms=1068.128
2019-07-12 20:54:43,543 [salt.state       :1780][INFO    ][13014] Running state [maas.process_maas_config] at time 20:54:43.543324
2019-07-12 20:54:43,543 [salt.state       :1813][INFO    ][13014] Executing state module.run for [maas.process_maas_config]
2019-07-12 20:54:43,544 [salt.utils.decorators:613 ][WARNING ][13014] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-12 20:54:43,545 [salt.loaded.ext.module.maas:92  ][INFO    ][13014] maasconfig name=enable_http_proxy value=True
2019-07-12 20:54:43,607 [salt.loaded.ext.module.maas:92  ][INFO    ][13014] maasconfig name=upstream_dns value=8.8.8.8
2019-07-12 20:54:43,662 [salt.loaded.ext.module.maas:92  ][INFO    ][13014] maasconfig name=commissioning_distro_series value=xenial
2019-07-12 20:54:43,720 [salt.loaded.ext.module.maas:92  ][INFO    ][13014] maasconfig name=default_osystem value=ubuntu
2019-07-12 20:54:45,055 [salt.loaded.ext.module.maas:92  ][INFO    ][13014] maasconfig name=active_discovery_interval value=600
2019-07-12 20:54:45,108 [salt.loaded.ext.module.maas:92  ][INFO    ][13014] maasconfig name=dnssec_validation value=no
2019-07-12 20:54:45,156 [salt.loaded.ext.module.maas:92  ][INFO    ][13014] maasconfig name=maas_name value=mas01
2019-07-12 20:54:45,208 [salt.loaded.ext.module.maas:92  ][INFO    ][13014] maasconfig name=network_discovery value=enabled
2019-07-12 20:54:45,311 [salt.loaded.ext.module.maas:92  ][INFO    ][13014] maasconfig name=enable_third_party_drivers value=True
2019-07-12 20:54:45,391 [salt.loaded.ext.module.maas:92  ][INFO    ][13014] maasconfig name=default_storage_layout value=lvm
2019-07-12 20:54:45,446 [salt.loaded.ext.module.maas:92  ][INFO    ][13014] maasconfig name=ntp_external_only value=True
2019-07-12 20:54:45,509 [salt.loaded.ext.module.maas:92  ][INFO    ][13014] maasconfig name=disk_erase_with_secure_erase value=False
2019-07-12 20:54:45,572 [salt.loaded.ext.module.maas:92  ][INFO    ][13014] maasconfig name=default_distro_series value=xenial
2019-07-12 20:54:45,632 [salt.loaded.ext.module.maas:92  ][INFO    ][13014] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-07-12 20:54:45,751 [salt.state       :300 ][INFO    ][13014] {'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-07-12 20:54:45,751 [salt.state       :1951][INFO    ][13014] Completed state [maas.process_maas_config] at time 20:54:45.751581 duration_in_ms=2208.256
2019-07-12 20:54:45,752 [salt.state       :1780][INFO    ][13014] Running state [pxe_admin] at time 20:54:45.752492
2019-07-12 20:54:45,753 [salt.state       :1813][INFO    ][13014] Executing state maasng.fabric_present for [pxe_admin]
2019-07-12 20:54:45,816 [salt.loaded.ext.module.maasng:945 ][INFO    ][13014] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, 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'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, 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'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'a3ythp', 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'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-07-12 20:54:45,887 [salt.loaded.ext.module.maasng:1008][WARNING ][13014] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-07-12 20:54:45,887 [salt.loaded.ext.module.maasng:1011][WARNING ][13014] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-07-12 20:54:45,952 [salt.state       :300 ][INFO    ][13014] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-07-12 20:54:45,953 [salt.state       :1951][INFO    ][13014] Completed state [pxe_admin] at time 20:54:45.953256 duration_in_ms=200.764
2019-07-12 20:54:45,954 [salt.state       :1780][INFO    ][13014] Running state [vlan 0] at time 20:54:45.954423
2019-07-12 20:54:45,954 [salt.state       :1813][INFO    ][13014] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-07-12 20:54:46,037 [salt.loaded.ext.module.maasng:945 ][INFO    ][13014] [{u'vlans': [{u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', 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'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'vlans': [{u'vid': 0, u'fabric': u'fabric-2', u'name': u'untagged', 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'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None, u'name': u'fabric-2', u'id': 2}, {u'vlans': [{u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', 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'a3ythp', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u'', u'name': u'pxe_admin', u'id': 1}]
2019-07-12 20:54:46,158 [salt.loaded.ext.module.maasng:945 ][INFO    ][13014] [{u'vlans': [{u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', 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'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'vlans': [{u'vid': 0, u'fabric': u'fabric-2', u'name': u'untagged', 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'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None, u'name': u'fabric-2', u'id': 2}, {u'vlans': [{u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', 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'a3ythp', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u'', u'name': u'pxe_admin', u'id': 1}]
2019-07-12 20:54:46,411 [salt.loaded.ext.module.maasng:945 ][INFO    ][13014] [{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'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-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'name': u'untagged', 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'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'a3ythp', u'name': u'untagged', 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-07-12 20:54:46,510 [salt.state       :300 ][INFO    ][13014] {'new': 'Vlan untagged was updated'}
2019-07-12 20:54:46,511 [salt.state       :1951][INFO    ][13014] Completed state [vlan 0] at time 20:54:46.510943 duration_in_ms=556.806
2019-07-12 20:54:46,512 [salt.state       :1780][INFO    ][13014] Running state [192.168.11.0/24] at time 20:54:46.512275
2019-07-12 20:54:46,512 [salt.state       :1813][INFO    ][13014] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-07-12 20:54:46,710 [salt.loaded.ext.module.maasng:945 ][INFO    ][13014] [{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'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-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'name': u'untagged', 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'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'a3ythp', u'name': u'untagged', 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-07-12 20:54:46,711 [salt.loaded.ext.module.maasng:1235][WARNING ][13014] Ignoring parameter vlan:0
2019-07-12 20:54:46,784 [salt.state       :300 ][INFO    ][13014] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-07-12 20:54:46,784 [salt.state       :1951][INFO    ][13014] Completed state [192.168.11.0/24] at time 20:54:46.784564 duration_in_ms=272.288
2019-07-12 20:54:46,785 [salt.state       :1780][INFO    ][13014] Running state [maas_create_iprange_1] at time 20:54:46.785531
2019-07-12 20:54:46,785 [salt.state       :1813][INFO    ][13014] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-07-12 20:54:46,843 [salt.state       :300 ][INFO    ][13014] Iprange maas_create_iprange_1 already exist.
2019-07-12 20:54:46,843 [salt.state       :1951][INFO    ][13014] Completed state [maas_create_iprange_1] at time 20:54:46.843559 duration_in_ms=58.027
2019-07-12 20:54:46,844 [salt.state       :1780][INFO    ][13014] Running state [vlan 0] at time 20:54:46.844016
2019-07-12 20:54:46,844 [salt.state       :1813][INFO    ][13014] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-07-12 20:54:46,914 [salt.loaded.ext.module.maasng:945 ][INFO    ][13014] [{u'vlans': [{u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', 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'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'vlans': [{u'vid': 0, u'fabric': u'fabric-2', u'name': u'untagged', 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'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None, u'name': u'fabric-2', u'id': 2}, {u'vlans': [{u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', 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'a3ythp', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u'', u'name': u'pxe_admin', u'id': 1}]
2019-07-12 20:54:47,009 [salt.loaded.ext.module.maasng:945 ][INFO    ][13014] [{u'id': 0, u'vlans': [{u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0'}, {u'id': 2, u'vlans': [{u'vid': 0, u'fabric': u'fabric-2', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None, u'name': u'fabric-2'}, {u'id': 1, u'vlans': [{u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'a3ythp', 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'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u'', u'name': u'pxe_admin'}]
2019-07-12 20:54:47,308 [salt.loaded.ext.module.maasng:945 ][INFO    ][13014] [{u'vlans': [{u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', 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'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'vlans': [{u'vid': 0, u'fabric': u'fabric-2', u'name': u'untagged', 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'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None, u'name': u'fabric-2', u'id': 2}, {u'vlans': [{u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', 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'a3ythp', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u'', u'name': u'pxe_admin', u'id': 1}]
2019-07-12 20:54:47,410 [salt.state       :300 ][INFO    ][13014] {'new': 'Vlan untagged was updated'}
2019-07-12 20:54:47,411 [salt.state       :1951][INFO    ][13014] Completed state [vlan 0] at time 20:54:47.410920 duration_in_ms=566.904
2019-07-12 20:54:47,411 [salt.state       :1780][INFO    ][13014] Running state [opnfv] at time 20:54:47.411707
2019-07-12 20:54:47,412 [salt.state       :1813][INFO    ][13014] Executing state maasng.sshkey_present for [opnfv]
2019-07-12 20:54:47,452 [salt.loaded.ext.module.maasng:1903][INFO    ][13014] [{u'keysource': u'', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b', u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/'}]
2019-07-12 20:54:47,452 [salt.state       :300 ][INFO    ][13014] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-07-12 20:54:47,452 [salt.state       :1951][INFO    ][13014] Completed state [opnfv] at time 20:54:47.452751 duration_in_ms=41.044
2019-07-12 20:54:47,453 [salt.state       :1780][INFO    ][13014] Running state [maas.process_tags] at time 20:54:47.453427
2019-07-12 20:54:47,454 [salt.state       :1813][INFO    ][13014] Executing state module.run for [maas.process_tags]
2019-07-12 20:54:47,455 [salt.utils.decorators:613 ][WARNING ][13014] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-12 20:54:47,491 [salt.loaded.ext.module.maas:92  ][INFO    ][13014] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-07-12 20:54:47,554 [salt.state       :300 ][INFO    ][13014] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-07-12 20:54:47,554 [salt.state       :1951][INFO    ][13014] Completed state [maas.process_tags] at time 20:54:47.554881 duration_in_ms=101.454
2019-07-12 20:54:47,561 [salt.minion      :1711][INFO    ][13014] Returning information for job: 20190712205424771092
2019-07-12 20:54:48,174 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command state.apply with jid 20190712205448163665
2019-07-12 20:54:48,200 [salt.minion      :1432][INFO    ][13473] Starting a new job with PID 13473
2019-07-12 20:54:54,195 [salt.state       :915 ][INFO    ][13473] Loading fresh modules for state activity
2019-07-12 20:54:54,298 [salt.state       :1780][INFO    ][13473] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:54:54.298494
2019-07-12 20:54:54,298 [salt.state       :1813][INFO    ][13473] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-12 20:54:54,300 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13473] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-12 20:54:56,183 [salt.state       :300 ][INFO    ][13473] {'pid': 13500, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-12 20:54:56,184 [salt.state       :1951][INFO    ][13473] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:54:56.184265 duration_in_ms=1885.771
2019-07-12 20:54:56,186 [salt.state       :1780][INFO    ][13473] Running state [maas.process_machines] at time 20:54:56.186679
2019-07-12 20:54:56,187 [salt.state       :1813][INFO    ][13473] Executing state module.run for [maas.process_machines]
2019-07-12 20:54:56,187 [salt.utils.decorators:613 ][WARNING ][13473] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-12 20:54:56,674 [salt.loaded.ext.module.maas:412 ][WARNING ][13473] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-12 20:54:56,675 [salt.loaded.ext.module.maas:92  ][INFO    ][13473] machine hostname=gtw01 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=k4tggq architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-12 20:54:57,916 [salt.loaded.ext.module.maas:412 ][WARNING ][13473] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-12 20:54:57,916 [salt.loaded.ext.module.maas:92  ][INFO    ][13473] 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=wascd3 architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-12 20:54:59,098 [salt.loaded.ext.module.maas:412 ][WARNING ][13473] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-12 20:54:59,099 [salt.loaded.ext.module.maas:92  ][INFO    ][13473] 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=pnfnkf architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-12 20:55:00,213 [salt.loaded.ext.module.maas:412 ][WARNING ][13473] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-12 20:55:00,215 [salt.loaded.ext.module.maas:92  ][INFO    ][13473] machine hostname=ctl01 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=k7ab78 architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-12 20:55:01,428 [salt.state       :300 ][INFO    ][13473] {'ret': {'updated': ['gtw01', 'cmp002', 'cmp001', 'ctl01'], 'errors': {}, 'success': []}}
2019-07-12 20:55:01,429 [salt.state       :1951][INFO    ][13473] Completed state [maas.process_machines] at time 20:55:01.429184 duration_in_ms=5242.502
2019-07-12 20:55:01,434 [salt.minion      :1711][INFO    ][13473] Returning information for job: 20190712205448163665
2019-07-12 20:55:33,970 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command state.apply with jid 20190712205533958798
2019-07-12 20:55:34,000 [salt.minion      :1432][INFO    ][13699] Starting a new job with PID 13699
2019-07-12 20:55:40,035 [salt.state       :915 ][INFO    ][13699] Loading fresh modules for state activity
2019-07-12 20:55:40,128 [salt.state       :1780][INFO    ][13699] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:55:40.128057
2019-07-12 20:55:40,128 [salt.state       :1813][INFO    ][13699] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-12 20:55:40,130 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13699] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-12 20:55:41,859 [salt.state       :300 ][INFO    ][13699] {'pid': 13738, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-12 20:55:41,860 [salt.state       :1951][INFO    ][13699] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:55:41.860193 duration_in_ms=1732.136
2019-07-12 20:55:41,863 [salt.state       :1780][INFO    ][13699] Running state [maas.wait_for_machine_status] at time 20:55:41.863455
2019-07-12 20:55:41,863 [salt.state       :1813][INFO    ][13699] Executing state module.run for [maas.wait_for_machine_status]
2019-07-12 20:55:41,864 [salt.utils.decorators:613 ][WARNING ][13699] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-12 20:55:43,893 [salt.state       :300 ][INFO    ][13699] {'ret': True}
2019-07-12 20:55:43,894 [salt.state       :1951][INFO    ][13699] Completed state [maas.wait_for_machine_status] at time 20:55:43.894250 duration_in_ms=2030.792
2019-07-12 20:55:43,899 [salt.minion      :1711][INFO    ][13699] Returning information for job: 20190712205533958798
2019-07-12 20:55:44,506 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command state.apply with jid 20190712205544441252
2019-07-12 20:55:44,528 [salt.minion      :1432][INFO    ][13752] Starting a new job with PID 13752
2019-07-12 20:55:45,400 [salt.state       :915 ][INFO    ][13752] Loading fresh modules for state activity
2019-07-12 20:55:45,565 [salt.state       :1780][INFO    ][13752] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:55:45.565345
2019-07-12 20:55:45,566 [salt.state       :1813][INFO    ][13752] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-12 20:55:45,568 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13752] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-12 20:55:47,427 [salt.state       :300 ][INFO    ][13752] {'pid': 13759, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-12 20:55:47,428 [salt.state       :1951][INFO    ][13752] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:55:47.428620 duration_in_ms=1863.274
2019-07-12 20:55:47,433 [salt.state       :1780][INFO    ][13752] Running state [maas_machines_storage_cmp002_lvm] at time 20:55:47.432983
2019-07-12 20:55:47,433 [salt.state       :1813][INFO    ][13752] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-07-12 20:55:47,908 [salt.state       :300 ][INFO    ][13752] Machine cmp002 is not in Ready state.
2019-07-12 20:55:47,908 [salt.state       :1951][INFO    ][13752] Completed state [maas_machines_storage_cmp002_lvm] at time 20:55:47.908523 duration_in_ms=475.541
2019-07-12 20:55:47,908 [salt.state       :1780][INFO    ][13752] Running state [maas_machines_storage_cmp001_lvm] at time 20:55:47.908863
2019-07-12 20:55:47,909 [salt.state       :1813][INFO    ][13752] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-07-12 20:55:48,367 [salt.state       :300 ][INFO    ][13752] Machine cmp001 is not in Ready state.
2019-07-12 20:55:48,367 [salt.state       :1951][INFO    ][13752] Completed state [maas_machines_storage_cmp001_lvm] at time 20:55:48.367839 duration_in_ms=458.974
2019-07-12 20:55:48,373 [salt.minion      :1711][INFO    ][13752] Returning information for job: 20190712205544441252
2019-07-12 20:55:48,941 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command state.apply with jid 20190712205548922738
2019-07-12 20:55:48,965 [salt.minion      :1432][INFO    ][13769] Starting a new job with PID 13769
2019-07-12 20:55:49,841 [salt.state       :915 ][INFO    ][13769] Loading fresh modules for state activity
2019-07-12 20:55:49,936 [salt.state       :1780][INFO    ][13769] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:55:49.936155
2019-07-12 20:55:49,936 [salt.state       :1813][INFO    ][13769] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-12 20:55:49,938 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13769] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-12 20:55:51,838 [salt.state       :300 ][INFO    ][13769] {'pid': 13776, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-12 20:55:51,839 [salt.state       :1951][INFO    ][13769] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:55:51.839469 duration_in_ms=1903.313
2019-07-12 20:55:51,842 [salt.state       :1780][INFO    ][13769] Running state [maas.deploy_machines] at time 20:55:51.842796
2019-07-12 20:55:51,843 [salt.state       :1813][INFO    ][13769] Executing state module.run for [maas.deploy_machines]
2019-07-12 20:55:51,843 [salt.utils.decorators:613 ][WARNING ][13769] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-12 20:55:52,499 [salt.loaded.ext.module.maas:684 ][INFO    ][13769] deploymachines hwe_kernel=hwe-16.04 system_id=k4tggq distro_series=xenial
2019-07-12 20:55:54,981 [salt.state       :300 ][INFO    ][13769] {'ret': {'updated': ['cmp002', 'cmp001', 'ctl01'], 'errors': {}, 'success': ['gtw01']}}
2019-07-12 20:55:54,982 [salt.state       :1951][INFO    ][13769] Completed state [maas.deploy_machines] at time 20:55:54.981685 duration_in_ms=3138.887
2019-07-12 20:55:54,985 [salt.minion      :1711][INFO    ][13769] Returning information for job: 20190712205548922738
2019-07-12 20:55:55,545 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command state.apply with jid 20190712205555533262
2019-07-12 20:55:55,577 [salt.minion      :1432][INFO    ][13841] Starting a new job with PID 13841
2019-07-12 20:55:56,460 [salt.state       :915 ][INFO    ][13841] Loading fresh modules for state activity
2019-07-12 20:55:56,559 [salt.state       :1780][INFO    ][13841] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:55:56.559005
2019-07-12 20:55:56,559 [salt.state       :1813][INFO    ][13841] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-12 20:55:56,561 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13841] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-12 20:55:58,429 [salt.state       :300 ][INFO    ][13841] {'pid': 13848, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-12 20:55:58,430 [salt.state       :1951][INFO    ][13841] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:55:58.430880 duration_in_ms=1871.875
2019-07-12 20:55:58,433 [salt.state       :1780][INFO    ][13841] Running state [maas.wait_for_machine_status] at time 20:55:58.433063
2019-07-12 20:55:58,433 [salt.state       :1813][INFO    ][13841] Executing state module.run for [maas.wait_for_machine_status]
2019-07-12 20:55:58,434 [salt.utils.decorators:613 ][WARNING ][13841] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-12 20:56:00,436 [salt.loaded.ext.module.maas:1023][INFO    ][13841] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2248.01130986s left)
2019-07-12 20:56:10,640 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712205610621592
2019-07-12 20:56:10,668 [salt.minion      :1432][INFO    ][13872] Starting a new job with PID 13872
2019-07-12 20:56:10,690 [salt.minion      :1711][INFO    ][13872] Returning information for job: 20190712205610621592
2019-07-12 20:56:32,424 [salt.loaded.ext.module.maas:1023][INFO    ][13841] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2216.02357292s left)
2019-07-12 20:56:40,720 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712205640702620
2019-07-12 20:56:40,744 [salt.minion      :1432][INFO    ][13925] Starting a new job with PID 13925
2019-07-12 20:56:40,766 [salt.minion      :1711][INFO    ][13925] Returning information for job: 20190712205640702620
2019-07-12 20:57:04,288 [salt.loaded.ext.module.maas:1023][INFO    ][13841] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2184.15944099s left)
2019-07-12 20:57:10,777 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712205710761512
2019-07-12 20:57:10,808 [salt.minion      :1432][INFO    ][13949] Starting a new job with PID 13949
2019-07-12 20:57:10,830 [salt.minion      :1711][INFO    ][13949] Returning information for job: 20190712205710761512
2019-07-12 20:57:36,163 [salt.loaded.ext.module.maas:1023][INFO    ][13841] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2152.28425789s left)
2019-07-12 20:57:40,865 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712205740848662
2019-07-12 20:57:40,893 [salt.minion      :1432][INFO    ][14015] Starting a new job with PID 14015
2019-07-12 20:57:40,918 [salt.minion      :1711][INFO    ][14015] Returning information for job: 20190712205740848662
2019-07-12 20:58:08,044 [salt.loaded.ext.module.maas:1023][INFO    ][13841] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2120.40315795s left)
2019-07-12 20:58:10,955 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712205810941601
2019-07-12 20:58:10,985 [salt.minion      :1432][INFO    ][14044] Starting a new job with PID 14044
2019-07-12 20:58:11,009 [salt.minion      :1711][INFO    ][14044] Returning information for job: 20190712205810941601
2019-07-12 20:58:39,918 [salt.loaded.ext.module.maas:1023][INFO    ][13841] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2088.52933884s left)
2019-07-12 20:58:41,036 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712205841026109
2019-07-12 20:58:41,063 [salt.minion      :1432][INFO    ][14097] Starting a new job with PID 14097
2019-07-12 20:58:41,090 [salt.minion      :1711][INFO    ][14097] Returning information for job: 20190712205841026109
2019-07-12 20:59:11,131 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712205911117330
2019-07-12 20:59:11,154 [salt.minion      :1432][INFO    ][14123] Starting a new job with PID 14123
2019-07-12 20:59:11,179 [salt.minion      :1711][INFO    ][14123] Returning information for job: 20190712205911117330
2019-07-12 20:59:12,063 [salt.loaded.ext.module.maas:1023][INFO    ][13841] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2056.38390899s left)
2019-07-12 20:59:41,214 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712205941205507
2019-07-12 20:59:41,239 [salt.minion      :1432][INFO    ][14170] Starting a new job with PID 14170
2019-07-12 20:59:41,262 [salt.minion      :1711][INFO    ][14170] Returning information for job: 20190712205941205507
2019-07-12 20:59:44,059 [salt.loaded.ext.module.maas:1023][INFO    ][13841] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2024.38815689s left)
2019-07-12 21:00:11,290 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712210011282625
2019-07-12 21:00:11,312 [salt.minion      :1432][INFO    ][14217] Starting a new job with PID 14217
2019-07-12 21:00:11,333 [salt.minion      :1711][INFO    ][14217] Returning information for job: 20190712210011282625
2019-07-12 21:00:15,818 [salt.loaded.ext.module.maas:1023][INFO    ][13841] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1992.62987185s left)
2019-07-12 21:00:41,356 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712210041346793
2019-07-12 21:00:41,380 [salt.minion      :1432][INFO    ][14273] Starting a new job with PID 14273
2019-07-12 21:00:41,403 [salt.minion      :1711][INFO    ][14273] Returning information for job: 20190712210041346793
2019-07-12 21:00:47,791 [salt.loaded.ext.module.maas:1023][INFO    ][13841] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1960.65669394s left)
2019-07-12 21:01:11,457 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712210111447558
2019-07-12 21:01:11,487 [salt.minion      :1432][INFO    ][14339] Starting a new job with PID 14339
2019-07-12 21:01:11,508 [salt.minion      :1711][INFO    ][14339] Returning information for job: 20190712210111447558
2019-07-12 21:01:19,823 [salt.loaded.ext.module.maas:1023][INFO    ][13841] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1928.62414885s left)
2019-07-12 21:01:41,591 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712210141574152
2019-07-12 21:01:41,618 [salt.minion      :1432][INFO    ][14507] Starting a new job with PID 14507
2019-07-12 21:01:41,643 [salt.minion      :1711][INFO    ][14507] Returning information for job: 20190712210141574152
2019-07-12 21:01:51,927 [salt.loaded.ext.module.maas:1023][INFO    ][13841] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1896.51979399s left)
2019-07-12 21:02:11,708 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712210211697004
2019-07-12 21:02:11,730 [salt.minion      :1432][INFO    ][14543] Starting a new job with PID 14543
2019-07-12 21:02:11,754 [salt.minion      :1711][INFO    ][14543] Returning information for job: 20190712210211697004
2019-07-12 21:02:23,914 [salt.loaded.ext.module.maas:1023][INFO    ][13841] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1864.53294992s left)
2019-07-12 21:02:41,832 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712210241821704
2019-07-12 21:02:41,860 [salt.minion      :1432][INFO    ][14615] Starting a new job with PID 14615
2019-07-12 21:02:41,886 [salt.minion      :1711][INFO    ][14615] Returning information for job: 20190712210241821704
2019-07-12 21:02:56,020 [salt.loaded.ext.module.maas:1023][INFO    ][13841] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1832.42729092s left)
2019-07-12 21:03:11,979 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712210311964073
2019-07-12 21:03:12,008 [salt.minion      :1432][INFO    ][14664] Starting a new job with PID 14664
2019-07-12 21:03:12,035 [salt.minion      :1711][INFO    ][14664] Returning information for job: 20190712210311964073
2019-07-12 21:03:28,099 [salt.loaded.ext.module.maas:1023][INFO    ][13841] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1800.34819293s left)
2019-07-12 21:03:42,138 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712210342120718
2019-07-12 21:03:42,166 [salt.minion      :1432][INFO    ][14839] Starting a new job with PID 14839
2019-07-12 21:03:42,191 [salt.minion      :1711][INFO    ][14839] Returning information for job: 20190712210342120718
2019-07-12 21:04:00,228 [salt.loaded.ext.module.maas:1023][INFO    ][13841] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1768.21942997s left)
2019-07-12 21:04:12,305 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712210412289486
2019-07-12 21:04:12,334 [salt.minion      :1432][INFO    ][14883] Starting a new job with PID 14883
2019-07-12 21:04:12,365 [salt.minion      :1711][INFO    ][14883] Returning information for job: 20190712210412289486
2019-07-12 21:04:32,218 [salt.loaded.ext.module.maas:1023][INFO    ][13841] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1736.22880387s left)
2019-07-12 21:04:42,455 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712210442439677
2019-07-12 21:04:42,481 [salt.minion      :1432][INFO    ][14935] Starting a new job with PID 14935
2019-07-12 21:04:42,502 [salt.minion      :1711][INFO    ][14935] Returning information for job: 20190712210442439677
2019-07-12 21:05:04,169 [salt.loaded.ext.module.maas:1023][INFO    ][13841] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1704.27865195s left)
2019-07-12 21:05:12,622 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712210512607949
2019-07-12 21:05:12,652 [salt.minion      :1432][INFO    ][14955] Starting a new job with PID 14955
2019-07-12 21:05:12,677 [salt.minion      :1711][INFO    ][14955] Returning information for job: 20190712210512607949
2019-07-12 21:05:36,116 [salt.loaded.ext.module.maas:1023][INFO    ][13841] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1672.33067203s left)
2019-07-12 21:05:42,787 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712210542771212
2019-07-12 21:05:42,816 [salt.minion      :1432][INFO    ][15005] Starting a new job with PID 15005
2019-07-12 21:05:42,842 [salt.minion      :1711][INFO    ][15005] Returning information for job: 20190712210542771212
2019-07-12 21:06:08,079 [salt.loaded.ext.module.maas:1023][INFO    ][13841] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1640.3677659s left)
2019-07-12 21:06:12,967 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712210612957008
2019-07-12 21:06:12,997 [salt.minion      :1432][INFO    ][15031] Starting a new job with PID 15031
2019-07-12 21:06:13,023 [salt.minion      :1711][INFO    ][15031] Returning information for job: 20190712210612957008
2019-07-12 21:06:40,148 [salt.loaded.ext.module.maas:1023][INFO    ][13841] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1608.29881883s left)
2019-07-12 21:06:43,139 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712210643126690
2019-07-12 21:06:43,165 [salt.minion      :1432][INFO    ][15082] Starting a new job with PID 15082
2019-07-12 21:06:43,191 [salt.minion      :1711][INFO    ][15082] Returning information for job: 20190712210643126690
2019-07-12 21:07:12,116 [salt.loaded.ext.module.maas:1023][INFO    ][13841] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1576.33107781s left)
2019-07-12 21:07:13,316 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712210713304425
2019-07-12 21:07:13,347 [salt.minion      :1432][INFO    ][15106] Starting a new job with PID 15106
2019-07-12 21:07:13,372 [salt.minion      :1711][INFO    ][15106] Returning information for job: 20190712210713304425
2019-07-12 21:07:43,499 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712210743488223
2019-07-12 21:07:43,523 [salt.minion      :1432][INFO    ][15189] Starting a new job with PID 15189
2019-07-12 21:07:43,547 [salt.minion      :1711][INFO    ][15189] Returning information for job: 20190712210743488223
2019-07-12 21:07:44,002 [salt.loaded.ext.module.maas:1023][INFO    ][13841] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1544.44545102s left)
2019-07-12 21:08:13,690 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712210813678240
2019-07-12 21:08:13,720 [salt.minion      :1432][INFO    ][15338] Starting a new job with PID 15338
2019-07-12 21:08:13,743 [salt.minion      :1711][INFO    ][15338] Returning information for job: 20190712210813678240
2019-07-12 21:08:16,187 [salt.loaded.ext.module.maas:1023][INFO    ][13841] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1512.25986695s left)
2019-07-12 21:08:43,276 [salt.utils.schedule:1377][INFO    ][5884] Running scheduled job: __mine_interval
2019-07-12 21:08:43,916 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712210843902833
2019-07-12 21:08:43,942 [salt.minion      :1432][INFO    ][15395] Starting a new job with PID 15395
2019-07-12 21:08:43,969 [salt.minion      :1711][INFO    ][15395] Returning information for job: 20190712210843902833
2019-07-12 21:08:48,099 [salt.loaded.ext.module.maas:1023][INFO    ][13841] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1480.34804296s left)
2019-07-12 21:09:14,143 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712210914127959
2019-07-12 21:09:14,172 [salt.minion      :1432][INFO    ][15435] Starting a new job with PID 15435
2019-07-12 21:09:14,201 [salt.minion      :1711][INFO    ][15435] Returning information for job: 20190712210914127959
2019-07-12 21:09:20,322 [salt.loaded.ext.module.maas:1023][INFO    ][13841] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1448.12524199s left)
2019-07-12 21:09:44,168 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190712210944157052
2019-07-12 21:09:44,196 [salt.minion      :1432][INFO    ][15584] Starting a new job with PID 15584
2019-07-12 21:09:44,218 [salt.minion      :1711][INFO    ][15584] Returning information for job: 20190712210944157052
2019-07-12 21:09:52,232 [salt.state       :300 ][INFO    ][13841] {'ret': True}
2019-07-12 21:09:52,232 [salt.state       :1951][INFO    ][13841] Completed state [maas.wait_for_machine_status] at time 21:09:52.232772 duration_in_ms=833799.706
2019-07-12 21:09:52,236 [salt.minion      :1711][INFO    ][13841] Returning information for job: 20190712205555533262
2019-07-12 22:03:09,681 [salt.minion      :1308][INFO    ][5884] User sudo_ubuntu Executing command cp.push_dir with jid 20190712220309669507
2019-07-12 22:03:09,716 [salt.minion      :1432][INFO    ][19348] Starting a new job with PID 19348
