2019-05-29 20:07:21,085 [salt.utils.decorators:613 ][WARNING ][2079] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-29 20:07:22,055 [salt.utils.decorators:613 ][WARNING ][2079] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-29 20:07:25,021 [salt.loaded.int.states.file:2298][WARNING ][2431] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-05-29 20:07:54,516 [salt.state       :2022][WARNING ][2972] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-29 20:07:57,618 [salt.utils.decorators:613 ][WARNING ][2972] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-29 20:08:10,872 [salt.utils.decorators:613 ][WARNING ][2972] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-29 20:08:24,334 [salt.utils.decorators:613 ][WARNING ][2972] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-29 20:08:25,294 [salt.utils.decorators:613 ][WARNING ][2972] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-29 20:08:27,679 [salt.loaded.ext.module.maasng:1008][WARNING ][2972] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-05-29 20:08:27,679 [salt.loaded.ext.module.maasng:1011][WARNING ][2972] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-05-29 20:08:28,452 [salt.loaded.ext.module.maasng:1235][WARNING ][2972] Ignoring parameter vlan:0
2019-05-29 20:08:29,447 [salt.utils.decorators:613 ][WARNING ][2972] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-29 20:08:38,766 [salt.utils.decorators:613 ][WARNING ][4987] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-29 20:08:38,832 [salt.loaded.ext.module.maas:412 ][WARNING ][4987] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-29 20:08:40,376 [salt.loaded.ext.module.maas:412 ][WARNING ][4987] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-29 20:08:41,723 [salt.loaded.ext.module.maas:412 ][WARNING ][4987] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-29 20:08:43,213 [salt.loaded.ext.module.maas:412 ][WARNING ][4987] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-29 20:08:48,994 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5526] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-29 20:08:49,033 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5526] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-05-29 20:08:49,098 [salt.utils.parsers:1051][WARNING ][382] Minion received a SIGTERM. Exiting.
2019-05-29 20:08:50,112 [salt.cli.daemons :293 ][INFO    ][5579] Setting up the Salt Minion "mas01.mcp-fdio-noha.local"
2019-05-29 20:08:50,237 [salt.cli.daemons :82  ][INFO    ][5579] Starting up the Salt Minion
2019-05-29 20:08:50,237 [salt.utils.event :1017][INFO    ][5579] Starting pull socket on /var/run/salt/minion/minion_event_38d774b16c_pull.ipc
2019-05-29 20:08:51,487 [salt.minion      :976 ][INFO    ][5579] Creating minion process manager
2019-05-29 20:08:53,376 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][5579] Executing command ['date', '+%z'] in directory '/root'
2019-05-29 20:08:53,406 [salt.utils.schedule:568 ][INFO    ][5579] Updating job settings for scheduled job: __mine_interval
2019-05-29 20:08:53,408 [salt.minion      :1108][INFO    ][5579] Added mine.update to scheduler
2019-05-29 20:08:53,413 [salt.minion      :1975][INFO    ][5579] Minion is starting as user 'root'
2019-05-29 20:08:53,430 [salt.minion      :2336][INFO    ][5579] Minion is ready to receive requests!
2019-05-29 20:09:15,629 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command state.apply with jid 20190529200915616910
2019-05-29 20:09:15,656 [salt.minion      :1432][INFO    ][5693] Starting a new job with PID 5693
2019-05-29 20:09:21,512 [salt.state       :915 ][INFO    ][5693] Loading fresh modules for state activity
2019-05-29 20:09:21,573 [salt.fileclient  :1219][INFO    ][5693] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-05-29 20:09:21,629 [salt.state       :1780][INFO    ][5693] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:09:21.629514
2019-05-29 20:09:21,630 [salt.state       :1813][INFO    ][5693] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-29 20:09:21,631 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5693] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-29 20:09:23,316 [salt.state       :300 ][INFO    ][5693] {'pid': 5700, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-29 20:09:23,318 [salt.state       :1951][INFO    ][5693] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:09:23.317618 duration_in_ms=1688.102
2019-05-29 20:09:23,321 [salt.state       :1780][INFO    ][5693] Running state [maas.wait_for_machine_status] at time 20:09:23.321891
2019-05-29 20:09:23,322 [salt.state       :1813][INFO    ][5693] Executing state module.run for [maas.wait_for_machine_status]
2019-05-29 20:09:23,323 [salt.utils.decorators:613 ][WARNING ][5693] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-29 20:09:23,955 [salt.loaded.ext.module.maas:1023][INFO    ][5693] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1499.3820889s left)
2019-05-29 20:09:30,718 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529200930700205
2019-05-29 20:09:30,749 [salt.minion      :1432][INFO    ][5715] Starting a new job with PID 5715
2019-05-29 20:09:30,772 [salt.minion      :1711][INFO    ][5715] Returning information for job: 20190529200930700205
2019-05-29 20:09:54,593 [salt.loaded.ext.module.maas:1023][INFO    ][5693] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1468.74454093s left)
2019-05-29 20:10:00,809 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529201000796574
2019-05-29 20:10:00,834 [salt.minion      :1432][INFO    ][5769] Starting a new job with PID 5769
2019-05-29 20:10:00,855 [salt.minion      :1711][INFO    ][5769] Returning information for job: 20190529201000796574
2019-05-29 20:10:25,218 [salt.loaded.ext.module.maas:1023][INFO    ][5693] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1438.11900306s left)
2019-05-29 20:10:30,904 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529201030881633
2019-05-29 20:10:30,924 [salt.minion      :1432][INFO    ][5787] Starting a new job with PID 5787
2019-05-29 20:10:30,943 [salt.minion      :1711][INFO    ][5787] Returning information for job: 20190529201030881633
2019-05-29 20:10:55,949 [salt.loaded.ext.module.maas:1023][INFO    ][5693] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1407.38870811s left)
2019-05-29 20:11:00,978 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529201100969730
2019-05-29 20:11:00,995 [salt.minion      :1432][INFO    ][5845] Starting a new job with PID 5845
2019-05-29 20:11:01,020 [salt.minion      :1711][INFO    ][5845] Returning information for job: 20190529201100969730
2019-05-29 20:11:26,903 [salt.loaded.ext.module.maas:1023][INFO    ][5693] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1376.43397808s left)
2019-05-29 20:11:31,055 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529201131046772
2019-05-29 20:11:31,079 [salt.minion      :1432][INFO    ][5896] Starting a new job with PID 5896
2019-05-29 20:11:31,099 [salt.minion      :1711][INFO    ][5896] Returning information for job: 20190529201131046772
2019-05-29 20:11:57,933 [salt.loaded.ext.module.maas:1023][INFO    ][5693] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1345.40441704s left)
2019-05-29 20:12:01,164 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529201201152505
2019-05-29 20:12:01,188 [salt.minion      :1432][INFO    ][6075] Starting a new job with PID 6075
2019-05-29 20:12:01,210 [salt.minion      :1711][INFO    ][6075] Returning information for job: 20190529201201152505
2019-05-29 20:12:28,789 [salt.loaded.ext.module.maas:1023][INFO    ][5693] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1314.54872894s left)
2019-05-29 20:12:31,258 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529201231245266
2019-05-29 20:12:31,285 [salt.minion      :1432][INFO    ][6135] Starting a new job with PID 6135
2019-05-29 20:12:31,307 [salt.minion      :1711][INFO    ][6135] Returning information for job: 20190529201231245266
2019-05-29 20:13:00,080 [salt.loaded.ext.module.maas:1023][INFO    ][5693] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1283.25696301s left)
2019-05-29 20:13:01,379 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529201301364064
2019-05-29 20:13:01,402 [salt.minion      :1432][INFO    ][6443] Starting a new job with PID 6443
2019-05-29 20:13:01,424 [salt.minion      :1711][INFO    ][6443] Returning information for job: 20190529201301364064
2019-05-29 20:13:31,276 [salt.loaded.ext.module.maas:1023][INFO    ][5693] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1252.06100202s left)
2019-05-29 20:13:31,483 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529201331470578
2019-05-29 20:13:31,506 [salt.minion      :1432][INFO    ][6508] Starting a new job with PID 6508
2019-05-29 20:13:31,529 [salt.minion      :1711][INFO    ][6508] Returning information for job: 20190529201331470578
2019-05-29 20:14:01,639 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529201401626828
2019-05-29 20:14:01,662 [salt.minion      :1432][INFO    ][6859] Starting a new job with PID 6859
2019-05-29 20:14:01,684 [salt.minion      :1711][INFO    ][6859] Returning information for job: 20190529201401626828
2019-05-29 20:14:03,001 [salt.loaded.ext.module.maas:1023][INFO    ][5693] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1220.33681393s left)
2019-05-29 20:14:31,748 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529201431735226
2019-05-29 20:14:31,776 [salt.minion      :1432][INFO    ][6954] Starting a new job with PID 6954
2019-05-29 20:14:31,804 [salt.minion      :1711][INFO    ][6954] Returning information for job: 20190529201431735226
2019-05-29 20:14:34,605 [salt.loaded.ext.module.maas:1023][INFO    ][5693] Waiting status:Ready|Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:1500s (1188.73225904s left)
2019-05-29 20:15:01,873 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529201501860525
2019-05-29 20:15:01,900 [salt.minion      :1432][INFO    ][7044] Starting a new job with PID 7044
2019-05-29 20:15:01,921 [salt.minion      :1711][INFO    ][7044] Returning information for job: 20190529201501860525
2019-05-29 20:15:06,389 [salt.loaded.ext.module.maas:1023][INFO    ][5693] Waiting status:Ready|Deployed for machines:['ctl01']
sleep for:30s Timeout:1500s (1156.94837403s left)
2019-05-29 20:15:31,991 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529201531983541
2019-05-29 20:15:32,012 [salt.minion      :1432][INFO    ][7117] Starting a new job with PID 7117
2019-05-29 20:15:32,034 [salt.minion      :1711][INFO    ][7117] Returning information for job: 20190529201531983541
2019-05-29 20:15:38,543 [salt.state       :300 ][INFO    ][5693] {'ret': True}
2019-05-29 20:15:38,544 [salt.state       :1951][INFO    ][5693] Completed state [maas.wait_for_machine_status] at time 20:15:38.544031 duration_in_ms=375222.139
2019-05-29 20:15:38,549 [salt.minion      :1711][INFO    ][5693] Returning information for job: 20190529200915616910
2019-05-29 20:15:39,139 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command state.apply with jid 20190529201539126447
2019-05-29 20:15:39,165 [salt.minion      :1432][INFO    ][7137] Starting a new job with PID 7137
2019-05-29 20:15:45,063 [salt.state       :915 ][INFO    ][7137] Loading fresh modules for state activity
2019-05-29 20:15:45,117 [salt.fileclient  :1219][INFO    ][7137] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-05-29 20:15:45,224 [salt.state       :1780][INFO    ][7137] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:15:45.224210
2019-05-29 20:15:45,224 [salt.state       :1813][INFO    ][7137] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-29 20:15:45,226 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7137] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-29 20:15:46,934 [salt.state       :300 ][INFO    ][7137] {'pid': 7156, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-29 20:15:46,935 [salt.state       :1951][INFO    ][7137] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:15:46.935723 duration_in_ms=1711.512
2019-05-29 20:15:46,939 [salt.state       :1780][INFO    ][7137] Running state [maas_machines_storage_cmp002_lvm] at time 20:15:46.939581
2019-05-29 20:15:46,940 [salt.state       :1813][INFO    ][7137] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-29 20:15:47,923 [salt.loaded.ext.module.maasng:610 ][INFO    ][7137] 63an73
2019-05-29 20:15:47,924 [salt.loaded.ext.module.maasng:626 ][INFO    ][7137] sda
2019-05-29 20:15:48,423 [salt.loaded.ext.module.maasng:361 ][INFO    ][7137] 63an73
2019-05-29 20:15:48,527 [salt.loaded.ext.module.maasng:367 ][INFO    ][7137] [{u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'id': 1, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'63an73', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'size': 800109715456, u'used_size': 800106479616, u'resource_uri': u'/MAAS/api/2.0/nodes/63an73/blockdevices/1/', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'c2a9f2e3-f5e3-46a9-98c3-c50cb0f2f24f', u'resource_uri': u'/MAAS/api/2.0/nodes/63an73/blockdevices/1/partition/1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'63an73', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'608ed51a-fada-4d33-a219-09874c0b3f52', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 1, u'size': 800101236736}]}, {u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'id': 3, u'uuid': u'340e5b91-2730-4fe3-a53d-5450c53e47bf', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'63an73', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'8ab5c611-3a5e-4947-b931-f711193a052b', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'size': 800097042432, u'used_size': 800097042432, u'resource_uri': u'/MAAS/api/2.0/nodes/63an73/blockdevices/3/', u'serial': None, u'partitions': []}]
2019-05-29 20:15:48,528 [salt.loaded.ext.module.maasng:632 ][INFO    ][7137] vgroot
2019-05-29 20:15:48,528 [salt.loaded.ext.module.maasng:635 ][INFO    ][7137] lvroot
2019-05-29 20:15:48,528 [salt.loaded.ext.module.maasng:639 ][INFO    ][7137] 107374182400
2019-05-29 20:15:49,119 [salt.loaded.ext.module.maasng:645 ][INFO    ][7137] {u'hwe_kernel': u'', u'swap_size': None, u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'blockdevice_set': [{u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'id': 1, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'63an73', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'size': 800109715456, u'used_size': 800106479616, u'resource_uri': u'/MAAS/api/2.0/nodes/63an73/blockdevices/1/', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'd409b0d3-9db5-494c-8c8c-27a63f7f20a5', u'resource_uri': u'/MAAS/api/2.0/nodes/63an73/blockdevices/1/partition/5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'63an73', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'3781d0b1-7707-4116-96ab-c35d872c7c16', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 5, u'size': 800101236736}]}, {u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'id': 9, u'uuid': u'47b602c5-c86c-465d-a4d4-ec112f3c9f66', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'63an73', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'231cc940-75ae-4407-b6b6-f2940969d414', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'size': 107374182400, u'used_size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/63an73/blockdevices/9/', u'serial': None, u'partitions': []}], u'zone': {u'description': u'', u'id': 1, u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'node_type': 0, u'testing_status': 2, u'system_id': u'63an73', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'id': 9, u'uuid': u'47b602c5-c86c-465d-a4d4-ec112f3c9f66', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'63an73', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'231cc940-75ae-4407-b6b6-f2940969d414', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'size': 107374182400, u'used_size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/63an73/blockdevices/9/', u'serial': None, u'partitions': []}], u'min_hwe_kernel': u'hwe-16.04', u'status': 4, u'bcaches': [], u'cpu_count': 40, u'power_state': u'off', u'physicalblockdevice_set': [{u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'id': 1, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'63an73', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'size': 800109715456, u'used_size': 800106479616, u'resource_uri': u'/MAAS/api/2.0/nodes/63an73/blockdevices/1/', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'd409b0d3-9db5-494c-8c8c-27a63f7f20a5', u'resource_uri': u'/MAAS/api/2.0/nodes/63an73/blockdevices/1/partition/5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'63an73', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'3781d0b1-7707-4116-96ab-c35d872c7c16', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 5, u'size': 800101236736}]}], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'63an73', u'id': 5}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gwps4n', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 16}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gwps4n', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gwps4n', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'63an73', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/63an73/interfaces/5/'}, u'current_testing_result_id': 5, u'cpu_test_status': -1, u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'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'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gwps4n', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 16}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gwps4n', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gwps4n', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'63an73', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/63an73/interfaces/5/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'id': 10, u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:50', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'63an73', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/63an73/interfaces/10/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'id': 14, u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:51', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'63an73', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/63an73/interfaces/14/'}, {u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 17}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'id': 11, u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:48', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'63an73', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/63an73/interfaces/11/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gwps4n', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'mode': u'link_up', u'id': 18}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gwps4n', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'id': 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'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gwps4n', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.40'}], u'mac_address': u'9c:b6:54:8a:10:1c', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'63an73', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/63an73/interfaces/12/'}, {u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 19}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'id': 13, u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:49', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'63an73', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/63an73/interfaces/13/'}], u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/63an73/', u'distro_series': u'', u'boot_disk': {u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'id': 1, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'63an73', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'size': 800109715456, u'used_size': 800106479616, u'resource_uri': u'/MAAS/api/2.0/nodes/63an73/blockdevices/1/', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'd409b0d3-9db5-494c-8c8c-27a63f7f20a5', u'resource_uri': u'/MAAS/api/2.0/nodes/63an73/blockdevices/1/partition/5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'63an73', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'3781d0b1-7707-4116-96ab-c35d872c7c16', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 5, u'size': 800101236736}]}}
2019-05-29 20:15:49,123 [salt.state       :300 ][INFO    ][7137] {'new': {'storage_layout': 'lvm'}}
2019-05-29 20:15:49,123 [salt.state       :1951][INFO    ][7137] Completed state [maas_machines_storage_cmp002_lvm] at time 20:15:49.123557 duration_in_ms=2183.975
2019-05-29 20:15:49,124 [salt.state       :1780][INFO    ][7137] Running state [maas_machines_storage_cmp001_lvm] at time 20:15:49.124071
2019-05-29 20:15:49,124 [salt.state       :1813][INFO    ][7137] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-29 20:15:50,079 [salt.loaded.ext.module.maasng:610 ][INFO    ][7137] fd8b7w
2019-05-29 20:15:50,080 [salt.loaded.ext.module.maasng:626 ][INFO    ][7137] sda
2019-05-29 20:15:50,557 [salt.loaded.ext.module.maasng:361 ][INFO    ][7137] fd8b7w
2019-05-29 20:15:50,660 [salt.loaded.ext.module.maasng:367 ][INFO    ][7137] [{u'size': 800109715456, u'name': u'sda', u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'uuid': u'c0a923a3-f58b-4cd2-9252-ce7e529a6a80', u'resource_uri': u'/MAAS/api/2.0/nodes/fd8b7w/blockdevices/2/partition/2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'fd8b7w', u'filesystem': {u'mount_options': None, u'uuid': u'f5ed7786-09a6-4b38-a3fd-b426990b5473', u'fstype': u'lvm-pv', u'mount_point': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 2, u'size': 800101236736}], u'uuid': None, u'id': 2, u'path': u'/dev/disk/by-dname/sda', u'system_id': u'fd8b7w', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'used_size': 800106479616, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/fd8b7w/blockdevices/2/'}, {u'size': 800097042432, u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'partitions': [], u'uuid': u'61e895a7-c385-4c02-85f0-e0959cff4444', u'id': 4, u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'fd8b7w', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'dc84cd43-172b-4d2b-a967-9a705f453497', u'fstype': u'ext4', u'mount_point': u'/', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'used_size': 800097042432, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/fd8b7w/blockdevices/4/'}]
2019-05-29 20:15:50,661 [salt.loaded.ext.module.maasng:632 ][INFO    ][7137] vgroot
2019-05-29 20:15:50,661 [salt.loaded.ext.module.maasng:635 ][INFO    ][7137] lvroot
2019-05-29 20:15:50,661 [salt.loaded.ext.module.maasng:639 ][INFO    ][7137] 107374182400
2019-05-29 20:15:51,268 [salt.loaded.ext.module.maasng:645 ][INFO    ][7137] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'swap_size': None, u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.39', u'192.168.11.43'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'commissioning_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'status_action': u'', u'blockdevice_set': [{u'block_size': 4096, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 2, u'partitions': [{u'size': 800101236736, u'uuid': u'3794f040-3067-4cc5-978f-205e7a390342', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'fd8b7w', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'51196f04-9ca3-4282-8d2a-d0ff3dc33750', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/fd8b7w/blockdevices/2/partition/6'}], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'fd8b7w', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456, u'type': u'physical', u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/fd8b7w/blockdevices/2/'}, {u'block_size': 4096, u'available_size': 0, u'uuid': u'337e81ee-9d88-4a6a-af48-e0c463009551', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'id': 10, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'fd8b7w', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'82c092bb-551c-427f-b080-02d0550399c6', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'size': 107374182400, u'type': u'virtual', u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/fd8b7w/blockdevices/10/'}], u'zone': {u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default', u'description': u''}, u'current_commissioning_result_id': 6, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'fd8b7w', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'disable_ipv4': False, u'architecture': u'amd64/generic', u'status': 4, u'storage_test_status': 2, u'cpu_count': 40, u'raids': [], u'physicalblockdevice_set': [{u'size': 800109715456, u'available_size': 0, u'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/fd8b7w/blockdevices/2/', u'used_size': 800106479616, u'id': 2, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'fd8b7w', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'size': 800101236736, u'uuid': u'3794f040-3067-4cc5-978f-205e7a390342', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'fd8b7w', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'51196f04-9ca3-4282-8d2a-d0ff3dc33750', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/fd8b7w/blockdevices/2/partition/6'}]}], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'fd8b7w', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'size': 800109715456, u'available_size': 0, u'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/fd8b7w/blockdevices/2/', u'used_size': 800106479616, u'id': 2, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'fd8b7w', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'size': 800101236736, u'uuid': u'3794f040-3067-4cc5-978f-205e7a390342', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'fd8b7w', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'51196f04-9ca3-4282-8d2a-d0ff3dc33750', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/fd8b7w/blockdevices/2/partition/6'}]}, u'current_testing_result_id': 7, u'cpu_test_status': -1, u'bcaches': [], u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'virtualblockdevice_set': [{u'size': 107374182400, u'available_size': 0, u'uuid': u'337e81ee-9d88-4a6a-af48-e0c463009551', u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/fd8b7w/blockdevices/10/', u'used_size': 107374182400, u'id': 10, u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'fd8b7w', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'82c092bb-551c-427f-b080-02d0550399c6', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'serial': None, u'partitions': []}], u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gwps4n', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 22}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gwps4n', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gwps4n', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'mac_address': u'9c:b6:54:8a:95:a0', u'system_id': u'fd8b7w', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/fd8b7w/interfaces/6/'}, u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gwps4n', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 22}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gwps4n', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gwps4n', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'mac_address': u'9c:b6:54:8a:95:a0', u'system_id': u'fd8b7w', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/fd8b7w/interfaces/6/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d4', u'system_id': u'fd8b7w', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/fd8b7w/interfaces/15/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d5', u'system_id': u'fd8b7w', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/fd8b7w/interfaces/17/'}, {u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 23}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cc', u'system_id': u'fd8b7w', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/fd8b7w/interfaces/16/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gwps4n', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'mode': u'link_up', u'id': 24}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gwps4n', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gwps4n', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.43'}], u'mac_address': u'9c:b6:54:8a:95:a4', u'system_id': u'fd8b7w', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/fd8b7w/interfaces/18/'}, {u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 25}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cd', u'system_id': u'fd8b7w', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/fd8b7w/interfaces/19/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/fd8b7w/'}
2019-05-29 20:15:51,272 [salt.state       :300 ][INFO    ][7137] {'new': {'storage_layout': 'lvm'}}
2019-05-29 20:15:51,272 [salt.state       :1951][INFO    ][7137] Completed state [maas_machines_storage_cmp001_lvm] at time 20:15:51.272653 duration_in_ms=2148.58
2019-05-29 20:15:51,276 [salt.minion      :1711][INFO    ][7137] Returning information for job: 20190529201539126447
2019-05-29 20:15:51,853 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command state.apply with jid 20190529201551836058
2019-05-29 20:15:51,880 [salt.minion      :1432][INFO    ][7176] Starting a new job with PID 7176
2019-05-29 20:15:52,794 [salt.state       :915 ][INFO    ][7176] Loading fresh modules for state activity
2019-05-29 20:15:52,849 [salt.fileclient  :1219][INFO    ][7176] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-05-29 20:15:52,898 [salt.state       :1780][INFO    ][7176] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:15:52.897651
2019-05-29 20:15:52,898 [salt.state       :1813][INFO    ][7176] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-29 20:15:52,900 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7176] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-29 20:15:54,606 [salt.state       :300 ][INFO    ][7176] {'pid': 7183, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-29 20:15:54,607 [salt.state       :1951][INFO    ][7176] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:15:54.606996 duration_in_ms=1709.344
2019-05-29 20:15:54,610 [salt.state       :1780][INFO    ][7176] Running state [maas.deploy_machines] at time 20:15:54.610129
2019-05-29 20:15:54,610 [salt.state       :1813][INFO    ][7176] Executing state module.run for [maas.deploy_machines]
2019-05-29 20:15:54,611 [salt.utils.decorators:613 ][WARNING ][7176] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-29 20:15:55,108 [salt.loaded.ext.module.maas:684 ][INFO    ][7176] deploymachines hwe_kernel=hwe-16.04 system_id=4ncgxy distro_series=xenial
2019-05-29 20:15:57,726 [salt.loaded.ext.module.maas:684 ][INFO    ][7176] deploymachines hwe_kernel=hwe-16.04 system_id=63an73 distro_series=xenial
2019-05-29 20:16:00,612 [salt.loaded.ext.module.maas:684 ][INFO    ][7176] deploymachines hwe_kernel=hwe-16.04 system_id=fd8b7w distro_series=xenial
2019-05-29 20:16:03,137 [salt.loaded.ext.module.maas:684 ][INFO    ][7176] deploymachines hwe_kernel=hwe-16.04 system_id=a6chp7 distro_series=xenial
2019-05-29 20:16:05,767 [salt.state       :300 ][INFO    ][7176] {'ret': {'updated': [], 'errors': {}, 'success': ['gtw01', 'cmp002', 'cmp001', 'ctl01']}}
2019-05-29 20:16:05,768 [salt.state       :1951][INFO    ][7176] Completed state [maas.deploy_machines] at time 20:16:05.768187 duration_in_ms=11158.055
2019-05-29 20:16:05,772 [salt.minion      :1711][INFO    ][7176] Returning information for job: 20190529201551836058
2019-05-29 20:16:06,358 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command state.apply with jid 20190529201606343897
2019-05-29 20:16:06,385 [salt.minion      :1432][INFO    ][7434] Starting a new job with PID 7434
2019-05-29 20:16:12,425 [salt.state       :915 ][INFO    ][7434] Loading fresh modules for state activity
2019-05-29 20:16:12,496 [salt.fileclient  :1219][INFO    ][7434] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-05-29 20:16:12,549 [salt.state       :1780][INFO    ][7434] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:16:12.549317
2019-05-29 20:16:12,549 [salt.state       :1813][INFO    ][7434] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-29 20:16:12,551 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7434] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-29 20:16:14,287 [salt.state       :300 ][INFO    ][7434] {'pid': 7448, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-29 20:16:14,288 [salt.state       :1951][INFO    ][7434] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:16:14.288036 duration_in_ms=1738.718
2019-05-29 20:16:14,292 [salt.state       :1780][INFO    ][7434] Running state [maas.wait_for_machine_status] at time 20:16:14.292016
2019-05-29 20:16:14,292 [salt.state       :1813][INFO    ][7434] Executing state module.run for [maas.wait_for_machine_status]
2019-05-29 20:16:14,293 [salt.utils.decorators:613 ][WARNING ][7434] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-29 20:16:16,427 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2247.87448907s left)
2019-05-29 20:16:21,388 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529201621375218
2019-05-29 20:16:21,415 [salt.minion      :1432][INFO    ][7470] Starting a new job with PID 7470
2019-05-29 20:16:21,436 [salt.minion      :1711][INFO    ][7470] Returning information for job: 20190529201621375218
2019-05-29 20:16:48,352 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2215.94938898s left)
2019-05-29 20:16:51,463 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529201651448895
2019-05-29 20:16:51,481 [salt.minion      :1432][INFO    ][7499] Starting a new job with PID 7499
2019-05-29 20:16:51,504 [salt.minion      :1711][INFO    ][7499] Returning information for job: 20190529201651448895
2019-05-29 20:17:20,346 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2183.95662999s left)
2019-05-29 20:17:21,537 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529201721523475
2019-05-29 20:17:21,567 [salt.minion      :1432][INFO    ][7549] Starting a new job with PID 7549
2019-05-29 20:17:21,592 [salt.minion      :1711][INFO    ][7549] Returning information for job: 20190529201721523475
2019-05-29 20:17:51,606 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529201751594030
2019-05-29 20:17:51,629 [salt.minion      :1432][INFO    ][7583] Starting a new job with PID 7583
2019-05-29 20:17:51,651 [salt.minion      :1711][INFO    ][7583] Returning information for job: 20190529201751594030
2019-05-29 20:17:52,325 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2151.97679806s left)
2019-05-29 20:18:21,692 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529201821675837
2019-05-29 20:18:21,717 [salt.minion      :1432][INFO    ][7640] Starting a new job with PID 7640
2019-05-29 20:18:21,738 [salt.minion      :1711][INFO    ][7640] Returning information for job: 20190529201821675837
2019-05-29 20:18:24,490 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2119.81225204s left)
2019-05-29 20:18:51,740 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529201851726750
2019-05-29 20:18:51,766 [salt.minion      :1432][INFO    ][7689] Starting a new job with PID 7689
2019-05-29 20:18:51,790 [salt.minion      :1711][INFO    ][7689] Returning information for job: 20190529201851726750
2019-05-29 20:18:56,433 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2087.86891198s left)
2019-05-29 20:19:21,834 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529201921816355
2019-05-29 20:19:21,861 [salt.minion      :1432][INFO    ][7791] Starting a new job with PID 7791
2019-05-29 20:19:21,886 [salt.minion      :1711][INFO    ][7791] Returning information for job: 20190529201921816355
2019-05-29 20:19:28,464 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2055.83750391s left)
2019-05-29 20:19:51,941 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529201951924103
2019-05-29 20:19:51,969 [salt.minion      :1432][INFO    ][7853] Starting a new job with PID 7853
2019-05-29 20:19:51,990 [salt.minion      :1711][INFO    ][7853] Returning information for job: 20190529201951924103
2019-05-29 20:20:01,568 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2022.73412108s left)
2019-05-29 20:20:22,028 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529202022020472
2019-05-29 20:20:22,051 [salt.minion      :1432][INFO    ][8169] Starting a new job with PID 8169
2019-05-29 20:20:22,071 [salt.minion      :1711][INFO    ][8169] Returning information for job: 20190529202022020472
2019-05-29 20:20:33,479 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1990.82288909s left)
2019-05-29 20:20:52,109 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529202052095078
2019-05-29 20:20:52,135 [salt.minion      :1432][INFO    ][8263] Starting a new job with PID 8263
2019-05-29 20:20:52,156 [salt.minion      :1711][INFO    ][8263] Returning information for job: 20190529202052095078
2019-05-29 20:21:05,364 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1958.93824506s left)
2019-05-29 20:21:22,258 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529202122250404
2019-05-29 20:21:22,282 [salt.minion      :1432][INFO    ][8501] Starting a new job with PID 8501
2019-05-29 20:21:22,305 [salt.minion      :1711][INFO    ][8501] Returning information for job: 20190529202122250404
2019-05-29 20:21:37,521 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1926.78068709s left)
2019-05-29 20:21:52,371 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529202152354191
2019-05-29 20:21:52,398 [salt.minion      :1432][INFO    ][8537] Starting a new job with PID 8537
2019-05-29 20:21:52,420 [salt.minion      :1711][INFO    ][8537] Returning information for job: 20190529202152354191
2019-05-29 20:22:09,594 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1894.70792603s left)
2019-05-29 20:22:22,523 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529202222506901
2019-05-29 20:22:22,554 [salt.minion      :1432][INFO    ][8839] Starting a new job with PID 8839
2019-05-29 20:22:22,575 [salt.minion      :1711][INFO    ][8839] Returning information for job: 20190529202222506901
2019-05-29 20:22:41,789 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1862.51280093s left)
2019-05-29 20:22:52,656 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529202252642845
2019-05-29 20:22:52,680 [salt.minion      :1432][INFO    ][8881] Starting a new job with PID 8881
2019-05-29 20:22:52,703 [salt.minion      :1711][INFO    ][8881] Returning information for job: 20190529202252642845
2019-05-29 20:23:13,869 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1830.43300295s left)
2019-05-29 20:23:22,829 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529202322806189
2019-05-29 20:23:22,858 [salt.minion      :1432][INFO    ][9148] Starting a new job with PID 9148
2019-05-29 20:23:22,881 [salt.minion      :1711][INFO    ][9148] Returning information for job: 20190529202322806189
2019-05-29 20:23:46,069 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1798.233042s left)
2019-05-29 20:23:53,001 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529202352988771
2019-05-29 20:23:53,032 [salt.minion      :1432][INFO    ][9177] Starting a new job with PID 9177
2019-05-29 20:23:53,055 [salt.minion      :1711][INFO    ][9177] Returning information for job: 20190529202352988771
2019-05-29 20:24:18,071 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1766.23079491s left)
2019-05-29 20:24:23,176 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529202423159338
2019-05-29 20:24:23,201 [salt.minion      :1432][INFO    ][9381] Starting a new job with PID 9381
2019-05-29 20:24:23,231 [salt.minion      :1711][INFO    ][9381] Returning information for job: 20190529202423159338
2019-05-29 20:24:50,056 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1734.24602699s left)
2019-05-29 20:24:53,334 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529202453322280
2019-05-29 20:24:53,364 [salt.minion      :1432][INFO    ][9416] Starting a new job with PID 9416
2019-05-29 20:24:53,385 [salt.minion      :1711][INFO    ][9416] Returning information for job: 20190529202453322280
2019-05-29 20:25:22,207 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1702.09440708s left)
2019-05-29 20:25:23,516 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529202523503779
2019-05-29 20:25:23,544 [salt.minion      :1432][INFO    ][9553] Starting a new job with PID 9553
2019-05-29 20:25:23,568 [salt.minion      :1711][INFO    ][9553] Returning information for job: 20190529202523503779
2019-05-29 20:25:53,692 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529202553679093
2019-05-29 20:25:53,719 [salt.minion      :1432][INFO    ][9605] Starting a new job with PID 9605
2019-05-29 20:25:53,740 [salt.minion      :1711][INFO    ][9605] Returning information for job: 20190529202553679093
2019-05-29 20:25:54,183 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1670.11883712s left)
2019-05-29 20:26:23,844 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529202623828376
2019-05-29 20:26:23,874 [salt.minion      :1432][INFO    ][9786] Starting a new job with PID 9786
2019-05-29 20:26:23,896 [salt.minion      :1711][INFO    ][9786] Returning information for job: 20190529202623828376
2019-05-29 20:26:26,264 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:2250s (1638.03824997s left)
2019-05-29 20:26:54,007 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529202653990709
2019-05-29 20:26:54,036 [salt.minion      :1432][INFO    ][9826] Starting a new job with PID 9826
2019-05-29 20:26:54,059 [salt.minion      :1711][INFO    ][9826] Returning information for job: 20190529202653990709
2019-05-29 20:26:58,842 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:2250s (1605.45956397s left)
2019-05-29 20:27:24,201 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529202724184086
2019-05-29 20:27:24,235 [salt.minion      :1432][INFO    ][9991] Starting a new job with PID 9991
2019-05-29 20:27:24,260 [salt.minion      :1711][INFO    ][9991] Returning information for job: 20190529202724184086
2019-05-29 20:27:30,823 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:2250s (1573.47868896s left)
2019-05-29 20:27:54,414 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529202754400539
2019-05-29 20:27:54,444 [salt.minion      :1432][INFO    ][10153] Starting a new job with PID 10153
2019-05-29 20:27:54,467 [salt.minion      :1711][INFO    ][10153] Returning information for job: 20190529202754400539
2019-05-29 20:28:02,842 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:2250s (1541.45938301s left)
2019-05-29 20:28:24,632 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529202824620781
2019-05-29 20:28:24,664 [salt.minion      :1432][INFO    ][10211] Starting a new job with PID 10211
2019-05-29 20:28:24,691 [salt.minion      :1711][INFO    ][10211] Returning information for job: 20190529202824620781
2019-05-29 20:28:35,119 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:2250s (1509.18312597s left)
2019-05-29 20:28:54,836 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529202854823953
2019-05-29 20:28:54,863 [salt.minion      :1432][INFO    ][10273] Starting a new job with PID 10273
2019-05-29 20:28:54,887 [salt.minion      :1711][INFO    ][10273] Returning information for job: 20190529202854823953
2019-05-29 20:29:07,144 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1477.15824795s left)
2019-05-29 20:29:24,965 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529202924949373
2019-05-29 20:29:24,994 [salt.minion      :1432][INFO    ][10394] Starting a new job with PID 10394
2019-05-29 20:29:25,016 [salt.minion      :1711][INFO    ][10394] Returning information for job: 20190529202924949373
2019-05-29 20:29:39,130 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1445.1720221s left)
2019-05-29 20:29:55,182 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529202955164957
2019-05-29 20:29:55,206 [salt.minion      :1432][INFO    ][10425] Starting a new job with PID 10425
2019-05-29 20:29:55,224 [salt.minion      :1711][INFO    ][10425] Returning information for job: 20190529202955164957
2019-05-29 20:30:11,139 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1413.16276789s left)
2019-05-29 20:30:25,223 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529203025205622
2019-05-29 20:30:25,253 [salt.minion      :1432][INFO    ][10544] Starting a new job with PID 10544
2019-05-29 20:30:25,273 [salt.minion      :1711][INFO    ][10544] Returning information for job: 20190529203025205622
2019-05-29 20:30:43,270 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1381.03172588s left)
2019-05-29 20:30:55,251 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529203055240680
2019-05-29 20:30:55,270 [salt.minion      :1432][INFO    ][10578] Starting a new job with PID 10578
2019-05-29 20:30:55,292 [salt.minion      :1711][INFO    ][10578] Returning information for job: 20190529203055240680
2019-05-29 20:31:15,272 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1349.03029203s left)
2019-05-29 20:31:25,285 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529203125268437
2019-05-29 20:31:25,319 [salt.minion      :1432][INFO    ][10622] Starting a new job with PID 10622
2019-05-29 20:31:25,339 [salt.minion      :1711][INFO    ][10622] Returning information for job: 20190529203125268437
2019-05-29 20:31:47,189 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1317.11307907s left)
2019-05-29 20:31:55,351 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529203155336292
2019-05-29 20:31:55,378 [salt.minion      :1432][INFO    ][10653] Starting a new job with PID 10653
2019-05-29 20:31:55,401 [salt.minion      :1711][INFO    ][10653] Returning information for job: 20190529203155336292
2019-05-29 20:32:19,225 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1285.07688403s left)
2019-05-29 20:32:25,421 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529203225404668
2019-05-29 20:32:25,451 [salt.minion      :1432][INFO    ][10696] Starting a new job with PID 10696
2019-05-29 20:32:25,473 [salt.minion      :1711][INFO    ][10696] Returning information for job: 20190529203225404668
2019-05-29 20:32:51,078 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1253.22400904s left)
2019-05-29 20:32:55,511 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529203255499781
2019-05-29 20:32:55,539 [salt.minion      :1432][INFO    ][10730] Starting a new job with PID 10730
2019-05-29 20:32:55,563 [salt.minion      :1711][INFO    ][10730] Returning information for job: 20190529203255499781
2019-05-29 20:33:22,975 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1221.32719493s left)
2019-05-29 20:33:25,605 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529203325588020
2019-05-29 20:33:25,635 [salt.minion      :1432][INFO    ][10775] Starting a new job with PID 10775
2019-05-29 20:33:25,656 [salt.minion      :1711][INFO    ][10775] Returning information for job: 20190529203325588020
2019-05-29 20:33:55,102 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1189.20026493s left)
2019-05-29 20:33:55,712 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529203355699240
2019-05-29 20:33:55,740 [salt.minion      :1432][INFO    ][10805] Starting a new job with PID 10805
2019-05-29 20:33:55,762 [salt.minion      :1711][INFO    ][10805] Returning information for job: 20190529203355699240
2019-05-29 20:34:25,840 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529203425823642
2019-05-29 20:34:25,860 [salt.minion      :1432][INFO    ][10848] Starting a new job with PID 10848
2019-05-29 20:34:25,882 [salt.minion      :1711][INFO    ][10848] Returning information for job: 20190529203425823642
2019-05-29 20:34:27,094 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1157.20750809s left)
2019-05-29 20:34:55,987 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529203455973023
2019-05-29 20:34:56,013 [salt.minion      :1432][INFO    ][10874] Starting a new job with PID 10874
2019-05-29 20:34:56,035 [salt.minion      :1711][INFO    ][10874] Returning information for job: 20190529203455973023
2019-05-29 20:34:59,200 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1125.102139s left)
2019-05-29 20:35:26,163 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529203526144941
2019-05-29 20:35:26,196 [salt.minion      :1432][INFO    ][10921] Starting a new job with PID 10921
2019-05-29 20:35:26,217 [salt.minion      :1711][INFO    ][10921] Returning information for job: 20190529203526144941
2019-05-29 20:35:31,112 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1093.18939209s left)
2019-05-29 20:35:56,341 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529203556324939
2019-05-29 20:35:56,372 [salt.minion      :1432][INFO    ][10951] Starting a new job with PID 10951
2019-05-29 20:35:56,397 [salt.minion      :1711][INFO    ][10951] Returning information for job: 20190529203556324939
2019-05-29 20:36:02,973 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1061.328403s left)
2019-05-29 20:36:26,497 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529203626487622
2019-05-29 20:36:26,516 [salt.minion      :1432][INFO    ][10994] Starting a new job with PID 10994
2019-05-29 20:36:26,537 [salt.minion      :1711][INFO    ][10994] Returning information for job: 20190529203626487622
2019-05-29 20:36:34,918 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1029.38353705s left)
2019-05-29 20:36:56,683 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529203656671619
2019-05-29 20:36:56,708 [salt.minion      :1432][INFO    ][11027] Starting a new job with PID 11027
2019-05-29 20:36:56,731 [salt.minion      :1711][INFO    ][11027] Returning information for job: 20190529203656671619
2019-05-29 20:37:06,808 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (997.493437052s left)
2019-05-29 20:37:26,909 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529203726897249
2019-05-29 20:37:26,934 [salt.minion      :1432][INFO    ][11071] Starting a new job with PID 11071
2019-05-29 20:37:26,957 [salt.minion      :1711][INFO    ][11071] Returning information for job: 20190529203726897249
2019-05-29 20:37:38,686 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (965.615473032s left)
2019-05-29 20:37:57,104 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529203757088380
2019-05-29 20:37:57,128 [salt.minion      :1432][INFO    ][11111] Starting a new job with PID 11111
2019-05-29 20:37:57,152 [salt.minion      :1711][INFO    ][11111] Returning information for job: 20190529203757088380
2019-05-29 20:38:10,704 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (933.598200083s left)
2019-05-29 20:38:27,151 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529203827137435
2019-05-29 20:38:27,180 [salt.minion      :1432][INFO    ][11167] Starting a new job with PID 11167
2019-05-29 20:38:27,205 [salt.minion      :1711][INFO    ][11167] Returning information for job: 20190529203827137435
2019-05-29 20:38:42,609 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (901.692797899s left)
2019-05-29 20:38:57,380 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529203857367587
2019-05-29 20:38:57,409 [salt.minion      :1432][INFO    ][11197] Starting a new job with PID 11197
2019-05-29 20:38:57,436 [salt.minion      :1711][INFO    ][11197] Returning information for job: 20190529203857367587
2019-05-29 20:39:14,640 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (869.662163973s left)
2019-05-29 20:39:27,453 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529203927442654
2019-05-29 20:39:27,480 [salt.minion      :1432][INFO    ][11242] Starting a new job with PID 11242
2019-05-29 20:39:27,501 [salt.minion      :1711][INFO    ][11242] Returning information for job: 20190529203927442654
2019-05-29 20:39:46,620 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (837.682271004s left)
2019-05-29 20:39:57,511 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529203957496500
2019-05-29 20:39:57,536 [salt.minion      :1432][INFO    ][11276] Starting a new job with PID 11276
2019-05-29 20:39:57,559 [salt.minion      :1711][INFO    ][11276] Returning information for job: 20190529203957496500
2019-05-29 20:40:18,601 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (805.701314926s left)
2019-05-29 20:40:27,612 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529204027598736
2019-05-29 20:40:27,643 [salt.minion      :1432][INFO    ][11322] Starting a new job with PID 11322
2019-05-29 20:40:27,667 [salt.minion      :1711][INFO    ][11322] Returning information for job: 20190529204027598736
2019-05-29 20:40:50,405 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (773.896985054s left)
2019-05-29 20:40:57,703 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529204057686341
2019-05-29 20:40:57,731 [salt.minion      :1432][INFO    ][11351] Starting a new job with PID 11351
2019-05-29 20:40:57,754 [salt.minion      :1711][INFO    ][11351] Returning information for job: 20190529204057686341
2019-05-29 20:41:22,305 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (741.99636507s left)
2019-05-29 20:41:27,847 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529204127834610
2019-05-29 20:41:27,872 [salt.minion      :1432][INFO    ][11396] Starting a new job with PID 11396
2019-05-29 20:41:27,897 [salt.minion      :1711][INFO    ][11396] Returning information for job: 20190529204127834610
2019-05-29 20:41:54,072 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (710.229501009s left)
2019-05-29 20:41:57,991 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529204157980275
2019-05-29 20:41:58,018 [salt.minion      :1432][INFO    ][11439] Starting a new job with PID 11439
2019-05-29 20:41:58,044 [salt.minion      :1711][INFO    ][11439] Returning information for job: 20190529204157980275
2019-05-29 20:42:26,066 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (678.235388994s left)
2019-05-29 20:42:28,192 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529204228177476
2019-05-29 20:42:28,220 [salt.minion      :1432][INFO    ][11472] Starting a new job with PID 11472
2019-05-29 20:42:28,243 [salt.minion      :1711][INFO    ][11472] Returning information for job: 20190529204228177476
2019-05-29 20:42:58,026 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (646.275871038s left)
2019-05-29 20:42:58,366 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529204258355187
2019-05-29 20:42:58,386 [salt.minion      :1432][INFO    ][11514] Starting a new job with PID 11514
2019-05-29 20:42:58,409 [salt.minion      :1711][INFO    ][11514] Returning information for job: 20190529204258355187
2019-05-29 20:43:28,592 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529204328579687
2019-05-29 20:43:28,617 [salt.minion      :1432][INFO    ][11544] Starting a new job with PID 11544
2019-05-29 20:43:28,642 [salt.minion      :1711][INFO    ][11544] Returning information for job: 20190529204328579687
2019-05-29 20:43:30,219 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (614.082433939s left)
2019-05-29 20:43:58,617 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529204358601522
2019-05-29 20:43:58,644 [salt.minion      :1432][INFO    ][11592] Starting a new job with PID 11592
2019-05-29 20:43:58,685 [salt.minion      :1711][INFO    ][11592] Returning information for job: 20190529204358601522
2019-05-29 20:44:02,064 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (582.237996101s left)
2019-05-29 20:44:28,692 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529204428676703
2019-05-29 20:44:28,721 [salt.minion      :1432][INFO    ][11621] Starting a new job with PID 11621
2019-05-29 20:44:28,748 [salt.minion      :1711][INFO    ][11621] Returning information for job: 20190529204428676703
2019-05-29 20:44:34,002 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (550.299635887s left)
2019-05-29 20:44:58,783 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529204458770269
2019-05-29 20:44:58,808 [salt.minion      :1432][INFO    ][11668] Starting a new job with PID 11668
2019-05-29 20:44:58,832 [salt.minion      :1711][INFO    ][11668] Returning information for job: 20190529204458770269
2019-05-29 20:45:05,883 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (518.419239044s left)
2019-05-29 20:45:28,880 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529204528869069
2019-05-29 20:45:28,906 [salt.minion      :1432][INFO    ][11694] Starting a new job with PID 11694
2019-05-29 20:45:28,932 [salt.minion      :1711][INFO    ][11694] Returning information for job: 20190529204528869069
2019-05-29 20:45:37,789 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (486.513139963s left)
2019-05-29 20:45:58,991 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529204558978557
2019-05-29 20:45:59,019 [salt.minion      :1432][INFO    ][11741] Starting a new job with PID 11741
2019-05-29 20:45:59,041 [salt.minion      :1711][INFO    ][11741] Returning information for job: 20190529204558978557
2019-05-29 20:46:09,811 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (454.491142988s left)
2019-05-29 20:46:29,045 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529204629031139
2019-05-29 20:46:29,073 [salt.minion      :1432][INFO    ][11766] Starting a new job with PID 11766
2019-05-29 20:46:29,097 [salt.minion      :1711][INFO    ][11766] Returning information for job: 20190529204629031139
2019-05-29 20:46:41,753 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (422.549334049s left)
2019-05-29 20:46:59,239 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529204659224511
2019-05-29 20:46:59,264 [salt.minion      :1432][INFO    ][11815] Starting a new job with PID 11815
2019-05-29 20:46:59,290 [salt.minion      :1711][INFO    ][11815] Returning information for job: 20190529204659224511
2019-05-29 20:47:13,637 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (390.664880991s left)
2019-05-29 20:47:29,398 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529204729382854
2019-05-29 20:47:29,428 [salt.minion      :1432][INFO    ][11843] Starting a new job with PID 11843
2019-05-29 20:47:29,453 [salt.minion      :1711][INFO    ][11843] Returning information for job: 20190529204729382854
2019-05-29 20:47:45,714 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (358.588556051s left)
2019-05-29 20:47:59,597 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529204759582943
2019-05-29 20:47:59,625 [salt.minion      :1432][INFO    ][12027] Starting a new job with PID 12027
2019-05-29 20:47:59,656 [salt.minion      :1711][INFO    ][12027] Returning information for job: 20190529204759582943
2019-05-29 20:48:17,762 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (326.539815903s left)
2019-05-29 20:48:29,797 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529204829784668
2019-05-29 20:48:29,824 [salt.minion      :1432][INFO    ][12061] Starting a new job with PID 12061
2019-05-29 20:48:29,848 [salt.minion      :1711][INFO    ][12061] Returning information for job: 20190529204829784668
2019-05-29 20:48:49,653 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (294.6491611s left)
2019-05-29 20:48:59,827 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529204859814441
2019-05-29 20:48:59,853 [salt.minion      :1432][INFO    ][12116] Starting a new job with PID 12116
2019-05-29 20:48:59,887 [salt.minion      :1711][INFO    ][12116] Returning information for job: 20190529204859814441
2019-05-29 20:49:21,544 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (262.757750034s left)
2019-05-29 20:49:29,883 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529204929872926
2019-05-29 20:49:29,903 [salt.minion      :1432][INFO    ][12136] Starting a new job with PID 12136
2019-05-29 20:49:29,927 [salt.minion      :1711][INFO    ][12136] Returning information for job: 20190529204929872926
2019-05-29 20:49:53,569 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (230.732815981s left)
2019-05-29 20:50:00,112 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529205000095564
2019-05-29 20:50:00,141 [salt.minion      :1432][INFO    ][12194] Starting a new job with PID 12194
2019-05-29 20:50:00,169 [salt.minion      :1711][INFO    ][12194] Returning information for job: 20190529205000095564
2019-05-29 20:50:25,471 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (198.831115007s left)
2019-05-29 20:50:30,245 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529205030230774
2019-05-29 20:50:30,272 [salt.minion      :1432][INFO    ][12213] Starting a new job with PID 12213
2019-05-29 20:50:30,297 [salt.minion      :1711][INFO    ][12213] Returning information for job: 20190529205030230774
2019-05-29 20:50:57,477 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (166.824587107s left)
2019-05-29 20:51:00,357 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529205100340469
2019-05-29 20:51:00,384 [salt.minion      :1432][INFO    ][12266] Starting a new job with PID 12266
2019-05-29 20:51:00,411 [salt.minion      :1711][INFO    ][12266] Returning information for job: 20190529205100340469
2019-05-29 20:51:29,418 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (134.883990049s left)
2019-05-29 20:51:30,487 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529205130472391
2019-05-29 20:51:30,516 [salt.minion      :1432][INFO    ][12284] Starting a new job with PID 12284
2019-05-29 20:51:30,547 [salt.minion      :1711][INFO    ][12284] Returning information for job: 20190529205130472391
2019-05-29 20:52:00,628 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529205200617641
2019-05-29 20:52:00,646 [salt.minion      :1432][INFO    ][12339] Starting a new job with PID 12339
2019-05-29 20:52:00,668 [salt.minion      :1711][INFO    ][12339] Returning information for job: 20190529205200617641
2019-05-29 20:52:01,369 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (102.933281898s left)
2019-05-29 20:52:30,829 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529205230813570
2019-05-29 20:52:30,857 [salt.minion      :1432][INFO    ][12357] Starting a new job with PID 12357
2019-05-29 20:52:30,882 [salt.minion      :1711][INFO    ][12357] Returning information for job: 20190529205230813570
2019-05-29 20:52:33,394 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (70.9076440334s left)
2019-05-29 20:53:00,989 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529205300972989
2019-05-29 20:53:01,017 [salt.minion      :1432][INFO    ][12410] Starting a new job with PID 12410
2019-05-29 20:53:01,043 [salt.minion      :1711][INFO    ][12410] Returning information for job: 20190529205300972989
2019-05-29 20:53:05,324 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (38.9777998924s left)
2019-05-29 20:53:31,206 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529205331196489
2019-05-29 20:53:31,231 [salt.minion      :1432][INFO    ][12428] Starting a new job with PID 12428
2019-05-29 20:53:31,252 [salt.minion      :1711][INFO    ][12428] Returning information for job: 20190529205331196489
2019-05-29 20:53:37,218 [salt.loaded.ext.module.maas:1023][INFO    ][7434] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (7.0838971138s left)
2019-05-29 20:54:01,409 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529205401394765
2019-05-29 20:54:01,439 [salt.minion      :1432][INFO    ][12483] Starting a new job with PID 12483
2019-05-29 20:54:01,461 [salt.minion      :1711][INFO    ][12483] Returning information for job: 20190529205401394765
2019-05-29 20:54:09,237 [salt.state       :302 ][ERROR   ][7434] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['gtw01']not in Deployed state
2019-05-29 20:54:09,238 [salt.state       :1951][INFO    ][7434] Completed state [maas.wait_for_machine_status] at time 20:54:09.238539 duration_in_ms=2274946.517
2019-05-29 20:54:09,249 [salt.minion      :1711][INFO    ][7434] Returning information for job: 20190529201606343897
2019-05-29 20:54:20,071 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command pillar.get with jid 20190529205420056719
2019-05-29 20:54:20,101 [salt.minion      :1432][INFO    ][12501] Starting a new job with PID 12501
2019-05-29 20:54:20,111 [salt.minion      :1711][INFO    ][12501] Returning information for job: 20190529205420056719
2019-05-29 20:54:20,684 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command service.status with jid 20190529205420671262
2019-05-29 20:54:20,712 [salt.minion      :1432][INFO    ][12506] Starting a new job with PID 12506
2019-05-29 20:54:21,268 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12506] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-05-29 20:54:21,311 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12506] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-05-29 20:54:21,332 [salt.minion      :1711][INFO    ][12506] Returning information for job: 20190529205420671262
2019-05-29 20:54:21,903 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command state.apply with jid 20190529205421889132
2019-05-29 20:54:21,932 [salt.minion      :1432][INFO    ][12517] Starting a new job with PID 12517
2019-05-29 20:54:27,844 [salt.state       :915 ][INFO    ][12517] Loading fresh modules for state activity
2019-05-29 20:54:28,433 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12517] Executing command 'salt-minion --version' in directory '/root'
2019-05-29 20:54:28,722 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12517] Executing command 'salt-minion --version' in directory '/root'
2019-05-29 20:54:29,689 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12517] Executing command 'salt-minion --version' in directory '/root'
2019-05-29 20:54:29,983 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12517] Executing command 'salt-minion --version' in directory '/root'
2019-05-29 20:54:31,801 [salt.state       :1780][INFO    ][12517] Running state [salt-minion] at time 20:54:31.800961
2019-05-29 20:54:31,801 [salt.state       :1813][INFO    ][12517] Executing state pkg.installed for [salt-minion]
2019-05-29 20:54:31,803 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12517] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-29 20:54:31,923 [salt.state       :300 ][INFO    ][12517] All specified packages are already installed
2019-05-29 20:54:31,923 [salt.state       :1951][INFO    ][12517] Completed state [salt-minion] at time 20:54:31.923832 duration_in_ms=122.872
2019-05-29 20:54:31,924 [salt.state       :1780][INFO    ][12517] Running state [salt_minion_dependency_packages] at time 20:54:31.924158
2019-05-29 20:54:31,924 [salt.state       :1813][INFO    ][12517] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-05-29 20:54:31,933 [salt.state       :300 ][INFO    ][12517] All specified packages are already installed
2019-05-29 20:54:31,933 [salt.state       :1951][INFO    ][12517] Completed state [salt_minion_dependency_packages] at time 20:54:31.933590 duration_in_ms=9.431
2019-05-29 20:54:31,937 [salt.state       :1780][INFO    ][12517] Running state [/etc/salt/minion.d/minion.conf] at time 20:54:31.937736
2019-05-29 20:54:31,938 [salt.state       :1813][INFO    ][12517] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-05-29 20:54:32,209 [salt.state       :300 ][INFO    ][12517] File /etc/salt/minion.d/minion.conf is in the correct state
2019-05-29 20:54:32,209 [salt.state       :1951][INFO    ][12517] Completed state [/etc/salt/minion.d/minion.conf] at time 20:54:32.209858 duration_in_ms=272.122
2019-05-29 20:54:32,212 [salt.state       :1780][INFO    ][12517] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 20:54:32.212867
2019-05-29 20:54:32,213 [salt.state       :1813][INFO    ][12517] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-05-29 20:54:32,228 [salt.state       :300 ][INFO    ][12517] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-05-29 20:54:32,228 [salt.state       :1951][INFO    ][12517] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 20:54:32.228668 duration_in_ms=15.8
2019-05-29 20:54:32,230 [salt.state       :1780][INFO    ][12517] Running state [salt-minion] at time 20:54:32.230173
2019-05-29 20:54:32,230 [salt.state       :1813][INFO    ][12517] Executing state service.running for [salt-minion]
2019-05-29 20:54:32,231 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12517] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-29 20:54:32,277 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12517] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-05-29 20:54:32,298 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12517] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-05-29 20:54:32,320 [salt.state       :300 ][INFO    ][12517] The service salt-minion is already running
2019-05-29 20:54:32,320 [salt.state       :1951][INFO    ][12517] Completed state [salt-minion] at time 20:54:32.320878 duration_in_ms=90.705
2019-05-29 20:54:32,323 [salt.state       :1780][INFO    ][12517] Running state [/etc/salt/grains.d] at time 20:54:32.323220
2019-05-29 20:54:32,323 [salt.state       :1813][INFO    ][12517] Executing state file.directory for [/etc/salt/grains.d]
2019-05-29 20:54:32,324 [salt.state       :300 ][INFO    ][12517] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-05-29 20:54:32,325 [salt.state       :1951][INFO    ][12517] Completed state [/etc/salt/grains.d] at time 20:54:32.325055 duration_in_ms=1.834
2019-05-29 20:54:32,326 [salt.state       :1780][INFO    ][12517] Running state [/etc/salt/grains] at time 20:54:32.326226
2019-05-29 20:54:32,326 [salt.state       :1813][INFO    ][12517] Executing state file.managed for [/etc/salt/grains]
2019-05-29 20:54:32,327 [salt.state       :300 ][INFO    ][12517] File /etc/salt/grains exists with proper permissions. No changes made.
2019-05-29 20:54:32,327 [salt.state       :1951][INFO    ][12517] Completed state [/etc/salt/grains] at time 20:54:32.327492 duration_in_ms=1.265
2019-05-29 20:54:32,328 [salt.state       :1780][INFO    ][12517] Running state [/etc/salt/grains.d/placeholder] at time 20:54:32.328082
2019-05-29 20:54:32,328 [salt.state       :1813][INFO    ][12517] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-05-29 20:54:32,329 [salt.state       :300 ][INFO    ][12517] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-05-29 20:54:32,329 [salt.state       :1951][INFO    ][12517] Completed state [/etc/salt/grains.d/placeholder] at time 20:54:32.329282 duration_in_ms=1.2
2019-05-29 20:54:32,332 [salt.state       :1780][INFO    ][12517] Running state [/etc/salt/grains.d/sphinx] at time 20:54:32.332106
2019-05-29 20:54:32,332 [salt.state       :1813][INFO    ][12517] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-05-29 20:54:32,333 [salt.state       :300 ][INFO    ][12517] File /etc/salt/grains.d/sphinx is in the correct state
2019-05-29 20:54:32,334 [salt.state       :1951][INFO    ][12517] Completed state [/etc/salt/grains.d/sphinx] at time 20:54:32.334094 duration_in_ms=1.988
2019-05-29 20:54:32,336 [salt.state       :1780][INFO    ][12517] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 20:54:32.336078
2019-05-29 20:54:32,336 [salt.state       :1813][INFO    ][12517] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-05-29 20:54:32,336 [salt.state       :300 ][INFO    ][12517] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-05-29 20:54:32,336 [salt.state       :1951][INFO    ][12517] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 20:54:32.336857 duration_in_ms=0.78
2019-05-29 20:54:32,337 [salt.state       :1780][INFO    ][12517] Running state [/etc/salt/grains.d/dns_records] at time 20:54:32.337329
2019-05-29 20:54:32,337 [salt.state       :1813][INFO    ][12517] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-05-29 20:54:32,338 [salt.state       :300 ][INFO    ][12517] File /etc/salt/grains.d/dns_records is in the correct state
2019-05-29 20:54:32,338 [salt.state       :1951][INFO    ][12517] Completed state [/etc/salt/grains.d/dns_records] at time 20:54:32.338691 duration_in_ms=1.363
2019-05-29 20:54:32,339 [salt.state       :1780][INFO    ][12517] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 20:54:32.339604
2019-05-29 20:54:32,339 [salt.state       :1813][INFO    ][12517] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-05-29 20:54:32,340 [salt.state       :300 ][INFO    ][12517] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-05-29 20:54:32,340 [salt.state       :1951][INFO    ][12517] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 20:54:32.340357 duration_in_ms=0.754
2019-05-29 20:54:32,340 [salt.state       :1780][INFO    ][12517] Running state [/etc/salt/grains.d/salt] at time 20:54:32.340830
2019-05-29 20:54:32,341 [salt.state       :1813][INFO    ][12517] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-05-29 20:54:32,343 [salt.state       :300 ][INFO    ][12517] File /etc/salt/grains.d/salt is in the correct state
2019-05-29 20:54:32,343 [salt.state       :1951][INFO    ][12517] Completed state [/etc/salt/grains.d/salt] at time 20:54:32.343877 duration_in_ms=3.047
2019-05-29 20:54:32,344 [salt.state       :1780][INFO    ][12517] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 20:54:32.344749
2019-05-29 20:54:32,345 [salt.state       :1813][INFO    ][12517] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-05-29 20:54:32,345 [salt.state       :300 ][INFO    ][12517] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-05-29 20:54:32,345 [salt.state       :1951][INFO    ][12517] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 20:54:32.345511 duration_in_ms=0.762
2019-05-29 20:54:32,347 [salt.state       :1780][INFO    ][12517] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 20:54:32.347417
2019-05-29 20:54:32,347 [salt.state       :1813][INFO    ][12517] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-05-29 20:54:32,348 [salt.state       :300 ][INFO    ][12517] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-05-29 20:54:32,348 [salt.state       :1951][INFO    ][12517] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 20:54:32.348173 duration_in_ms=0.757
2019-05-29 20:54:32,348 [salt.state       :1780][INFO    ][12517] Running state [mine.update] at time 20:54:32.348826
2019-05-29 20:54:32,349 [salt.state       :1813][INFO    ][12517] Executing state module.wait for [mine.update]
2019-05-29 20:54:32,349 [salt.state       :300 ][INFO    ][12517] No changes made for mine.update
2019-05-29 20:54:32,349 [salt.state       :1951][INFO    ][12517] Completed state [mine.update] at time 20:54:32.349537 duration_in_ms=0.711
2019-05-29 20:54:32,349 [salt.state       :1780][INFO    ][12517] Running state [ca-certificates] at time 20:54:32.349784
2019-05-29 20:54:32,350 [salt.state       :1813][INFO    ][12517] Executing state pkg.installed for [ca-certificates]
2019-05-29 20:54:32,360 [salt.state       :300 ][INFO    ][12517] All specified packages are already installed
2019-05-29 20:54:32,360 [salt.state       :1951][INFO    ][12517] Completed state [ca-certificates] at time 20:54:32.360877 duration_in_ms=11.093
2019-05-29 20:54:32,362 [salt.state       :1780][INFO    ][12517] Running state [update-ca-certificates] at time 20:54:32.361800
2019-05-29 20:54:32,362 [salt.state       :1813][INFO    ][12517] Executing state cmd.wait for [update-ca-certificates]
2019-05-29 20:54:32,362 [salt.state       :300 ][INFO    ][12517] No changes made for update-ca-certificates
2019-05-29 20:54:32,362 [salt.state       :1951][INFO    ][12517] Completed state [update-ca-certificates] at time 20:54:32.362823 duration_in_ms=1.023
2019-05-29 20:54:32,363 [salt.state       :1780][INFO    ][12517] Running state [iptables] at time 20:54:32.363063
2019-05-29 20:54:32,363 [salt.state       :1813][INFO    ][12517] Executing state pkg.installed for [iptables]
2019-05-29 20:54:32,372 [salt.state       :300 ][INFO    ][12517] All specified packages are already installed
2019-05-29 20:54:32,372 [salt.state       :1951][INFO    ][12517] Completed state [iptables] at time 20:54:32.372755 duration_in_ms=9.692
2019-05-29 20:54:32,373 [salt.state       :1780][INFO    ][12517] Running state [iptables-persistent] at time 20:54:32.373005
2019-05-29 20:54:32,373 [salt.state       :1813][INFO    ][12517] Executing state pkg.installed for [iptables-persistent]
2019-05-29 20:54:32,382 [salt.state       :300 ][INFO    ][12517] All specified packages are already installed
2019-05-29 20:54:32,382 [salt.state       :1951][INFO    ][12517] Completed state [iptables-persistent] at time 20:54:32.382821 duration_in_ms=9.815
2019-05-29 20:54:32,383 [salt.state       :1780][INFO    ][12517] Running state [iptables_modules_v4_load] at time 20:54:32.383786
2019-05-29 20:54:32,384 [salt.state       :1813][INFO    ][12517] Executing state kmod.present for [iptables_modules_v4_load]
2019-05-29 20:54:32,384 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12517] Executing command 'lsmod' in directory '/root'
2019-05-29 20:54:32,407 [salt.state       :300 ][INFO    ][12517] Kernel modules iptable_filter, ip_tables are already present
2019-05-29 20:54:32,407 [salt.state       :1951][INFO    ][12517] Completed state [iptables_modules_v4_load] at time 20:54:32.407827 duration_in_ms=24.039
2019-05-29 20:54:32,409 [salt.state       :1780][INFO    ][12517] Running state [/etc/iptables/rules.v4] at time 20:54:32.409074
2019-05-29 20:54:32,409 [salt.state       :1813][INFO    ][12517] Executing state file.managed for [/etc/iptables/rules.v4]
2019-05-29 20:54:32,513 [salt.state       :300 ][INFO    ][12517] File /etc/iptables/rules.v4 is in the correct state
2019-05-29 20:54:32,514 [salt.state       :1951][INFO    ][12517] Completed state [/etc/iptables/rules.v4] at time 20:54:32.513938 duration_in_ms=104.864
2019-05-29 20:54:32,515 [salt.state       :1780][INFO    ][12517] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 20:54:32.515068
2019-05-29 20:54:32,515 [salt.state       :1813][INFO    ][12517] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-05-29 20:54:32,515 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12517] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-05-29 20:54:32,532 [salt.state       :300 ][INFO    ][12517] onlyif execution failed
2019-05-29 20:54:32,533 [salt.state       :1951][INFO    ][12517] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 20:54:32.533204 duration_in_ms=18.135
2019-05-29 20:54:32,536 [salt.state       :1780][INFO    ][12517] Running state [netfilter-persistent] at time 20:54:32.536667
2019-05-29 20:54:32,537 [salt.state       :1813][INFO    ][12517] Executing state service.running for [netfilter-persistent]
2019-05-29 20:54:32,538 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12517] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-05-29 20:54:32,564 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12517] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-05-29 20:54:32,587 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12517] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-05-29 20:54:32,610 [salt.state       :300 ][INFO    ][12517] The service netfilter-persistent is already running
2019-05-29 20:54:32,611 [salt.state       :1951][INFO    ][12517] Completed state [netfilter-persistent] at time 20:54:32.611072 duration_in_ms=74.404
2019-05-29 20:54:32,612 [salt.state       :1780][INFO    ][12517] Running state [iptables_extra.remove_stale_tables] at time 20:54:32.612625
2019-05-29 20:54:32,613 [salt.state       :1813][INFO    ][12517] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-05-29 20:54:32,614 [salt.state       :300 ][INFO    ][12517] No changes made for iptables_extra.remove_stale_tables
2019-05-29 20:54:32,614 [salt.state       :1951][INFO    ][12517] Completed state [iptables_extra.remove_stale_tables] at time 20:54:32.614335 duration_in_ms=1.711
2019-05-29 20:54:32,614 [salt.state       :1780][INFO    ][12517] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 20:54:32.614788
2019-05-29 20:54:32,615 [salt.state       :1813][INFO    ][12517] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-05-29 20:54:32,616 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12517] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-05-29 20:54:32,633 [salt.state       :300 ][INFO    ][12517] onlyif execution failed
2019-05-29 20:54:32,634 [salt.state       :1951][INFO    ][12517] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 20:54:32.634064 duration_in_ms=19.276
2019-05-29 20:54:32,635 [salt.state       :1780][INFO    ][12517] Running state [/etc/iptables/rules.v6] at time 20:54:32.635888
2019-05-29 20:54:32,636 [salt.state       :1813][INFO    ][12517] Executing state file.absent for [/etc/iptables/rules.v6]
2019-05-29 20:54:32,637 [salt.state       :300 ][INFO    ][12517] File /etc/iptables/rules.v6 is not present
2019-05-29 20:54:32,637 [salt.state       :1951][INFO    ][12517] Completed state [/etc/iptables/rules.v6] at time 20:54:32.637692 duration_in_ms=1.804
2019-05-29 20:54:32,639 [salt.state       :1780][INFO    ][12517] Running state [iptables_extra.flush_all] at time 20:54:32.638984
2019-05-29 20:54:32,639 [salt.state       :1813][INFO    ][12517] Executing state module.wait for [iptables_extra.flush_all]
2019-05-29 20:54:32,640 [salt.state       :300 ][INFO    ][12517] No changes made for iptables_extra.flush_all
2019-05-29 20:54:32,640 [salt.state       :1951][INFO    ][12517] Completed state [iptables_extra.flush_all] at time 20:54:32.640438 duration_in_ms=1.454
2019-05-29 20:54:32,647 [salt.minion      :1711][INFO    ][12517] Returning information for job: 20190529205421889132
2019-05-29 20:54:33,212 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command state.apply with jid 20190529205433199621
2019-05-29 20:54:33,239 [salt.minion      :1432][INFO    ][12597] Starting a new job with PID 12597
2019-05-29 20:54:34,157 [salt.state       :915 ][INFO    ][12597] Loading fresh modules for state activity
2019-05-29 20:54:35,055 [salt.state       :1780][INFO    ][12597] Running state [maas-rack-controller] at time 20:54:35.054977
2019-05-29 20:54:35,055 [salt.state       :1813][INFO    ][12597] Executing state pkg.installed for [maas-rack-controller]
2019-05-29 20:54:35,056 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12597] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-29 20:54:35,191 [salt.state       :300 ][INFO    ][12597] All specified packages are already installed
2019-05-29 20:54:35,192 [salt.state       :1951][INFO    ][12597] Completed state [maas-rack-controller] at time 20:54:35.192345 duration_in_ms=137.362
2019-05-29 20:54:35,192 [salt.state       :1780][INFO    ][12597] Running state [ipmitool] at time 20:54:35.192812
2019-05-29 20:54:35,193 [salt.state       :1813][INFO    ][12597] Executing state pkg.installed for [ipmitool]
2019-05-29 20:54:35,202 [salt.state       :300 ][INFO    ][12597] All specified packages are already installed
2019-05-29 20:54:35,203 [salt.state       :1951][INFO    ][12597] Completed state [ipmitool] at time 20:54:35.203041 duration_in_ms=10.229
2019-05-29 20:54:35,208 [salt.state       :1780][INFO    ][12597] Running state [/etc/maas/rackd.conf] at time 20:54:35.208809
2019-05-29 20:54:35,209 [salt.state       :1813][INFO    ][12597] Executing state file.line for [/etc/maas/rackd.conf]
2019-05-29 20:54:35,211 [salt.state       :300 ][INFO    ][12597] No changes needed to be made
2019-05-29 20:54:35,211 [salt.state       :1951][INFO    ][12597] Completed state [/etc/maas/rackd.conf] at time 20:54:35.211238 duration_in_ms=2.428
2019-05-29 20:54:35,211 [salt.state       :1780][INFO    ][12597] Running state [/etc/maas/rackd.conf] at time 20:54:35.211479
2019-05-29 20:54:35,211 [salt.state       :1813][INFO    ][12597] Executing state file.managed for [/etc/maas/rackd.conf]
2019-05-29 20:54:35,212 [salt.loaded.int.states.file:2298][WARNING ][12597] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-05-29 20:54:35,212 [salt.state       :300 ][INFO    ][12597] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-05-29 20:54:35,213 [salt.state       :1951][INFO    ][12597] Completed state [/etc/maas/rackd.conf] at time 20:54:35.213069 duration_in_ms=1.59
2019-05-29 20:54:35,214 [salt.state       :1780][INFO    ][12597] Running state [maas-rackd] at time 20:54:35.214196
2019-05-29 20:54:35,214 [salt.state       :1813][INFO    ][12597] Executing state service.running for [maas-rackd]
2019-05-29 20:54:35,215 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12597] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-05-29 20:54:35,261 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12597] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-05-29 20:54:35,285 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12597] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-05-29 20:54:35,309 [salt.state       :300 ][INFO    ][12597] The service maas-rackd is already running
2019-05-29 20:54:35,310 [salt.state       :1951][INFO    ][12597] Completed state [maas-rackd] at time 20:54:35.310217 duration_in_ms=96.021
2019-05-29 20:54:35,313 [salt.minion      :1711][INFO    ][12597] Returning information for job: 20190529205433199621
2019-05-29 20:54:35,874 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command state.apply with jid 20190529205435860083
2019-05-29 20:54:35,904 [salt.minion      :1432][INFO    ][12620] Starting a new job with PID 12620
2019-05-29 20:54:36,796 [salt.state       :915 ][INFO    ][12620] Loading fresh modules for state activity
2019-05-29 20:54:37,851 [salt.state       :1780][INFO    ][12620] Running state [maas-region-controller] at time 20:54:37.851209
2019-05-29 20:54:37,851 [salt.state       :1813][INFO    ][12620] Executing state pkg.installed for [maas-region-controller]
2019-05-29 20:54:37,852 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12620] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-29 20:54:37,975 [salt.state       :300 ][INFO    ][12620] All specified packages are already installed
2019-05-29 20:54:37,976 [salt.state       :1951][INFO    ][12620] Completed state [maas-region-controller] at time 20:54:37.976107 duration_in_ms=124.898
2019-05-29 20:54:37,976 [salt.state       :1780][INFO    ][12620] Running state [python-oauth] at time 20:54:37.976442
2019-05-29 20:54:37,976 [salt.state       :1813][INFO    ][12620] Executing state pkg.installed for [python-oauth]
2019-05-29 20:54:37,986 [salt.state       :300 ][INFO    ][12620] All specified packages are already installed
2019-05-29 20:54:37,986 [salt.state       :1951][INFO    ][12620] Completed state [python-oauth] at time 20:54:37.986407 duration_in_ms=9.965
2019-05-29 20:54:37,989 [salt.state       :1780][INFO    ][12620] Running state [/etc/maas/regiond.conf] at time 20:54:37.989585
2019-05-29 20:54:37,989 [salt.state       :1813][INFO    ][12620] Executing state file.replace for [/etc/maas/regiond.conf]
2019-05-29 20:54:37,994 [salt.state       :300 ][INFO    ][12620] No changes needed to be made
2019-05-29 20:54:37,995 [salt.state       :1951][INFO    ][12620] Completed state [/etc/maas/regiond.conf] at time 20:54:37.995156 duration_in_ms=5.571
2019-05-29 20:54:37,995 [salt.state       :1780][INFO    ][12620] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 20:54:37.995633
2019-05-29 20:54:37,995 [salt.state       :1813][INFO    ][12620] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-05-29 20:54:38,057 [salt.state       :300 ][INFO    ][12620] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-05-29 20:54:38,058 [salt.state       :1951][INFO    ][12620] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 20:54:38.058067 duration_in_ms=62.434
2019-05-29 20:54:38,058 [salt.state       :1780][INFO    ][12620] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 20:54:38.058548
2019-05-29 20:54:38,058 [salt.state       :1813][INFO    ][12620] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-29 20:54:38,062 [salt.state       :300 ][INFO    ][12620] No changes needed to be made
2019-05-29 20:54:38,062 [salt.state       :1951][INFO    ][12620] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 20:54:38.062537 duration_in_ms=3.989
2019-05-29 20:54:38,063 [salt.state       :1780][INFO    ][12620] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 20:54:38.063011
2019-05-29 20:54:38,063 [salt.state       :1813][INFO    ][12620] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-29 20:54:38,067 [salt.state       :300 ][INFO    ][12620] No changes needed to be made
2019-05-29 20:54:38,067 [salt.state       :1951][INFO    ][12620] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 20:54:38.067468 duration_in_ms=4.457
2019-05-29 20:54:38,067 [salt.state       :1780][INFO    ][12620] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 20:54:38.067942
2019-05-29 20:54:38,068 [salt.state       :1813][INFO    ][12620] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-05-29 20:54:38,096 [salt.state       :300 ][INFO    ][12620] No changes needed to be made
2019-05-29 20:54:38,097 [salt.state       :1951][INFO    ][12620] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 20:54:38.097081 duration_in_ms=29.139
2019-05-29 20:54:38,097 [salt.state       :1780][INFO    ][12620] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 20:54:38.097628
2019-05-29 20:54:38,097 [salt.state       :1813][INFO    ][12620] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-05-29 20:54:38,111 [salt.state       :300 ][INFO    ][12620] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-05-29 20:54:38,111 [salt.state       :1951][INFO    ][12620] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 20:54:38.111752 duration_in_ms=14.124
2019-05-29 20:54:38,113 [salt.state       :1780][INFO    ][12620] Running state [a2enmod headers] at time 20:54:38.113007
2019-05-29 20:54:38,113 [salt.state       :1813][INFO    ][12620] Executing state cmd.run for [a2enmod headers]
2019-05-29 20:54:38,116 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12620] Executing command 'a2enmod headers' in directory '/root'
2019-05-29 20:54:38,178 [salt.state       :300 ][INFO    ][12620] {'pid': 12639, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-05-29 20:54:38,179 [salt.state       :1951][INFO    ][12620] Completed state [a2enmod headers] at time 20:54:38.179147 duration_in_ms=66.139
2019-05-29 20:54:38,179 [salt.state       :1780][INFO    ][12620] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 20:54:38.179727
2019-05-29 20:54:38,180 [salt.state       :1813][INFO    ][12620] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-05-29 20:54:38,202 [salt.state       :300 ][INFO    ][12620] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-05-29 20:54:38,203 [salt.state       :1951][INFO    ][12620] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 20:54:38.203066 duration_in_ms=23.339
2019-05-29 20:54:38,203 [salt.state       :1780][INFO    ][12620] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 20:54:38.203744
2019-05-29 20:54:38,204 [salt.state       :1813][INFO    ][12620] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-05-29 20:54:38,267 [salt.state       :300 ][INFO    ][12620] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-05-29 20:54:38,268 [salt.state       :1951][INFO    ][12620] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 20:54:38.268025 duration_in_ms=64.281
2019-05-29 20:54:38,268 [salt.state       :1780][INFO    ][12620] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 20:54:38.268521
2019-05-29 20:54:38,268 [salt.state       :1813][INFO    ][12620] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-05-29 20:54:38,334 [salt.state       :300 ][INFO    ][12620] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-05-29 20:54:38,334 [salt.state       :1951][INFO    ][12620] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 20:54:38.334699 duration_in_ms=66.178
2019-05-29 20:54:38,335 [salt.state       :1780][INFO    ][12620] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 20:54:38.335210
2019-05-29 20:54:38,335 [salt.state       :1813][INFO    ][12620] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-05-29 20:54:38,409 [salt.state       :300 ][INFO    ][12620] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-05-29 20:54:38,410 [salt.state       :1951][INFO    ][12620] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 20:54:38.410060 duration_in_ms=74.849
2019-05-29 20:54:38,410 [salt.state       :1780][INFO    ][12620] Running state [/root/.pgpass] at time 20:54:38.410343
2019-05-29 20:54:38,410 [salt.state       :1813][INFO    ][12620] Executing state file.managed for [/root/.pgpass]
2019-05-29 20:54:38,463 [salt.state       :300 ][INFO    ][12620] File /root/.pgpass is in the correct state
2019-05-29 20:54:38,463 [salt.state       :1951][INFO    ][12620] Completed state [/root/.pgpass] at time 20:54:38.463348 duration_in_ms=53.005
2019-05-29 20:54:38,468 [salt.state       :1780][INFO    ][12620] Running state [maas-region syncdb --noinput] at time 20:54:38.468218
2019-05-29 20:54:38,468 [salt.state       :1813][INFO    ][12620] Executing state cmd.run for [maas-region syncdb --noinput]
2019-05-29 20:54:38,469 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12620] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-05-29 20:54:40,922 [salt.state       :300 ][INFO    ][12620] {'pid': 12652, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: sessions, metadataserver, piston3, contenttypes, auth, maasserver, sites\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-05-29 20:54:40,923 [salt.state       :1951][INFO    ][12620] Completed state [maas-region syncdb --noinput] at time 20:54:40.922931 duration_in_ms=2454.713
2019-05-29 20:54:40,923 [salt.state       :2022][WARNING ][12620] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-29 20:54:40,927 [salt.state       :1780][INFO    ][12620] Running state [maas-regiond] at time 20:54:40.927373
2019-05-29 20:54:40,928 [salt.state       :1813][INFO    ][12620] Executing state service.running for [maas-regiond]
2019-05-29 20:54:40,929 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12620] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-05-29 20:54:40,974 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12620] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-05-29 20:54:40,996 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12620] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-05-29 20:54:41,017 [salt.state       :300 ][INFO    ][12620] The service maas-regiond is already running
2019-05-29 20:54:41,020 [salt.state       :1951][INFO    ][12620] Completed state [maas-regiond] at time 20:54:41.020046 duration_in_ms=92.675
2019-05-29 20:54:41,023 [salt.state       :1780][INFO    ][12620] Running state [bind9] at time 20:54:41.023337
2019-05-29 20:54:41,023 [salt.state       :1813][INFO    ][12620] Executing state service.running for [bind9]
2019-05-29 20:54:41,025 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12620] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-05-29 20:54:41,049 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12620] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-05-29 20:54:41,071 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12620] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-05-29 20:54:41,094 [salt.state       :300 ][INFO    ][12620] The service bind9 is already running
2019-05-29 20:54:41,095 [salt.state       :1951][INFO    ][12620] Completed state [bind9] at time 20:54:41.094947 duration_in_ms=71.611
2019-05-29 20:54:41,097 [salt.state       :1780][INFO    ][12620] Running state [apache2] at time 20:54:41.097874
2019-05-29 20:54:41,098 [salt.state       :1813][INFO    ][12620] Executing state service.running for [apache2]
2019-05-29 20:54:41,099 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12620] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-05-29 20:54:41,120 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12620] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-05-29 20:54:41,143 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12620] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-05-29 20:54:41,171 [salt.state       :300 ][INFO    ][12620] The service apache2 is already running
2019-05-29 20:54:41,172 [salt.state       :1951][INFO    ][12620] Completed state [apache2] at time 20:54:41.172138 duration_in_ms=74.264
2019-05-29 20:54:41,177 [salt.state       :1780][INFO    ][12620] Running state [maasng.wait_for_http_code] at time 20:54:41.177165
2019-05-29 20:54:41,177 [salt.state       :1813][INFO    ][12620] Executing state module.run for [maasng.wait_for_http_code]
2019-05-29 20:54:41,178 [salt.utils.decorators:613 ][WARNING ][12620] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-29 20:54:41,291 [salt.state       :300 ][INFO    ][12620] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-05-29 20:54:41,292 [salt.state       :1951][INFO    ][12620] Completed state [maasng.wait_for_http_code] at time 20:54:41.291935 duration_in_ms=114.769
2019-05-29 20:54:41,293 [salt.state       :1780][INFO    ][12620] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 20:54:41.293234
2019-05-29 20:54:41,295 [salt.state       :1813][INFO    ][12620] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-05-29 20:54:41,296 [salt.state       :300 ][INFO    ][12620] /var/lib/maas/.setup_admin exists
2019-05-29 20:54:41,296 [salt.state       :1951][INFO    ][12620] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 20:54:41.296649 duration_in_ms=3.415
2019-05-29 20:54:41,298 [salt.state       :1780][INFO    ][12620] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:54:41.298499
2019-05-29 20:54:41,299 [salt.state       :1813][INFO    ][12620] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-29 20:54:41,299 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12620] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-29 20:54:42,992 [salt.state       :300 ][INFO    ][12620] {'pid': 12673, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-29 20:54:42,994 [salt.state       :1951][INFO    ][12620] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:54:42.993630 duration_in_ms=1695.13
2019-05-29 20:54:43,004 [salt.state       :1780][INFO    ][12620] Running state [maas_region_boot_source_resources_mirror] at time 20:54:43.004098
2019-05-29 20:54:43,004 [salt.state       :1813][INFO    ][12620] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-05-29 20:54:43,093 [salt.state       :300 ][INFO    ][12620] {'changes': {}}
2019-05-29 20:54:43,093 [salt.state       :1951][INFO    ][12620] Completed state [maas_region_boot_source_resources_mirror] at time 20:54:43.093566 duration_in_ms=89.468
2019-05-29 20:54:43,095 [salt.state       :1780][INFO    ][12620] Running state [maasng.boot_resources_import] at time 20:54:43.095290
2019-05-29 20:54:43,095 [salt.state       :1813][INFO    ][12620] Executing state module.run for [maasng.boot_resources_import]
2019-05-29 20:54:43,096 [salt.utils.decorators:613 ][WARNING ][12620] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-29 20:54:44,556 [salt.loaded.ext.module.maasng:1600][INFO    ][12620] Waiting boot-resources import done
sleep for:5s Left:899.0/900s
2019-05-29 20:54:49,627 [salt.loaded.ext.module.maasng:1600][INFO    ][12620] Waiting boot-resources import done
sleep for:5s Left:894.0/900s
2019-05-29 20:54:50,977 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529205450965675
2019-05-29 20:54:51,005 [salt.minion      :1432][INFO    ][12838] Starting a new job with PID 12838
2019-05-29 20:54:51,028 [salt.minion      :1711][INFO    ][12838] Returning information for job: 20190529205450965675
2019-05-29 20:54:54,730 [salt.state       :300 ][INFO    ][12620] {'ret': True}
2019-05-29 20:54:54,730 [salt.state       :1951][INFO    ][12620] Completed state [maasng.boot_resources_import] at time 20:54:54.730845 duration_in_ms=11635.553
2019-05-29 20:54:54,732 [salt.state       :1780][INFO    ][12620] Running state [maas_region_boot_sources_selection_xenial] at time 20:54:54.732312
2019-05-29 20:54:54,732 [salt.state       :1813][INFO    ][12620] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-05-29 20:54:54,921 [salt.state       :300 ][INFO    ][12620] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-05-29 20:54:54,922 [salt.state       :1951][INFO    ][12620] Completed state [maas_region_boot_sources_selection_xenial] at time 20:54:54.921996 duration_in_ms=189.684
2019-05-29 20:54:54,924 [salt.state       :1780][INFO    ][12620] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 20:54:54.923959
2019-05-29 20:54:54,924 [salt.state       :1813][INFO    ][12620] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-05-29 20:54:54,925 [salt.utils.decorators:613 ][WARNING ][12620] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-29 20:54:54,925 [salt.loaded.ext.module.maasng:1771][INFO    ][12620] boot-sources sync initiated for ALL Rack's
2019-05-29 20:54:56,010 [salt.state       :300 ][INFO    ][12620] {'ret': True}
2019-05-29 20:54:56,010 [salt.state       :1951][INFO    ][12620] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 20:54:56.010867 duration_in_ms=1086.907
2019-05-29 20:54:56,013 [salt.state       :1780][INFO    ][12620] Running state [maas.process_maas_config] at time 20:54:56.012955
2019-05-29 20:54:56,013 [salt.state       :1813][INFO    ][12620] Executing state module.run for [maas.process_maas_config]
2019-05-29 20:54:56,014 [salt.utils.decorators:613 ][WARNING ][12620] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-29 20:54:56,014 [salt.loaded.ext.module.maas:92  ][INFO    ][12620] maasconfig name=enable_http_proxy value=True
2019-05-29 20:54:56,071 [salt.loaded.ext.module.maas:92  ][INFO    ][12620] maasconfig name=upstream_dns value=8.8.8.8
2019-05-29 20:54:57,536 [salt.loaded.ext.module.maas:92  ][INFO    ][12620] maasconfig name=commissioning_distro_series value=xenial
2019-05-29 20:54:57,598 [salt.loaded.ext.module.maas:92  ][INFO    ][12620] maasconfig name=default_osystem value=ubuntu
2019-05-29 20:54:57,649 [salt.loaded.ext.module.maas:92  ][INFO    ][12620] maasconfig name=active_discovery_interval value=600
2019-05-29 20:54:57,685 [salt.loaded.ext.module.maas:92  ][INFO    ][12620] maasconfig name=dnssec_validation value=no
2019-05-29 20:54:57,735 [salt.loaded.ext.module.maas:92  ][INFO    ][12620] maasconfig name=maas_name value=mas01
2019-05-29 20:54:57,778 [salt.loaded.ext.module.maas:92  ][INFO    ][12620] maasconfig name=network_discovery value=enabled
2019-05-29 20:54:57,922 [salt.loaded.ext.module.maas:92  ][INFO    ][12620] maasconfig name=enable_third_party_drivers value=True
2019-05-29 20:54:57,980 [salt.loaded.ext.module.maas:92  ][INFO    ][12620] maasconfig name=default_storage_layout value=lvm
2019-05-29 20:54:58,038 [salt.loaded.ext.module.maas:92  ][INFO    ][12620] maasconfig name=ntp_external_only value=True
2019-05-29 20:54:58,087 [salt.loaded.ext.module.maas:92  ][INFO    ][12620] maasconfig name=disk_erase_with_secure_erase value=False
2019-05-29 20:54:58,136 [salt.loaded.ext.module.maas:92  ][INFO    ][12620] maasconfig name=default_distro_series value=xenial
2019-05-29 20:54:58,186 [salt.loaded.ext.module.maas:92  ][INFO    ][12620] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-05-29 20:54:58,289 [salt.state       :300 ][INFO    ][12620] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-05-29 20:54:58,289 [salt.state       :1951][INFO    ][12620] Completed state [maas.process_maas_config] at time 20:54:58.289323 duration_in_ms=2276.368
2019-05-29 20:54:58,290 [salt.state       :1780][INFO    ][12620] Running state [pxe_admin] at time 20:54:58.290111
2019-05-29 20:54:58,290 [salt.state       :1813][INFO    ][12620] Executing state maasng.fabric_present for [pxe_admin]
2019-05-29 20:54:58,356 [salt.loaded.ext.module.maasng:945 ][INFO    ][12620] [{u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None, u'name': u'fabric-2', u'id': 2}, {u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gwps4n', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u'', u'name': u'pxe_admin', u'id': 1}]
2019-05-29 20:54:58,433 [salt.loaded.ext.module.maasng:1008][WARNING ][12620] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-05-29 20:54:58,434 [salt.loaded.ext.module.maasng:1011][WARNING ][12620] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-05-29 20:54:58,525 [salt.state       :300 ][INFO    ][12620] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-05-29 20:54:58,527 [salt.state       :1951][INFO    ][12620] Completed state [pxe_admin] at time 20:54:58.527023 duration_in_ms=236.912
2019-05-29 20:54:58,527 [salt.state       :1780][INFO    ][12620] Running state [vlan 0] at time 20:54:58.527313
2019-05-29 20:54:58,527 [salt.state       :1813][INFO    ][12620] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-29 20:54:58,584 [salt.loaded.ext.module.maasng:945 ][INFO    ][12620] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gwps4n', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-29 20:54:58,690 [salt.loaded.ext.module.maasng:945 ][INFO    ][12620] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gwps4n', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-29 20:54:58,915 [salt.loaded.ext.module.maasng:945 ][INFO    ][12620] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gwps4n', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-29 20:54:58,988 [salt.state       :300 ][INFO    ][12620] {'new': 'Vlan untagged was updated'}
2019-05-29 20:54:58,988 [salt.state       :1951][INFO    ][12620] Completed state [vlan 0] at time 20:54:58.988472 duration_in_ms=461.156
2019-05-29 20:54:58,990 [salt.state       :1780][INFO    ][12620] Running state [192.168.11.0/24] at time 20:54:58.990784
2019-05-29 20:54:58,991 [salt.state       :1813][INFO    ][12620] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-05-29 20:54:59,143 [salt.loaded.ext.module.maasng:945 ][INFO    ][12620] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gwps4n', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u''}]
2019-05-29 20:54:59,143 [salt.loaded.ext.module.maasng:1235][WARNING ][12620] Ignoring parameter vlan:0
2019-05-29 20:54:59,244 [salt.state       :300 ][INFO    ][12620] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-05-29 20:54:59,245 [salt.state       :1951][INFO    ][12620] Completed state [192.168.11.0/24] at time 20:54:59.245106 duration_in_ms=254.321
2019-05-29 20:54:59,247 [salt.state       :1780][INFO    ][12620] Running state [maas_create_iprange_1] at time 20:54:59.247306
2019-05-29 20:54:59,247 [salt.state       :1813][INFO    ][12620] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-05-29 20:54:59,299 [salt.state       :300 ][INFO    ][12620] Iprange maas_create_iprange_1 already exist.
2019-05-29 20:54:59,299 [salt.state       :1951][INFO    ][12620] Completed state [maas_create_iprange_1] at time 20:54:59.299558 duration_in_ms=52.252
2019-05-29 20:54:59,299 [salt.state       :1780][INFO    ][12620] Running state [vlan 0] at time 20:54:59.299828
2019-05-29 20:54:59,300 [salt.state       :1813][INFO    ][12620] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-29 20:54:59,353 [salt.loaded.ext.module.maasng:945 ][INFO    ][12620] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gwps4n', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u''}]
2019-05-29 20:54:59,455 [salt.loaded.ext.module.maasng:945 ][INFO    ][12620] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gwps4n', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-29 20:54:59,914 [salt.loaded.ext.module.maasng:945 ][INFO    ][12620] [{u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None, u'name': u'fabric-2', u'id': 2}, {u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gwps4n', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u'', u'name': u'pxe_admin', u'id': 1}]
2019-05-29 20:55:00,006 [salt.state       :300 ][INFO    ][12620] {'new': 'Vlan untagged was updated'}
2019-05-29 20:55:00,007 [salt.state       :1951][INFO    ][12620] Completed state [vlan 0] at time 20:55:00.007028 duration_in_ms=707.199
2019-05-29 20:55:00,007 [salt.state       :1780][INFO    ][12620] Running state [opnfv] at time 20:55:00.007766
2019-05-29 20:55:00,008 [salt.state       :1813][INFO    ][12620] Executing state maasng.sshkey_present for [opnfv]
2019-05-29 20:55:00,045 [salt.loaded.ext.module.maasng:1903][INFO    ][12620] [{u'keysource': u'', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b', u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/'}]
2019-05-29 20:55:00,046 [salt.state       :300 ][INFO    ][12620] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-05-29 20:55:00,046 [salt.state       :1951][INFO    ][12620] Completed state [opnfv] at time 20:55:00.046654 duration_in_ms=38.887
2019-05-29 20:55:00,047 [salt.state       :1780][INFO    ][12620] Running state [maas.process_tags] at time 20:55:00.047691
2019-05-29 20:55:00,048 [salt.state       :1813][INFO    ][12620] Executing state module.run for [maas.process_tags]
2019-05-29 20:55:00,048 [salt.utils.decorators:613 ][WARNING ][12620] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-29 20:55:00,087 [salt.loaded.ext.module.maas:92  ][INFO    ][12620] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-05-29 20:55:00,160 [salt.state       :300 ][INFO    ][12620] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-05-29 20:55:00,161 [salt.state       :1951][INFO    ][12620] Completed state [maas.process_tags] at time 20:55:00.161144 duration_in_ms=113.453
2019-05-29 20:55:00,165 [salt.minion      :1711][INFO    ][12620] Returning information for job: 20190529205435860083
2019-05-29 20:55:00,766 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command state.apply with jid 20190529205500757547
2019-05-29 20:55:00,785 [salt.minion      :1432][INFO    ][13097] Starting a new job with PID 13097
2019-05-29 20:55:06,786 [salt.state       :915 ][INFO    ][13097] Loading fresh modules for state activity
2019-05-29 20:55:06,886 [salt.state       :1780][INFO    ][13097] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:55:06.886230
2019-05-29 20:55:06,886 [salt.state       :1813][INFO    ][13097] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-29 20:55:06,888 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13097] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-29 20:55:08,599 [salt.state       :300 ][INFO    ][13097] {'pid': 13120, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-29 20:55:08,600 [salt.state       :1951][INFO    ][13097] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:55:08.600044 duration_in_ms=1713.812
2019-05-29 20:55:08,603 [salt.state       :1780][INFO    ][13097] Running state [maas.process_machines] at time 20:55:08.603123
2019-05-29 20:55:08,604 [salt.state       :1813][INFO    ][13097] Executing state module.run for [maas.process_machines]
2019-05-29 20:55:08,608 [salt.utils.decorators:613 ][WARNING ][13097] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-29 20:55:09,081 [salt.loaded.ext.module.maas:412 ][WARNING ][13097] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-29 20:55:09,082 [salt.loaded.ext.module.maas:92  ][INFO    ][13097] 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=4ncgxy architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-29 20:55:10,287 [salt.loaded.ext.module.maas:412 ][WARNING ][13097] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-29 20:55:10,288 [salt.loaded.ext.module.maas:92  ][INFO    ][13097] 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=63an73 architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-29 20:55:11,572 [salt.loaded.ext.module.maas:412 ][WARNING ][13097] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-29 20:55:11,573 [salt.loaded.ext.module.maas:92  ][INFO    ][13097] 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=fd8b7w architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-29 20:55:12,834 [salt.loaded.ext.module.maas:412 ][WARNING ][13097] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-29 20:55:12,835 [salt.loaded.ext.module.maas:92  ][INFO    ][13097] 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=a6chp7 architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-29 20:55:14,041 [salt.state       :300 ][INFO    ][13097] {'ret': {'updated': ['gtw01', 'cmp002', 'cmp001', 'ctl01'], 'errors': {}, 'success': []}}
2019-05-29 20:55:14,043 [salt.state       :1951][INFO    ][13097] Completed state [maas.process_machines] at time 20:55:14.043277 duration_in_ms=5440.153
2019-05-29 20:55:14,047 [salt.minion      :1711][INFO    ][13097] Returning information for job: 20190529205500757547
2019-05-29 20:55:46,628 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command state.apply with jid 20190529205546615315
2019-05-29 20:55:46,657 [salt.minion      :1432][INFO    ][13316] Starting a new job with PID 13316
2019-05-29 20:55:52,554 [salt.state       :915 ][INFO    ][13316] Loading fresh modules for state activity
2019-05-29 20:55:52,666 [salt.state       :1780][INFO    ][13316] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:55:52.666318
2019-05-29 20:55:52,666 [salt.state       :1813][INFO    ][13316] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-29 20:55:52,668 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13316] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-29 20:55:54,347 [salt.state       :300 ][INFO    ][13316] {'pid': 13326, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-29 20:55:54,349 [salt.state       :1951][INFO    ][13316] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:55:54.348953 duration_in_ms=1682.634
2019-05-29 20:55:54,353 [salt.state       :1780][INFO    ][13316] Running state [maas.wait_for_machine_status] at time 20:55:54.353391
2019-05-29 20:55:54,354 [salt.state       :1813][INFO    ][13316] Executing state module.run for [maas.wait_for_machine_status]
2019-05-29 20:55:54,355 [salt.utils.decorators:613 ][WARNING ][13316] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-29 20:55:56,332 [salt.state       :300 ][INFO    ][13316] {'ret': True}
2019-05-29 20:55:56,332 [salt.state       :1951][INFO    ][13316] Completed state [maas.wait_for_machine_status] at time 20:55:56.332849 duration_in_ms=1979.457
2019-05-29 20:55:56,336 [salt.minion      :1711][INFO    ][13316] Returning information for job: 20190529205546615315
2019-05-29 20:55:56,897 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command state.apply with jid 20190529205556884010
2019-05-29 20:55:56,925 [salt.minion      :1432][INFO    ][13338] Starting a new job with PID 13338
2019-05-29 20:55:57,884 [salt.state       :915 ][INFO    ][13338] Loading fresh modules for state activity
2019-05-29 20:55:58,064 [salt.state       :1780][INFO    ][13338] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:55:58.064291
2019-05-29 20:55:58,064 [salt.state       :1813][INFO    ][13338] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-29 20:55:58,067 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13338] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-29 20:55:59,819 [salt.state       :300 ][INFO    ][13338] {'pid': 13364, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-29 20:55:59,820 [salt.state       :1951][INFO    ][13338] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:55:59.819927 duration_in_ms=1755.636
2019-05-29 20:55:59,824 [salt.state       :1780][INFO    ][13338] Running state [maas_machines_storage_cmp002_lvm] at time 20:55:59.824028
2019-05-29 20:55:59,824 [salt.state       :1813][INFO    ][13338] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-29 20:56:00,323 [salt.state       :300 ][INFO    ][13338] Machine cmp002 is not in Ready state.
2019-05-29 20:56:00,323 [salt.state       :1951][INFO    ][13338] Completed state [maas_machines_storage_cmp002_lvm] at time 20:56:00.323615 duration_in_ms=499.586
2019-05-29 20:56:00,324 [salt.state       :1780][INFO    ][13338] Running state [maas_machines_storage_cmp001_lvm] at time 20:56:00.324221
2019-05-29 20:56:00,324 [salt.state       :1813][INFO    ][13338] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-29 20:56:00,791 [salt.state       :300 ][INFO    ][13338] Machine cmp001 is not in Ready state.
2019-05-29 20:56:00,791 [salt.state       :1951][INFO    ][13338] Completed state [maas_machines_storage_cmp001_lvm] at time 20:56:00.791640 duration_in_ms=467.419
2019-05-29 20:56:00,798 [salt.minion      :1711][INFO    ][13338] Returning information for job: 20190529205556884010
2019-05-29 20:56:01,353 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command state.apply with jid 20190529205601340082
2019-05-29 20:56:01,381 [salt.minion      :1432][INFO    ][13389] Starting a new job with PID 13389
2019-05-29 20:56:02,265 [salt.state       :915 ][INFO    ][13389] Loading fresh modules for state activity
2019-05-29 20:56:02,358 [salt.state       :1780][INFO    ][13389] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:56:02.358259
2019-05-29 20:56:02,358 [salt.state       :1813][INFO    ][13389] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-29 20:56:02,360 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13389] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-29 20:56:04,092 [salt.state       :300 ][INFO    ][13389] {'pid': 13396, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-29 20:56:04,093 [salt.state       :1951][INFO    ][13389] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:56:04.092964 duration_in_ms=1734.704
2019-05-29 20:56:04,096 [salt.state       :1780][INFO    ][13389] Running state [maas.deploy_machines] at time 20:56:04.096826
2019-05-29 20:56:04,097 [salt.state       :1813][INFO    ][13389] Executing state module.run for [maas.deploy_machines]
2019-05-29 20:56:04,098 [salt.utils.decorators:613 ][WARNING ][13389] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-29 20:56:04,544 [salt.loaded.ext.module.maas:684 ][INFO    ][13389] deploymachines hwe_kernel=hwe-16.04 system_id=4ncgxy distro_series=xenial
2019-05-29 20:56:06,921 [salt.state       :300 ][INFO    ][13389] {'ret': {'updated': ['cmp002', 'cmp001', 'ctl01'], 'errors': {}, 'success': ['gtw01']}}
2019-05-29 20:56:06,921 [salt.state       :1951][INFO    ][13389] Completed state [maas.deploy_machines] at time 20:56:06.921356 duration_in_ms=2824.53
2019-05-29 20:56:06,923 [salt.minion      :1711][INFO    ][13389] Returning information for job: 20190529205601340082
2019-05-29 20:56:07,475 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command state.apply with jid 20190529205607467948
2019-05-29 20:56:07,500 [salt.minion      :1432][INFO    ][13458] Starting a new job with PID 13458
2019-05-29 20:56:08,367 [salt.state       :915 ][INFO    ][13458] Loading fresh modules for state activity
2019-05-29 20:56:08,464 [salt.state       :1780][INFO    ][13458] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:56:08.464037
2019-05-29 20:56:08,464 [salt.state       :1813][INFO    ][13458] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-29 20:56:08,466 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13458] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-29 20:56:10,171 [salt.state       :300 ][INFO    ][13458] {'pid': 13465, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-29 20:56:10,172 [salt.state       :1951][INFO    ][13458] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:56:10.172392 duration_in_ms=1708.354
2019-05-29 20:56:10,176 [salt.state       :1780][INFO    ][13458] Running state [maas.wait_for_machine_status] at time 20:56:10.176766
2019-05-29 20:56:10,177 [salt.state       :1813][INFO    ][13458] Executing state module.run for [maas.wait_for_machine_status]
2019-05-29 20:56:10,179 [salt.utils.decorators:613 ][WARNING ][13458] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-29 20:56:12,118 [salt.loaded.ext.module.maas:1023][INFO    ][13458] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2248.07292795s left)
2019-05-29 20:56:22,638 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529205622567357
2019-05-29 20:56:22,665 [salt.minion      :1432][INFO    ][13484] Starting a new job with PID 13484
2019-05-29 20:56:22,688 [salt.minion      :1711][INFO    ][13484] Returning information for job: 20190529205622567357
2019-05-29 20:56:44,009 [salt.loaded.ext.module.maas:1023][INFO    ][13458] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2216.18219995s left)
2019-05-29 20:56:52,720 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529205652703394
2019-05-29 20:56:52,746 [salt.minion      :1432][INFO    ][13512] Starting a new job with PID 13512
2019-05-29 20:56:52,769 [salt.minion      :1711][INFO    ][13512] Returning information for job: 20190529205652703394
2019-05-29 20:57:15,903 [salt.loaded.ext.module.maas:1023][INFO    ][13458] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2184.28785586s left)
2019-05-29 20:57:22,805 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529205722788532
2019-05-29 20:57:22,835 [salt.minion      :1432][INFO    ][13560] Starting a new job with PID 13560
2019-05-29 20:57:22,855 [salt.minion      :1711][INFO    ][13560] Returning information for job: 20190529205722788532
2019-05-29 20:57:47,888 [salt.loaded.ext.module.maas:1023][INFO    ][13458] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2152.30271006s left)
2019-05-29 20:57:52,877 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529205752863500
2019-05-29 20:57:52,906 [salt.minion      :1432][INFO    ][13595] Starting a new job with PID 13595
2019-05-29 20:57:52,928 [salt.minion      :1711][INFO    ][13595] Returning information for job: 20190529205752863500
2019-05-29 20:58:19,744 [salt.loaded.ext.module.maas:1023][INFO    ][13458] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2120.44715595s left)
2019-05-29 20:58:22,956 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529205822941508
2019-05-29 20:58:22,988 [salt.minion      :1432][INFO    ][13655] Starting a new job with PID 13655
2019-05-29 20:58:23,010 [salt.minion      :1711][INFO    ][13655] Returning information for job: 20190529205822941508
2019-05-29 20:58:51,775 [salt.loaded.ext.module.maas:1023][INFO    ][13458] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2088.41634393s left)
2019-05-29 20:58:53,045 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529205853028767
2019-05-29 20:58:53,076 [salt.minion      :1432][INFO    ][13683] Starting a new job with PID 13683
2019-05-29 20:58:53,104 [salt.minion      :1711][INFO    ][13683] Returning information for job: 20190529205853028767
2019-05-29 20:59:23,141 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529205923132755
2019-05-29 20:59:23,158 [salt.minion      :1432][INFO    ][13729] Starting a new job with PID 13729
2019-05-29 20:59:23,180 [salt.minion      :1711][INFO    ][13729] Returning information for job: 20190529205923132755
2019-05-29 20:59:23,683 [salt.loaded.ext.module.maas:1023][INFO    ][13458] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2056.50790691s left)
2019-05-29 20:59:53,221 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529205953204557
2019-05-29 20:59:53,248 [salt.minion      :1432][INFO    ][13757] Starting a new job with PID 13757
2019-05-29 20:59:53,272 [salt.minion      :1711][INFO    ][13757] Returning information for job: 20190529205953204557
2019-05-29 20:59:55,579 [salt.loaded.ext.module.maas:1023][INFO    ][13458] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2024.61162686s left)
2019-05-29 21:00:23,300 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529210023291668
2019-05-29 21:00:23,320 [salt.minion      :1432][INFO    ][13828] Starting a new job with PID 13828
2019-05-29 21:00:23,340 [salt.minion      :1711][INFO    ][13828] Returning information for job: 20190529210023291668
2019-05-29 21:00:27,263 [salt.loaded.ext.module.maas:1023][INFO    ][13458] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1992.92818093s left)
2019-05-29 21:00:53,374 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529210053360459
2019-05-29 21:00:53,403 [salt.minion      :1432][INFO    ][13882] Starting a new job with PID 13882
2019-05-29 21:00:53,434 [salt.minion      :1711][INFO    ][13882] Returning information for job: 20190529210053360459
2019-05-29 21:00:59,705 [salt.loaded.ext.module.maas:1023][INFO    ][13458] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1960.48571301s left)
2019-05-29 21:01:23,508 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529210123495150
2019-05-29 21:01:23,538 [salt.minion      :1432][INFO    ][14004] Starting a new job with PID 14004
2019-05-29 21:01:23,563 [salt.minion      :1711][INFO    ][14004] Returning information for job: 20190529210123495150
2019-05-29 21:01:31,704 [salt.loaded.ext.module.maas:1023][INFO    ][13458] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1928.48666096s left)
2019-05-29 21:01:53,607 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529210153591267
2019-05-29 21:01:53,637 [salt.minion      :1432][INFO    ][14032] Starting a new job with PID 14032
2019-05-29 21:01:53,663 [salt.minion      :1711][INFO    ][14032] Returning information for job: 20190529210153591267
2019-05-29 21:02:03,617 [salt.loaded.ext.module.maas:1023][INFO    ][13458] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1896.573807s left)
2019-05-29 21:02:23,745 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529210223731894
2019-05-29 21:02:23,772 [salt.minion      :1432][INFO    ][14168] Starting a new job with PID 14168
2019-05-29 21:02:23,799 [salt.minion      :1711][INFO    ][14168] Returning information for job: 20190529210223731894
2019-05-29 21:02:35,583 [salt.loaded.ext.module.maas:1023][INFO    ][13458] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1864.607795s left)
2019-05-29 21:02:53,854 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529210253839704
2019-05-29 21:02:53,879 [salt.minion      :1432][INFO    ][14201] Starting a new job with PID 14201
2019-05-29 21:02:53,901 [salt.minion      :1711][INFO    ][14201] Returning information for job: 20190529210253839704
2019-05-29 21:03:07,569 [salt.loaded.ext.module.maas:1023][INFO    ][13458] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1832.62212896s left)
2019-05-29 21:03:23,992 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529210323976248
2019-05-29 21:03:24,022 [salt.minion      :1432][INFO    ][14278] Starting a new job with PID 14278
2019-05-29 21:03:24,045 [salt.minion      :1711][INFO    ][14278] Returning information for job: 20190529210323976248
2019-05-29 21:03:39,856 [salt.loaded.ext.module.maas:1023][INFO    ][13458] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1800.33517289s left)
2019-05-29 21:03:54,143 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529210354127600
2019-05-29 21:03:54,175 [salt.minion      :1432][INFO    ][14307] Starting a new job with PID 14307
2019-05-29 21:03:54,198 [salt.minion      :1711][INFO    ][14307] Returning information for job: 20190529210354127600
2019-05-29 21:04:11,805 [salt.loaded.ext.module.maas:1023][INFO    ][13458] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1768.38571405s left)
2019-05-29 21:04:24,299 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529210424282805
2019-05-29 21:04:24,331 [salt.minion      :1432][INFO    ][14511] Starting a new job with PID 14511
2019-05-29 21:04:24,358 [salt.minion      :1711][INFO    ][14511] Returning information for job: 20190529210424282805
2019-05-29 21:04:43,781 [salt.loaded.ext.module.maas:1023][INFO    ][13458] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1736.41038895s left)
2019-05-29 21:04:54,452 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529210454435882
2019-05-29 21:04:54,478 [salt.minion      :1432][INFO    ][14542] Starting a new job with PID 14542
2019-05-29 21:04:54,506 [salt.minion      :1711][INFO    ][14542] Returning information for job: 20190529210454435882
2019-05-29 21:05:15,790 [salt.loaded.ext.module.maas:1023][INFO    ][13458] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1704.40090895s left)
2019-05-29 21:05:24,615 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529210524602977
2019-05-29 21:05:24,645 [salt.minion      :1432][INFO    ][14590] Starting a new job with PID 14590
2019-05-29 21:05:24,669 [salt.minion      :1711][INFO    ][14590] Returning information for job: 20190529210524602977
2019-05-29 21:05:48,041 [salt.loaded.ext.module.maas:1023][INFO    ][13458] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1672.15004301s left)
2019-05-29 21:05:54,771 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529210554763258
2019-05-29 21:05:54,799 [salt.minion      :1432][INFO    ][14617] Starting a new job with PID 14617
2019-05-29 21:05:54,825 [salt.minion      :1711][INFO    ][14617] Returning information for job: 20190529210554763258
2019-05-29 21:06:20,027 [salt.loaded.ext.module.maas:1023][INFO    ][13458] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1640.16404986s left)
2019-05-29 21:06:24,933 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529210624925025
2019-05-29 21:06:24,963 [salt.minion      :1432][INFO    ][14664] Starting a new job with PID 14664
2019-05-29 21:06:24,991 [salt.minion      :1711][INFO    ][14664] Returning information for job: 20190529210624925025
2019-05-29 21:06:51,988 [salt.loaded.ext.module.maas:1023][INFO    ][13458] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1608.20256805s left)
2019-05-29 21:06:55,114 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529210655098987
2019-05-29 21:06:55,143 [salt.minion      :1432][INFO    ][14693] Starting a new job with PID 14693
2019-05-29 21:06:55,167 [salt.minion      :1711][INFO    ][14693] Returning information for job: 20190529210655098987
2019-05-29 21:07:23,917 [salt.loaded.ext.module.maas:1023][INFO    ][13458] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1576.27443886s left)
2019-05-29 21:07:25,276 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529210725262499
2019-05-29 21:07:25,303 [salt.minion      :1432][INFO    ][14741] Starting a new job with PID 14741
2019-05-29 21:07:25,337 [salt.minion      :1711][INFO    ][14741] Returning information for job: 20190529210725262499
2019-05-29 21:07:55,474 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529210755456772
2019-05-29 21:07:55,500 [salt.minion      :1432][INFO    ][14779] Starting a new job with PID 14779
2019-05-29 21:07:55,528 [salt.minion      :1711][INFO    ][14779] Returning information for job: 20190529210755456772
2019-05-29 21:07:56,123 [salt.loaded.ext.module.maas:1023][INFO    ][13458] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1544.06790996s left)
2019-05-29 21:08:25,676 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command saltutil.find_job with jid 20190529210825659530
2019-05-29 21:08:25,700 [salt.minion      :1432][INFO    ][15006] Starting a new job with PID 15006
2019-05-29 21:08:25,722 [salt.minion      :1711][INFO    ][15006] Returning information for job: 20190529210825659530
2019-05-29 21:08:28,288 [salt.state       :300 ][INFO    ][13458] {'ret': True}
2019-05-29 21:08:28,288 [salt.state       :1951][INFO    ][13458] Completed state [maas.wait_for_machine_status] at time 21:08:28.288854 duration_in_ms=738112.088
2019-05-29 21:08:28,292 [salt.minion      :1711][INFO    ][13458] Returning information for job: 20190529205607467948
2019-05-29 21:08:54,434 [salt.utils.schedule:1377][INFO    ][5579] Running scheduled job: __mine_interval
2019-05-29 22:00:07,376 [salt.minion      :1308][INFO    ][5579] User sudo_ubuntu Executing command cp.push_dir with jid 20190529220007361699
2019-05-29 22:00:07,404 [salt.minion      :1432][INFO    ][18658] Starting a new job with PID 18658
