2019-06-05 20:06:34,332 [salt.utils.decorators:613 ][WARNING ][1942] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-05 20:06:35,277 [salt.utils.decorators:613 ][WARNING ][1942] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-05 20:06:38,875 [salt.loaded.int.states.file:2298][WARNING ][2429] 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-06-05 20:07:09,361 [salt.state       :2022][WARNING ][2850] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-06-05 20:07:12,560 [salt.utils.decorators:613 ][WARNING ][2850] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-05 20:09:29,164 [salt.utils.decorators:613 ][WARNING ][2850] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-05 20:25:03,537 [salt.utils.decorators:613 ][WARNING ][2850] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-05 20:38:49,038 [salt.utils.decorators:613 ][WARNING ][2850] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-05 20:38:55,383 [salt.loaded.ext.module.maasng:1008][WARNING ][2850] Detected cidr:192.168.11.0/24 in fabric:fabric-4
2019-06-05 20:38:55,383 [salt.loaded.ext.module.maasng:1011][WARNING ][2850] Guessing, that fabric with current name:fabric-4
 should be renamed to:pxe_admin
2019-06-05 20:38:56,154 [salt.loaded.ext.module.maasng:1235][WARNING ][2850] Ignoring parameter vlan:0
2019-06-05 20:38:56,867 [salt.utils.decorators:613 ][WARNING ][2850] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-05 20:39:08,083 [salt.utils.decorators:613 ][WARNING ][37941] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-05 20:39:08,146 [salt.loaded.ext.module.maas:412 ][WARNING ][37941] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-05 20:39:09,493 [salt.loaded.ext.module.maas:412 ][WARNING ][37941] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-05 20:39:10,868 [salt.loaded.ext.module.maas:412 ][WARNING ][37941] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-05 20:39:12,088 [salt.loaded.ext.module.maas:412 ][WARNING ][37941] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-05 20:39:16,891 [salt.loaded.int.module.cmdmod:395 ][INFO    ][38473] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-06-05 20:39:16,931 [salt.loaded.int.module.cmdmod:395 ][INFO    ][38473] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-06-05 20:39:16,978 [salt.utils.parsers:1051][WARNING ][380] Minion received a SIGTERM. Exiting.
2019-06-05 20:39:17,985 [salt.cli.daemons :293 ][INFO    ][38525] Setting up the Salt Minion "mas01.mcp-fdio-noha.local"
2019-06-05 20:39:18,113 [salt.cli.daemons :82  ][INFO    ][38525] Starting up the Salt Minion
2019-06-05 20:39:18,114 [salt.utils.event :1017][INFO    ][38525] Starting pull socket on /var/run/salt/minion/minion_event_38d774b16c_pull.ipc
2019-06-05 20:39:19,347 [salt.minion      :976 ][INFO    ][38525] Creating minion process manager
2019-06-05 20:39:21,391 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][38525] Executing command ['date', '+%z'] in directory '/root'
2019-06-05 20:39:21,420 [salt.utils.schedule:568 ][INFO    ][38525] Updating job settings for scheduled job: __mine_interval
2019-06-05 20:39:21,427 [salt.minion      :1108][INFO    ][38525] Added mine.update to scheduler
2019-06-05 20:39:21,438 [salt.minion      :1975][INFO    ][38525] Minion is starting as user 'root'
2019-06-05 20:39:21,457 [salt.minion      :2336][INFO    ][38525] Minion is ready to receive requests!
2019-06-05 20:39:44,543 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command state.apply with jid 20190605203944531659
2019-06-05 20:39:44,569 [salt.minion      :1432][INFO    ][38637] Starting a new job with PID 38637
2019-06-05 20:39:52,408 [salt.state       :915 ][INFO    ][38637] Loading fresh modules for state activity
2019-06-05 20:39:52,473 [salt.fileclient  :1219][INFO    ][38637] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-06-05 20:39:52,523 [salt.state       :1780][INFO    ][38637] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:39:52.523181
2019-06-05 20:39:52,523 [salt.state       :1813][INFO    ][38637] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-05 20:39:52,525 [salt.loaded.int.module.cmdmod:395 ][INFO    ][38637] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-05 20:39:54,205 [salt.state       :300 ][INFO    ][38637] {'pid': 38644, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-05 20:39:54,207 [salt.state       :1951][INFO    ][38637] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:39:54.207057 duration_in_ms=1683.876
2019-06-05 20:39:54,209 [salt.state       :1780][INFO    ][38637] Running state [maas.wait_for_machine_status] at time 20:39:54.209482
2019-06-05 20:39:54,210 [salt.state       :1813][INFO    ][38637] Executing state module.run for [maas.wait_for_machine_status]
2019-06-05 20:39:54,211 [salt.utils.decorators:613 ][WARNING ][38637] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-05 20:39:54,808 [salt.loaded.ext.module.maas:1023][INFO    ][38637] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1499.41704297s left)
2019-06-05 20:39:59,615 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605203959597225
2019-06-05 20:39:59,644 [salt.minion      :1432][INFO    ][38667] Starting a new job with PID 38667
2019-06-05 20:39:59,670 [salt.minion      :1711][INFO    ][38667] Returning information for job: 20190605203959597225
2019-06-05 20:40:25,435 [salt.loaded.ext.module.maas:1023][INFO    ][38637] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1468.7897191s left)
2019-06-05 20:40:29,684 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605204029668172
2019-06-05 20:40:29,715 [salt.minion      :1432][INFO    ][38698] Starting a new job with PID 38698
2019-06-05 20:40:29,740 [salt.minion      :1711][INFO    ][38698] Returning information for job: 20190605204029668172
2019-06-05 20:40:56,096 [salt.loaded.ext.module.maas:1023][INFO    ][38637] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1438.128757s left)
2019-06-05 20:40:59,817 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605204059805122
2019-06-05 20:40:59,845 [salt.minion      :1432][INFO    ][38742] Starting a new job with PID 38742
2019-06-05 20:40:59,869 [salt.minion      :1711][INFO    ][38742] Returning information for job: 20190605204059805122
2019-06-05 20:41:26,713 [salt.loaded.ext.module.maas:1023][INFO    ][38637] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1407.5119729s left)
2019-06-05 20:41:29,887 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605204129876403
2019-06-05 20:41:29,914 [salt.minion      :1432][INFO    ][38774] Starting a new job with PID 38774
2019-06-05 20:41:29,934 [salt.minion      :1711][INFO    ][38774] Returning information for job: 20190605204129876403
2019-06-05 20:41:57,510 [salt.loaded.ext.module.maas:1023][INFO    ][38637] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1376.71476603s left)
2019-06-05 20:41:59,983 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605204159968644
2019-06-05 20:42:00,013 [salt.minion      :1432][INFO    ][38850] Starting a new job with PID 38850
2019-06-05 20:42:00,041 [salt.minion      :1711][INFO    ][38850] Returning information for job: 20190605204159968644
2019-06-05 20:42:28,331 [salt.loaded.ext.module.maas:1023][INFO    ][38637] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1345.89333296s left)
2019-06-05 20:42:30,080 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605204230063537
2019-06-05 20:42:30,108 [salt.minion      :1432][INFO    ][38979] Starting a new job with PID 38979
2019-06-05 20:42:30,135 [salt.minion      :1711][INFO    ][38979] Returning information for job: 20190605204230063537
2019-06-05 20:42:59,187 [salt.loaded.ext.module.maas:1023][INFO    ][38637] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1315.03796291s left)
2019-06-05 20:43:00,197 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605204300183902
2019-06-05 20:43:00,224 [salt.minion      :1432][INFO    ][39116] Starting a new job with PID 39116
2019-06-05 20:43:00,245 [salt.minion      :1711][INFO    ][39116] Returning information for job: 20190605204300183902
2019-06-05 20:43:30,117 [salt.loaded.ext.module.maas:1023][INFO    ][38637] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1284.10812402s left)
2019-06-05 20:43:30,323 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605204330311941
2019-06-05 20:43:30,345 [salt.minion      :1432][INFO    ][39373] Starting a new job with PID 39373
2019-06-05 20:43:30,368 [salt.minion      :1711][INFO    ][39373] Returning information for job: 20190605204330311941
2019-06-05 20:44:00,409 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605204400395933
2019-06-05 20:44:00,431 [salt.minion      :1432][INFO    ][39503] Starting a new job with PID 39503
2019-06-05 20:44:00,455 [salt.minion      :1711][INFO    ][39503] Returning information for job: 20190605204400395933
2019-06-05 20:44:01,541 [salt.loaded.ext.module.maas:1023][INFO    ][38637] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1252.68403602s left)
2019-06-05 20:44:30,524 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605204430512396
2019-06-05 20:44:30,544 [salt.minion      :1432][INFO    ][39694] Starting a new job with PID 39694
2019-06-05 20:44:30,562 [salt.minion      :1711][INFO    ][39694] Returning information for job: 20190605204430512396
2019-06-05 20:44:33,297 [salt.loaded.ext.module.maas:1023][INFO    ][38637] Waiting status:Ready|Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:1500s (1220.92794609s left)
2019-06-05 20:45:00,631 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605204500617084
2019-06-05 20:45:00,659 [salt.minion      :1432][INFO    ][39893] Starting a new job with PID 39893
2019-06-05 20:45:00,682 [salt.minion      :1711][INFO    ][39893] Returning information for job: 20190605204500617084
2019-06-05 20:45:04,878 [salt.loaded.ext.module.maas:1023][INFO    ][38637] Waiting status:Ready|Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:1500s (1189.34691906s left)
2019-06-05 20:45:30,748 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605204530734311
2019-06-05 20:45:30,773 [salt.minion      :1432][INFO    ][39993] Starting a new job with PID 39993
2019-06-05 20:45:30,796 [salt.minion      :1711][INFO    ][39993] Returning information for job: 20190605204530734311
2019-06-05 20:45:36,685 [salt.loaded.ext.module.maas:1023][INFO    ][38637] Waiting status:Ready|Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:1500s (1157.53981805s left)
2019-06-05 20:46:00,883 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605204600867449
2019-06-05 20:46:00,909 [salt.minion      :1432][INFO    ][40108] Starting a new job with PID 40108
2019-06-05 20:46:00,929 [salt.minion      :1711][INFO    ][40108] Returning information for job: 20190605204600867449
2019-06-05 20:46:08,693 [salt.state       :300 ][INFO    ][38637] {'ret': True}
2019-06-05 20:46:08,693 [salt.state       :1951][INFO    ][38637] Completed state [maas.wait_for_machine_status] at time 20:46:08.693485 duration_in_ms=374484.002
2019-06-05 20:46:08,697 [salt.minion      :1711][INFO    ][38637] Returning information for job: 20190605203944531659
2019-06-05 20:46:09,295 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command state.apply with jid 20190605204609275324
2019-06-05 20:46:09,320 [salt.minion      :1432][INFO    ][40127] Starting a new job with PID 40127
2019-06-05 20:46:17,287 [salt.state       :915 ][INFO    ][40127] Loading fresh modules for state activity
2019-06-05 20:46:17,351 [salt.fileclient  :1219][INFO    ][40127] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-06-05 20:46:17,452 [salt.state       :1780][INFO    ][40127] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:46:17.452402
2019-06-05 20:46:17,452 [salt.state       :1813][INFO    ][40127] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-05 20:46:17,454 [salt.loaded.int.module.cmdmod:395 ][INFO    ][40127] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-05 20:46:19,169 [salt.state       :300 ][INFO    ][40127] {'pid': 40161, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-05 20:46:19,170 [salt.state       :1951][INFO    ][40127] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:46:19.170686 duration_in_ms=1718.284
2019-06-05 20:46:19,174 [salt.state       :1780][INFO    ][40127] Running state [maas_machines_storage_cmp002_lvm] at time 20:46:19.174477
2019-06-05 20:46:19,175 [salt.state       :1813][INFO    ][40127] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-06-05 20:46:20,192 [salt.loaded.ext.module.maasng:610 ][INFO    ][40127] ex8b6e
2019-06-05 20:46:20,193 [salt.loaded.ext.module.maasng:626 ][INFO    ][40127] sda
2019-06-05 20:46:20,675 [salt.loaded.ext.module.maasng:361 ][INFO    ][40127] ex8b6e
2019-06-05 20:46:20,764 [salt.loaded.ext.module.maasng:367 ][INFO    ][40127] [{u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/ex8b6e/blockdevices/1/', u'used_size': 800106479616, u'partitions': [{u'uuid': u'0980bc45-8da0-49ea-ad8f-ef7bfeb4008e', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ex8b6e', u'device_id': 1, u'filesystem': {u'uuid': u'643ad44e-e677-4777-a77d-090d7822fe00', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/ex8b6e/blockdevices/1/partition/1'}], u'filesystem': None, u'uuid': None, u'id': 1, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'ex8b6e', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'tags': [u'ssd'], u'size': 800109715456}, {u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/ex8b6e/blockdevices/3/', u'used_size': 800097042432, u'partitions': [], u'filesystem': {u'uuid': u'3d0f58d1-a8d9-4e5d-adb9-f588202cf54e', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'uuid': u'ce1f7f6f-68a4-40e2-b3a1-bd78a2545d57', u'id': 3, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'ex8b6e', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'serial': None, u'tags': [], u'size': 800097042432}]
2019-06-05 20:46:20,764 [salt.loaded.ext.module.maasng:632 ][INFO    ][40127] vgroot
2019-06-05 20:46:20,765 [salt.loaded.ext.module.maasng:635 ][INFO    ][40127] lvroot
2019-06-05 20:46:20,765 [salt.loaded.ext.module.maasng:639 ][INFO    ][40127] 107374182400
2019-06-05 20:46:21,363 [salt.loaded.ext.module.maasng:645 ][INFO    ][40127] {u'hwe_kernel': u'', u'status_action': 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'memory_test_status_name': u'Unknown', u'min_hwe_kernel': u'hwe-16.04', 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'boot_disk': {u'model': u'LOGICAL VOLUME', u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/ex8b6e/blockdevices/1/', u'used_size': 800106479616, u'partitions': [{u'uuid': u'24551564-74ee-4497-b0ea-1a76653d35e7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ex8b6e', u'device_id': 1, u'filesystem': {u'uuid': u'33a29e0f-3325-48e1-9280-7b806981a801', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/ex8b6e/blockdevices/1/partition/5'}], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'ex8b6e', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'type': u'physical', u'id': 1, u'tags': [u'ssd'], u'size': 800109715456}, u'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'id': 1}, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'ex8b6e', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'model': None, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/ex8b6e/blockdevices/9/', u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'uuid': u'9d8334fb-e412-48c8-acdb-6febfa3c1f40', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'uuid': u'3c3185de-4cad-4856-aa44-5c4cf111c980', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'ex8b6e', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 9, u'tags': [], u'size': 107374182400}], u'blockdevice_set': [{u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/ex8b6e/blockdevices/1/', u'used_size': 800106479616, u'partitions': [{u'uuid': u'24551564-74ee-4497-b0ea-1a76653d35e7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ex8b6e', u'device_id': 1, u'filesystem': {u'uuid': u'33a29e0f-3325-48e1-9280-7b806981a801', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/ex8b6e/blockdevices/1/partition/5'}], u'filesystem': None, u'uuid': None, u'id': 1, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'ex8b6e', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'tags': [u'ssd'], u'size': 800109715456}, {u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/ex8b6e/blockdevices/9/', u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'uuid': u'9d8334fb-e412-48c8-acdb-6febfa3c1f40', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'uuid': u'3c3185de-4cad-4856-aa44-5c4cf111c980', u'id': 9, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'ex8b6e', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'serial': None, u'tags': [], u'size': 107374182400}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'ex8b6e', u'id': 5}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'boot_interface': {u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'pstx7e', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, 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': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.38'}], u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'pstx7e', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, 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': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 19}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'pstx7e', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, u'enabled': True, u'parents': [], u'effective_mtu': 1500, u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'id': 5, u'system_id': u'ex8b6e', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ex8b6e/interfaces/5/'}, u'current_testing_result_id': 5, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'model': u'LOGICAL VOLUME', u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/ex8b6e/blockdevices/1/', u'used_size': 800106479616, u'partitions': [{u'uuid': u'24551564-74ee-4497-b0ea-1a76653d35e7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ex8b6e', u'device_id': 1, u'filesystem': {u'uuid': u'33a29e0f-3325-48e1-9280-7b806981a801', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/ex8b6e/blockdevices/1/partition/5'}], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'ex8b6e', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'type': u'physical', u'id': 1, u'tags': [u'ssd'], u'size': 800109715456}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'disable_ipv4': False, u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'interface_set': [{u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'pstx7e', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, 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': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.38'}], u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'pstx7e', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, 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': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 19}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'pstx7e', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, u'enabled': True, u'parents': [], u'effective_mtu': 1500, u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'id': 5, u'system_id': u'ex8b6e', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ex8b6e/interfaces/5/'}, {u'discovered': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'parents': [], u'effective_mtu': 1500, u'params': u'', u'mac_address': u'38:ea:a7:8f:07:51', u'id': 11, u'system_id': u'ex8b6e', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ex8b6e/interfaces/11/'}, {u'discovered': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'parents': [], u'effective_mtu': 1500, u'params': u'', u'mac_address': u'38:ea:a7:8f:07:50', u'id': 13, u'system_id': u'ex8b6e', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ex8b6e/interfaces/13/'}, {u'discovered': None, u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 20}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', 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'parents': [], u'effective_mtu': 1500, u'params': u'', u'mac_address': u'38:ea:a7:8f:12:48', u'id': 10, u'system_id': u'ex8b6e', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ex8b6e/interfaces/10/'}, {u'discovered': None, u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 21}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', 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'parents': [], u'effective_mtu': 1500, u'params': u'', u'mac_address': u'38:ea:a7:8f:12:49', u'id': 12, u'system_id': u'ex8b6e', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ex8b6e/interfaces/12/'}, {u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'pstx7e', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, 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': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.40'}], u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'pstx7e', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, 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': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'mode': u'link_up', u'id': 22}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'pstx7e', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, u'enabled': True, u'parents': [], u'effective_mtu': 1500, u'params': u'', u'mac_address': u'9c:b6:54:8a:10:1c', u'id': 14, u'system_id': u'ex8b6e', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ex8b6e/interfaces/14/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/ex8b6e/'}
2019-06-05 20:46:21,366 [salt.state       :300 ][INFO    ][40127] {'new': {'storage_layout': 'lvm'}}
2019-06-05 20:46:21,367 [salt.state       :1951][INFO    ][40127] Completed state [maas_machines_storage_cmp002_lvm] at time 20:46:21.367328 duration_in_ms=2192.85
2019-06-05 20:46:21,368 [salt.state       :1780][INFO    ][40127] Running state [maas_machines_storage_cmp001_lvm] at time 20:46:21.368022
2019-06-05 20:46:21,368 [salt.state       :1813][INFO    ][40127] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-06-05 20:46:22,321 [salt.loaded.ext.module.maasng:610 ][INFO    ][40127] a4tnyp
2019-06-05 20:46:22,324 [salt.loaded.ext.module.maasng:626 ][INFO    ][40127] sda
2019-06-05 20:46:22,804 [salt.loaded.ext.module.maasng:361 ][INFO    ][40127] a4tnyp
2019-06-05 20:46:22,894 [salt.loaded.ext.module.maasng:367 ][INFO    ][40127] [{u'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/a4tnyp/blockdevices/2/', u'used_size': 800106479616, u'partitions': [{u'uuid': u'b3b82a00-950e-49a1-b7a4-cc58d81b63a6', u'resource_uri': u'/MAAS/api/2.0/nodes/a4tnyp/blockdevices/2/partition/2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'a4tnyp', u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'a2d6aa03-6219-440e-96bf-2e10693be76d', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 2, u'device_id': 2}], u'tags': [u'ssd'], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'a4tnyp', 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'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, {u'uuid': u'e54f15f5-cc39-4017-84a7-ba0e30ffac64', u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/a4tnyp/blockdevices/4/', u'used_size': 800097042432, u'partitions': [], u'tags': [], u'filesystem': {u'label': u'root', u'mount_point': u'/', u'uuid': u'e5c07d54-6cc9-4378-a7dc-acd3d5ec65a0', u'fstype': u'ext4', u'mount_options': None}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'a4tnyp', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 4, u'serial': None, u'size': 800097042432}]
2019-06-05 20:46:22,894 [salt.loaded.ext.module.maasng:632 ][INFO    ][40127] vgroot
2019-06-05 20:46:22,895 [salt.loaded.ext.module.maasng:635 ][INFO    ][40127] lvroot
2019-06-05 20:46:22,895 [salt.loaded.ext.module.maasng:639 ][INFO    ][40127] 107374182400
2019-06-05 20:46:23,512 [salt.loaded.ext.module.maasng:645 ][INFO    ][40127] {u'hwe_kernel': u'', u'status_action': u'', u'testing_status_name': u'Passed', u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.39', u'192.168.11.43'], u'cpu_count': 40, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'min_hwe_kernel': u'hwe-16.04', 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'boot_disk': {u'model': u'LOGICAL VOLUME', u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/a4tnyp/blockdevices/2/', u'used_size': 800106479616, u'partitions': [{u'uuid': u'df471bcc-de90-4651-b23c-2d67b1196c72', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'a4tnyp', u'device_id': 2, u'filesystem': {u'uuid': u'a25e7717-d598-4b80-9863-ff95ea628cf4', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/a4tnyp/blockdevices/2/partition/6'}], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'a4tnyp', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'type': u'physical', u'id': 2, u'tags': [u'ssd'], u'size': 800109715456}, u'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'id': 1}, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'a4tnyp', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'model': None, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/a4tnyp/blockdevices/10/', u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'uuid': u'59f4f8ad-27e8-47f2-b784-3ead001bc88e', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'uuid': u'fcf9cfbd-89df-4b3a-8711-418af2ac40e6', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'a4tnyp', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 10, u'tags': [], u'size': 107374182400}], u'blockdevice_set': [{u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/a4tnyp/blockdevices/2/', u'used_size': 800106479616, u'partitions': [{u'uuid': u'df471bcc-de90-4651-b23c-2d67b1196c72', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'a4tnyp', u'device_id': 2, u'filesystem': {u'uuid': u'a25e7717-d598-4b80-9863-ff95ea628cf4', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/a4tnyp/blockdevices/2/partition/6'}], u'filesystem': None, u'uuid': None, u'id': 2, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'a4tnyp', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'tags': [u'ssd'], u'size': 800109715456}, {u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/a4tnyp/blockdevices/10/', u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'uuid': u'59f4f8ad-27e8-47f2-b784-3ead001bc88e', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'uuid': u'fcf9cfbd-89df-4b3a-8711-418af2ac40e6', u'id': 10, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'a4tnyp', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'serial': None, u'tags': [], u'size': 107374182400}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'a4tnyp', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 6, u'boot_interface': {u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'pstx7e', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, 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': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.39'}], u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'pstx7e', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, 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': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 25}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'pstx7e', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, u'enabled': True, u'parents': [], u'effective_mtu': 1500, u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'id': 6, u'system_id': u'a4tnyp', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/a4tnyp/interfaces/6/'}, u'current_testing_result_id': 7, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'model': u'LOGICAL VOLUME', u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/a4tnyp/blockdevices/2/', u'used_size': 800106479616, u'partitions': [{u'uuid': u'df471bcc-de90-4651-b23c-2d67b1196c72', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'a4tnyp', u'device_id': 2, u'filesystem': {u'uuid': u'a25e7717-d598-4b80-9863-ff95ea628cf4', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/a4tnyp/blockdevices/2/partition/6'}], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'a4tnyp', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'type': u'physical', u'id': 2, u'tags': [u'ssd'], u'size': 800109715456}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'disable_ipv4': False, u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'interface_set': [{u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'pstx7e', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, 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': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.39'}], u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'pstx7e', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, 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': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 25}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'pstx7e', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, u'enabled': True, u'parents': [], u'effective_mtu': 1500, u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'id': 6, u'system_id': u'a4tnyp', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/a4tnyp/interfaces/6/'}, {u'discovered': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'parents': [], u'effective_mtu': 1500, u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d5', u'id': 16, u'system_id': u'a4tnyp', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/a4tnyp/interfaces/16/'}, {u'discovered': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'parents': [], u'effective_mtu': 1500, u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d4', u'id': 18, u'system_id': u'a4tnyp', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/a4tnyp/interfaces/18/'}, {u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'pstx7e', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, 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': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.43'}], u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'pstx7e', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, 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': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'mode': u'link_up', u'id': 26}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'pstx7e', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, u'enabled': True, u'parents': [], u'effective_mtu': 1500, u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a4', u'id': 15, u'system_id': u'a4tnyp', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/a4tnyp/interfaces/15/'}, {u'discovered': None, u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 27}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', 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'parents': [], u'effective_mtu': 1500, u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cc', u'id': 17, u'system_id': u'a4tnyp', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/a4tnyp/interfaces/17/'}, {u'discovered': None, u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 28}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', 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'parents': [], u'effective_mtu': 1500, u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cd', u'id': 19, u'system_id': u'a4tnyp', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/a4tnyp/interfaces/19/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/a4tnyp/'}
2019-06-05 20:46:23,516 [salt.state       :300 ][INFO    ][40127] {'new': {'storage_layout': 'lvm'}}
2019-06-05 20:46:23,516 [salt.state       :1951][INFO    ][40127] Completed state [maas_machines_storage_cmp001_lvm] at time 20:46:23.516309 duration_in_ms=2148.287
2019-06-05 20:46:23,519 [salt.minion      :1711][INFO    ][40127] Returning information for job: 20190605204609275324
2019-06-05 20:46:24,103 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command state.apply with jid 20190605204624087744
2019-06-05 20:46:24,130 [salt.minion      :1432][INFO    ][40180] Starting a new job with PID 40180
2019-06-05 20:46:25,196 [salt.state       :915 ][INFO    ][40180] Loading fresh modules for state activity
2019-06-05 20:46:25,244 [salt.fileclient  :1219][INFO    ][40180] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-06-05 20:46:25,293 [salt.state       :1780][INFO    ][40180] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:46:25.293107
2019-06-05 20:46:25,293 [salt.state       :1813][INFO    ][40180] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-05 20:46:25,295 [salt.loaded.int.module.cmdmod:395 ][INFO    ][40180] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-05 20:46:27,003 [salt.state       :300 ][INFO    ][40180] {'pid': 40187, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-05 20:46:27,004 [salt.state       :1951][INFO    ][40180] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:46:27.004736 duration_in_ms=1711.628
2019-06-05 20:46:27,008 [salt.state       :1780][INFO    ][40180] Running state [maas.deploy_machines] at time 20:46:27.008341
2019-06-05 20:46:27,009 [salt.state       :1813][INFO    ][40180] Executing state module.run for [maas.deploy_machines]
2019-06-05 20:46:27,010 [salt.utils.decorators:613 ][WARNING ][40180] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-05 20:46:27,502 [salt.loaded.ext.module.maas:684 ][INFO    ][40180] deploymachines hwe_kernel=hwe-16.04 system_id=qbdge3 distro_series=xenial
2019-06-05 20:46:29,975 [salt.loaded.ext.module.maas:684 ][INFO    ][40180] deploymachines hwe_kernel=hwe-16.04 system_id=ex8b6e distro_series=xenial
2019-06-05 20:46:32,496 [salt.loaded.ext.module.maas:684 ][INFO    ][40180] deploymachines hwe_kernel=hwe-16.04 system_id=a4tnyp distro_series=xenial
2019-06-05 20:46:34,943 [salt.loaded.ext.module.maas:684 ][INFO    ][40180] deploymachines hwe_kernel=hwe-16.04 system_id=qkpcnd distro_series=xenial
2019-06-05 20:46:37,530 [salt.state       :300 ][INFO    ][40180] {'ret': {'updated': [], 'errors': {}, 'success': ['gtw01', 'cmp002', 'cmp001', 'ctl01']}}
2019-06-05 20:46:37,530 [salt.state       :1951][INFO    ][40180] Completed state [maas.deploy_machines] at time 20:46:37.530594 duration_in_ms=10522.252
2019-06-05 20:46:37,533 [salt.minion      :1711][INFO    ][40180] Returning information for job: 20190605204624087744
2019-06-05 20:46:38,111 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command state.apply with jid 20190605204638098140
2019-06-05 20:46:38,131 [salt.minion      :1432][INFO    ][40405] Starting a new job with PID 40405
2019-06-05 20:46:46,374 [salt.state       :915 ][INFO    ][40405] Loading fresh modules for state activity
2019-06-05 20:46:46,443 [salt.fileclient  :1219][INFO    ][40405] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-06-05 20:46:46,497 [salt.state       :1780][INFO    ][40405] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:46:46.497708
2019-06-05 20:46:46,498 [salt.state       :1813][INFO    ][40405] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-05 20:46:46,499 [salt.loaded.int.module.cmdmod:395 ][INFO    ][40405] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-05 20:46:48,197 [salt.state       :300 ][INFO    ][40405] {'pid': 40441, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-05 20:46:48,198 [salt.state       :1951][INFO    ][40405] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:46:48.198580 duration_in_ms=1700.873
2019-06-05 20:46:48,201 [salt.state       :1780][INFO    ][40405] Running state [maas.wait_for_machine_status] at time 20:46:48.201651
2019-06-05 20:46:48,202 [salt.state       :1813][INFO    ][40405] Executing state module.run for [maas.wait_for_machine_status]
2019-06-05 20:46:48,202 [salt.utils.decorators:613 ][WARNING ][40405] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-05 20:46:50,156 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2248.05725813s left)
2019-06-05 20:46:53,237 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605204653223760
2019-06-05 20:46:53,268 [salt.minion      :1432][INFO    ][40451] Starting a new job with PID 40451
2019-06-05 20:46:53,293 [salt.minion      :1711][INFO    ][40451] Returning information for job: 20190605204653223760
2019-06-05 20:47:22,090 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2216.12354612s left)
2019-06-05 20:47:23,325 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605204723312740
2019-06-05 20:47:23,350 [salt.minion      :1432][INFO    ][40512] Starting a new job with PID 40512
2019-06-05 20:47:23,372 [salt.minion      :1711][INFO    ][40512] Returning information for job: 20190605204723312740
2019-06-05 20:47:53,409 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605204753399130
2019-06-05 20:47:53,429 [salt.minion      :1432][INFO    ][40551] Starting a new job with PID 40551
2019-06-05 20:47:53,451 [salt.minion      :1711][INFO    ][40551] Returning information for job: 20190605204753399130
2019-06-05 20:47:54,195 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2184.01779509s left)
2019-06-05 20:48:23,480 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605204823464742
2019-06-05 20:48:23,508 [salt.minion      :1432][INFO    ][40602] Starting a new job with PID 40602
2019-06-05 20:48:23,530 [salt.minion      :1711][INFO    ][40602] Returning information for job: 20190605204823464742
2019-06-05 20:48:26,154 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2152.059798s left)
2019-06-05 20:48:53,549 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605204853535699
2019-06-05 20:48:53,574 [salt.minion      :1432][INFO    ][40635] Starting a new job with PID 40635
2019-06-05 20:48:53,595 [salt.minion      :1711][INFO    ][40635] Returning information for job: 20190605204853535699
2019-06-05 20:48:58,120 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2120.09308696s left)
2019-06-05 20:49:23,607 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605204923588489
2019-06-05 20:49:23,637 [salt.minion      :1432][INFO    ][40694] Starting a new job with PID 40694
2019-06-05 20:49:23,659 [salt.minion      :1711][INFO    ][40694] Returning information for job: 20190605204923588489
2019-06-05 20:49:30,345 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2087.86834407s left)
2019-06-05 20:49:53,706 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605204953688123
2019-06-05 20:49:53,736 [salt.minion      :1432][INFO    ][40784] Starting a new job with PID 40784
2019-06-05 20:49:53,759 [salt.minion      :1711][INFO    ][40784] Returning information for job: 20190605204953688123
2019-06-05 20:50:02,716 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2055.49728703s left)
2019-06-05 20:50:23,853 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605205023837399
2019-06-05 20:50:23,878 [salt.minion      :1432][INFO    ][41017] Starting a new job with PID 41017
2019-06-05 20:50:23,901 [salt.minion      :1711][INFO    ][41017] Returning information for job: 20190605205023837399
2019-06-05 20:50:34,691 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2023.52198601s left)
2019-06-05 20:50:53,944 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605205053923852
2019-06-05 20:50:53,964 [salt.minion      :1432][INFO    ][41133] Starting a new job with PID 41133
2019-06-05 20:50:53,987 [salt.minion      :1711][INFO    ][41133] Returning information for job: 20190605205053923852
2019-06-05 20:51:06,760 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1991.45311093s left)
2019-06-05 20:51:24,076 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605205124056524
2019-06-05 20:51:24,104 [salt.minion      :1432][INFO    ][41282] Starting a new job with PID 41282
2019-06-05 20:51:24,127 [salt.minion      :1711][INFO    ][41282] Returning information for job: 20190605205124056524
2019-06-05 20:51:38,777 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1959.43612909s left)
2019-06-05 20:51:54,212 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605205154196217
2019-06-05 20:51:54,240 [salt.minion      :1432][INFO    ][41347] Starting a new job with PID 41347
2019-06-05 20:51:54,260 [salt.minion      :1711][INFO    ][41347] Returning information for job: 20190605205154196217
2019-06-05 20:52:10,755 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1927.45828509s left)
2019-06-05 20:52:24,348 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605205224337614
2019-06-05 20:52:24,373 [salt.minion      :1432][INFO    ][41573] Starting a new job with PID 41573
2019-06-05 20:52:24,396 [salt.minion      :1711][INFO    ][41573] Returning information for job: 20190605205224337614
2019-06-05 20:52:42,893 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1895.32064605s left)
2019-06-05 20:52:54,472 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605205254457822
2019-06-05 20:52:54,502 [salt.minion      :1432][INFO    ][41645] Starting a new job with PID 41645
2019-06-05 20:52:54,523 [salt.minion      :1711][INFO    ][41645] Returning information for job: 20190605205254457822
2019-06-05 20:53:15,763 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1862.45048213s left)
2019-06-05 20:53:24,648 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605205324631020
2019-06-05 20:53:24,678 [salt.minion      :1432][INFO    ][41976] Starting a new job with PID 41976
2019-06-05 20:53:24,703 [salt.minion      :1711][INFO    ][41976] Returning information for job: 20190605205324631020
2019-06-05 20:53:47,748 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1830.46526313s left)
2019-06-05 20:53:54,803 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605205354786891
2019-06-05 20:53:54,834 [salt.minion      :1432][INFO    ][42112] Starting a new job with PID 42112
2019-06-05 20:53:54,857 [salt.minion      :1711][INFO    ][42112] Returning information for job: 20190605205354786891
2019-06-05 20:54:19,743 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1798.47012401s left)
2019-06-05 20:54:24,955 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605205424944275
2019-06-05 20:54:24,977 [salt.minion      :1432][INFO    ][42275] Starting a new job with PID 42275
2019-06-05 20:54:25,001 [salt.minion      :1711][INFO    ][42275] Returning information for job: 20190605205424944275
2019-06-05 20:54:51,892 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1766.32146311s left)
2019-06-05 20:54:55,114 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605205455098301
2019-06-05 20:54:55,137 [salt.minion      :1432][INFO    ][42372] Starting a new job with PID 42372
2019-06-05 20:54:55,159 [salt.minion      :1711][INFO    ][42372] Returning information for job: 20190605205455098301
2019-06-05 20:55:24,017 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1734.19674397s left)
2019-06-05 20:55:25,264 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605205525252585
2019-06-05 20:55:25,290 [salt.minion      :1432][INFO    ][42459] Starting a new job with PID 42459
2019-06-05 20:55:25,314 [salt.minion      :1711][INFO    ][42459] Returning information for job: 20190605205525252585
2019-06-05 20:55:55,411 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605205555399720
2019-06-05 20:55:55,432 [salt.minion      :1432][INFO    ][42517] Starting a new job with PID 42517
2019-06-05 20:55:55,456 [salt.minion      :1711][INFO    ][42517] Returning information for job: 20190605205555399720
2019-06-05 20:55:56,101 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1702.11230612s left)
2019-06-05 20:56:25,505 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605205625490980
2019-06-05 20:56:25,542 [salt.minion      :1432][INFO    ][42671] Starting a new job with PID 42671
2019-06-05 20:56:25,568 [salt.minion      :1711][INFO    ][42671] Returning information for job: 20190605205625490980
2019-06-05 20:56:28,173 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1670.04051709s left)
2019-06-05 20:56:55,713 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605205655697585
2019-06-05 20:56:55,739 [salt.minion      :1432][INFO    ][42776] Starting a new job with PID 42776
2019-06-05 20:56:55,768 [salt.minion      :1711][INFO    ][42776] Returning information for job: 20190605205655697585
2019-06-05 20:57:00,269 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:2250s (1637.9446671s left)
2019-06-05 20:57:25,924 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605205725908314
2019-06-05 20:57:25,954 [salt.minion      :1432][INFO    ][43054] Starting a new job with PID 43054
2019-06-05 20:57:25,979 [salt.minion      :1711][INFO    ][43054] Returning information for job: 20190605205725908314
2019-06-05 20:57:32,187 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:2250s (1606.02586508s left)
2019-06-05 20:57:56,096 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605205756077280
2019-06-05 20:57:56,121 [salt.minion      :1432][INFO    ][43102] Starting a new job with PID 43102
2019-06-05 20:57:56,146 [salt.minion      :1711][INFO    ][43102] Returning information for job: 20190605205756077280
2019-06-05 20:58:04,163 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:2250s (1574.04994392s left)
2019-06-05 20:58:26,276 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605205826262281
2019-06-05 20:58:26,307 [salt.minion      :1432][INFO    ][43136] Starting a new job with PID 43136
2019-06-05 20:58:26,331 [salt.minion      :1711][INFO    ][43136] Returning information for job: 20190605205826262281
2019-06-05 20:58:36,077 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:2250s (1542.13637805s left)
2019-06-05 20:58:56,473 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605205856462982
2019-06-05 20:58:56,494 [salt.minion      :1432][INFO    ][43203] Starting a new job with PID 43203
2019-06-05 20:58:56,517 [salt.minion      :1711][INFO    ][43203] Returning information for job: 20190605205856462982
2019-06-05 20:59:08,057 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1510.15610695s left)
2019-06-05 20:59:26,657 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605205926643847
2019-06-05 20:59:26,687 [salt.minion      :1432][INFO    ][43306] Starting a new job with PID 43306
2019-06-05 20:59:26,708 [salt.minion      :1711][INFO    ][43306] Returning information for job: 20190605205926643847
2019-06-05 20:59:40,206 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1478.00814199s left)
2019-06-05 20:59:56,864 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605205956848824
2019-06-05 20:59:56,892 [salt.minion      :1432][INFO    ][43377] Starting a new job with PID 43377
2019-06-05 20:59:56,915 [salt.minion      :1711][INFO    ][43377] Returning information for job: 20190605205956848824
2019-06-05 21:00:12,177 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1446.03598213s left)
2019-06-05 21:00:26,880 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605210026869497
2019-06-05 21:00:26,910 [salt.minion      :1432][INFO    ][43429] Starting a new job with PID 43429
2019-06-05 21:00:26,932 [salt.minion      :1711][INFO    ][43429] Returning information for job: 20190605210026869497
2019-06-05 21:00:44,323 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1413.89031696s left)
2019-06-05 21:00:56,909 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605210056897061
2019-06-05 21:00:56,929 [salt.minion      :1432][INFO    ][43470] Starting a new job with PID 43470
2019-06-05 21:00:56,962 [salt.minion      :1711][INFO    ][43470] Returning information for job: 20190605210056897061
2019-06-05 21:01:16,338 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1381.87558603s left)
2019-06-05 21:01:26,956 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605210126939739
2019-06-05 21:01:26,983 [salt.minion      :1432][INFO    ][43500] Starting a new job with PID 43500
2019-06-05 21:01:27,006 [salt.minion      :1711][INFO    ][43500] Returning information for job: 20190605210126939739
2019-06-05 21:01:48,376 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1349.837322s left)
2019-06-05 21:01:57,008 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605210156995378
2019-06-05 21:01:57,030 [salt.minion      :1432][INFO    ][43541] Starting a new job with PID 43541
2019-06-05 21:01:57,052 [salt.minion      :1711][INFO    ][43541] Returning information for job: 20190605210156995378
2019-06-05 21:02:20,340 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1317.87318707s left)
2019-06-05 21:02:27,041 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605210227027278
2019-06-05 21:02:27,065 [salt.minion      :1432][INFO    ][43575] Starting a new job with PID 43575
2019-06-05 21:02:27,088 [salt.minion      :1711][INFO    ][43575] Returning information for job: 20190605210227027278
2019-06-05 21:02:52,268 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1285.94518495s left)
2019-06-05 21:02:57,109 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605210257093057
2019-06-05 21:02:57,140 [salt.minion      :1432][INFO    ][43625] Starting a new job with PID 43625
2019-06-05 21:02:57,162 [salt.minion      :1711][INFO    ][43625] Returning information for job: 20190605210257093057
2019-06-05 21:03:24,214 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1253.99960709s left)
2019-06-05 21:03:27,211 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605210327192805
2019-06-05 21:03:27,240 [salt.minion      :1432][INFO    ][43657] Starting a new job with PID 43657
2019-06-05 21:03:27,260 [salt.minion      :1711][INFO    ][43657] Returning information for job: 20190605210327192805
2019-06-05 21:03:56,453 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1221.7599051s left)
2019-06-05 21:03:57,307 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605210357295712
2019-06-05 21:03:57,334 [salt.minion      :1432][INFO    ][43707] Starting a new job with PID 43707
2019-06-05 21:03:57,357 [salt.minion      :1711][INFO    ][43707] Returning information for job: 20190605210357295712
2019-06-05 21:04:27,393 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605210427382481
2019-06-05 21:04:27,417 [salt.minion      :1432][INFO    ][43738] Starting a new job with PID 43738
2019-06-05 21:04:27,440 [salt.minion      :1711][INFO    ][43738] Returning information for job: 20190605210427382481
2019-06-05 21:04:28,351 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1189.86272192s left)
2019-06-05 21:04:57,538 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605210457524064
2019-06-05 21:04:57,568 [salt.minion      :1432][INFO    ][43781] Starting a new job with PID 43781
2019-06-05 21:04:57,587 [salt.minion      :1711][INFO    ][43781] Returning information for job: 20190605210457524064
2019-06-05 21:05:00,349 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1157.86419296s left)
2019-06-05 21:05:27,676 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605210527666687
2019-06-05 21:05:27,700 [salt.minion      :1432][INFO    ][43815] Starting a new job with PID 43815
2019-06-05 21:05:27,721 [salt.minion      :1711][INFO    ][43815] Returning information for job: 20190605210527666687
2019-06-05 21:05:32,200 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1126.01286292s left)
2019-06-05 21:05:57,840 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605210557826491
2019-06-05 21:05:57,869 [salt.minion      :1432][INFO    ][43861] Starting a new job with PID 43861
2019-06-05 21:05:57,894 [salt.minion      :1711][INFO    ][43861] Returning information for job: 20190605210557826491
2019-06-05 21:06:04,253 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1093.96078706s left)
2019-06-05 21:06:27,989 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605210627978687
2019-06-05 21:06:28,012 [salt.minion      :1432][INFO    ][43893] Starting a new job with PID 43893
2019-06-05 21:06:28,036 [salt.minion      :1711][INFO    ][43893] Returning information for job: 20190605210627978687
2019-06-05 21:06:36,168 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1062.04536104s left)
2019-06-05 21:06:58,140 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605210658126203
2019-06-05 21:06:58,168 [salt.minion      :1432][INFO    ][43941] Starting a new job with PID 43941
2019-06-05 21:06:58,195 [salt.minion      :1711][INFO    ][43941] Returning information for job: 20190605210658126203
2019-06-05 21:07:08,111 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1030.10255599s left)
2019-06-05 21:07:28,340 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605210728324913
2019-06-05 21:07:28,372 [salt.minion      :1432][INFO    ][43990] Starting a new job with PID 43990
2019-06-05 21:07:28,396 [salt.minion      :1711][INFO    ][43990] Returning information for job: 20190605210728324913
2019-06-05 21:07:39,992 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (998.221231937s left)
2019-06-05 21:07:58,529 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605210758515509
2019-06-05 21:07:58,555 [salt.minion      :1432][INFO    ][44035] Starting a new job with PID 44035
2019-06-05 21:07:58,576 [salt.minion      :1711][INFO    ][44035] Returning information for job: 20190605210758515509
2019-06-05 21:08:12,003 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (966.210044146s left)
2019-06-05 21:08:28,757 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605210828744507
2019-06-05 21:08:28,785 [salt.minion      :1432][INFO    ][44071] Starting a new job with PID 44071
2019-06-05 21:08:28,811 [salt.minion      :1711][INFO    ][44071] Returning information for job: 20190605210828744507
2019-06-05 21:08:48,688 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (929.525635958s left)
2019-06-05 21:08:58,962 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605210858945656
2019-06-05 21:08:58,986 [salt.minion      :1432][INFO    ][44128] Starting a new job with PID 44128
2019-06-05 21:08:59,010 [salt.minion      :1711][INFO    ][44128] Returning information for job: 20190605210858945656
2019-06-05 21:09:20,566 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (897.647387981s left)
2019-06-05 21:09:28,989 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605210928976411
2019-06-05 21:09:29,020 [salt.minion      :1432][INFO    ][44285] Starting a new job with PID 44285
2019-06-05 21:09:29,043 [salt.minion      :1711][INFO    ][44285] Returning information for job: 20190605210928976411
2019-06-05 21:09:52,736 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (865.477771997s left)
2019-06-05 21:09:59,049 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605210959037153
2019-06-05 21:09:59,076 [salt.minion      :1432][INFO    ][44329] Starting a new job with PID 44329
2019-06-05 21:09:59,099 [salt.minion      :1711][INFO    ][44329] Returning information for job: 20190605210959037153
2019-06-05 21:10:24,642 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (833.571285009s left)
2019-06-05 21:10:29,145 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605211029127139
2019-06-05 21:10:29,172 [salt.minion      :1432][INFO    ][44360] Starting a new job with PID 44360
2019-06-05 21:10:29,194 [salt.minion      :1711][INFO    ][44360] Returning information for job: 20190605211029127139
2019-06-05 21:10:56,784 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (801.429458141s left)
2019-06-05 21:10:59,251 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605211059235875
2019-06-05 21:10:59,280 [salt.minion      :1432][INFO    ][44401] Starting a new job with PID 44401
2019-06-05 21:10:59,302 [salt.minion      :1711][INFO    ][44401] Returning information for job: 20190605211059235875
2019-06-05 21:11:28,654 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (769.559566975s left)
2019-06-05 21:11:29,367 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605211129346584
2019-06-05 21:11:29,389 [salt.minion      :1432][INFO    ][44431] Starting a new job with PID 44431
2019-06-05 21:11:29,413 [salt.minion      :1711][INFO    ][44431] Returning information for job: 20190605211129346584
2019-06-05 21:11:59,470 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605211159462604
2019-06-05 21:11:59,489 [salt.minion      :1432][INFO    ][44471] Starting a new job with PID 44471
2019-06-05 21:11:59,513 [salt.minion      :1711][INFO    ][44471] Returning information for job: 20190605211159462604
2019-06-05 21:12:00,460 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (737.752920151s left)
2019-06-05 21:12:29,616 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605211229605524
2019-06-05 21:12:29,640 [salt.minion      :1432][INFO    ][44505] Starting a new job with PID 44505
2019-06-05 21:12:29,664 [salt.minion      :1711][INFO    ][44505] Returning information for job: 20190605211229605524
2019-06-05 21:12:32,344 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (705.86886692s left)
2019-06-05 21:12:59,788 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605211259771844
2019-06-05 21:12:59,819 [salt.minion      :1432][INFO    ][44545] Starting a new job with PID 44545
2019-06-05 21:12:59,841 [salt.minion      :1711][INFO    ][44545] Returning information for job: 20190605211259771844
2019-06-05 21:13:04,188 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (674.025444984s left)
2019-06-05 21:13:29,971 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605211329954650
2019-06-05 21:13:30,001 [salt.minion      :1432][INFO    ][44576] Starting a new job with PID 44576
2019-06-05 21:13:30,024 [salt.minion      :1711][INFO    ][44576] Returning information for job: 20190605211329954650
2019-06-05 21:13:36,207 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (642.006087065s left)
2019-06-05 21:14:00,179 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605211400165842
2019-06-05 21:14:00,203 [salt.minion      :1432][INFO    ][44614] Starting a new job with PID 44614
2019-06-05 21:14:00,231 [salt.minion      :1711][INFO    ][44614] Returning information for job: 20190605211400165842
2019-06-05 21:14:08,016 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (610.197423935s left)
2019-06-05 21:14:30,365 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605211430354920
2019-06-05 21:14:30,392 [salt.minion      :1432][INFO    ][44648] Starting a new job with PID 44648
2019-06-05 21:14:30,414 [salt.minion      :1711][INFO    ][44648] Returning information for job: 20190605211430354920
2019-06-05 21:14:39,946 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (578.266849995s left)
2019-06-05 21:15:00,591 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605211500577570
2019-06-05 21:15:00,613 [salt.minion      :1432][INFO    ][44692] Starting a new job with PID 44692
2019-06-05 21:15:00,639 [salt.minion      :1711][INFO    ][44692] Returning information for job: 20190605211500577570
2019-06-05 21:15:12,084 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (546.129563093s left)
2019-06-05 21:15:30,800 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605211530781391
2019-06-05 21:15:30,826 [salt.minion      :1432][INFO    ][44723] Starting a new job with PID 44723
2019-06-05 21:15:30,849 [salt.minion      :1711][INFO    ][44723] Returning information for job: 20190605211530781391
2019-06-05 21:15:44,036 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (514.176932096s left)
2019-06-05 21:16:00,881 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605211600868387
2019-06-05 21:16:00,911 [salt.minion      :1432][INFO    ][44765] Starting a new job with PID 44765
2019-06-05 21:16:00,941 [salt.minion      :1711][INFO    ][44765] Returning information for job: 20190605211600868387
2019-06-05 21:16:15,893 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (482.320671082s left)
2019-06-05 21:16:30,979 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605211630957147
2019-06-05 21:16:31,008 [salt.minion      :1432][INFO    ][44797] Starting a new job with PID 44797
2019-06-05 21:16:31,033 [salt.minion      :1711][INFO    ][44797] Returning information for job: 20190605211630957147
2019-06-05 21:16:47,979 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (450.234411955s left)
2019-06-05 21:17:01,139 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605211701122635
2019-06-05 21:17:01,167 [salt.minion      :1432][INFO    ][44846] Starting a new job with PID 44846
2019-06-05 21:17:01,189 [salt.minion      :1711][INFO    ][44846] Returning information for job: 20190605211701122635
2019-06-05 21:17:19,869 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (418.344794035s left)
2019-06-05 21:17:31,247 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605211731235515
2019-06-05 21:17:31,273 [salt.minion      :1432][INFO    ][44896] Starting a new job with PID 44896
2019-06-05 21:17:31,298 [salt.minion      :1711][INFO    ][44896] Returning information for job: 20190605211731235515
2019-06-05 21:17:51,731 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (386.482751131s left)
2019-06-05 21:18:01,420 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605211801404942
2019-06-05 21:18:01,451 [salt.minion      :1432][INFO    ][44940] Starting a new job with PID 44940
2019-06-05 21:18:01,488 [salt.minion      :1711][INFO    ][44940] Returning information for job: 20190605211801404942
2019-06-05 21:18:23,656 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (354.557673931s left)
2019-06-05 21:18:31,613 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605211831601374
2019-06-05 21:18:31,641 [salt.minion      :1432][INFO    ][44974] Starting a new job with PID 44974
2019-06-05 21:18:31,665 [salt.minion      :1711][INFO    ][44974] Returning information for job: 20190605211831601374
2019-06-05 21:18:55,799 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (322.414188147s left)
2019-06-05 21:19:01,837 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605211901820068
2019-06-05 21:19:01,865 [salt.minion      :1432][INFO    ][45016] Starting a new job with PID 45016
2019-06-05 21:19:01,891 [salt.minion      :1711][INFO    ][45016] Returning information for job: 20190605211901820068
2019-06-05 21:19:27,661 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (290.552144051s left)
2019-06-05 21:19:32,063 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605211932048500
2019-06-05 21:19:32,093 [salt.minion      :1432][INFO    ][45052] Starting a new job with PID 45052
2019-06-05 21:19:32,116 [salt.minion      :1711][INFO    ][45052] Returning information for job: 20190605211932048500
2019-06-05 21:19:59,538 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (258.67543602s left)
2019-06-05 21:20:02,268 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605212002251311
2019-06-05 21:20:02,298 [salt.minion      :1432][INFO    ][45094] Starting a new job with PID 45094
2019-06-05 21:20:02,322 [salt.minion      :1711][INFO    ][45094] Returning information for job: 20190605212002251311
2019-06-05 21:20:31,522 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (226.690760136s left)
2019-06-05 21:20:32,337 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605212032320878
2019-06-05 21:20:32,368 [salt.minion      :1432][INFO    ][45128] Starting a new job with PID 45128
2019-06-05 21:20:32,392 [salt.minion      :1711][INFO    ][45128] Returning information for job: 20190605212032320878
2019-06-05 21:21:02,424 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605212102414768
2019-06-05 21:21:02,446 [salt.minion      :1432][INFO    ][45169] Starting a new job with PID 45169
2019-06-05 21:21:02,470 [salt.minion      :1711][INFO    ][45169] Returning information for job: 20190605212102414768
2019-06-05 21:21:03,440 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (194.773308992s left)
2019-06-05 21:21:32,521 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605212132508240
2019-06-05 21:21:32,552 [salt.minion      :1432][INFO    ][45201] Starting a new job with PID 45201
2019-06-05 21:21:32,574 [salt.minion      :1711][INFO    ][45201] Returning information for job: 20190605212132508240
2019-06-05 21:21:35,458 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (162.755101919s left)
2019-06-05 21:22:02,756 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605212202743051
2019-06-05 21:22:02,784 [salt.minion      :1432][INFO    ][45244] Starting a new job with PID 45244
2019-06-05 21:22:02,811 [salt.minion      :1711][INFO    ][45244] Returning information for job: 20190605212202743051
2019-06-05 21:22:07,336 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (130.877624989s left)
2019-06-05 21:22:32,892 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605212232876567
2019-06-05 21:22:32,921 [salt.minion      :1432][INFO    ][45279] Starting a new job with PID 45279
2019-06-05 21:22:32,944 [salt.minion      :1711][INFO    ][45279] Returning information for job: 20190605212232876567
2019-06-05 21:22:39,300 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (98.9137420654s left)
2019-06-05 21:23:02,979 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605212302963111
2019-06-05 21:23:03,005 [salt.minion      :1432][INFO    ][45320] Starting a new job with PID 45320
2019-06-05 21:23:03,030 [salt.minion      :1711][INFO    ][45320] Returning information for job: 20190605212302963111
2019-06-05 21:23:11,218 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (66.9959909916s left)
2019-06-05 21:23:33,185 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605212333171509
2019-06-05 21:23:33,211 [salt.minion      :1432][INFO    ][45352] Starting a new job with PID 45352
2019-06-05 21:23:33,234 [salt.minion      :1711][INFO    ][45352] Returning information for job: 20190605212333171509
2019-06-05 21:23:43,218 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (34.9952831268s left)
2019-06-05 21:24:03,348 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605212403334333
2019-06-05 21:24:03,369 [salt.minion      :1432][INFO    ][45400] Starting a new job with PID 45400
2019-06-05 21:24:03,392 [salt.minion      :1711][INFO    ][45400] Returning information for job: 20190605212403334333
2019-06-05 21:24:15,228 [salt.loaded.ext.module.maas:1023][INFO    ][40405] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2.98556613922s left)
2019-06-05 21:24:33,522 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605212433511596
2019-06-05 21:24:33,549 [salt.minion      :1432][INFO    ][45431] Starting a new job with PID 45431
2019-06-05 21:24:33,572 [salt.minion      :1711][INFO    ][45431] Returning information for job: 20190605212433511596
2019-06-05 21:24:47,136 [salt.state       :302 ][ERROR   ][40405] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['gtw01']not in Deployed state
2019-06-05 21:24:47,137 [salt.state       :1951][INFO    ][40405] Completed state [maas.wait_for_machine_status] at time 21:24:47.137207 duration_in_ms=2278935.539
2019-06-05 21:24:47,143 [salt.minion      :1711][INFO    ][40405] Returning information for job: 20190605204638098140
2019-06-05 21:24:57,965 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command pillar.get with jid 20190605212457948759
2019-06-05 21:24:57,992 [salt.minion      :1432][INFO    ][45476] Starting a new job with PID 45476
2019-06-05 21:24:58,000 [salt.minion      :1711][INFO    ][45476] Returning information for job: 20190605212457948759
2019-06-05 21:24:58,577 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command service.status with jid 20190605212458564036
2019-06-05 21:24:58,606 [salt.minion      :1432][INFO    ][45481] Starting a new job with PID 45481
2019-06-05 21:24:59,152 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][45481] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-06-05 21:24:59,195 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][45481] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-06-05 21:24:59,214 [salt.minion      :1711][INFO    ][45481] Returning information for job: 20190605212458564036
2019-06-05 21:24:59,790 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command state.apply with jid 20190605212459775338
2019-06-05 21:24:59,817 [salt.minion      :1432][INFO    ][45492] Starting a new job with PID 45492
2019-06-05 21:25:07,822 [salt.state       :915 ][INFO    ][45492] Loading fresh modules for state activity
2019-06-05 21:25:08,386 [salt.loaded.int.module.cmdmod:395 ][INFO    ][45492] Executing command 'salt-minion --version' in directory '/root'
2019-06-05 21:25:08,658 [salt.loaded.int.module.cmdmod:395 ][INFO    ][45492] Executing command 'salt-minion --version' in directory '/root'
2019-06-05 21:25:09,559 [salt.loaded.int.module.cmdmod:395 ][INFO    ][45492] Executing command 'salt-minion --version' in directory '/root'
2019-06-05 21:25:09,848 [salt.loaded.int.module.cmdmod:395 ][INFO    ][45492] Executing command 'salt-minion --version' in directory '/root'
2019-06-05 21:25:11,627 [salt.state       :1780][INFO    ][45492] Running state [salt-minion] at time 21:25:11.627713
2019-06-05 21:25:11,628 [salt.state       :1813][INFO    ][45492] Executing state pkg.installed for [salt-minion]
2019-06-05 21:25:11,628 [salt.loaded.int.module.cmdmod:395 ][INFO    ][45492] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-05 21:25:11,733 [salt.state       :300 ][INFO    ][45492] All specified packages are already installed
2019-06-05 21:25:11,734 [salt.state       :1951][INFO    ][45492] Completed state [salt-minion] at time 21:25:11.734486 duration_in_ms=106.774
2019-06-05 21:25:11,734 [salt.state       :1780][INFO    ][45492] Running state [salt_minion_dependency_packages] at time 21:25:11.734828
2019-06-05 21:25:11,735 [salt.state       :1813][INFO    ][45492] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-06-05 21:25:11,745 [salt.state       :300 ][INFO    ][45492] All specified packages are already installed
2019-06-05 21:25:11,745 [salt.state       :1951][INFO    ][45492] Completed state [salt_minion_dependency_packages] at time 21:25:11.745416 duration_in_ms=10.588
2019-06-05 21:25:11,753 [salt.state       :1780][INFO    ][45492] Running state [/etc/salt/minion.d/minion.conf] at time 21:25:11.753079
2019-06-05 21:25:11,753 [salt.state       :1813][INFO    ][45492] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-06-05 21:25:12,036 [salt.state       :300 ][INFO    ][45492] File /etc/salt/minion.d/minion.conf is in the correct state
2019-06-05 21:25:12,036 [salt.state       :1951][INFO    ][45492] Completed state [/etc/salt/minion.d/minion.conf] at time 21:25:12.036271 duration_in_ms=283.193
2019-06-05 21:25:12,039 [salt.state       :1780][INFO    ][45492] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 21:25:12.039678
2019-06-05 21:25:12,039 [salt.state       :1813][INFO    ][45492] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-06-05 21:25:12,063 [salt.state       :300 ][INFO    ][45492] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-06-05 21:25:12,063 [salt.state       :1951][INFO    ][45492] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 21:25:12.063770 duration_in_ms=24.092
2019-06-05 21:25:12,065 [salt.state       :1780][INFO    ][45492] Running state [salt-minion] at time 21:25:12.065278
2019-06-05 21:25:12,065 [salt.state       :1813][INFO    ][45492] Executing state service.running for [salt-minion]
2019-06-05 21:25:12,066 [salt.loaded.int.module.cmdmod:395 ][INFO    ][45492] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-06-05 21:25:12,116 [salt.loaded.int.module.cmdmod:395 ][INFO    ][45492] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-06-05 21:25:12,138 [salt.loaded.int.module.cmdmod:395 ][INFO    ][45492] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-06-05 21:25:12,162 [salt.state       :300 ][INFO    ][45492] The service salt-minion is already running
2019-06-05 21:25:12,162 [salt.state       :1951][INFO    ][45492] Completed state [salt-minion] at time 21:25:12.162635 duration_in_ms=97.355
2019-06-05 21:25:12,165 [salt.state       :1780][INFO    ][45492] Running state [/etc/salt/grains.d] at time 21:25:12.165677
2019-06-05 21:25:12,166 [salt.state       :1813][INFO    ][45492] Executing state file.directory for [/etc/salt/grains.d]
2019-06-05 21:25:12,168 [salt.state       :300 ][INFO    ][45492] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-06-05 21:25:12,168 [salt.state       :1951][INFO    ][45492] Completed state [/etc/salt/grains.d] at time 21:25:12.168420 duration_in_ms=2.743
2019-06-05 21:25:12,171 [salt.state       :1780][INFO    ][45492] Running state [/etc/salt/grains] at time 21:25:12.171181
2019-06-05 21:25:12,171 [salt.state       :1813][INFO    ][45492] Executing state file.managed for [/etc/salt/grains]
2019-06-05 21:25:12,172 [salt.state       :300 ][INFO    ][45492] File /etc/salt/grains exists with proper permissions. No changes made.
2019-06-05 21:25:12,173 [salt.state       :1951][INFO    ][45492] Completed state [/etc/salt/grains] at time 21:25:12.173213 duration_in_ms=2.031
2019-06-05 21:25:12,174 [salt.state       :1780][INFO    ][45492] Running state [/etc/salt/grains.d/placeholder] at time 21:25:12.174048
2019-06-05 21:25:12,174 [salt.state       :1813][INFO    ][45492] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-06-05 21:25:12,175 [salt.state       :300 ][INFO    ][45492] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-06-05 21:25:12,175 [salt.state       :1951][INFO    ][45492] Completed state [/etc/salt/grains.d/placeholder] at time 21:25:12.175732 duration_in_ms=1.685
2019-06-05 21:25:12,176 [salt.state       :1780][INFO    ][45492] Running state [/etc/salt/grains.d/sphinx] at time 21:25:12.176467
2019-06-05 21:25:12,176 [salt.state       :1813][INFO    ][45492] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-06-05 21:25:12,179 [salt.state       :300 ][INFO    ][45492] File /etc/salt/grains.d/sphinx is in the correct state
2019-06-05 21:25:12,179 [salt.state       :1951][INFO    ][45492] Completed state [/etc/salt/grains.d/sphinx] at time 21:25:12.179359 duration_in_ms=2.893
2019-06-05 21:25:12,183 [salt.state       :1780][INFO    ][45492] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 21:25:12.183850
2019-06-05 21:25:12,184 [salt.state       :1813][INFO    ][45492] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-06-05 21:25:12,184 [salt.state       :300 ][INFO    ][45492] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-06-05 21:25:12,185 [salt.state       :1951][INFO    ][45492] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 21:25:12.185144 duration_in_ms=1.295
2019-06-05 21:25:12,187 [salt.state       :1780][INFO    ][45492] Running state [/etc/salt/grains.d/dns_records] at time 21:25:12.187050
2019-06-05 21:25:12,187 [salt.state       :1813][INFO    ][45492] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-06-05 21:25:12,188 [salt.state       :300 ][INFO    ][45492] File /etc/salt/grains.d/dns_records is in the correct state
2019-06-05 21:25:12,188 [salt.state       :1951][INFO    ][45492] Completed state [/etc/salt/grains.d/dns_records] at time 21:25:12.188411 duration_in_ms=1.361
2019-06-05 21:25:12,189 [salt.state       :1780][INFO    ][45492] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 21:25:12.189324
2019-06-05 21:25:12,189 [salt.state       :1813][INFO    ][45492] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-06-05 21:25:12,189 [salt.state       :300 ][INFO    ][45492] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-06-05 21:25:12,190 [salt.state       :1951][INFO    ][45492] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 21:25:12.190078 duration_in_ms=0.754
2019-06-05 21:25:12,190 [salt.state       :1780][INFO    ][45492] Running state [/etc/salt/grains.d/salt] at time 21:25:12.190537
2019-06-05 21:25:12,190 [salt.state       :1813][INFO    ][45492] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-06-05 21:25:12,191 [salt.state       :300 ][INFO    ][45492] File /etc/salt/grains.d/salt is in the correct state
2019-06-05 21:25:12,191 [salt.state       :1951][INFO    ][45492] Completed state [/etc/salt/grains.d/salt] at time 21:25:12.191896 duration_in_ms=1.359
2019-06-05 21:25:12,192 [salt.state       :1780][INFO    ][45492] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 21:25:12.192764
2019-06-05 21:25:12,193 [salt.state       :1813][INFO    ][45492] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-06-05 21:25:12,193 [salt.state       :300 ][INFO    ][45492] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-06-05 21:25:12,193 [salt.state       :1951][INFO    ][45492] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 21:25:12.193506 duration_in_ms=0.742
2019-06-05 21:25:12,196 [salt.state       :1780][INFO    ][45492] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 21:25:12.196596
2019-06-05 21:25:12,196 [salt.state       :1813][INFO    ][45492] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-06-05 21:25:12,197 [salt.state       :300 ][INFO    ][45492] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-06-05 21:25:12,197 [salt.state       :1951][INFO    ][45492] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 21:25:12.197372 duration_in_ms=0.777
2019-06-05 21:25:12,198 [salt.state       :1780][INFO    ][45492] Running state [mine.update] at time 21:25:12.198644
2019-06-05 21:25:12,198 [salt.state       :1813][INFO    ][45492] Executing state module.wait for [mine.update]
2019-06-05 21:25:12,199 [salt.state       :300 ][INFO    ][45492] No changes made for mine.update
2019-06-05 21:25:12,199 [salt.state       :1951][INFO    ][45492] Completed state [mine.update] at time 21:25:12.199348 duration_in_ms=0.703
2019-06-05 21:25:12,199 [salt.state       :1780][INFO    ][45492] Running state [ca-certificates] at time 21:25:12.199583
2019-06-05 21:25:12,199 [salt.state       :1813][INFO    ][45492] Executing state pkg.installed for [ca-certificates]
2019-06-05 21:25:12,210 [salt.state       :300 ][INFO    ][45492] All specified packages are already installed
2019-06-05 21:25:12,210 [salt.state       :1951][INFO    ][45492] Completed state [ca-certificates] at time 21:25:12.210929 duration_in_ms=11.346
2019-06-05 21:25:12,211 [salt.state       :1780][INFO    ][45492] Running state [update-ca-certificates] at time 21:25:12.211849
2019-06-05 21:25:12,212 [salt.state       :1813][INFO    ][45492] Executing state cmd.wait for [update-ca-certificates]
2019-06-05 21:25:12,212 [salt.state       :300 ][INFO    ][45492] No changes made for update-ca-certificates
2019-06-05 21:25:12,212 [salt.state       :1951][INFO    ][45492] Completed state [update-ca-certificates] at time 21:25:12.212583 duration_in_ms=0.734
2019-06-05 21:25:12,212 [salt.state       :1780][INFO    ][45492] Running state [iptables] at time 21:25:12.212824
2019-06-05 21:25:12,213 [salt.state       :1813][INFO    ][45492] Executing state pkg.installed for [iptables]
2019-06-05 21:25:12,222 [salt.state       :300 ][INFO    ][45492] All specified packages are already installed
2019-06-05 21:25:12,222 [salt.state       :1951][INFO    ][45492] Completed state [iptables] at time 21:25:12.222574 duration_in_ms=9.75
2019-06-05 21:25:12,222 [salt.state       :1780][INFO    ][45492] Running state [iptables-persistent] at time 21:25:12.222815
2019-06-05 21:25:12,223 [salt.state       :1813][INFO    ][45492] Executing state pkg.installed for [iptables-persistent]
2019-06-05 21:25:12,232 [salt.state       :300 ][INFO    ][45492] All specified packages are already installed
2019-06-05 21:25:12,232 [salt.state       :1951][INFO    ][45492] Completed state [iptables-persistent] at time 21:25:12.232655 duration_in_ms=9.84
2019-06-05 21:25:12,233 [salt.state       :1780][INFO    ][45492] Running state [iptables_modules_v4_load] at time 21:25:12.233616
2019-06-05 21:25:12,233 [salt.state       :1813][INFO    ][45492] Executing state kmod.present for [iptables_modules_v4_load]
2019-06-05 21:25:12,234 [salt.loaded.int.module.cmdmod:395 ][INFO    ][45492] Executing command 'lsmod' in directory '/root'
2019-06-05 21:25:12,256 [salt.state       :300 ][INFO    ][45492] Kernel modules iptable_filter, ip_tables are already present
2019-06-05 21:25:12,257 [salt.state       :1951][INFO    ][45492] Completed state [iptables_modules_v4_load] at time 21:25:12.257007 duration_in_ms=23.391
2019-06-05 21:25:12,257 [salt.state       :1780][INFO    ][45492] Running state [/etc/iptables/rules.v4] at time 21:25:12.257645
2019-06-05 21:25:12,258 [salt.state       :1813][INFO    ][45492] Executing state file.managed for [/etc/iptables/rules.v4]
2019-06-05 21:25:12,373 [salt.state       :300 ][INFO    ][45492] File /etc/iptables/rules.v4 is in the correct state
2019-06-05 21:25:12,373 [salt.state       :1951][INFO    ][45492] Completed state [/etc/iptables/rules.v4] at time 21:25:12.373622 duration_in_ms=115.977
2019-06-05 21:25:12,375 [salt.state       :1780][INFO    ][45492] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 21:25:12.375363
2019-06-05 21:25:12,375 [salt.state       :1813][INFO    ][45492] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-06-05 21:25:12,376 [salt.loaded.int.module.cmdmod:395 ][INFO    ][45492] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-06-05 21:25:12,396 [salt.state       :300 ][INFO    ][45492] onlyif execution failed
2019-06-05 21:25:12,396 [salt.state       :1951][INFO    ][45492] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 21:25:12.396617 duration_in_ms=21.253
2019-06-05 21:25:12,400 [salt.state       :1780][INFO    ][45492] Running state [netfilter-persistent] at time 21:25:12.400464
2019-06-05 21:25:12,400 [salt.state       :1813][INFO    ][45492] Executing state service.running for [netfilter-persistent]
2019-06-05 21:25:12,402 [salt.loaded.int.module.cmdmod:395 ][INFO    ][45492] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-06-05 21:25:12,429 [salt.loaded.int.module.cmdmod:395 ][INFO    ][45492] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-06-05 21:25:12,456 [salt.loaded.int.module.cmdmod:395 ][INFO    ][45492] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-06-05 21:25:12,480 [salt.state       :300 ][INFO    ][45492] The service netfilter-persistent is already running
2019-06-05 21:25:12,480 [salt.state       :1951][INFO    ][45492] Completed state [netfilter-persistent] at time 21:25:12.480824 duration_in_ms=80.36
2019-06-05 21:25:12,484 [salt.state       :1780][INFO    ][45492] Running state [iptables_extra.remove_stale_tables] at time 21:25:12.484043
2019-06-05 21:25:12,484 [salt.state       :1813][INFO    ][45492] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-06-05 21:25:12,485 [salt.state       :300 ][INFO    ][45492] No changes made for iptables_extra.remove_stale_tables
2019-06-05 21:25:12,485 [salt.state       :1951][INFO    ][45492] Completed state [iptables_extra.remove_stale_tables] at time 21:25:12.485498 duration_in_ms=1.456
2019-06-05 21:25:12,486 [salt.state       :1780][INFO    ][45492] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 21:25:12.486217
2019-06-05 21:25:12,486 [salt.state       :1813][INFO    ][45492] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-06-05 21:25:12,487 [salt.loaded.int.module.cmdmod:395 ][INFO    ][45492] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-06-05 21:25:12,503 [salt.state       :300 ][INFO    ][45492] onlyif execution failed
2019-06-05 21:25:12,503 [salt.state       :1951][INFO    ][45492] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 21:25:12.503893 duration_in_ms=17.675
2019-06-05 21:25:12,505 [salt.state       :1780][INFO    ][45492] Running state [/etc/iptables/rules.v6] at time 21:25:12.505182
2019-06-05 21:25:12,505 [salt.state       :1813][INFO    ][45492] Executing state file.absent for [/etc/iptables/rules.v6]
2019-06-05 21:25:12,507 [salt.state       :300 ][INFO    ][45492] File /etc/iptables/rules.v6 is not present
2019-06-05 21:25:12,507 [salt.state       :1951][INFO    ][45492] Completed state [/etc/iptables/rules.v6] at time 21:25:12.507668 duration_in_ms=2.485
2019-06-05 21:25:12,508 [salt.state       :1780][INFO    ][45492] Running state [iptables_extra.flush_all] at time 21:25:12.508614
2019-06-05 21:25:12,509 [salt.state       :1813][INFO    ][45492] Executing state module.wait for [iptables_extra.flush_all]
2019-06-05 21:25:12,509 [salt.state       :300 ][INFO    ][45492] No changes made for iptables_extra.flush_all
2019-06-05 21:25:12,511 [salt.state       :1951][INFO    ][45492] Completed state [iptables_extra.flush_all] at time 21:25:12.509653 duration_in_ms=1.039
2019-06-05 21:25:12,515 [salt.minion      :1711][INFO    ][45492] Returning information for job: 20190605212459775338
2019-06-05 21:25:13,114 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command state.apply with jid 20190605212513101229
2019-06-05 21:25:13,144 [salt.minion      :1432][INFO    ][45580] Starting a new job with PID 45580
2019-06-05 21:25:14,177 [salt.state       :915 ][INFO    ][45580] Loading fresh modules for state activity
2019-06-05 21:25:15,103 [salt.state       :1780][INFO    ][45580] Running state [maas-rack-controller] at time 21:25:15.103390
2019-06-05 21:25:15,103 [salt.state       :1813][INFO    ][45580] Executing state pkg.installed for [maas-rack-controller]
2019-06-05 21:25:15,104 [salt.loaded.int.module.cmdmod:395 ][INFO    ][45580] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-05 21:25:15,217 [salt.state       :300 ][INFO    ][45580] All specified packages are already installed
2019-06-05 21:25:15,218 [salt.state       :1951][INFO    ][45580] Completed state [maas-rack-controller] at time 21:25:15.217625 duration_in_ms=114.235
2019-06-05 21:25:15,218 [salt.state       :1780][INFO    ][45580] Running state [ipmitool] at time 21:25:15.218849
2019-06-05 21:25:15,219 [salt.state       :1813][INFO    ][45580] Executing state pkg.installed for [ipmitool]
2019-06-05 21:25:15,227 [salt.state       :300 ][INFO    ][45580] All specified packages are already installed
2019-06-05 21:25:15,228 [salt.state       :1951][INFO    ][45580] Completed state [ipmitool] at time 21:25:15.228074 duration_in_ms=9.225
2019-06-05 21:25:15,231 [salt.state       :1780][INFO    ][45580] Running state [/etc/maas/rackd.conf] at time 21:25:15.231799
2019-06-05 21:25:15,232 [salt.state       :1813][INFO    ][45580] Executing state file.line for [/etc/maas/rackd.conf]
2019-06-05 21:25:15,232 [salt.state       :300 ][INFO    ][45580] No changes needed to be made
2019-06-05 21:25:15,233 [salt.state       :1951][INFO    ][45580] Completed state [/etc/maas/rackd.conf] at time 21:25:15.233163 duration_in_ms=1.364
2019-06-05 21:25:15,233 [salt.state       :1780][INFO    ][45580] Running state [/etc/maas/rackd.conf] at time 21:25:15.233416
2019-06-05 21:25:15,233 [salt.state       :1813][INFO    ][45580] Executing state file.managed for [/etc/maas/rackd.conf]
2019-06-05 21:25:15,233 [salt.loaded.int.states.file:2298][WARNING ][45580] 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-06-05 21:25:15,234 [salt.state       :300 ][INFO    ][45580] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-06-05 21:25:15,234 [salt.state       :1951][INFO    ][45580] Completed state [/etc/maas/rackd.conf] at time 21:25:15.234893 duration_in_ms=1.476
2019-06-05 21:25:15,235 [salt.state       :1780][INFO    ][45580] Running state [maas-rackd] at time 21:25:15.235885
2019-06-05 21:25:15,236 [salt.state       :1813][INFO    ][45580] Executing state service.running for [maas-rackd]
2019-06-05 21:25:15,236 [salt.loaded.int.module.cmdmod:395 ][INFO    ][45580] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-06-05 21:25:15,280 [salt.loaded.int.module.cmdmod:395 ][INFO    ][45580] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-06-05 21:25:15,300 [salt.loaded.int.module.cmdmod:395 ][INFO    ][45580] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-06-05 21:25:15,320 [salt.state       :300 ][INFO    ][45580] The service maas-rackd is already running
2019-06-05 21:25:15,320 [salt.state       :1951][INFO    ][45580] Completed state [maas-rackd] at time 21:25:15.320832 duration_in_ms=84.946
2019-06-05 21:25:15,323 [salt.minion      :1711][INFO    ][45580] Returning information for job: 20190605212513101229
2019-06-05 21:25:15,882 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command state.apply with jid 20190605212515865328
2019-06-05 21:25:15,909 [salt.minion      :1432][INFO    ][45607] Starting a new job with PID 45607
2019-06-05 21:25:16,939 [salt.state       :915 ][INFO    ][45607] Loading fresh modules for state activity
2019-06-05 21:25:18,001 [salt.state       :1780][INFO    ][45607] Running state [maas-region-controller] at time 21:25:18.001410
2019-06-05 21:25:18,003 [salt.state       :1813][INFO    ][45607] Executing state pkg.installed for [maas-region-controller]
2019-06-05 21:25:18,003 [salt.loaded.int.module.cmdmod:395 ][INFO    ][45607] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-05 21:25:18,115 [salt.state       :300 ][INFO    ][45607] All specified packages are already installed
2019-06-05 21:25:18,116 [salt.state       :1951][INFO    ][45607] Completed state [maas-region-controller] at time 21:25:18.116060 duration_in_ms=114.648
2019-06-05 21:25:18,116 [salt.state       :1780][INFO    ][45607] Running state [python-oauth] at time 21:25:18.116871
2019-06-05 21:25:18,117 [salt.state       :1813][INFO    ][45607] Executing state pkg.installed for [python-oauth]
2019-06-05 21:25:18,129 [salt.state       :300 ][INFO    ][45607] All specified packages are already installed
2019-06-05 21:25:18,130 [salt.state       :1951][INFO    ][45607] Completed state [python-oauth] at time 21:25:18.130095 duration_in_ms=13.225
2019-06-05 21:25:18,133 [salt.state       :1780][INFO    ][45607] Running state [/etc/maas/regiond.conf] at time 21:25:18.133309
2019-06-05 21:25:18,133 [salt.state       :1813][INFO    ][45607] Executing state file.replace for [/etc/maas/regiond.conf]
2019-06-05 21:25:18,139 [salt.state       :300 ][INFO    ][45607] No changes needed to be made
2019-06-05 21:25:18,139 [salt.state       :1951][INFO    ][45607] Completed state [/etc/maas/regiond.conf] at time 21:25:18.139712 duration_in_ms=6.402
2019-06-05 21:25:18,140 [salt.state       :1780][INFO    ][45607] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 21:25:18.140394
2019-06-05 21:25:18,140 [salt.state       :1813][INFO    ][45607] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-06-05 21:25:18,202 [salt.state       :300 ][INFO    ][45607] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-06-05 21:25:18,202 [salt.state       :1951][INFO    ][45607] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 21:25:18.202741 duration_in_ms=62.346
2019-06-05 21:25:18,203 [salt.state       :1780][INFO    ][45607] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 21:25:18.203504
2019-06-05 21:25:18,203 [salt.state       :1813][INFO    ][45607] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-06-05 21:25:18,208 [salt.state       :300 ][INFO    ][45607] No changes needed to be made
2019-06-05 21:25:18,208 [salt.state       :1951][INFO    ][45607] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 21:25:18.208641 duration_in_ms=5.136
2019-06-05 21:25:18,209 [salt.state       :1780][INFO    ][45607] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 21:25:18.209112
2019-06-05 21:25:18,209 [salt.state       :1813][INFO    ][45607] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-06-05 21:25:18,213 [salt.state       :300 ][INFO    ][45607] No changes needed to be made
2019-06-05 21:25:18,213 [salt.state       :1951][INFO    ][45607] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 21:25:18.213332 duration_in_ms=4.219
2019-06-05 21:25:18,214 [salt.state       :1780][INFO    ][45607] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 21:25:18.214161
2019-06-05 21:25:18,214 [salt.state       :1813][INFO    ][45607] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-06-05 21:25:18,237 [salt.state       :300 ][INFO    ][45607] No changes needed to be made
2019-06-05 21:25:18,238 [salt.state       :1951][INFO    ][45607] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 21:25:18.238111 duration_in_ms=23.95
2019-06-05 21:25:18,238 [salt.state       :1780][INFO    ][45607] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 21:25:18.238588
2019-06-05 21:25:18,238 [salt.state       :1813][INFO    ][45607] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-06-05 21:25:18,259 [salt.state       :300 ][INFO    ][45607] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-06-05 21:25:18,260 [salt.state       :1951][INFO    ][45607] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 21:25:18.260167 duration_in_ms=21.577
2019-06-05 21:25:18,262 [salt.state       :1780][INFO    ][45607] Running state [a2enmod headers] at time 21:25:18.262529
2019-06-05 21:25:18,263 [salt.state       :1813][INFO    ][45607] Executing state cmd.run for [a2enmod headers]
2019-06-05 21:25:18,264 [salt.loaded.int.module.cmdmod:395 ][INFO    ][45607] Executing command 'a2enmod headers' in directory '/root'
2019-06-05 21:25:18,334 [salt.state       :300 ][INFO    ][45607] {'pid': 45626, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-06-05 21:25:18,334 [salt.state       :1951][INFO    ][45607] Completed state [a2enmod headers] at time 21:25:18.334577 duration_in_ms=72.047
2019-06-05 21:25:18,335 [salt.state       :1780][INFO    ][45607] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 21:25:18.335231
2019-06-05 21:25:18,335 [salt.state       :1813][INFO    ][45607] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-06-05 21:25:18,361 [salt.state       :300 ][INFO    ][45607] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-06-05 21:25:18,365 [salt.state       :1951][INFO    ][45607] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 21:25:18.361668 duration_in_ms=26.435
2019-06-05 21:25:18,365 [salt.state       :1780][INFO    ][45607] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 21:25:18.365659
2019-06-05 21:25:18,366 [salt.state       :1813][INFO    ][45607] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-06-05 21:25:18,430 [salt.state       :300 ][INFO    ][45607] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-06-05 21:25:18,430 [salt.state       :1951][INFO    ][45607] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 21:25:18.430685 duration_in_ms=65.026
2019-06-05 21:25:18,431 [salt.state       :1780][INFO    ][45607] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 21:25:18.431177
2019-06-05 21:25:18,431 [salt.state       :1813][INFO    ][45607] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-06-05 21:25:18,489 [salt.state       :300 ][INFO    ][45607] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-06-05 21:25:18,490 [salt.state       :1951][INFO    ][45607] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 21:25:18.489967 duration_in_ms=58.789
2019-06-05 21:25:18,490 [salt.state       :1780][INFO    ][45607] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 21:25:18.490454
2019-06-05 21:25:18,490 [salt.state       :1813][INFO    ][45607] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-06-05 21:25:18,565 [salt.state       :300 ][INFO    ][45607] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-06-05 21:25:18,565 [salt.state       :1951][INFO    ][45607] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 21:25:18.565290 duration_in_ms=74.835
2019-06-05 21:25:18,565 [salt.state       :1780][INFO    ][45607] Running state [/root/.pgpass] at time 21:25:18.565556
2019-06-05 21:25:18,566 [salt.state       :1813][INFO    ][45607] Executing state file.managed for [/root/.pgpass]
2019-06-05 21:25:18,611 [salt.state       :300 ][INFO    ][45607] File /root/.pgpass is in the correct state
2019-06-05 21:25:18,612 [salt.state       :1951][INFO    ][45607] Completed state [/root/.pgpass] at time 21:25:18.612177 duration_in_ms=46.621
2019-06-05 21:25:18,618 [salt.state       :1780][INFO    ][45607] Running state [maas-region syncdb --noinput] at time 21:25:18.618743
2019-06-05 21:25:18,619 [salt.state       :1813][INFO    ][45607] Executing state cmd.run for [maas-region syncdb --noinput]
2019-06-05 21:25:18,619 [salt.loaded.int.module.cmdmod:395 ][INFO    ][45607] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-06-05 21:25:21,173 [salt.state       :300 ][INFO    ][45607] {'pid': 45639, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: sites, contenttypes, auth, maasserver, metadataserver, sessions, piston3\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-06-05 21:25:21,174 [salt.state       :1951][INFO    ][45607] Completed state [maas-region syncdb --noinput] at time 21:25:21.174596 duration_in_ms=2555.852
2019-06-05 21:25:21,175 [salt.state       :2022][WARNING ][45607] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-06-05 21:25:21,178 [salt.state       :1780][INFO    ][45607] Running state [maas-regiond] at time 21:25:21.178882
2019-06-05 21:25:21,179 [salt.state       :1813][INFO    ][45607] Executing state service.running for [maas-regiond]
2019-06-05 21:25:21,181 [salt.loaded.int.module.cmdmod:395 ][INFO    ][45607] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-06-05 21:25:21,228 [salt.loaded.int.module.cmdmod:395 ][INFO    ][45607] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-06-05 21:25:21,249 [salt.loaded.int.module.cmdmod:395 ][INFO    ][45607] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-06-05 21:25:21,273 [salt.state       :300 ][INFO    ][45607] The service maas-regiond is already running
2019-06-05 21:25:21,273 [salt.state       :1951][INFO    ][45607] Completed state [maas-regiond] at time 21:25:21.273551 duration_in_ms=94.655
2019-06-05 21:25:21,276 [salt.state       :1780][INFO    ][45607] Running state [bind9] at time 21:25:21.276808
2019-06-05 21:25:21,277 [salt.state       :1813][INFO    ][45607] Executing state service.running for [bind9]
2019-06-05 21:25:21,280 [salt.loaded.int.module.cmdmod:395 ][INFO    ][45607] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-06-05 21:25:21,301 [salt.loaded.int.module.cmdmod:395 ][INFO    ][45607] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-06-05 21:25:21,322 [salt.loaded.int.module.cmdmod:395 ][INFO    ][45607] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-06-05 21:25:21,343 [salt.state       :300 ][INFO    ][45607] The service bind9 is already running
2019-06-05 21:25:21,344 [salt.state       :1951][INFO    ][45607] Completed state [bind9] at time 21:25:21.344436 duration_in_ms=67.628
2019-06-05 21:25:21,347 [salt.state       :1780][INFO    ][45607] Running state [apache2] at time 21:25:21.347431
2019-06-05 21:25:21,348 [salt.state       :1813][INFO    ][45607] Executing state service.running for [apache2]
2019-06-05 21:25:21,349 [salt.loaded.int.module.cmdmod:395 ][INFO    ][45607] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-06-05 21:25:21,369 [salt.loaded.int.module.cmdmod:395 ][INFO    ][45607] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-06-05 21:25:21,391 [salt.loaded.int.module.cmdmod:395 ][INFO    ][45607] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-06-05 21:25:21,418 [salt.state       :300 ][INFO    ][45607] The service apache2 is already running
2019-06-05 21:25:21,418 [salt.state       :1951][INFO    ][45607] Completed state [apache2] at time 21:25:21.418569 duration_in_ms=71.137
2019-06-05 21:25:21,420 [salt.state       :1780][INFO    ][45607] Running state [maasng.wait_for_http_code] at time 21:25:21.420438
2019-06-05 21:25:21,420 [salt.state       :1813][INFO    ][45607] Executing state module.run for [maasng.wait_for_http_code]
2019-06-05 21:25:21,421 [salt.utils.decorators:613 ][WARNING ][45607] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-05 21:25:21,580 [salt.state       :300 ][INFO    ][45607] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-06-05 21:25:21,581 [salt.state       :1951][INFO    ][45607] Completed state [maasng.wait_for_http_code] at time 21:25:21.581255 duration_in_ms=160.817
2019-06-05 21:25:21,583 [salt.state       :1780][INFO    ][45607] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 21:25:21.583459
2019-06-05 21:25:21,584 [salt.state       :1813][INFO    ][45607] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-06-05 21:25:21,584 [salt.state       :300 ][INFO    ][45607] /var/lib/maas/.setup_admin exists
2019-06-05 21:25:21,585 [salt.state       :1951][INFO    ][45607] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 21:25:21.585052 duration_in_ms=1.593
2019-06-05 21:25:21,586 [salt.state       :1780][INFO    ][45607] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:25:21.586310
2019-06-05 21:25:21,586 [salt.state       :1813][INFO    ][45607] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-05 21:25:21,587 [salt.loaded.int.module.cmdmod:395 ][INFO    ][45607] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-05 21:25:23,292 [salt.state       :300 ][INFO    ][45607] {'pid': 45660, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-05 21:25:23,293 [salt.state       :1951][INFO    ][45607] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:25:23.293111 duration_in_ms=1706.792
2019-06-05 21:25:23,304 [salt.state       :1780][INFO    ][45607] Running state [maas_region_boot_source_resources_mirror] at time 21:25:23.303921
2019-06-05 21:25:23,304 [salt.state       :1813][INFO    ][45607] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-06-05 21:25:23,414 [salt.state       :300 ][INFO    ][45607] {'changes': {}}
2019-06-05 21:25:23,415 [salt.state       :1951][INFO    ][45607] Completed state [maas_region_boot_source_resources_mirror] at time 21:25:23.415074 duration_in_ms=111.154
2019-06-05 21:25:23,416 [salt.state       :1780][INFO    ][45607] Running state [maasng.boot_resources_import] at time 21:25:23.416243
2019-06-05 21:25:23,417 [salt.state       :1813][INFO    ][45607] Executing state module.run for [maasng.boot_resources_import]
2019-06-05 21:25:23,418 [salt.utils.decorators:613 ][WARNING ][45607] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-05 21:25:23,512 [salt.loaded.ext.module.maasng:1600][INFO    ][45607] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-06-05 21:25:28,555 [salt.loaded.ext.module.maasng:1600][INFO    ][45607] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-06-05 21:25:31,004 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605212530985224
2019-06-05 21:25:31,036 [salt.minion      :1432][INFO    ][45687] Starting a new job with PID 45687
2019-06-05 21:25:31,067 [salt.minion      :1711][INFO    ][45687] Returning information for job: 20190605212530985224
2019-06-05 21:25:33,609 [salt.loaded.ext.module.maasng:1600][INFO    ][45607] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-06-05 21:25:38,711 [salt.state       :300 ][INFO    ][45607] {'ret': True}
2019-06-05 21:25:38,711 [salt.state       :1951][INFO    ][45607] Completed state [maasng.boot_resources_import] at time 21:25:38.711721 duration_in_ms=15295.478
2019-06-05 21:25:38,713 [salt.state       :1780][INFO    ][45607] Running state [maas_region_boot_sources_selection_xenial] at time 21:25:38.712957
2019-06-05 21:25:38,713 [salt.state       :1813][INFO    ][45607] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-06-05 21:25:39,025 [salt.state       :300 ][INFO    ][45607] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-06-05 21:25:39,025 [salt.state       :1951][INFO    ][45607] Completed state [maas_region_boot_sources_selection_xenial] at time 21:25:39.025426 duration_in_ms=312.469
2019-06-05 21:25:39,028 [salt.state       :1780][INFO    ][45607] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 21:25:39.028014
2019-06-05 21:25:39,028 [salt.state       :1813][INFO    ][45607] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-06-05 21:25:39,029 [salt.utils.decorators:613 ][WARNING ][45607] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-05 21:25:39,029 [salt.loaded.ext.module.maasng:1771][INFO    ][45607] boot-sources sync initiated for ALL Rack's
2019-06-05 21:25:40,083 [salt.state       :300 ][INFO    ][45607] {'ret': True}
2019-06-05 21:25:40,084 [salt.state       :1951][INFO    ][45607] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 21:25:40.084016 duration_in_ms=1056.002
2019-06-05 21:25:40,087 [salt.state       :1780][INFO    ][45607] Running state [maas.process_maas_config] at time 21:25:40.087816
2019-06-05 21:25:40,088 [salt.state       :1813][INFO    ][45607] Executing state module.run for [maas.process_maas_config]
2019-06-05 21:25:40,088 [salt.utils.decorators:613 ][WARNING ][45607] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-05 21:25:40,089 [salt.loaded.ext.module.maas:92  ][INFO    ][45607] maasconfig name=enable_http_proxy value=True
2019-06-05 21:25:40,151 [salt.loaded.ext.module.maas:92  ][INFO    ][45607] maasconfig name=upstream_dns value=8.8.8.8
2019-06-05 21:25:44,807 [salt.loaded.ext.module.maas:92  ][INFO    ][45607] maasconfig name=commissioning_distro_series value=xenial
2019-06-05 21:25:44,983 [salt.loaded.ext.module.maas:92  ][INFO    ][45607] maasconfig name=default_osystem value=ubuntu
2019-06-05 21:25:45,049 [salt.loaded.ext.module.maas:92  ][INFO    ][45607] maasconfig name=active_discovery_interval value=600
2019-06-05 21:25:45,100 [salt.loaded.ext.module.maas:92  ][INFO    ][45607] maasconfig name=dnssec_validation value=no
2019-06-05 21:25:45,144 [salt.loaded.ext.module.maas:92  ][INFO    ][45607] maasconfig name=maas_name value=mas01
2019-06-05 21:25:45,219 [salt.loaded.ext.module.maas:92  ][INFO    ][45607] maasconfig name=network_discovery value=enabled
2019-06-05 21:25:45,348 [salt.loaded.ext.module.maas:92  ][INFO    ][45607] maasconfig name=enable_third_party_drivers value=True
2019-06-05 21:25:45,409 [salt.loaded.ext.module.maas:92  ][INFO    ][45607] maasconfig name=default_storage_layout value=lvm
2019-06-05 21:25:45,472 [salt.loaded.ext.module.maas:92  ][INFO    ][45607] maasconfig name=ntp_external_only value=True
2019-06-05 21:25:45,517 [salt.loaded.ext.module.maas:92  ][INFO    ][45607] maasconfig name=disk_erase_with_secure_erase value=False
2019-06-05 21:25:45,576 [salt.loaded.ext.module.maas:92  ][INFO    ][45607] maasconfig name=default_distro_series value=xenial
2019-06-05 21:25:45,658 [salt.loaded.ext.module.maas:92  ][INFO    ][45607] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-06-05 21:25:45,796 [salt.state       :300 ][INFO    ][45607] {'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-06-05 21:25:45,797 [salt.state       :1951][INFO    ][45607] Completed state [maas.process_maas_config] at time 21:25:45.796933 duration_in_ms=5709.118
2019-06-05 21:25:45,798 [salt.state       :1780][INFO    ][45607] Running state [pxe_admin] at time 21:25:45.798118
2019-06-05 21:25:45,798 [salt.state       :1813][INFO    ][45607] Executing state maasng.fabric_present for [pxe_admin]
2019-06-05 21:25:45,865 [salt.loaded.ext.module.maasng:945 ][INFO    ][45607] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', 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'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-3', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 3, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'id': 3, u'name': u'fabric-3', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'pstx7e', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'id': 4, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/'}]
2019-06-05 21:25:45,925 [salt.loaded.ext.module.maasng:1008][WARNING ][45607] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-06-05 21:25:45,925 [salt.loaded.ext.module.maasng:1011][WARNING ][45607] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-06-05 21:25:45,985 [salt.state       :300 ][INFO    ][45607] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-06-05 21:25:45,985 [salt.state       :1951][INFO    ][45607] Completed state [pxe_admin] at time 21:25:45.985501 duration_in_ms=187.382
2019-06-05 21:25:45,987 [salt.state       :1780][INFO    ][45607] Running state [vlan 0] at time 21:25:45.987364
2019-06-05 21:25:45,987 [salt.state       :1813][INFO    ][45607] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-06-05 21:25:46,052 [salt.loaded.ext.module.maasng:945 ][INFO    ][45607] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', 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'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-3', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 3, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'id': 3, u'name': u'fabric-3', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'pstx7e', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'id': 4, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/'}]
2019-06-05 21:25:46,163 [salt.loaded.ext.module.maasng:945 ][INFO    ][45607] [{u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 3, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-3', u'relay_vlan': None, u'external_dhcp': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/3/', u'id': 3, u'name': u'fabric-3', u'class_type': None}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'pstx7e', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/4/', u'id': 4, u'name': u'pxe_admin', u'class_type': u''}]
2019-06-05 21:25:46,403 [salt.loaded.ext.module.maasng:945 ][INFO    ][45607] [{u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 3, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-3', u'relay_vlan': None, u'external_dhcp': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/3/', u'id': 3, u'name': u'fabric-3', u'class_type': None}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'pstx7e', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/4/', u'id': 4, u'name': u'pxe_admin', u'class_type': u''}]
2019-06-05 21:25:46,483 [salt.state       :300 ][INFO    ][45607] {'new': 'Vlan untagged was updated'}
2019-06-05 21:25:46,484 [salt.state       :1951][INFO    ][45607] Completed state [vlan 0] at time 21:25:46.484290 duration_in_ms=496.924
2019-06-05 21:25:46,486 [salt.state       :1780][INFO    ][45607] Running state [192.168.11.0/24] at time 21:25:46.486208
2019-06-05 21:25:46,486 [salt.state       :1813][INFO    ][45607] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-06-05 21:25:46,667 [salt.loaded.ext.module.maasng:945 ][INFO    ][45607] [{u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 3, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-3', u'relay_vlan': None, u'external_dhcp': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/3/', u'id': 3, u'name': u'fabric-3', u'class_type': None}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'pstx7e', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/4/', u'id': 4, u'name': u'pxe_admin', u'class_type': u''}]
2019-06-05 21:25:46,668 [salt.loaded.ext.module.maasng:1235][WARNING ][45607] Ignoring parameter vlan:0
2019-06-05 21:25:46,759 [salt.state       :300 ][INFO    ][45607] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-06-05 21:25:46,760 [salt.state       :1951][INFO    ][45607] Completed state [192.168.11.0/24] at time 21:25:46.760144 duration_in_ms=273.935
2019-06-05 21:25:46,761 [salt.state       :1780][INFO    ][45607] Running state [maas_create_iprange_1] at time 21:25:46.761348
2019-06-05 21:25:46,762 [salt.state       :1813][INFO    ][45607] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-06-05 21:25:46,810 [salt.state       :300 ][INFO    ][45607] Iprange maas_create_iprange_1 already exist.
2019-06-05 21:25:46,811 [salt.state       :1951][INFO    ][45607] Completed state [maas_create_iprange_1] at time 21:25:46.810941 duration_in_ms=49.594
2019-06-05 21:25:46,811 [salt.state       :1780][INFO    ][45607] Running state [vlan 0] at time 21:25:46.811237
2019-06-05 21:25:46,811 [salt.state       :1813][INFO    ][45607] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-06-05 21:25:46,867 [salt.loaded.ext.module.maasng:945 ][INFO    ][45607] [{u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 3, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-3', u'relay_vlan': None, u'external_dhcp': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/3/', u'id': 3, u'name': u'fabric-3', u'class_type': None}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'pstx7e', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/4/', u'id': 4, u'name': u'pxe_admin', u'class_type': u''}]
2019-06-05 21:25:46,989 [salt.loaded.ext.module.maasng:945 ][INFO    ][45607] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', 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'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-3', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 3, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'id': 3, u'name': u'fabric-3', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'pstx7e', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'id': 4, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/'}]
2019-06-05 21:25:47,257 [salt.loaded.ext.module.maasng:945 ][INFO    ][45607] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', 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'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-3', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 3, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'id': 3, u'name': u'fabric-3', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'pstx7e', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'id': 4, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/'}]
2019-06-05 21:25:47,359 [salt.state       :300 ][INFO    ][45607] {'new': 'Vlan untagged was updated'}
2019-06-05 21:25:47,359 [salt.state       :1951][INFO    ][45607] Completed state [vlan 0] at time 21:25:47.359494 duration_in_ms=548.255
2019-06-05 21:25:47,360 [salt.state       :1780][INFO    ][45607] Running state [opnfv] at time 21:25:47.360279
2019-06-05 21:25:47,360 [salt.state       :1813][INFO    ][45607] Executing state maasng.sshkey_present for [opnfv]
2019-06-05 21:25:47,423 [salt.loaded.ext.module.maasng:1903][INFO    ][45607] [{u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b', u'keysource': u''}]
2019-06-05 21:25:47,424 [salt.state       :300 ][INFO    ][45607] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-06-05 21:25:47,424 [salt.state       :1951][INFO    ][45607] Completed state [opnfv] at time 21:25:47.424422 duration_in_ms=64.142
2019-06-05 21:25:47,425 [salt.state       :1780][INFO    ][45607] Running state [maas.process_tags] at time 21:25:47.425026
2019-06-05 21:25:47,425 [salt.state       :1813][INFO    ][45607] Executing state module.run for [maas.process_tags]
2019-06-05 21:25:47,427 [salt.utils.decorators:613 ][WARNING ][45607] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-05 21:25:47,476 [salt.loaded.ext.module.maas:92  ][INFO    ][45607] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-06-05 21:25:47,540 [salt.state       :300 ][INFO    ][45607] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-06-05 21:25:47,540 [salt.state       :1951][INFO    ][45607] Completed state [maas.process_tags] at time 21:25:47.540410 duration_in_ms=115.384
2019-06-05 21:25:47,543 [salt.minion      :1711][INFO    ][45607] Returning information for job: 20190605212515865328
2019-06-05 21:25:48,208 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command state.apply with jid 20190605212548196098
2019-06-05 21:25:48,228 [salt.minion      :1432][INFO    ][46061] Starting a new job with PID 46061
2019-06-05 21:25:56,461 [salt.state       :915 ][INFO    ][46061] Loading fresh modules for state activity
2019-06-05 21:25:56,574 [salt.state       :1780][INFO    ][46061] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:25:56.574620
2019-06-05 21:25:56,574 [salt.state       :1813][INFO    ][46061] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-05 21:25:56,576 [salt.loaded.int.module.cmdmod:395 ][INFO    ][46061] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-05 21:25:58,299 [salt.state       :300 ][INFO    ][46061] {'pid': 46097, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-05 21:25:58,300 [salt.state       :1951][INFO    ][46061] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:25:58.300596 duration_in_ms=1725.975
2019-06-05 21:25:58,304 [salt.state       :1780][INFO    ][46061] Running state [maas.process_machines] at time 21:25:58.304601
2019-06-05 21:25:58,305 [salt.state       :1813][INFO    ][46061] Executing state module.run for [maas.process_machines]
2019-06-05 21:25:58,306 [salt.utils.decorators:613 ][WARNING ][46061] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-05 21:25:58,804 [salt.loaded.ext.module.maas:412 ][WARNING ][46061] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-05 21:25:58,806 [salt.loaded.ext.module.maas:92  ][INFO    ][46061] 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=qbdge3 architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-05 21:25:59,960 [salt.loaded.ext.module.maas:412 ][WARNING ][46061] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-05 21:25:59,962 [salt.loaded.ext.module.maas:92  ][INFO    ][46061] 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=ex8b6e architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-05 21:26:01,160 [salt.loaded.ext.module.maas:412 ][WARNING ][46061] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-05 21:26:01,161 [salt.loaded.ext.module.maas:92  ][INFO    ][46061] 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=a4tnyp architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-05 21:26:02,368 [salt.loaded.ext.module.maas:412 ][WARNING ][46061] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-05 21:26:02,369 [salt.loaded.ext.module.maas:92  ][INFO    ][46061] 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=qkpcnd architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-05 21:26:03,332 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605212603317642
2019-06-05 21:26:03,358 [salt.minion      :1432][INFO    ][46269] Starting a new job with PID 46269
2019-06-05 21:26:03,383 [salt.minion      :1711][INFO    ][46269] Returning information for job: 20190605212603317642
2019-06-05 21:26:03,615 [salt.state       :300 ][INFO    ][46061] {'ret': {'updated': ['gtw01', 'cmp002', 'cmp001', 'ctl01'], 'errors': {}, 'success': []}}
2019-06-05 21:26:03,616 [salt.state       :1951][INFO    ][46061] Completed state [maas.process_machines] at time 21:26:03.616087 duration_in_ms=5311.485
2019-06-05 21:26:03,620 [salt.minion      :1711][INFO    ][46061] Returning information for job: 20190605212548196098
2019-06-05 21:26:36,189 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command state.apply with jid 20190605212636168762
2019-06-05 21:26:36,219 [salt.minion      :1432][INFO    ][46298] Starting a new job with PID 46298
2019-06-05 21:26:44,192 [salt.state       :915 ][INFO    ][46298] Loading fresh modules for state activity
2019-06-05 21:26:44,302 [salt.state       :1780][INFO    ][46298] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:26:44.302730
2019-06-05 21:26:44,303 [salt.state       :1813][INFO    ][46298] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-05 21:26:44,304 [salt.loaded.int.module.cmdmod:395 ][INFO    ][46298] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-05 21:26:46,044 [salt.state       :300 ][INFO    ][46298] {'pid': 46329, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-05 21:26:46,045 [salt.state       :1951][INFO    ][46298] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:26:46.045224 duration_in_ms=1742.494
2019-06-05 21:26:46,051 [salt.state       :1780][INFO    ][46298] Running state [maas.wait_for_machine_status] at time 21:26:46.051379
2019-06-05 21:26:46,052 [salt.state       :1813][INFO    ][46298] Executing state module.run for [maas.wait_for_machine_status]
2019-06-05 21:26:46,052 [salt.utils.decorators:613 ][WARNING ][46298] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-05 21:26:47,973 [salt.state       :300 ][INFO    ][46298] {'ret': True}
2019-06-05 21:26:47,974 [salt.state       :1951][INFO    ][46298] Completed state [maas.wait_for_machine_status] at time 21:26:47.974005 duration_in_ms=1922.625
2019-06-05 21:26:47,977 [salt.minion      :1711][INFO    ][46298] Returning information for job: 20190605212636168762
2019-06-05 21:26:48,560 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command state.apply with jid 20190605212648548187
2019-06-05 21:26:48,586 [salt.minion      :1432][INFO    ][46341] Starting a new job with PID 46341
2019-06-05 21:26:49,639 [salt.state       :915 ][INFO    ][46341] Loading fresh modules for state activity
2019-06-05 21:26:49,790 [salt.state       :1780][INFO    ][46341] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:26:49.790438
2019-06-05 21:26:49,791 [salt.state       :1813][INFO    ][46341] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-05 21:26:49,795 [salt.loaded.int.module.cmdmod:395 ][INFO    ][46341] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-05 21:26:51,631 [salt.state       :300 ][INFO    ][46341] {'pid': 46348, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-05 21:26:51,632 [salt.state       :1951][INFO    ][46341] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:26:51.632692 duration_in_ms=1842.254
2019-06-05 21:26:51,636 [salt.state       :1780][INFO    ][46341] Running state [maas_machines_storage_cmp002_lvm] at time 21:26:51.636672
2019-06-05 21:26:51,637 [salt.state       :1813][INFO    ][46341] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-06-05 21:26:52,124 [salt.state       :300 ][INFO    ][46341] Machine cmp002 is not in Ready state.
2019-06-05 21:26:52,125 [salt.state       :1951][INFO    ][46341] Completed state [maas_machines_storage_cmp002_lvm] at time 21:26:52.125017 duration_in_ms=488.344
2019-06-05 21:26:52,125 [salt.state       :1780][INFO    ][46341] Running state [maas_machines_storage_cmp001_lvm] at time 21:26:52.125534
2019-06-05 21:26:52,125 [salt.state       :1813][INFO    ][46341] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-06-05 21:26:52,753 [salt.state       :300 ][INFO    ][46341] Machine cmp001 is not in Ready state.
2019-06-05 21:26:52,755 [salt.state       :1951][INFO    ][46341] Completed state [maas_machines_storage_cmp001_lvm] at time 21:26:52.755643 duration_in_ms=630.11
2019-06-05 21:26:52,760 [salt.minion      :1711][INFO    ][46341] Returning information for job: 20190605212648548187
2019-06-05 21:26:53,323 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command state.apply with jid 20190605212653309109
2019-06-05 21:26:53,352 [salt.minion      :1432][INFO    ][46358] Starting a new job with PID 46358
2019-06-05 21:26:54,459 [salt.state       :915 ][INFO    ][46358] Loading fresh modules for state activity
2019-06-05 21:26:54,557 [salt.state       :1780][INFO    ][46358] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:26:54.557361
2019-06-05 21:26:54,557 [salt.state       :1813][INFO    ][46358] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-05 21:26:54,559 [salt.loaded.int.module.cmdmod:395 ][INFO    ][46358] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-05 21:26:56,464 [salt.state       :300 ][INFO    ][46358] {'pid': 46365, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-05 21:26:56,466 [salt.state       :1951][INFO    ][46358] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:26:56.465626 duration_in_ms=1908.264
2019-06-05 21:26:56,469 [salt.state       :1780][INFO    ][46358] Running state [maas.deploy_machines] at time 21:26:56.469298
2019-06-05 21:26:56,470 [salt.state       :1813][INFO    ][46358] Executing state module.run for [maas.deploy_machines]
2019-06-05 21:26:56,471 [salt.utils.decorators:613 ][WARNING ][46358] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-05 21:26:56,946 [salt.loaded.ext.module.maas:684 ][INFO    ][46358] deploymachines hwe_kernel=hwe-16.04 system_id=qbdge3 distro_series=xenial
2019-06-05 21:26:59,406 [salt.state       :300 ][INFO    ][46358] {'ret': {'updated': ['cmp002', 'cmp001', 'ctl01'], 'errors': {}, 'success': ['gtw01']}}
2019-06-05 21:26:59,407 [salt.state       :1951][INFO    ][46358] Completed state [maas.deploy_machines] at time 21:26:59.407219 duration_in_ms=2937.921
2019-06-05 21:26:59,410 [salt.minion      :1711][INFO    ][46358] Returning information for job: 20190605212653309109
2019-06-05 21:26:59,997 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command state.apply with jid 20190605212659986393
2019-06-05 21:27:00,021 [salt.minion      :1432][INFO    ][46445] Starting a new job with PID 46445
2019-06-05 21:27:07,767 [salt.state       :915 ][INFO    ][46445] Loading fresh modules for state activity
2019-06-05 21:27:07,874 [salt.state       :1780][INFO    ][46445] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:27:07.873711
2019-06-05 21:27:07,874 [salt.state       :1813][INFO    ][46445] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-05 21:27:07,876 [salt.loaded.int.module.cmdmod:395 ][INFO    ][46445] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-05 21:27:09,701 [salt.state       :300 ][INFO    ][46445] {'pid': 46462, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-05 21:27:09,703 [salt.state       :1951][INFO    ][46445] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:27:09.703106 duration_in_ms=1829.393
2019-06-05 21:27:09,707 [salt.state       :1780][INFO    ][46445] Running state [maas.wait_for_machine_status] at time 21:27:09.707322
2019-06-05 21:27:09,708 [salt.state       :1813][INFO    ][46445] Executing state module.run for [maas.wait_for_machine_status]
2019-06-05 21:27:09,708 [salt.utils.decorators:613 ][WARNING ][46445] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-05 21:27:11,591 [salt.loaded.ext.module.maas:1023][INFO    ][46445] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2248.13313293s left)
2019-06-05 21:27:15,082 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605212715070581
2019-06-05 21:27:15,106 [salt.minion      :1432][INFO    ][46492] Starting a new job with PID 46492
2019-06-05 21:27:15,130 [salt.minion      :1711][INFO    ][46492] Returning information for job: 20190605212715070581
2019-06-05 21:27:43,627 [salt.loaded.ext.module.maas:1023][INFO    ][46445] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2216.09685707s left)
2019-06-05 21:27:45,301 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605212745245119
2019-06-05 21:27:45,330 [salt.minion      :1432][INFO    ][46536] Starting a new job with PID 46536
2019-06-05 21:27:45,355 [salt.minion      :1711][INFO    ][46536] Returning information for job: 20190605212745245119
2019-06-05 21:28:15,392 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605212815379767
2019-06-05 21:28:15,417 [salt.minion      :1432][INFO    ][46578] Starting a new job with PID 46578
2019-06-05 21:28:15,439 [salt.minion      :1711][INFO    ][46578] Returning information for job: 20190605212815379767
2019-06-05 21:28:15,582 [salt.loaded.ext.module.maas:1023][INFO    ][46445] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2184.14120603s left)
2019-06-05 21:28:45,475 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605212845460290
2019-06-05 21:28:45,505 [salt.minion      :1432][INFO    ][46615] Starting a new job with PID 46615
2019-06-05 21:28:45,539 [salt.minion      :1711][INFO    ][46615] Returning information for job: 20190605212845460290
2019-06-05 21:28:47,504 [salt.loaded.ext.module.maas:1023][INFO    ][46445] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2152.21937799s left)
2019-06-05 21:29:15,603 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605212915590329
2019-06-05 21:29:15,633 [salt.minion      :1432][INFO    ][46654] Starting a new job with PID 46654
2019-06-05 21:29:15,666 [salt.minion      :1711][INFO    ][46654] Returning information for job: 20190605212915590329
2019-06-05 21:29:19,644 [salt.loaded.ext.module.maas:1023][INFO    ][46445] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2120.08015609s left)
2019-06-05 21:29:45,704 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605212945693820
2019-06-05 21:29:45,733 [salt.minion      :1432][INFO    ][46690] Starting a new job with PID 46690
2019-06-05 21:29:45,765 [salt.minion      :1711][INFO    ][46690] Returning information for job: 20190605212945693820
2019-06-05 21:29:51,619 [salt.loaded.ext.module.maas:1023][INFO    ][46445] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2088.10490608s left)
2019-06-05 21:30:15,809 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605213015794492
2019-06-05 21:30:15,837 [salt.minion      :1432][INFO    ][46729] Starting a new job with PID 46729
2019-06-05 21:30:15,869 [salt.minion      :1711][INFO    ][46729] Returning information for job: 20190605213015794492
2019-06-05 21:30:23,491 [salt.loaded.ext.module.maas:1023][INFO    ][46445] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2056.23286104s left)
2019-06-05 21:30:45,907 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605213045894731
2019-06-05 21:30:45,933 [salt.minion      :1432][INFO    ][46766] Starting a new job with PID 46766
2019-06-05 21:30:45,965 [salt.minion      :1711][INFO    ][46766] Returning information for job: 20190605213045894731
2019-06-05 21:30:55,543 [salt.loaded.ext.module.maas:1023][INFO    ][46445] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2024.18090892s left)
2019-06-05 21:31:16,018 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605213116007600
2019-06-05 21:31:16,039 [salt.minion      :1432][INFO    ][46830] Starting a new job with PID 46830
2019-06-05 21:31:16,071 [salt.minion      :1711][INFO    ][46830] Returning information for job: 20190605213116007600
2019-06-05 21:31:27,529 [salt.loaded.ext.module.maas:1023][INFO    ][46445] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1992.194767s left)
2019-06-05 21:31:46,123 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605213146107963
2019-06-05 21:31:46,151 [salt.minion      :1432][INFO    ][46860] Starting a new job with PID 46860
2019-06-05 21:31:46,181 [salt.minion      :1711][INFO    ][46860] Returning information for job: 20190605213146107963
2019-06-05 21:31:59,487 [salt.loaded.ext.module.maas:1023][INFO    ][46445] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1960.23633194s left)
2019-06-05 21:32:16,266 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605213216250574
2019-06-05 21:32:16,294 [salt.minion      :1432][INFO    ][46996] Starting a new job with PID 46996
2019-06-05 21:32:16,324 [salt.minion      :1711][INFO    ][46996] Returning information for job: 20190605213216250574
2019-06-05 21:32:31,380 [salt.loaded.ext.module.maas:1023][INFO    ][46445] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1928.343472s left)
2019-06-05 21:32:46,395 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605213246380017
2019-06-05 21:32:46,413 [salt.minion      :1432][INFO    ][47060] Starting a new job with PID 47060
2019-06-05 21:32:46,448 [salt.minion      :1711][INFO    ][47060] Returning information for job: 20190605213246380017
2019-06-05 21:33:03,328 [salt.loaded.ext.module.maas:1023][INFO    ][46445] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1896.39545703s left)
2019-06-05 21:33:16,529 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605213316515065
2019-06-05 21:33:16,558 [salt.minion      :1432][INFO    ][47163] Starting a new job with PID 47163
2019-06-05 21:33:16,589 [salt.minion      :1711][INFO    ][47163] Returning information for job: 20190605213316515065
2019-06-05 21:33:35,312 [salt.loaded.ext.module.maas:1023][INFO    ][46445] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1864.41214991s left)
2019-06-05 21:33:46,673 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605213346659857
2019-06-05 21:33:46,705 [salt.minion      :1432][INFO    ][47199] Starting a new job with PID 47199
2019-06-05 21:33:46,741 [salt.minion      :1711][INFO    ][47199] Returning information for job: 20190605213346659857
2019-06-05 21:34:07,388 [salt.loaded.ext.module.maas:1023][INFO    ][46445] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1832.33611798s left)
2019-06-05 21:34:16,824 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605213416804157
2019-06-05 21:34:16,855 [salt.minion      :1432][INFO    ][47277] Starting a new job with PID 47277
2019-06-05 21:34:16,888 [salt.minion      :1711][INFO    ][47277] Returning information for job: 20190605213416804157
2019-06-05 21:34:39,364 [salt.loaded.ext.module.maas:1023][INFO    ][46445] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1800.35931301s left)
2019-06-05 21:34:46,999 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605213446982285
2019-06-05 21:34:47,029 [salt.minion      :1432][INFO    ][47309] Starting a new job with PID 47309
2019-06-05 21:34:47,068 [salt.minion      :1711][INFO    ][47309] Returning information for job: 20190605213446982285
2019-06-05 21:35:11,411 [salt.loaded.ext.module.maas:1023][INFO    ][46445] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1768.31219912s left)
2019-06-05 21:35:17,193 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605213517176071
2019-06-05 21:35:17,221 [salt.minion      :1432][INFO    ][47462] Starting a new job with PID 47462
2019-06-05 21:35:17,260 [salt.minion      :1711][INFO    ][47462] Returning information for job: 20190605213517176071
2019-06-05 21:35:43,484 [salt.loaded.ext.module.maas:1023][INFO    ][46445] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1736.24004006s left)
2019-06-05 21:35:47,384 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605213547367466
2019-06-05 21:35:47,415 [salt.minion      :1432][INFO    ][47512] Starting a new job with PID 47512
2019-06-05 21:35:47,444 [salt.minion      :1711][INFO    ][47512] Returning information for job: 20190605213547367466
2019-06-05 21:36:15,621 [salt.loaded.ext.module.maas:1023][INFO    ][46445] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1704.10254788s left)
2019-06-05 21:36:17,560 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605213617543155
2019-06-05 21:36:17,587 [salt.minion      :1432][INFO    ][47554] Starting a new job with PID 47554
2019-06-05 21:36:17,617 [salt.minion      :1711][INFO    ][47554] Returning information for job: 20190605213617543155
2019-06-05 21:36:47,574 [salt.loaded.ext.module.maas:1023][INFO    ][46445] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1672.150352s left)
2019-06-05 21:36:47,728 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605213647716628
2019-06-05 21:36:47,756 [salt.minion      :1432][INFO    ][47588] Starting a new job with PID 47588
2019-06-05 21:36:47,795 [salt.minion      :1711][INFO    ][47588] Returning information for job: 20190605213647716628
2019-06-05 21:37:17,909 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605213717892408
2019-06-05 21:37:17,934 [salt.minion      :1432][INFO    ][47644] Starting a new job with PID 47644
2019-06-05 21:37:17,976 [salt.minion      :1711][INFO    ][47644] Returning information for job: 20190605213717892408
2019-06-05 21:37:19,551 [salt.loaded.ext.module.maas:1023][INFO    ][46445] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1640.17302108s left)
2019-06-05 21:37:48,120 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605213748103590
2019-06-05 21:37:48,149 [salt.minion      :1432][INFO    ][47683] Starting a new job with PID 47683
2019-06-05 21:37:48,179 [salt.minion      :1711][INFO    ][47683] Returning information for job: 20190605213748103590
2019-06-05 21:37:51,527 [salt.loaded.ext.module.maas:1023][INFO    ][46445] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1608.196347s left)
2019-06-05 21:38:18,315 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605213818299063
2019-06-05 21:38:18,334 [salt.minion      :1432][INFO    ][47727] Starting a new job with PID 47727
2019-06-05 21:38:18,364 [salt.minion      :1711][INFO    ][47727] Returning information for job: 20190605213818299063
2019-06-05 21:38:23,518 [salt.loaded.ext.module.maas:1023][INFO    ][46445] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1576.20585704s left)
2019-06-05 21:38:48,509 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605213848493392
2019-06-05 21:38:48,538 [salt.minion      :1432][INFO    ][47768] Starting a new job with PID 47768
2019-06-05 21:38:48,569 [salt.minion      :1711][INFO    ][47768] Returning information for job: 20190605213848493392
2019-06-05 21:38:55,448 [salt.loaded.ext.module.maas:1023][INFO    ][46445] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1544.27610493s left)
2019-06-05 21:39:18,738 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605213918721561
2019-06-05 21:39:18,766 [salt.minion      :1432][INFO    ][47827] Starting a new job with PID 47827
2019-06-05 21:39:18,799 [salt.minion      :1711][INFO    ][47827] Returning information for job: 20190605213918721561
2019-06-05 21:39:22,464 [salt.utils.schedule:1377][INFO    ][38525] Running scheduled job: __mine_interval
2019-06-05 21:39:27,700 [salt.loaded.ext.module.maas:1023][INFO    ][46445] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1512.023525s left)
2019-06-05 21:39:48,886 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605213948871446
2019-06-05 21:39:48,918 [salt.minion      :1432][INFO    ][47891] Starting a new job with PID 47891
2019-06-05 21:39:48,947 [salt.minion      :1711][INFO    ][47891] Returning information for job: 20190605213948871446
2019-06-05 21:39:59,830 [salt.state       :300 ][INFO    ][46445] {'ret': True}
2019-06-05 21:39:59,831 [salt.state       :1951][INFO    ][46445] Completed state [maas.wait_for_machine_status] at time 21:39:59.831357 duration_in_ms=770124.029
2019-06-05 21:39:59,839 [salt.minion      :1711][INFO    ][46445] Returning information for job: 20190605212659986393
2019-06-05 22:33:47,376 [salt.minion      :1308][INFO    ][38525] User sudo_ubuntu Executing command cp.push_dir with jid 20190605223347364988
2019-06-05 22:33:47,404 [salt.minion      :1432][INFO    ][51964] Starting a new job with PID 51964
