2019-06-25 02:04:29,488 [salt.utils.decorators:613 ][WARNING ][2344] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-25 02:04:30,384 [salt.utils.decorators:613 ][WARNING ][2344] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-25 02:04:33,243 [salt.loaded.int.states.file:2298][WARNING ][2595] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-06-25 02:04:57,192 [salt.state       :2022][WARNING ][2993] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-06-25 02:05:00,372 [salt.utils.decorators:613 ][WARNING ][2993] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-25 02:05:11,310 [salt.utils.decorators:613 ][WARNING ][2993] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-25 02:05:38,299 [salt.utils.decorators:613 ][WARNING ][2993] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-25 02:05:39,208 [salt.utils.decorators:613 ][WARNING ][2993] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-25 02:05:41,648 [salt.loaded.ext.module.maasng:1008][WARNING ][2993] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-06-25 02:05:41,648 [salt.loaded.ext.module.maasng:1011][WARNING ][2993] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-06-25 02:05:42,323 [salt.loaded.ext.module.maasng:1235][WARNING ][2993] Ignoring parameter vlan:0
2019-06-25 02:05:45,407 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5292] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-06-25 02:05:45,429 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5292] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-06-25 02:05:45,496 [salt.utils.parsers:1051][WARNING ][378] Minion received a SIGTERM. Exiting.
2019-06-25 02:05:46,535 [salt.cli.daemons :293 ][INFO    ][5369] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-06-25 02:05:46,647 [salt.cli.daemons :82  ][INFO    ][5369] Starting up the Salt Minion
2019-06-25 02:05:46,648 [salt.utils.event :1017][INFO    ][5369] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-06-25 02:05:47,727 [salt.minion      :976 ][INFO    ][5369] Creating minion process manager
2019-06-25 02:05:49,464 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][5369] Executing command ['date', '+%z'] in directory '/root'
2019-06-25 02:05:49,482 [salt.utils.schedule:568 ][INFO    ][5369] Updating job settings for scheduled job: __mine_interval
2019-06-25 02:05:49,484 [salt.minion      :1108][INFO    ][5369] Added mine.update to scheduler
2019-06-25 02:05:49,488 [salt.minion      :1975][INFO    ][5369] Minion is starting as user 'root'
2019-06-25 02:05:49,500 [salt.minion      :2336][INFO    ][5369] Minion is ready to receive requests!
2019-06-25 02:05:51,839 [salt.utils.decorators:613 ][WARNING ][5303] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-25 02:05:51,891 [salt.loaded.ext.module.maas:412 ][WARNING ][5303] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-25 02:05:53,313 [salt.loaded.ext.module.maas:412 ][WARNING ][5303] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-25 02:05:54,635 [salt.loaded.ext.module.maas:412 ][WARNING ][5303] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-25 02:05:55,752 [salt.loaded.ext.module.maas:412 ][WARNING ][5303] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-25 02:05:56,844 [salt.loaded.ext.module.maas:412 ][WARNING ][5303] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-25 02:06:28,961 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command state.apply with jid 20190625020628951859
2019-06-25 02:06:28,976 [salt.minion      :1432][INFO    ][5786] Starting a new job with PID 5786
2019-06-25 02:06:34,876 [salt.state       :915 ][INFO    ][5786] Loading fresh modules for state activity
2019-06-25 02:06:34,931 [salt.fileclient  :1219][INFO    ][5786] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-06-25 02:06:34,978 [salt.state       :1780][INFO    ][5786] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:06:34.978805
2019-06-25 02:06:34,979 [salt.state       :1813][INFO    ][5786] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-25 02:06:34,980 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5786] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-25 02:06:36,649 [salt.state       :300 ][INFO    ][5786] {'pid': 5806, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-25 02:06:36,651 [salt.state       :1951][INFO    ][5786] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:06:36.650904 duration_in_ms=1672.099
2019-06-25 02:06:36,655 [salt.state       :1780][INFO    ][5786] Running state [maas.wait_for_machine_status] at time 02:06:36.655029
2019-06-25 02:06:36,655 [salt.state       :1813][INFO    ][5786] Executing state module.run for [maas.wait_for_machine_status]
2019-06-25 02:06:36,656 [salt.utils.decorators:613 ][WARNING ][5786] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-25 02:06:37,824 [salt.loaded.ext.module.maas:1023][INFO    ][5786] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1498.84511304s left)
2019-06-25 02:06:44,044 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625020644035617
2019-06-25 02:06:44,070 [salt.minion      :1432][INFO    ][5818] Starting a new job with PID 5818
2019-06-25 02:06:44,091 [salt.minion      :1711][INFO    ][5818] Returning information for job: 20190625020644035617
2019-06-25 02:07:08,641 [salt.loaded.ext.module.maas:1023][INFO    ][5786] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.02772903s left)
2019-06-25 02:07:14,107 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625020714095769
2019-06-25 02:07:14,131 [salt.minion      :1432][INFO    ][5900] Starting a new job with PID 5900
2019-06-25 02:07:14,154 [salt.minion      :1711][INFO    ][5900] Returning information for job: 20190625020714095769
2019-06-25 02:07:39,394 [salt.loaded.ext.module.maas:1023][INFO    ][5786] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.27491903s left)
2019-06-25 02:07:44,180 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625020744171431
2019-06-25 02:07:44,200 [salt.minion      :1432][INFO    ][5925] Starting a new job with PID 5925
2019-06-25 02:07:44,230 [salt.minion      :1711][INFO    ][5925] Returning information for job: 20190625020744171431
2019-06-25 02:08:10,103 [salt.loaded.ext.module.maas:1023][INFO    ][5786] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.56577992s left)
2019-06-25 02:08:14,240 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625020814230098
2019-06-25 02:08:14,261 [salt.minion      :1432][INFO    ][5975] Starting a new job with PID 5975
2019-06-25 02:08:14,287 [salt.minion      :1711][INFO    ][5975] Returning information for job: 20190625020814230098
2019-06-25 02:08:41,161 [salt.loaded.ext.module.maas:1023][INFO    ][5786] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.50758505s left)
2019-06-25 02:08:44,323 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625020844310414
2019-06-25 02:08:44,345 [salt.minion      :1432][INFO    ][6026] Starting a new job with PID 6026
2019-06-25 02:08:44,373 [salt.minion      :1711][INFO    ][6026] Returning information for job: 20190625020844310414
2019-06-25 02:09:12,166 [salt.loaded.ext.module.maas:1023][INFO    ][5786] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.50285888s left)
2019-06-25 02:09:14,417 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625020914408124
2019-06-25 02:09:14,435 [salt.minion      :1432][INFO    ][6189] Starting a new job with PID 6189
2019-06-25 02:09:14,469 [salt.minion      :1711][INFO    ][6189] Returning information for job: 20190625020914408124
2019-06-25 02:09:43,382 [salt.loaded.ext.module.maas:1023][INFO    ][5786] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1313.28759193s left)
2019-06-25 02:09:44,514 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625020944500506
2019-06-25 02:09:44,540 [salt.minion      :1432][INFO    ][6307] Starting a new job with PID 6307
2019-06-25 02:09:44,567 [salt.minion      :1711][INFO    ][6307] Returning information for job: 20190625020944500506
2019-06-25 02:10:14,625 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625021014616578
2019-06-25 02:10:14,640 [salt.minion      :1432][INFO    ][6590] Starting a new job with PID 6590
2019-06-25 02:10:14,672 [salt.minion      :1711][INFO    ][6590] Returning information for job: 20190625021014616578
2019-06-25 02:10:14,860 [salt.loaded.ext.module.maas:1023][INFO    ][5786] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1281.80921698s left)
2019-06-25 02:10:44,767 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625021044751038
2019-06-25 02:10:44,789 [salt.minion      :1432][INFO    ][6707] Starting a new job with PID 6707
2019-06-25 02:10:44,815 [salt.minion      :1711][INFO    ][6707] Returning information for job: 20190625021044751038
2019-06-25 02:10:46,583 [salt.loaded.ext.module.maas:1023][INFO    ][5786] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1250.08617401s left)
2019-06-25 02:11:14,950 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625021114936598
2019-06-25 02:11:14,976 [salt.minion      :1432][INFO    ][7185] Starting a new job with PID 7185
2019-06-25 02:11:15,007 [salt.minion      :1711][INFO    ][7185] Returning information for job: 20190625021114936598
2019-06-25 02:11:19,068 [salt.loaded.ext.module.maas:1023][INFO    ][5786] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1217.60073996s left)
2019-06-25 02:11:45,078 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625021145055480
2019-06-25 02:11:45,106 [salt.minion      :1432][INFO    ][7287] Starting a new job with PID 7287
2019-06-25 02:11:45,131 [salt.minion      :1711][INFO    ][7287] Returning information for job: 20190625021145055480
2019-06-25 02:11:51,311 [salt.loaded.ext.module.maas:1023][INFO    ][5786] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1185.35815597s left)
2019-06-25 02:12:15,218 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625021215203881
2019-06-25 02:12:15,244 [salt.minion      :1432][INFO    ][7431] Starting a new job with PID 7431
2019-06-25 02:12:15,276 [salt.minion      :1711][INFO    ][7431] Returning information for job: 20190625021215203881
2019-06-25 02:12:24,111 [salt.state       :300 ][INFO    ][5786] {'ret': True}
2019-06-25 02:12:24,112 [salt.state       :1951][INFO    ][5786] Completed state [maas.wait_for_machine_status] at time 02:12:24.111948 duration_in_ms=347456.918
2019-06-25 02:12:24,116 [salt.minion      :1711][INFO    ][5786] Returning information for job: 20190625020628951859
2019-06-25 02:12:24,932 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command state.apply with jid 20190625021224918290
2019-06-25 02:12:24,957 [salt.minion      :1432][INFO    ][7447] Starting a new job with PID 7447
2019-06-25 02:12:31,158 [salt.state       :915 ][INFO    ][7447] Loading fresh modules for state activity
2019-06-25 02:12:31,228 [salt.fileclient  :1219][INFO    ][7447] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-06-25 02:12:31,329 [salt.state       :1780][INFO    ][7447] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:12:31.329057
2019-06-25 02:12:31,329 [salt.state       :1813][INFO    ][7447] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-25 02:12:31,335 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7447] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-25 02:12:33,372 [salt.state       :300 ][INFO    ][7447] {'pid': 7486, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-25 02:12:33,374 [salt.state       :1951][INFO    ][7447] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:12:33.374378 duration_in_ms=2045.32
2019-06-25 02:12:33,377 [salt.state       :1780][INFO    ][7447] Running state [maas_machines_storage_cmp002_lvm] at time 02:12:33.377579
2019-06-25 02:12:33,378 [salt.state       :1813][INFO    ][7447] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-06-25 02:12:34,554 [salt.loaded.ext.module.maasng:610 ][INFO    ][7447] ydp8df
2019-06-25 02:12:34,554 [salt.loaded.ext.module.maasng:626 ][INFO    ][7447] sda
2019-06-25 02:12:35,119 [salt.loaded.ext.module.maasng:361 ][INFO    ][7447] ydp8df
2019-06-25 02:12:35,228 [salt.loaded.ext.module.maasng:367 ][INFO    ][7447] [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'ydp8df', 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'resource_uri': u'/MAAS/api/2.0/nodes/ydp8df/blockdevices/1/', u'size': 800109715456, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'2db08cd5-a2e9-43eb-b558-17fc7b13f228', u'resource_uri': u'/MAAS/api/2.0/nodes/ydp8df/blockdevices/1/partition/1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ydp8df', u'filesystem': {u'uuid': u'26aefb63-8545-40cb-b521-1a30a30bf1ba', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 1, u'device_id': 1}]}, {u'model': None, u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'uuid': u'af359f12-46ce-40de-880f-1ba941572707', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'ydp8df', u'partition_table_type': None, u'filesystem': {u'uuid': u'4d9e5268-4634-45a3-bb0c-2a507a098cb8', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/ydp8df/blockdevices/3/', u'size': 800097042432, u'used_size': 800097042432, u'id': 3, u'serial': None, u'partitions': []}]
2019-06-25 02:12:35,229 [salt.loaded.ext.module.maasng:632 ][INFO    ][7447] vgroot
2019-06-25 02:12:35,229 [salt.loaded.ext.module.maasng:635 ][INFO    ][7447] lvroot
2019-06-25 02:12:35,230 [salt.loaded.ext.module.maasng:639 ][INFO    ][7447] 107374182400
2019-06-25 02:12:35,856 [salt.loaded.ext.module.maasng:645 ][INFO    ][7447] {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'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'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'status_action': u'', u'zone': {u'description': u'', u'id': 1, u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/ydp8df/', u'current_commissioning_result_id': 2, u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'ydp8df', 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'disable_ipv4': False, u'blockdevice_set': [{u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'90147166-99c7-4154-99d4-8005ac7eed68', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ydp8df', u'device_id': 1, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'd7624fa4-518c-4ca2-899e-4bc5a24f4041', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/ydp8df/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'size': 800101236736}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'ydp8df', 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'resource_uri': u'/MAAS/api/2.0/nodes/ydp8df/blockdevices/1/', u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}, {u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'33e70ffa-86e5-4797-852b-3aecf8e0760d', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'ydp8df', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'298c4d53-1e64-45ff-8438-5e8e05b04664', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/ydp8df/blockdevices/11/', u'used_size': 107374182400, u'id': 11, u'serial': None, u'size': 107374182400}], u'status': 4, u'storage_test_status': 2, u'cpu_count': 40, u'power_state': u'off', u'physicalblockdevice_set': [{u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'90147166-99c7-4154-99d4-8005ac7eed68', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ydp8df', u'device_id': 1, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'd7624fa4-518c-4ca2-899e-4bc5a24f4041', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/ydp8df/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'size': 800101236736}], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'ydp8df', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/ydp8df/blockdevices/1/', u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'ydp8df', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'90147166-99c7-4154-99d4-8005ac7eed68', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ydp8df', u'device_id': 1, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'd7624fa4-518c-4ca2-899e-4bc5a24f4041', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/ydp8df/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'size': 800101236736}], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'ydp8df', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/ydp8df/blockdevices/1/', u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}, 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'awmegn', 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.38', u'id': 18, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'awmegn', 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'awmegn', 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.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'ydp8df', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/ydp8df/interfaces/4/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:50', u'system_id': u'ydp8df', 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/ydp8df/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:07:51', u'system_id': u'ydp8df', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/ydp8df/interfaces/12/'}, {u'name': u'ens2f1', u'links': [{u'id': 19, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'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:12:49', u'system_id': u'ydp8df', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/ydp8df/interfaces/14/'}, {u'name': u'ens2f0', u'links': [{u'id': 20, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'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:12:48', u'system_id': u'ydp8df', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/ydp8df/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'awmegn', 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'id': 21, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'awmegn', 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'awmegn', 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.40'}], u'mac_address': u'9c:b6:54:8a:10:1c', u'system_id': u'ydp8df', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/ydp8df/interfaces/13/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'virtualblockdevice_set': [{u'block_size': 4096, u'uuid': u'33e70ffa-86e5-4797-852b-3aecf8e0760d', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'ydp8df', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'298c4d53-1e64-45ff-8438-5e8e05b04664', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/ydp8df/blockdevices/11/', u'used_size': 107374182400, u'id': 11, u'serial': None, u'size': 107374182400}], u'commissioning_status': 2, u'min_hwe_kernel': u'ga-16.04', u'commissioning_status_name': u'Passed', 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'awmegn', 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.38', u'id': 18, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'awmegn', 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'awmegn', 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.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'ydp8df', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/ydp8df/interfaces/4/'}, u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'node_type_name': u'Machine'}
2019-06-25 02:12:35,860 [salt.state       :300 ][INFO    ][7447] {'new': {'storage_layout': 'lvm'}}
2019-06-25 02:12:35,860 [salt.state       :1951][INFO    ][7447] Completed state [maas_machines_storage_cmp002_lvm] at time 02:12:35.860594 duration_in_ms=2483.013
2019-06-25 02:12:35,861 [salt.state       :1780][INFO    ][7447] Running state [maas_machines_storage_cmp001_lvm] at time 02:12:35.861127
2019-06-25 02:12:35,861 [salt.state       :1813][INFO    ][7447] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-06-25 02:12:37,006 [salt.loaded.ext.module.maasng:610 ][INFO    ][7447] n8r8tb
2019-06-25 02:12:37,007 [salt.loaded.ext.module.maasng:626 ][INFO    ][7447] sda
2019-06-25 02:12:37,567 [salt.loaded.ext.module.maasng:361 ][INFO    ][7447] n8r8tb
2019-06-25 02:12:37,686 [salt.loaded.ext.module.maasng:367 ][INFO    ][7447] [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'filesystem': None, u'name': u'sda', u'system_id': u'n8r8tb', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'3c5f8037-af2d-49aa-9084-96d444e151ec', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'n8r8tb', u'device_id': 2, u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'256fe805-3a38-4f9a-970f-7839257ff72e', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/n8r8tb/blockdevices/2/partition/2', u'type': u'partition', u'id': 2, u'size': 800101236736}], u'type': u'physical', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/n8r8tb/blockdevices/2/'}, {u'size': 800097042432, u'model': None, u'block_size': 4096, u'uuid': u'e2f07f5b-e70f-499f-8dfd-0ad171ed88d2', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 800097042432, u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'b3b1a113-da35-4bd9-ba83-77fa3b113ccb', u'label': u'root'}, u'name': u'vgroot-lvroot', u'system_id': u'n8r8tb', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'partitions': [], u'type': u'virtual', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/n8r8tb/blockdevices/4/'}]
2019-06-25 02:12:37,687 [salt.loaded.ext.module.maasng:632 ][INFO    ][7447] vgroot
2019-06-25 02:12:37,687 [salt.loaded.ext.module.maasng:635 ][INFO    ][7447] lvroot
2019-06-25 02:12:37,688 [salt.loaded.ext.module.maasng:639 ][INFO    ][7447] 107374182400
2019-06-25 02:12:38,294 [salt.loaded.ext.module.maasng:645 ][INFO    ][7447] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'disable_ipv4': False, u'cpu_count': 40, u'owner_data': {}, u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'fqdn': u'cmp001.maas', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'boot_disk': {u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'426afa8f-9bb8-454d-8dfe-c3f617b98013', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'n8r8tb', u'device_id': 2, u'filesystem': {u'uuid': u'6685a595-78ad-4af2-aed1-36ad27899691', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/n8r8tb/blockdevices/2/partition/7'}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'n8r8tb', 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'size': 800109715456, u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/n8r8tb/blockdevices/2/'}, u'blockdevice_set': [{u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'426afa8f-9bb8-454d-8dfe-c3f617b98013', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'n8r8tb', u'device_id': 2, u'filesystem': {u'uuid': u'6685a595-78ad-4af2-aed1-36ad27899691', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/n8r8tb/blockdevices/2/partition/7'}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'n8r8tb', 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'size': 800109715456, u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/n8r8tb/blockdevices/2/'}, {u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'uuid': u'9dea81e2-8a57-4758-955f-cf129c9e186c', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'n8r8tb', u'partition_table_type': None, u'filesystem': {u'uuid': u'2572e461-0790-4cb9-a128-c6a43e03760b', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'size': 107374182400, u'type': u'virtual', u'id': 12, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/n8r8tb/blockdevices/12/'}], u'zone': {u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default', u'description': u''}, u'resource_uri': u'/MAAS/api/2.0/machines/n8r8tb/', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'n8r8tb', 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'ip_addresses': [u'192.168.11.39', u'192.168.11.41'], u'architecture': u'amd64/generic', u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'power_state': u'off', u'power_type': u'ipmi', u'physicalblockdevice_set': [{u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'426afa8f-9bb8-454d-8dfe-c3f617b98013', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'n8r8tb', u'device_id': 2, u'filesystem': {u'uuid': u'6685a595-78ad-4af2-aed1-36ad27899691', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/n8r8tb/blockdevices/2/partition/7'}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'n8r8tb', 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'size': 800109715456, u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/n8r8tb/blockdevices/2/'}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'n8r8tb', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'commissioning_status_name': u'Passed', u'current_testing_result_id': 5, u'cpu_test_status': -1, u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'status_action': u'', u'virtualblockdevice_set': [{u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'uuid': u'9dea81e2-8a57-4758-955f-cf129c9e186c', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'n8r8tb', u'partition_table_type': None, u'filesystem': {u'uuid': u'2572e461-0790-4cb9-a128-c6a43e03760b', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'size': 107374182400, u'type': u'virtual', u'id': 12, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/n8r8tb/blockdevices/12/'}], u'commissioning_status': 2, u'min_hwe_kernel': u'ga-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'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'awmegn', u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'id': 24, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'awmegn', u'mtu': 1500, 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'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'awmegn', u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'n8r8tb', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/n8r8tb/interfaces/5/'}, 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'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'awmegn', u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'id': 24, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'awmegn', u'mtu': 1500, 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'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'awmegn', u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'n8r8tb', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/n8r8tb/interfaces/5/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d5', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'n8r8tb', u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/n8r8tb/interfaces/17/'}, {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'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'n8r8tb', u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/n8r8tb/interfaces/20/'}, {u'name': u'ens2f0', u'links': [{u'id': 25, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cc', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'n8r8tb', u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/n8r8tb/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'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'awmegn', u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 26, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'awmegn', u'mtu': 1500, 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'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'awmegn', u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.41'}], u'mac_address': u'9c:b6:54:8a:95:a4', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'n8r8tb', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/n8r8tb/interfaces/18/'}, {u'name': u'ens2f1', u'links': [{u'id': 27, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cd', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'n8r8tb', u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/n8r8tb/interfaces/19/'}], u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'node_type_name': u'Machine'}
2019-06-25 02:12:38,297 [salt.state       :300 ][INFO    ][7447] {'new': {'storage_layout': 'lvm'}}
2019-06-25 02:12:38,298 [salt.state       :1951][INFO    ][7447] Completed state [maas_machines_storage_cmp001_lvm] at time 02:12:38.298282 duration_in_ms=2437.155
2019-06-25 02:12:38,301 [salt.minion      :1711][INFO    ][7447] Returning information for job: 20190625021224918290
2019-06-25 02:12:39,004 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command state.apply with jid 20190625021238992224
2019-06-25 02:12:39,029 [salt.minion      :1432][INFO    ][7506] Starting a new job with PID 7506
2019-06-25 02:12:40,071 [salt.state       :915 ][INFO    ][7506] Loading fresh modules for state activity
2019-06-25 02:12:40,131 [salt.fileclient  :1219][INFO    ][7506] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-06-25 02:12:40,186 [salt.state       :1780][INFO    ][7506] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:12:40.186908
2019-06-25 02:12:40,187 [salt.state       :1813][INFO    ][7506] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-25 02:12:40,189 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7506] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-25 02:12:42,089 [salt.state       :300 ][INFO    ][7506] {'pid': 7513, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-25 02:12:42,090 [salt.state       :1951][INFO    ][7506] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:12:42.090342 duration_in_ms=1903.434
2019-06-25 02:12:42,093 [salt.state       :1780][INFO    ][7506] Running state [maas.deploy_machines] at time 02:12:42.093368
2019-06-25 02:12:42,094 [salt.state       :1813][INFO    ][7506] Executing state module.run for [maas.deploy_machines]
2019-06-25 02:12:42,094 [salt.utils.decorators:613 ][WARNING ][7506] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-25 02:12:42,676 [salt.loaded.ext.module.maas:684 ][INFO    ][7506] deploymachines hwe_kernel=ga-16.04 system_id=ydp8df distro_series=xenial
2019-06-25 02:12:45,451 [salt.loaded.ext.module.maas:684 ][INFO    ][7506] deploymachines hwe_kernel=ga-16.04 system_id=n8r8tb distro_series=xenial
2019-06-25 02:12:47,945 [salt.loaded.ext.module.maas:684 ][INFO    ][7506] deploymachines hwe_kernel=ga-16.04 system_id=q4xqxs distro_series=xenial
2019-06-25 02:12:50,561 [salt.loaded.ext.module.maas:684 ][INFO    ][7506] deploymachines hwe_kernel=ga-16.04 system_id=qxcxdx distro_series=xenial
2019-06-25 02:12:53,119 [salt.loaded.ext.module.maas:684 ][INFO    ][7506] deploymachines hwe_kernel=ga-16.04 system_id=pqh7ct distro_series=xenial
2019-06-25 02:12:54,035 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625021254019308
2019-06-25 02:12:54,067 [salt.minion      :1432][INFO    ][7760] Starting a new job with PID 7760
2019-06-25 02:12:54,099 [salt.minion      :1711][INFO    ][7760] Returning information for job: 20190625021254019308
2019-06-25 02:12:55,715 [salt.state       :300 ][INFO    ][7506] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-06-25 02:12:55,715 [salt.state       :1951][INFO    ][7506] Completed state [maas.deploy_machines] at time 02:12:55.715787 duration_in_ms=13622.418
2019-06-25 02:12:55,722 [salt.minion      :1711][INFO    ][7506] Returning information for job: 20190625021238992224
2019-06-25 02:12:56,444 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command state.apply with jid 20190625021256428434
2019-06-25 02:12:56,468 [salt.minion      :1432][INFO    ][7799] Starting a new job with PID 7799
2019-06-25 02:13:02,693 [salt.state       :915 ][INFO    ][7799] Loading fresh modules for state activity
2019-06-25 02:13:02,760 [salt.fileclient  :1219][INFO    ][7799] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-06-25 02:13:02,817 [salt.state       :1780][INFO    ][7799] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:13:02.816950
2019-06-25 02:13:02,817 [salt.state       :1813][INFO    ][7799] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-25 02:13:02,819 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7799] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-25 02:13:04,741 [salt.state       :300 ][INFO    ][7799] {'pid': 7844, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-25 02:13:04,744 [salt.state       :1951][INFO    ][7799] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:13:04.743902 duration_in_ms=1926.952
2019-06-25 02:13:04,748 [salt.state       :1780][INFO    ][7799] Running state [maas.wait_for_machine_status] at time 02:13:04.748634
2019-06-25 02:13:04,749 [salt.state       :1813][INFO    ][7799] Executing state module.run for [maas.wait_for_machine_status]
2019-06-25 02:13:04,750 [salt.utils.decorators:613 ][WARNING ][7799] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-25 02:13:07,836 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.92999506s left)
2019-06-25 02:13:11,542 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625021311525993
2019-06-25 02:13:11,572 [salt.minion      :1432][INFO    ][7863] Starting a new job with PID 7863
2019-06-25 02:13:11,602 [salt.minion      :1711][INFO    ][7863] Returning information for job: 20190625021311525993
2019-06-25 02:13:40,603 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.16299605s left)
2019-06-25 02:13:41,638 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625021341627916
2019-06-25 02:13:41,664 [salt.minion      :1432][INFO    ][7889] Starting a new job with PID 7889
2019-06-25 02:13:41,693 [salt.minion      :1711][INFO    ][7889] Returning information for job: 20190625021341627916
2019-06-25 02:14:11,732 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625021411717555
2019-06-25 02:14:11,757 [salt.minion      :1432][INFO    ][7939] Starting a new job with PID 7939
2019-06-25 02:14:11,788 [salt.minion      :1711][INFO    ][7939] Returning information for job: 20190625021411717555
2019-06-25 02:14:13,496 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.26938605s left)
2019-06-25 02:14:41,814 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625021441803241
2019-06-25 02:14:41,831 [salt.minion      :1432][INFO    ][7962] Starting a new job with PID 7962
2019-06-25 02:14:41,862 [salt.minion      :1711][INFO    ][7962] Returning information for job: 20190625021441803241
2019-06-25 02:14:46,523 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2148.24275899s left)
2019-06-25 02:15:11,910 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625021511888822
2019-06-25 02:15:11,939 [salt.minion      :1432][INFO    ][8032] Starting a new job with PID 8032
2019-06-25 02:15:11,965 [salt.minion      :1711][INFO    ][8032] Returning information for job: 20190625021511888822
2019-06-25 02:15:19,530 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2115.23538399s left)
2019-06-25 02:15:42,013 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625021541993533
2019-06-25 02:15:42,041 [salt.minion      :1432][INFO    ][8078] Starting a new job with PID 8078
2019-06-25 02:15:42,066 [salt.minion      :1711][INFO    ][8078] Returning information for job: 20190625021541993533
2019-06-25 02:15:52,317 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2082.44862294s left)
2019-06-25 02:16:12,105 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625021612094229
2019-06-25 02:16:12,132 [salt.minion      :1432][INFO    ][8237] Starting a new job with PID 8237
2019-06-25 02:16:12,162 [salt.minion      :1711][INFO    ][8237] Returning information for job: 20190625021612094229
2019-06-25 02:16:25,277 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2049.48832107s left)
2019-06-25 02:16:42,216 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625021642205406
2019-06-25 02:16:42,244 [salt.minion      :1432][INFO    ][8352] Starting a new job with PID 8352
2019-06-25 02:16:42,274 [salt.minion      :1711][INFO    ][8352] Returning information for job: 20190625021642205406
2019-06-25 02:16:58,425 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2016.34071708s left)
2019-06-25 02:17:12,365 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625021712351890
2019-06-25 02:17:12,394 [salt.minion      :1432][INFO    ][8635] Starting a new job with PID 8635
2019-06-25 02:17:12,424 [salt.minion      :1711][INFO    ][8635] Returning information for job: 20190625021712351890
2019-06-25 02:17:31,522 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1983.24372005s left)
2019-06-25 02:17:42,485 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625021742469705
2019-06-25 02:17:42,514 [salt.minion      :1432][INFO    ][8740] Starting a new job with PID 8740
2019-06-25 02:17:42,543 [salt.minion      :1711][INFO    ][8740] Returning information for job: 20190625021742469705
2019-06-25 02:18:04,989 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1949.77710795s left)
2019-06-25 02:18:12,668 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625021812652650
2019-06-25 02:18:12,696 [salt.minion      :1432][INFO    ][9081] Starting a new job with PID 9081
2019-06-25 02:18:12,726 [salt.minion      :1711][INFO    ][9081] Returning information for job: 20190625021812652650
2019-06-25 02:18:37,928 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1916.83750987s left)
2019-06-25 02:18:42,812 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625021842795668
2019-06-25 02:18:42,839 [salt.minion      :1432][INFO    ][9225] Starting a new job with PID 9225
2019-06-25 02:18:42,868 [salt.minion      :1711][INFO    ][9225] Returning information for job: 20190625021842795668
2019-06-25 02:19:10,720 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1884.04612899s left)
2019-06-25 02:19:12,947 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625021912934791
2019-06-25 02:19:12,977 [salt.minion      :1432][INFO    ][9365] Starting a new job with PID 9365
2019-06-25 02:19:13,004 [salt.minion      :1711][INFO    ][9365] Returning information for job: 20190625021912934791
2019-06-25 02:19:43,111 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625021943099543
2019-06-25 02:19:43,133 [salt.minion      :1432][INFO    ][9481] Starting a new job with PID 9481
2019-06-25 02:19:43,162 [salt.minion      :1711][INFO    ][9481] Returning information for job: 20190625021943099543
2019-06-25 02:19:43,828 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1850.93818903s left)
2019-06-25 02:20:13,282 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625022013270046
2019-06-25 02:20:13,307 [salt.minion      :1432][INFO    ][9734] Starting a new job with PID 9734
2019-06-25 02:20:13,337 [salt.minion      :1711][INFO    ][9734] Returning information for job: 20190625022013270046
2019-06-25 02:20:16,992 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1817.77353907s left)
2019-06-25 02:20:43,429 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625022043413630
2019-06-25 02:20:43,458 [salt.minion      :1432][INFO    ][9801] Starting a new job with PID 9801
2019-06-25 02:20:43,484 [salt.minion      :1711][INFO    ][9801] Returning information for job: 20190625022043413630
2019-06-25 02:20:49,811 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1784.95478988s left)
2019-06-25 02:21:13,612 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625022113601655
2019-06-25 02:21:13,630 [salt.minion      :1432][INFO    ][10016] Starting a new job with PID 10016
2019-06-25 02:21:13,654 [salt.minion      :1711][INFO    ][10016] Returning information for job: 20190625022113601655
2019-06-25 02:21:22,686 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1752.08002996s left)
2019-06-25 02:21:43,738 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625022143725629
2019-06-25 02:21:43,769 [salt.minion      :1432][INFO    ][10080] Starting a new job with PID 10080
2019-06-25 02:21:43,796 [salt.minion      :1711][INFO    ][10080] Returning information for job: 20190625022143725629
2019-06-25 02:21:55,573 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1719.192981s left)
2019-06-25 02:22:13,902 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625022213887154
2019-06-25 02:22:13,927 [salt.minion      :1432][INFO    ][10167] Starting a new job with PID 10167
2019-06-25 02:22:13,959 [salt.minion      :1711][INFO    ][10167] Returning information for job: 20190625022213887154
2019-06-25 02:22:28,530 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1686.23651099s left)
2019-06-25 02:22:44,000 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625022243987309
2019-06-25 02:22:44,034 [salt.minion      :1432][INFO    ][10270] Starting a new job with PID 10270
2019-06-25 02:22:44,065 [salt.minion      :1711][INFO    ][10270] Returning information for job: 20190625022243987309
2019-06-25 02:23:02,001 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1652.76456189s left)
2019-06-25 02:23:14,231 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625022314207991
2019-06-25 02:23:14,262 [salt.minion      :1432][INFO    ][10448] Starting a new job with PID 10448
2019-06-25 02:23:14,302 [salt.minion      :1711][INFO    ][10448] Returning information for job: 20190625022314207991
2019-06-25 02:23:34,839 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1619.92625809s left)
2019-06-25 02:23:44,410 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625022344398536
2019-06-25 02:23:44,436 [salt.minion      :1432][INFO    ][10489] Starting a new job with PID 10489
2019-06-25 02:23:44,465 [salt.minion      :1711][INFO    ][10489] Returning information for job: 20190625022344398536
2019-06-25 02:24:07,768 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1586.99795389s left)
2019-06-25 02:24:14,596 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625022414579629
2019-06-25 02:24:14,622 [salt.minion      :1432][INFO    ][10538] Starting a new job with PID 10538
2019-06-25 02:24:14,652 [salt.minion      :1711][INFO    ][10538] Returning information for job: 20190625022414579629
2019-06-25 02:24:40,528 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1554.23818302s left)
2019-06-25 02:24:44,779 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625022444768303
2019-06-25 02:24:44,811 [salt.minion      :1432][INFO    ][10563] Starting a new job with PID 10563
2019-06-25 02:24:44,848 [salt.minion      :1711][INFO    ][10563] Returning information for job: 20190625022444768303
2019-06-25 02:25:13,527 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1521.23826504s left)
2019-06-25 02:25:14,802 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625022514791667
2019-06-25 02:25:14,830 [salt.minion      :1432][INFO    ][10804] Starting a new job with PID 10804
2019-06-25 02:25:14,864 [salt.minion      :1711][INFO    ][10804] Returning information for job: 20190625022514791667
2019-06-25 02:25:45,008 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625022544995744
2019-06-25 02:25:45,033 [salt.minion      :1432][INFO    ][10918] Starting a new job with PID 10918
2019-06-25 02:25:45,065 [salt.minion      :1711][INFO    ][10918] Returning information for job: 20190625022544995744
2019-06-25 02:25:46,197 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1488.56859207s left)
2019-06-25 02:26:15,068 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625022615059177
2019-06-25 02:26:15,099 [salt.minion      :1432][INFO    ][11121] Starting a new job with PID 11121
2019-06-25 02:26:15,130 [salt.minion      :1711][INFO    ][11121] Returning information for job: 20190625022615059177
2019-06-25 02:26:19,016 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1455.74977493s left)
2019-06-25 02:26:45,104 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625022645089944
2019-06-25 02:26:45,136 [salt.minion      :1432][INFO    ][11172] Starting a new job with PID 11172
2019-06-25 02:26:45,169 [salt.minion      :1711][INFO    ][11172] Returning information for job: 20190625022645089944
2019-06-25 02:26:51,735 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1423.030514s left)
2019-06-25 02:27:15,163 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625022715150410
2019-06-25 02:27:15,192 [salt.minion      :1432][INFO    ][11222] Starting a new job with PID 11222
2019-06-25 02:27:15,222 [salt.minion      :1711][INFO    ][11222] Returning information for job: 20190625022715150410
2019-06-25 02:27:24,584 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1390.18132186s left)
2019-06-25 02:27:45,236 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625022745226480
2019-06-25 02:27:45,267 [salt.minion      :1432][INFO    ][11247] Starting a new job with PID 11247
2019-06-25 02:27:45,303 [salt.minion      :1711][INFO    ][11247] Returning information for job: 20190625022745226480
2019-06-25 02:27:57,733 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1357.03326297s left)
2019-06-25 02:28:15,297 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625022815281667
2019-06-25 02:28:15,325 [salt.minion      :1432][INFO    ][11299] Starting a new job with PID 11299
2019-06-25 02:28:15,353 [salt.minion      :1711][INFO    ][11299] Returning information for job: 20190625022815281667
2019-06-25 02:28:30,564 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1324.20194793s left)
2019-06-25 02:28:45,372 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625022845361596
2019-06-25 02:28:45,400 [salt.minion      :1432][INFO    ][11324] Starting a new job with PID 11324
2019-06-25 02:28:45,428 [salt.minion      :1711][INFO    ][11324] Returning information for job: 20190625022845361596
2019-06-25 02:29:03,292 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1291.47336006s left)
2019-06-25 02:29:15,452 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625022915436442
2019-06-25 02:29:15,484 [salt.minion      :1432][INFO    ][11379] Starting a new job with PID 11379
2019-06-25 02:29:15,511 [salt.minion      :1711][INFO    ][11379] Returning information for job: 20190625022915436442
2019-06-25 02:29:36,189 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1258.57712388s left)
2019-06-25 02:29:45,567 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625022945551415
2019-06-25 02:29:45,596 [salt.minion      :1432][INFO    ][11403] Starting a new job with PID 11403
2019-06-25 02:29:45,625 [salt.minion      :1711][INFO    ][11403] Returning information for job: 20190625022945551415
2019-06-25 02:30:08,924 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1225.84161305s left)
2019-06-25 02:30:15,661 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625023015644492
2019-06-25 02:30:15,697 [salt.minion      :1432][INFO    ][11466] Starting a new job with PID 11466
2019-06-25 02:30:15,726 [salt.minion      :1711][INFO    ][11466] Returning information for job: 20190625023015644492
2019-06-25 02:30:41,824 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1192.94184899s left)
2019-06-25 02:30:45,816 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625023045805591
2019-06-25 02:30:45,846 [salt.minion      :1432][INFO    ][11492] Starting a new job with PID 11492
2019-06-25 02:30:45,881 [salt.minion      :1711][INFO    ][11492] Returning information for job: 20190625023045805591
2019-06-25 02:31:14,463 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1160.30233788s left)
2019-06-25 02:31:15,956 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625023115940145
2019-06-25 02:31:15,985 [salt.minion      :1432][INFO    ][11551] Starting a new job with PID 11551
2019-06-25 02:31:16,020 [salt.minion      :1711][INFO    ][11551] Returning information for job: 20190625023115940145
2019-06-25 02:31:46,097 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625023146083691
2019-06-25 02:31:46,121 [salt.minion      :1432][INFO    ][11573] Starting a new job with PID 11573
2019-06-25 02:31:46,150 [salt.minion      :1711][INFO    ][11573] Returning information for job: 20190625023146083691
2019-06-25 02:31:47,470 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1127.29568005s left)
2019-06-25 02:32:16,247 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625023216234215
2019-06-25 02:32:16,267 [salt.minion      :1432][INFO    ][11624] Starting a new job with PID 11624
2019-06-25 02:32:16,295 [salt.minion      :1711][INFO    ][11624] Returning information for job: 20190625023216234215
2019-06-25 02:32:20,128 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1094.63730097s left)
2019-06-25 02:32:46,414 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625023246401408
2019-06-25 02:32:46,443 [salt.minion      :1432][INFO    ][11651] Starting a new job with PID 11651
2019-06-25 02:32:46,470 [salt.minion      :1711][INFO    ][11651] Returning information for job: 20190625023246401408
2019-06-25 02:32:53,184 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1061.58186698s left)
2019-06-25 02:33:16,604 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625023316587963
2019-06-25 02:33:16,630 [salt.minion      :1432][INFO    ][11702] Starting a new job with PID 11702
2019-06-25 02:33:16,659 [salt.minion      :1711][INFO    ][11702] Returning information for job: 20190625023316587963
2019-06-25 02:33:25,880 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1028.88577199s left)
2019-06-25 02:33:46,789 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625023346775133
2019-06-25 02:33:46,825 [salt.minion      :1432][INFO    ][11738] Starting a new job with PID 11738
2019-06-25 02:33:46,860 [salt.minion      :1711][INFO    ][11738] Returning information for job: 20190625023346775133
2019-06-25 02:33:58,763 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (996.002356052s left)
2019-06-25 02:34:16,820 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625023416809138
2019-06-25 02:34:16,847 [salt.minion      :1432][INFO    ][11786] Starting a new job with PID 11786
2019-06-25 02:34:16,876 [salt.minion      :1711][INFO    ][11786] Returning information for job: 20190625023416809138
2019-06-25 02:34:31,630 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (963.135766029s left)
2019-06-25 02:34:46,855 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625023446842673
2019-06-25 02:34:46,884 [salt.minion      :1432][INFO    ][11826] Starting a new job with PID 11826
2019-06-25 02:34:46,916 [salt.minion      :1711][INFO    ][11826] Returning information for job: 20190625023446842673
2019-06-25 02:35:04,535 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (930.230629921s left)
2019-06-25 02:35:16,903 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625023516890845
2019-06-25 02:35:16,929 [salt.minion      :1432][INFO    ][11889] Starting a new job with PID 11889
2019-06-25 02:35:16,961 [salt.minion      :1711][INFO    ][11889] Returning information for job: 20190625023516890845
2019-06-25 02:35:37,128 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (897.637575865s left)
2019-06-25 02:35:46,940 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625023546927349
2019-06-25 02:35:46,966 [salt.minion      :1432][INFO    ][11922] Starting a new job with PID 11922
2019-06-25 02:35:46,996 [salt.minion      :1711][INFO    ][11922] Returning information for job: 20190625023546927349
2019-06-25 02:36:10,119 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (864.646920919s left)
2019-06-25 02:36:17,017 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625023617004668
2019-06-25 02:36:17,044 [salt.minion      :1432][INFO    ][11968] Starting a new job with PID 11968
2019-06-25 02:36:17,080 [salt.minion      :1711][INFO    ][11968] Returning information for job: 20190625023617004668
2019-06-25 02:36:42,933 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (831.832658052s left)
2019-06-25 02:36:47,107 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625023647092184
2019-06-25 02:36:47,130 [salt.minion      :1432][INFO    ][12008] Starting a new job with PID 12008
2019-06-25 02:36:47,159 [salt.minion      :1711][INFO    ][12008] Returning information for job: 20190625023647092184
2019-06-25 02:37:15,727 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (799.038426876s left)
2019-06-25 02:37:17,214 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625023717200024
2019-06-25 02:37:17,233 [salt.minion      :1432][INFO    ][12049] Starting a new job with PID 12049
2019-06-25 02:37:17,262 [salt.minion      :1711][INFO    ][12049] Returning information for job: 20190625023717200024
2019-06-25 02:37:47,319 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625023747311233
2019-06-25 02:37:47,335 [salt.minion      :1432][INFO    ][12080] Starting a new job with PID 12080
2019-06-25 02:37:47,363 [salt.minion      :1711][INFO    ][12080] Returning information for job: 20190625023747311233
2019-06-25 02:37:48,467 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (766.29863286s left)
2019-06-25 02:38:17,447 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625023817435623
2019-06-25 02:38:17,468 [salt.minion      :1432][INFO    ][12121] Starting a new job with PID 12121
2019-06-25 02:38:17,497 [salt.minion      :1711][INFO    ][12121] Returning information for job: 20190625023817435623
2019-06-25 02:38:21,201 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (733.565231085s left)
2019-06-25 02:38:47,624 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625023847609419
2019-06-25 02:38:47,653 [salt.minion      :1432][INFO    ][12157] Starting a new job with PID 12157
2019-06-25 02:38:47,684 [salt.minion      :1711][INFO    ][12157] Returning information for job: 20190625023847609419
2019-06-25 02:38:53,809 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (700.957197905s left)
2019-06-25 02:39:17,776 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625023917763854
2019-06-25 02:39:17,805 [salt.minion      :1432][INFO    ][12201] Starting a new job with PID 12201
2019-06-25 02:39:17,836 [salt.minion      :1711][INFO    ][12201] Returning information for job: 20190625023917763854
2019-06-25 02:39:26,859 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (667.90627408s left)
2019-06-25 02:39:47,998 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625023947987322
2019-06-25 02:39:48,025 [salt.minion      :1432][INFO    ][12232] Starting a new job with PID 12232
2019-06-25 02:39:48,056 [salt.minion      :1711][INFO    ][12232] Returning information for job: 20190625023947987322
2019-06-25 02:39:59,518 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (635.247336864s left)
2019-06-25 02:40:18,214 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625024018202223
2019-06-25 02:40:18,240 [salt.minion      :1432][INFO    ][12275] Starting a new job with PID 12275
2019-06-25 02:40:18,270 [salt.minion      :1711][INFO    ][12275] Returning information for job: 20190625024018202223
2019-06-25 02:40:32,464 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (602.301594973s left)
2019-06-25 02:40:48,259 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625024048245385
2019-06-25 02:40:48,279 [salt.minion      :1432][INFO    ][12308] Starting a new job with PID 12308
2019-06-25 02:40:48,311 [salt.minion      :1711][INFO    ][12308] Returning information for job: 20190625024048245385
2019-06-25 02:41:05,275 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (569.491080999s left)
2019-06-25 02:41:18,477 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625024118468312
2019-06-25 02:41:18,503 [salt.minion      :1432][INFO    ][12355] Starting a new job with PID 12355
2019-06-25 02:41:18,534 [salt.minion      :1711][INFO    ][12355] Returning information for job: 20190625024118468312
2019-06-25 02:41:38,012 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (536.753933907s left)
2019-06-25 02:41:48,556 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625024148541400
2019-06-25 02:41:48,581 [salt.minion      :1432][INFO    ][12387] Starting a new job with PID 12387
2019-06-25 02:41:48,611 [salt.minion      :1711][INFO    ][12387] Returning information for job: 20190625024148541400
2019-06-25 02:42:10,712 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (504.053994894s left)
2019-06-25 02:42:18,631 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625024218611758
2019-06-25 02:42:18,659 [salt.minion      :1432][INFO    ][12428] Starting a new job with PID 12428
2019-06-25 02:42:18,701 [salt.minion      :1711][INFO    ][12428] Returning information for job: 20190625024218611758
2019-06-25 02:42:43,577 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (471.188551903s left)
2019-06-25 02:42:48,774 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625024248759144
2019-06-25 02:42:48,799 [salt.minion      :1432][INFO    ][12465] Starting a new job with PID 12465
2019-06-25 02:42:48,832 [salt.minion      :1711][INFO    ][12465] Returning information for job: 20190625024248759144
2019-06-25 02:43:16,702 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (438.063410997s left)
2019-06-25 02:43:18,870 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625024318856990
2019-06-25 02:43:18,898 [salt.minion      :1432][INFO    ][12507] Starting a new job with PID 12507
2019-06-25 02:43:18,930 [salt.minion      :1711][INFO    ][12507] Returning information for job: 20190625024318856990
2019-06-25 02:43:49,052 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625024349041188
2019-06-25 02:43:49,072 [salt.minion      :1432][INFO    ][12539] Starting a new job with PID 12539
2019-06-25 02:43:49,114 [salt.minion      :1711][INFO    ][12539] Returning information for job: 20190625024349041188
2019-06-25 02:43:49,488 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (405.277567863s left)
2019-06-25 02:44:19,243 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625024419233027
2019-06-25 02:44:19,265 [salt.minion      :1432][INFO    ][12576] Starting a new job with PID 12576
2019-06-25 02:44:19,294 [salt.minion      :1711][INFO    ][12576] Returning information for job: 20190625024419233027
2019-06-25 02:44:22,171 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (372.594315052s left)
2019-06-25 02:44:49,436 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625024449422276
2019-06-25 02:44:49,465 [salt.minion      :1432][INFO    ][12739] Starting a new job with PID 12739
2019-06-25 02:44:49,509 [salt.minion      :1711][INFO    ][12739] Returning information for job: 20190625024449422276
2019-06-25 02:44:55,019 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (339.746492863s left)
2019-06-25 02:45:19,483 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625024519470048
2019-06-25 02:45:19,510 [salt.minion      :1432][INFO    ][12799] Starting a new job with PID 12799
2019-06-25 02:45:19,539 [salt.minion      :1711][INFO    ][12799] Returning information for job: 20190625024519470048
2019-06-25 02:45:27,533 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (307.232980013s left)
2019-06-25 02:45:49,699 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625024549684792
2019-06-25 02:45:49,729 [salt.minion      :1432][INFO    ][12831] Starting a new job with PID 12831
2019-06-25 02:45:49,761 [salt.minion      :1711][INFO    ][12831] Returning information for job: 20190625024549684792
2019-06-25 02:46:00,557 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (274.208852053s left)
2019-06-25 02:46:19,783 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625024619771704
2019-06-25 02:46:19,807 [salt.minion      :1432][INFO    ][12872] Starting a new job with PID 12872
2019-06-25 02:46:19,843 [salt.minion      :1711][INFO    ][12872] Returning information for job: 20190625024619771704
2019-06-25 02:46:33,588 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (241.177835941s left)
2019-06-25 02:46:49,833 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625024649819852
2019-06-25 02:46:49,859 [salt.minion      :1432][INFO    ][12908] Starting a new job with PID 12908
2019-06-25 02:46:49,891 [salt.minion      :1711][INFO    ][12908] Returning information for job: 20190625024649819852
2019-06-25 02:47:06,495 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (208.270288944s left)
2019-06-25 02:47:19,933 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625024719917663
2019-06-25 02:47:19,960 [salt.minion      :1432][INFO    ][12950] Starting a new job with PID 12950
2019-06-25 02:47:19,990 [salt.minion      :1711][INFO    ][12950] Returning information for job: 20190625024719917663
2019-06-25 02:47:39,088 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (175.677471876s left)
2019-06-25 02:47:49,980 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625024749965595
2019-06-25 02:47:50,005 [salt.minion      :1432][INFO    ][12987] Starting a new job with PID 12987
2019-06-25 02:47:50,035 [salt.minion      :1711][INFO    ][12987] Returning information for job: 20190625024749965595
2019-06-25 02:48:11,775 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (142.990777016s left)
2019-06-25 02:48:20,122 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625024820112233
2019-06-25 02:48:20,157 [salt.minion      :1432][INFO    ][13031] Starting a new job with PID 13031
2019-06-25 02:48:20,187 [salt.minion      :1711][INFO    ][13031] Returning information for job: 20190625024820112233
2019-06-25 02:48:44,495 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (110.270482063s left)
2019-06-25 02:48:50,228 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625024850209204
2019-06-25 02:48:50,254 [salt.minion      :1432][INFO    ][13064] Starting a new job with PID 13064
2019-06-25 02:48:50,283 [salt.minion      :1711][INFO    ][13064] Returning information for job: 20190625024850209204
2019-06-25 02:49:17,635 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (77.1307520866s left)
2019-06-25 02:49:20,392 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625024920375359
2019-06-25 02:49:20,418 [salt.minion      :1432][INFO    ][13110] Starting a new job with PID 13110
2019-06-25 02:49:20,445 [salt.minion      :1711][INFO    ][13110] Returning information for job: 20190625024920375359
2019-06-25 02:49:50,240 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (44.5253798962s left)
2019-06-25 02:49:50,561 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625024950547293
2019-06-25 02:49:50,585 [salt.minion      :1432][INFO    ][13144] Starting a new job with PID 13144
2019-06-25 02:49:50,615 [salt.minion      :1711][INFO    ][13144] Returning information for job: 20190625024950547293
2019-06-25 02:50:20,747 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625025020732264
2019-06-25 02:50:20,771 [salt.minion      :1432][INFO    ][13186] Starting a new job with PID 13186
2019-06-25 02:50:20,800 [salt.minion      :1711][INFO    ][13186] Returning information for job: 20190625025020732264
2019-06-25 02:50:23,251 [salt.loaded.ext.module.maas:1023][INFO    ][7799] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (11.5149428844s left)
2019-06-25 02:50:50,956 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625025050947959
2019-06-25 02:50:50,981 [salt.minion      :1432][INFO    ][13217] Starting a new job with PID 13217
2019-06-25 02:50:51,011 [salt.minion      :1711][INFO    ][13217] Returning information for job: 20190625025050947959
2019-06-25 02:50:55,823 [salt.state       :302 ][ERROR   ][7799] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-06-25 02:50:55,824 [salt.state       :1951][INFO    ][7799] Completed state [maas.wait_for_machine_status] at time 02:50:55.824278 duration_in_ms=2271075.642
2019-06-25 02:50:55,829 [salt.minion      :1711][INFO    ][7799] Returning information for job: 20190625021256428434
2019-06-25 02:51:06,801 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command pillar.get with jid 20190625025106789818
2019-06-25 02:51:06,827 [salt.minion      :1432][INFO    ][13259] Starting a new job with PID 13259
2019-06-25 02:51:06,836 [salt.minion      :1711][INFO    ][13259] Returning information for job: 20190625025106789818
2019-06-25 02:51:07,543 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command service.status with jid 20190625025107533864
2019-06-25 02:51:07,568 [salt.minion      :1432][INFO    ][13264] Starting a new job with PID 13264
2019-06-25 02:51:08,541 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13264] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-06-25 02:51:08,584 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13264] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-06-25 02:51:08,604 [salt.minion      :1711][INFO    ][13264] Returning information for job: 20190625025107533864
2019-06-25 02:51:09,325 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command state.apply with jid 20190625025109315837
2019-06-25 02:51:09,347 [salt.minion      :1432][INFO    ][13275] Starting a new job with PID 13275
2019-06-25 02:51:15,435 [salt.state       :915 ][INFO    ][13275] Loading fresh modules for state activity
2019-06-25 02:51:16,099 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13275] Executing command 'salt-minion --version' in directory '/root'
2019-06-25 02:51:16,468 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13275] Executing command 'salt-minion --version' in directory '/root'
2019-06-25 02:51:17,604 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13275] Executing command 'salt-minion --version' in directory '/root'
2019-06-25 02:51:17,959 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13275] Executing command 'salt-minion --version' in directory '/root'
2019-06-25 02:51:20,221 [salt.state       :1780][INFO    ][13275] Running state [salt-minion] at time 02:51:20.221152
2019-06-25 02:51:20,221 [salt.state       :1813][INFO    ][13275] Executing state pkg.installed for [salt-minion]
2019-06-25 02:51:20,223 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13275] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-25 02:51:20,347 [salt.state       :300 ][INFO    ][13275] All specified packages are already installed
2019-06-25 02:51:20,347 [salt.state       :1951][INFO    ][13275] Completed state [salt-minion] at time 02:51:20.347476 duration_in_ms=126.324
2019-06-25 02:51:20,347 [salt.state       :1780][INFO    ][13275] Running state [salt_minion_dependency_packages] at time 02:51:20.347850
2019-06-25 02:51:20,348 [salt.state       :1813][INFO    ][13275] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-06-25 02:51:20,358 [salt.state       :300 ][INFO    ][13275] All specified packages are already installed
2019-06-25 02:51:20,358 [salt.state       :1951][INFO    ][13275] Completed state [salt_minion_dependency_packages] at time 02:51:20.358687 duration_in_ms=10.837
2019-06-25 02:51:20,361 [salt.state       :1780][INFO    ][13275] Running state [/etc/salt/minion.d/minion.conf] at time 02:51:20.361790
2019-06-25 02:51:20,362 [salt.state       :1813][INFO    ][13275] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-06-25 02:51:20,632 [salt.state       :300 ][INFO    ][13275] File /etc/salt/minion.d/minion.conf is in the correct state
2019-06-25 02:51:20,632 [salt.state       :1951][INFO    ][13275] Completed state [/etc/salt/minion.d/minion.conf] at time 02:51:20.632547 duration_in_ms=270.757
2019-06-25 02:51:20,632 [salt.state       :1780][INFO    ][13275] Running state [python-netaddr] at time 02:51:20.632849
2019-06-25 02:51:20,633 [salt.state       :1813][INFO    ][13275] Executing state pkg.installed for [python-netaddr]
2019-06-25 02:51:20,642 [salt.state       :300 ][INFO    ][13275] All specified packages are already installed
2019-06-25 02:51:20,642 [salt.state       :1951][INFO    ][13275] Completed state [python-netaddr] at time 02:51:20.642586 duration_in_ms=9.737
2019-06-25 02:51:20,646 [salt.state       :1780][INFO    ][13275] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:51:20.645667
2019-06-25 02:51:20,646 [salt.state       :1813][INFO    ][13275] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-06-25 02:51:20,662 [salt.state       :300 ][INFO    ][13275] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-06-25 02:51:20,663 [salt.state       :1951][INFO    ][13275] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:51:20.663195 duration_in_ms=17.528
2019-06-25 02:51:20,664 [salt.state       :1780][INFO    ][13275] Running state [salt-minion] at time 02:51:20.664865
2019-06-25 02:51:20,665 [salt.state       :1813][INFO    ][13275] Executing state service.running for [salt-minion]
2019-06-25 02:51:20,667 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13275] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-06-25 02:51:20,712 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13275] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-06-25 02:51:20,734 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13275] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-06-25 02:51:20,757 [salt.state       :300 ][INFO    ][13275] The service salt-minion is already running
2019-06-25 02:51:20,757 [salt.state       :1951][INFO    ][13275] Completed state [salt-minion] at time 02:51:20.757678 duration_in_ms=92.813
2019-06-25 02:51:20,760 [salt.state       :1780][INFO    ][13275] Running state [/etc/salt/grains.d] at time 02:51:20.760671
2019-06-25 02:51:20,761 [salt.state       :1813][INFO    ][13275] Executing state file.directory for [/etc/salt/grains.d]
2019-06-25 02:51:20,765 [salt.state       :300 ][INFO    ][13275] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-06-25 02:51:20,765 [salt.state       :1951][INFO    ][13275] Completed state [/etc/salt/grains.d] at time 02:51:20.765679 duration_in_ms=5.008
2019-06-25 02:51:20,766 [salt.state       :1780][INFO    ][13275] Running state [/etc/salt/grains] at time 02:51:20.766586
2019-06-25 02:51:20,767 [salt.state       :1813][INFO    ][13275] Executing state file.managed for [/etc/salt/grains]
2019-06-25 02:51:20,767 [salt.state       :300 ][INFO    ][13275] File /etc/salt/grains exists with proper permissions. No changes made.
2019-06-25 02:51:20,768 [salt.state       :1951][INFO    ][13275] Completed state [/etc/salt/grains] at time 02:51:20.767970 duration_in_ms=1.384
2019-06-25 02:51:20,768 [salt.state       :1780][INFO    ][13275] Running state [/etc/salt/grains.d/placeholder] at time 02:51:20.768609
2019-06-25 02:51:20,769 [salt.state       :1813][INFO    ][13275] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-06-25 02:51:20,769 [salt.state       :300 ][INFO    ][13275] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-06-25 02:51:20,770 [salt.state       :1951][INFO    ][13275] Completed state [/etc/salt/grains.d/placeholder] at time 02:51:20.769978 duration_in_ms=1.369
2019-06-25 02:51:20,770 [salt.state       :1780][INFO    ][13275] Running state [/etc/salt/grains.d/sphinx] at time 02:51:20.770619
2019-06-25 02:51:20,771 [salt.state       :1813][INFO    ][13275] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-06-25 02:51:20,772 [salt.state       :300 ][INFO    ][13275] File /etc/salt/grains.d/sphinx is in the correct state
2019-06-25 02:51:20,772 [salt.state       :1951][INFO    ][13275] Completed state [/etc/salt/grains.d/sphinx] at time 02:51:20.772874 duration_in_ms=2.254
2019-06-25 02:51:20,777 [salt.state       :1780][INFO    ][13275] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:51:20.777064
2019-06-25 02:51:20,777 [salt.state       :1813][INFO    ][13275] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-06-25 02:51:20,778 [salt.state       :300 ][INFO    ][13275] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-06-25 02:51:20,778 [salt.state       :1951][INFO    ][13275] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:51:20.778535 duration_in_ms=1.471
2019-06-25 02:51:20,779 [salt.state       :1780][INFO    ][13275] Running state [/etc/salt/grains.d/dns_records] at time 02:51:20.779053
2019-06-25 02:51:20,779 [salt.state       :1813][INFO    ][13275] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-06-25 02:51:20,780 [salt.state       :300 ][INFO    ][13275] File /etc/salt/grains.d/dns_records is in the correct state
2019-06-25 02:51:20,780 [salt.state       :1951][INFO    ][13275] Completed state [/etc/salt/grains.d/dns_records] at time 02:51:20.780662 duration_in_ms=1.609
2019-06-25 02:51:20,781 [salt.state       :1780][INFO    ][13275] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:51:20.781623
2019-06-25 02:51:20,781 [salt.state       :1813][INFO    ][13275] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-06-25 02:51:20,782 [salt.state       :300 ][INFO    ][13275] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-06-25 02:51:20,782 [salt.state       :1951][INFO    ][13275] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:51:20.782477 duration_in_ms=0.853
2019-06-25 02:51:20,783 [salt.state       :1780][INFO    ][13275] Running state [/etc/salt/grains.d/salt] at time 02:51:20.782989
2019-06-25 02:51:20,783 [salt.state       :1813][INFO    ][13275] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-06-25 02:51:20,784 [salt.state       :300 ][INFO    ][13275] File /etc/salt/grains.d/salt is in the correct state
2019-06-25 02:51:20,784 [salt.state       :1951][INFO    ][13275] Completed state [/etc/salt/grains.d/salt] at time 02:51:20.784564 duration_in_ms=1.575
2019-06-25 02:51:20,785 [salt.state       :1780][INFO    ][13275] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:51:20.785495
2019-06-25 02:51:20,787 [salt.state       :1813][INFO    ][13275] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-06-25 02:51:20,787 [salt.state       :300 ][INFO    ][13275] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-06-25 02:51:20,788 [salt.state       :1951][INFO    ][13275] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:51:20.788050 duration_in_ms=2.556
2019-06-25 02:51:20,790 [salt.state       :1780][INFO    ][13275] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:51:20.790609
2019-06-25 02:51:20,790 [salt.state       :1813][INFO    ][13275] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-06-25 02:51:20,791 [salt.state       :300 ][INFO    ][13275] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-06-25 02:51:20,791 [salt.state       :1951][INFO    ][13275] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:51:20.791474 duration_in_ms=0.865
2019-06-25 02:51:20,792 [salt.state       :1780][INFO    ][13275] Running state [mine.update] at time 02:51:20.792171
2019-06-25 02:51:20,792 [salt.state       :1813][INFO    ][13275] Executing state module.wait for [mine.update]
2019-06-25 02:51:20,792 [salt.state       :300 ][INFO    ][13275] No changes made for mine.update
2019-06-25 02:51:20,793 [salt.state       :1951][INFO    ][13275] Completed state [mine.update] at time 02:51:20.792965 duration_in_ms=0.794
2019-06-25 02:51:20,793 [salt.state       :1780][INFO    ][13275] Running state [ca-certificates] at time 02:51:20.793240
2019-06-25 02:51:20,793 [salt.state       :1813][INFO    ][13275] Executing state pkg.installed for [ca-certificates]
2019-06-25 02:51:20,804 [salt.state       :300 ][INFO    ][13275] All specified packages are already installed
2019-06-25 02:51:20,805 [salt.state       :1951][INFO    ][13275] Completed state [ca-certificates] at time 02:51:20.805175 duration_in_ms=11.934
2019-06-25 02:51:20,806 [salt.state       :1780][INFO    ][13275] Running state [update-ca-certificates] at time 02:51:20.806226
2019-06-25 02:51:20,806 [salt.state       :1813][INFO    ][13275] Executing state cmd.wait for [update-ca-certificates]
2019-06-25 02:51:20,806 [salt.state       :300 ][INFO    ][13275] No changes made for update-ca-certificates
2019-06-25 02:51:20,807 [salt.state       :1951][INFO    ][13275] Completed state [update-ca-certificates] at time 02:51:20.807056 duration_in_ms=0.829
2019-06-25 02:51:20,807 [salt.state       :1780][INFO    ][13275] Running state [iptables] at time 02:51:20.807333
2019-06-25 02:51:20,807 [salt.state       :1813][INFO    ][13275] Executing state pkg.installed for [iptables]
2019-06-25 02:51:20,818 [salt.state       :300 ][INFO    ][13275] All specified packages are already installed
2019-06-25 02:51:20,819 [salt.state       :1951][INFO    ][13275] Completed state [iptables] at time 02:51:20.819032 duration_in_ms=11.699
2019-06-25 02:51:20,819 [salt.state       :1780][INFO    ][13275] Running state [iptables-persistent] at time 02:51:20.819328
2019-06-25 02:51:20,819 [salt.state       :1813][INFO    ][13275] Executing state pkg.installed for [iptables-persistent]
2019-06-25 02:51:20,830 [salt.state       :300 ][INFO    ][13275] All specified packages are already installed
2019-06-25 02:51:20,830 [salt.state       :1951][INFO    ][13275] Completed state [iptables-persistent] at time 02:51:20.830749 duration_in_ms=11.422
2019-06-25 02:51:20,832 [salt.state       :1780][INFO    ][13275] Running state [iptables_modules_v4_load] at time 02:51:20.832044
2019-06-25 02:51:20,832 [salt.state       :1813][INFO    ][13275] Executing state kmod.present for [iptables_modules_v4_load]
2019-06-25 02:51:20,832 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13275] Executing command 'lsmod' in directory '/root'
2019-06-25 02:51:20,864 [salt.state       :300 ][INFO    ][13275] Kernel modules iptable_filter, ip_tables are already present
2019-06-25 02:51:20,864 [salt.state       :1951][INFO    ][13275] Completed state [iptables_modules_v4_load] at time 02:51:20.864716 duration_in_ms=32.672
2019-06-25 02:51:20,866 [salt.state       :1780][INFO    ][13275] Running state [/etc/iptables/rules.v4] at time 02:51:20.865641
2019-06-25 02:51:20,866 [salt.state       :1813][INFO    ][13275] Executing state file.managed for [/etc/iptables/rules.v4]
2019-06-25 02:51:20,978 [salt.state       :300 ][INFO    ][13275] File /etc/iptables/rules.v4 is in the correct state
2019-06-25 02:51:20,978 [salt.state       :1951][INFO    ][13275] Completed state [/etc/iptables/rules.v4] at time 02:51:20.978797 duration_in_ms=113.157
2019-06-25 02:51:20,979 [salt.state       :1780][INFO    ][13275] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:51:20.979906
2019-06-25 02:51:20,980 [salt.state       :1813][INFO    ][13275] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-06-25 02:51:20,980 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13275] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-06-25 02:51:21,004 [salt.state       :300 ][INFO    ][13275] onlyif execution failed
2019-06-25 02:51:21,005 [salt.state       :1951][INFO    ][13275] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:51:21.005005 duration_in_ms=25.099
2019-06-25 02:51:21,007 [salt.state       :1780][INFO    ][13275] Running state [netfilter-persistent] at time 02:51:21.007306
2019-06-25 02:51:21,007 [salt.state       :1813][INFO    ][13275] Executing state service.running for [netfilter-persistent]
2019-06-25 02:51:21,009 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13275] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-06-25 02:51:21,033 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13275] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-06-25 02:51:21,051 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13275] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-06-25 02:51:21,070 [salt.state       :300 ][INFO    ][13275] The service netfilter-persistent is already running
2019-06-25 02:51:21,071 [salt.state       :1951][INFO    ][13275] Completed state [netfilter-persistent] at time 02:51:21.071148 duration_in_ms=63.842
2019-06-25 02:51:21,072 [salt.state       :1780][INFO    ][13275] Running state [iptables_extra.remove_stale_tables] at time 02:51:21.072617
2019-06-25 02:51:21,073 [salt.state       :1813][INFO    ][13275] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-06-25 02:51:21,075 [salt.state       :300 ][INFO    ][13275] No changes made for iptables_extra.remove_stale_tables
2019-06-25 02:51:21,075 [salt.state       :1951][INFO    ][13275] Completed state [iptables_extra.remove_stale_tables] at time 02:51:21.075727 duration_in_ms=3.111
2019-06-25 02:51:21,076 [salt.state       :1780][INFO    ][13275] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:51:21.076257
2019-06-25 02:51:21,076 [salt.state       :1813][INFO    ][13275] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-06-25 02:51:21,077 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13275] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-06-25 02:51:21,096 [salt.state       :300 ][INFO    ][13275] onlyif execution failed
2019-06-25 02:51:21,096 [salt.state       :1951][INFO    ][13275] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:51:21.096871 duration_in_ms=20.614
2019-06-25 02:51:21,099 [salt.state       :1780][INFO    ][13275] Running state [/etc/iptables/rules.v6] at time 02:51:21.099872
2019-06-25 02:51:21,100 [salt.state       :1813][INFO    ][13275] Executing state file.absent for [/etc/iptables/rules.v6]
2019-06-25 02:51:21,100 [salt.state       :300 ][INFO    ][13275] File /etc/iptables/rules.v6 is not present
2019-06-25 02:51:21,101 [salt.state       :1951][INFO    ][13275] Completed state [/etc/iptables/rules.v6] at time 02:51:21.101235 duration_in_ms=1.363
2019-06-25 02:51:21,102 [salt.state       :1780][INFO    ][13275] Running state [iptables_extra.flush_all] at time 02:51:21.102722
2019-06-25 02:51:21,103 [salt.state       :1813][INFO    ][13275] Executing state module.wait for [iptables_extra.flush_all]
2019-06-25 02:51:21,103 [salt.state       :300 ][INFO    ][13275] No changes made for iptables_extra.flush_all
2019-06-25 02:51:21,103 [salt.state       :1951][INFO    ][13275] Completed state [iptables_extra.flush_all] at time 02:51:21.103858 duration_in_ms=1.136
2019-06-25 02:51:21,108 [salt.minion      :1711][INFO    ][13275] Returning information for job: 20190625025109315837
2019-06-25 02:51:21,817 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command state.apply with jid 20190625025121804549
2019-06-25 02:51:21,846 [salt.minion      :1432][INFO    ][13356] Starting a new job with PID 13356
2019-06-25 02:51:22,842 [salt.state       :915 ][INFO    ][13356] Loading fresh modules for state activity
2019-06-25 02:51:24,162 [salt.state       :1780][INFO    ][13356] Running state [maas-rack-controller] at time 02:51:24.162576
2019-06-25 02:51:24,163 [salt.state       :1813][INFO    ][13356] Executing state pkg.installed for [maas-rack-controller]
2019-06-25 02:51:24,163 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13356] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-25 02:51:24,284 [salt.state       :300 ][INFO    ][13356] All specified packages are already installed
2019-06-25 02:51:24,285 [salt.state       :1951][INFO    ][13356] Completed state [maas-rack-controller] at time 02:51:24.285121 duration_in_ms=122.546
2019-06-25 02:51:24,285 [salt.state       :1780][INFO    ][13356] Running state [ipmitool] at time 02:51:24.285493
2019-06-25 02:51:24,286 [salt.state       :1813][INFO    ][13356] Executing state pkg.installed for [ipmitool]
2019-06-25 02:51:24,295 [salt.state       :300 ][INFO    ][13356] All specified packages are already installed
2019-06-25 02:51:24,295 [salt.state       :1951][INFO    ][13356] Completed state [ipmitool] at time 02:51:24.295800 duration_in_ms=10.307
2019-06-25 02:51:24,299 [salt.state       :1780][INFO    ][13356] Running state [/etc/maas/rackd.conf] at time 02:51:24.299019
2019-06-25 02:51:24,299 [salt.state       :1813][INFO    ][13356] Executing state file.line for [/etc/maas/rackd.conf]
2019-06-25 02:51:24,300 [salt.state       :300 ][INFO    ][13356] No changes needed to be made
2019-06-25 02:51:24,300 [salt.state       :1951][INFO    ][13356] Completed state [/etc/maas/rackd.conf] at time 02:51:24.300510 duration_in_ms=1.491
2019-06-25 02:51:24,300 [salt.state       :1780][INFO    ][13356] Running state [/etc/maas/rackd.conf] at time 02:51:24.300794
2019-06-25 02:51:24,301 [salt.state       :1813][INFO    ][13356] Executing state file.managed for [/etc/maas/rackd.conf]
2019-06-25 02:51:24,301 [salt.loaded.int.states.file:2298][WARNING ][13356] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-06-25 02:51:24,303 [salt.state       :300 ][INFO    ][13356] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-06-25 02:51:24,303 [salt.state       :1951][INFO    ][13356] Completed state [/etc/maas/rackd.conf] at time 02:51:24.303344 duration_in_ms=2.551
2019-06-25 02:51:24,304 [salt.state       :1780][INFO    ][13356] Running state [maas-rackd] at time 02:51:24.304385
2019-06-25 02:51:24,304 [salt.state       :1813][INFO    ][13356] Executing state service.running for [maas-rackd]
2019-06-25 02:51:24,305 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13356] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-06-25 02:51:24,347 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13356] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-06-25 02:51:24,366 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13356] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-06-25 02:51:24,391 [salt.state       :300 ][INFO    ][13356] The service maas-rackd is already running
2019-06-25 02:51:24,392 [salt.state       :1951][INFO    ][13356] Completed state [maas-rackd] at time 02:51:24.392223 duration_in_ms=87.836
2019-06-25 02:51:24,394 [salt.minion      :1711][INFO    ][13356] Returning information for job: 20190625025121804549
2019-06-25 02:51:25,113 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command state.apply with jid 20190625025125100649
2019-06-25 02:51:25,140 [salt.minion      :1432][INFO    ][13379] Starting a new job with PID 13379
2019-06-25 02:51:26,105 [salt.state       :915 ][INFO    ][13379] Loading fresh modules for state activity
2019-06-25 02:51:27,560 [salt.state       :1780][INFO    ][13379] Running state [maas-region-controller] at time 02:51:27.560515
2019-06-25 02:51:27,561 [salt.state       :1813][INFO    ][13379] Executing state pkg.installed for [maas-region-controller]
2019-06-25 02:51:27,562 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13379] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-25 02:51:27,690 [salt.state       :300 ][INFO    ][13379] All specified packages are already installed
2019-06-25 02:51:27,690 [salt.state       :1951][INFO    ][13379] Completed state [maas-region-controller] at time 02:51:27.690586 duration_in_ms=130.072
2019-06-25 02:51:27,691 [salt.state       :1780][INFO    ][13379] Running state [python-oauth] at time 02:51:27.690949
2019-06-25 02:51:27,691 [salt.state       :1813][INFO    ][13379] Executing state pkg.installed for [python-oauth]
2019-06-25 02:51:27,700 [salt.state       :300 ][INFO    ][13379] All specified packages are already installed
2019-06-25 02:51:27,701 [salt.state       :1951][INFO    ][13379] Completed state [python-oauth] at time 02:51:27.701208 duration_in_ms=10.259
2019-06-25 02:51:27,704 [salt.state       :1780][INFO    ][13379] Running state [/etc/maas/regiond.conf] at time 02:51:27.704468
2019-06-25 02:51:27,704 [salt.state       :1813][INFO    ][13379] Executing state file.replace for [/etc/maas/regiond.conf]
2019-06-25 02:51:27,709 [salt.state       :300 ][INFO    ][13379] No changes needed to be made
2019-06-25 02:51:27,710 [salt.state       :1951][INFO    ][13379] Completed state [/etc/maas/regiond.conf] at time 02:51:27.710542 duration_in_ms=6.074
2019-06-25 02:51:27,711 [salt.state       :1780][INFO    ][13379] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:51:27.711054
2019-06-25 02:51:27,711 [salt.state       :1813][INFO    ][13379] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-06-25 02:51:27,773 [salt.state       :300 ][INFO    ][13379] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-06-25 02:51:27,773 [salt.state       :1951][INFO    ][13379] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:51:27.773653 duration_in_ms=62.598
2019-06-25 02:51:27,774 [salt.state       :1780][INFO    ][13379] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:51:27.774285
2019-06-25 02:51:27,774 [salt.state       :1813][INFO    ][13379] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-06-25 02:51:27,779 [salt.state       :300 ][INFO    ][13379] No changes needed to be made
2019-06-25 02:51:27,779 [salt.state       :1951][INFO    ][13379] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:51:27.779449 duration_in_ms=5.164
2019-06-25 02:51:27,780 [salt.state       :1780][INFO    ][13379] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:51:27.779965
2019-06-25 02:51:27,780 [salt.state       :1813][INFO    ][13379] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-06-25 02:51:27,785 [salt.state       :300 ][INFO    ][13379] No changes needed to be made
2019-06-25 02:51:27,785 [salt.state       :1951][INFO    ][13379] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:51:27.785401 duration_in_ms=5.436
2019-06-25 02:51:27,785 [salt.state       :1780][INFO    ][13379] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:51:27.785926
2019-06-25 02:51:27,786 [salt.state       :1813][INFO    ][13379] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-06-25 02:51:27,816 [salt.state       :300 ][INFO    ][13379] No changes needed to be made
2019-06-25 02:51:27,816 [salt.state       :1951][INFO    ][13379] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:51:27.816470 duration_in_ms=30.544
2019-06-25 02:51:27,817 [salt.state       :1780][INFO    ][13379] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:51:27.817055
2019-06-25 02:51:27,817 [salt.state       :1813][INFO    ][13379] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-06-25 02:51:27,839 [salt.state       :300 ][INFO    ][13379] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-06-25 02:51:27,840 [salt.state       :1951][INFO    ][13379] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:51:27.840359 duration_in_ms=23.304
2019-06-25 02:51:27,843 [salt.state       :1780][INFO    ][13379] Running state [a2enmod headers] at time 02:51:27.843526
2019-06-25 02:51:27,843 [salt.state       :1813][INFO    ][13379] Executing state cmd.run for [a2enmod headers]
2019-06-25 02:51:27,844 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13379] Executing command 'a2enmod headers' in directory '/root'
2019-06-25 02:51:27,918 [salt.state       :300 ][INFO    ][13379] {'pid': 13400, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-06-25 02:51:27,919 [salt.state       :1951][INFO    ][13379] Completed state [a2enmod headers] at time 02:51:27.919040 duration_in_ms=75.513
2019-06-25 02:51:27,919 [salt.state       :1780][INFO    ][13379] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:51:27.919648
2019-06-25 02:51:27,920 [salt.state       :1813][INFO    ][13379] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-06-25 02:51:27,944 [salt.state       :300 ][INFO    ][13379] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-06-25 02:51:27,944 [salt.state       :1951][INFO    ][13379] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:51:27.944534 duration_in_ms=24.886
2019-06-25 02:51:27,945 [salt.state       :1780][INFO    ][13379] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:51:27.945506
2019-06-25 02:51:27,945 [salt.state       :1813][INFO    ][13379] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-06-25 02:51:28,004 [salt.state       :300 ][INFO    ][13379] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-06-25 02:51:28,005 [salt.state       :1951][INFO    ][13379] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:51:28.005367 duration_in_ms=59.86
2019-06-25 02:51:28,006 [salt.state       :1780][INFO    ][13379] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:51:28.006318
2019-06-25 02:51:28,006 [salt.state       :1813][INFO    ][13379] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-06-25 02:51:28,068 [salt.state       :300 ][INFO    ][13379] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-06-25 02:51:28,069 [salt.state       :1951][INFO    ][13379] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:51:28.068986 duration_in_ms=62.668
2019-06-25 02:51:28,069 [salt.state       :1780][INFO    ][13379] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:51:28.069529
2019-06-25 02:51:28,070 [salt.state       :1813][INFO    ][13379] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-06-25 02:51:28,148 [salt.state       :300 ][INFO    ][13379] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-06-25 02:51:28,148 [salt.state       :1951][INFO    ][13379] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:51:28.148679 duration_in_ms=79.149
2019-06-25 02:51:28,149 [salt.state       :1780][INFO    ][13379] Running state [/root/.pgpass] at time 02:51:28.149163
2019-06-25 02:51:28,149 [salt.state       :1813][INFO    ][13379] Executing state file.managed for [/root/.pgpass]
2019-06-25 02:51:28,209 [salt.state       :300 ][INFO    ][13379] File /root/.pgpass is in the correct state
2019-06-25 02:51:28,209 [salt.state       :1951][INFO    ][13379] Completed state [/root/.pgpass] at time 02:51:28.209543 duration_in_ms=60.38
2019-06-25 02:51:28,215 [salt.state       :1780][INFO    ][13379] Running state [maas-region syncdb --noinput] at time 02:51:28.215311
2019-06-25 02:51:28,215 [salt.state       :1813][INFO    ][13379] Executing state cmd.run for [maas-region syncdb --noinput]
2019-06-25 02:51:28,216 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13379] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-06-25 02:51:31,073 [salt.state       :300 ][INFO    ][13379] {'pid': 13413, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: contenttypes, auth, sites, piston3, sessions, maasserver, metadataserver\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-06-25 02:51:31,075 [salt.state       :1951][INFO    ][13379] Completed state [maas-region syncdb --noinput] at time 02:51:31.074944 duration_in_ms=2859.63
2019-06-25 02:51:31,075 [salt.state       :2022][WARNING ][13379] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-06-25 02:51:31,079 [salt.state       :1780][INFO    ][13379] Running state [maas-regiond] at time 02:51:31.079140
2019-06-25 02:51:31,079 [salt.state       :1813][INFO    ][13379] Executing state service.running for [maas-regiond]
2019-06-25 02:51:31,081 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13379] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-06-25 02:51:31,125 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13379] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-06-25 02:51:31,146 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13379] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-06-25 02:51:31,169 [salt.state       :300 ][INFO    ][13379] The service maas-regiond is already running
2019-06-25 02:51:31,170 [salt.state       :1951][INFO    ][13379] Completed state [maas-regiond] at time 02:51:31.170461 duration_in_ms=91.32
2019-06-25 02:51:31,174 [salt.state       :1780][INFO    ][13379] Running state [bind9] at time 02:51:31.173673
2019-06-25 02:51:31,174 [salt.state       :1813][INFO    ][13379] Executing state service.running for [bind9]
2019-06-25 02:51:31,175 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13379] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-06-25 02:51:31,205 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13379] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-06-25 02:51:31,230 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13379] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-06-25 02:51:31,249 [salt.state       :300 ][INFO    ][13379] The service bind9 is already running
2019-06-25 02:51:31,251 [salt.state       :1951][INFO    ][13379] Completed state [bind9] at time 02:51:31.250996 duration_in_ms=77.324
2019-06-25 02:51:31,255 [salt.state       :1780][INFO    ][13379] Running state [apache2] at time 02:51:31.253665
2019-06-25 02:51:31,255 [salt.state       :1813][INFO    ][13379] Executing state service.running for [apache2]
2019-06-25 02:51:31,256 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13379] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-06-25 02:51:31,285 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13379] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-06-25 02:51:31,308 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13379] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-06-25 02:51:31,342 [salt.state       :300 ][INFO    ][13379] The service apache2 is already running
2019-06-25 02:51:31,342 [salt.state       :1951][INFO    ][13379] Completed state [apache2] at time 02:51:31.342751 duration_in_ms=89.084
2019-06-25 02:51:31,348 [salt.state       :1780][INFO    ][13379] Running state [maasng.wait_for_http_code] at time 02:51:31.348761
2019-06-25 02:51:31,349 [salt.state       :1813][INFO    ][13379] Executing state module.run for [maasng.wait_for_http_code]
2019-06-25 02:51:31,351 [salt.utils.decorators:613 ][WARNING ][13379] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-25 02:51:31,367 [salt.state       :300 ][INFO    ][13379] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-06-25 02:51:31,367 [salt.state       :1951][INFO    ][13379] Completed state [maasng.wait_for_http_code] at time 02:51:31.367250 duration_in_ms=18.49
2019-06-25 02:51:31,368 [salt.state       :1780][INFO    ][13379] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:51:31.368111
2019-06-25 02:51:31,368 [salt.state       :1813][INFO    ][13379] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-06-25 02:51:31,368 [salt.state       :300 ][INFO    ][13379] /var/lib/maas/.setup_admin exists
2019-06-25 02:51:31,369 [salt.state       :1951][INFO    ][13379] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:51:31.369086 duration_in_ms=0.976
2019-06-25 02:51:31,371 [salt.state       :1780][INFO    ][13379] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:51:31.371595
2019-06-25 02:51:31,371 [salt.state       :1813][INFO    ][13379] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-25 02:51:31,372 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13379] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-25 02:51:33,353 [salt.state       :300 ][INFO    ][13379] {'pid': 13442, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-25 02:51:33,354 [salt.state       :1951][INFO    ][13379] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:51:33.354643 duration_in_ms=1983.04
2019-06-25 02:51:33,366 [salt.state       :1780][INFO    ][13379] Running state [maas_region_boot_source_resources_mirror] at time 02:51:33.366602
2019-06-25 02:51:33,366 [salt.state       :1813][INFO    ][13379] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-06-25 02:51:33,469 [salt.state       :300 ][INFO    ][13379] {'changes': {}}
2019-06-25 02:51:33,469 [salt.state       :1951][INFO    ][13379] Completed state [maas_region_boot_source_resources_mirror] at time 02:51:33.469426 duration_in_ms=102.823
2019-06-25 02:51:33,471 [salt.state       :1780][INFO    ][13379] Running state [maasng.boot_resources_import] at time 02:51:33.471065
2019-06-25 02:51:33,471 [salt.state       :1813][INFO    ][13379] Executing state module.run for [maasng.boot_resources_import]
2019-06-25 02:51:33,472 [salt.utils.decorators:613 ][WARNING ][13379] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-25 02:51:33,573 [salt.loaded.ext.module.maasng:1600][INFO    ][13379] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-06-25 02:51:38,629 [salt.loaded.ext.module.maasng:1600][INFO    ][13379] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-06-25 02:51:40,170 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625025140154884
2019-06-25 02:51:40,201 [salt.minion      :1432][INFO    ][13472] Starting a new job with PID 13472
2019-06-25 02:51:40,231 [salt.minion      :1711][INFO    ][13472] Returning information for job: 20190625025140154884
2019-06-25 02:51:43,746 [salt.state       :300 ][INFO    ][13379] {'ret': True}
2019-06-25 02:51:43,747 [salt.state       :1951][INFO    ][13379] Completed state [maasng.boot_resources_import] at time 02:51:43.746937 duration_in_ms=10275.872
2019-06-25 02:51:43,748 [salt.state       :1780][INFO    ][13379] Running state [maas_region_boot_sources_selection_xenial] at time 02:51:43.748503
2019-06-25 02:51:43,749 [salt.state       :1813][INFO    ][13379] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-06-25 02:51:43,931 [salt.state       :300 ][INFO    ][13379] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-06-25 02:51:43,932 [salt.state       :1951][INFO    ][13379] Completed state [maas_region_boot_sources_selection_xenial] at time 02:51:43.932125 duration_in_ms=183.621
2019-06-25 02:51:43,933 [salt.state       :1780][INFO    ][13379] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 02:51:43.933568
2019-06-25 02:51:43,935 [salt.state       :1813][INFO    ][13379] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-06-25 02:51:43,935 [salt.utils.decorators:613 ][WARNING ][13379] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-25 02:51:43,936 [salt.loaded.ext.module.maasng:1771][INFO    ][13379] boot-sources sync initiated for ALL Rack's
2019-06-25 02:51:45,062 [salt.state       :300 ][INFO    ][13379] {'ret': True}
2019-06-25 02:51:45,063 [salt.state       :1951][INFO    ][13379] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 02:51:45.063152 duration_in_ms=1129.583
2019-06-25 02:51:45,065 [salt.state       :1780][INFO    ][13379] Running state [maas.process_maas_config] at time 02:51:45.065558
2019-06-25 02:51:45,067 [salt.state       :1813][INFO    ][13379] Executing state module.run for [maas.process_maas_config]
2019-06-25 02:51:45,067 [salt.utils.decorators:613 ][WARNING ][13379] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-25 02:51:45,068 [salt.loaded.ext.module.maas:92  ][INFO    ][13379] maasconfig name=enable_http_proxy value=True
2019-06-25 02:51:45,126 [salt.loaded.ext.module.maas:92  ][INFO    ][13379] maasconfig name=upstream_dns value=8.8.8.8
2019-06-25 02:51:45,179 [salt.loaded.ext.module.maas:92  ][INFO    ][13379] maasconfig name=commissioning_distro_series value=xenial
2019-06-25 02:51:46,575 [salt.loaded.ext.module.maas:92  ][INFO    ][13379] maasconfig name=default_osystem value=ubuntu
2019-06-25 02:51:46,638 [salt.loaded.ext.module.maas:92  ][INFO    ][13379] maasconfig name=active_discovery_interval value=600
2019-06-25 02:51:46,700 [salt.loaded.ext.module.maas:92  ][INFO    ][13379] maasconfig name=dnssec_validation value=no
2019-06-25 02:51:46,786 [salt.loaded.ext.module.maas:92  ][INFO    ][13379] maasconfig name=maas_name value=mas01
2019-06-25 02:51:46,836 [salt.loaded.ext.module.maas:92  ][INFO    ][13379] maasconfig name=network_discovery value=enabled
2019-06-25 02:51:46,942 [salt.loaded.ext.module.maas:92  ][INFO    ][13379] maasconfig name=enable_third_party_drivers value=True
2019-06-25 02:51:46,991 [salt.loaded.ext.module.maas:92  ][INFO    ][13379] maasconfig name=default_storage_layout value=lvm
2019-06-25 02:51:47,034 [salt.loaded.ext.module.maas:92  ][INFO    ][13379] maasconfig name=ntp_external_only value=True
2019-06-25 02:51:47,083 [salt.loaded.ext.module.maas:92  ][INFO    ][13379] maasconfig name=disk_erase_with_secure_erase value=False
2019-06-25 02:51:47,129 [salt.loaded.ext.module.maas:92  ][INFO    ][13379] maasconfig name=default_distro_series value=xenial
2019-06-25 02:51:47,192 [salt.loaded.ext.module.maas:92  ][INFO    ][13379] maasconfig name=default_min_hwe_kernel value=ga-16.04
2019-06-25 02:51:47,376 [salt.state       :300 ][INFO    ][13379] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-06-25 02:51:47,377 [salt.state       :1951][INFO    ][13379] Completed state [maas.process_maas_config] at time 02:51:47.377157 duration_in_ms=2311.598
2019-06-25 02:51:47,381 [salt.state       :1780][INFO    ][13379] Running state [pxe_admin] at time 02:51:47.381152
2019-06-25 02:51:47,382 [salt.state       :1813][INFO    ][13379] Executing state maasng.fabric_present for [pxe_admin]
2019-06-25 02:51:47,449 [salt.loaded.ext.module.maasng:945 ][INFO    ][13379] [{u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, 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'id': 2, u'name': u'fabric-2', u'class_type': None}, {u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'awmegn', u'mtu': 1500, 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'id': 1, u'name': u'pxe_admin', u'class_type': u''}]
2019-06-25 02:51:47,517 [salt.loaded.ext.module.maasng:1008][WARNING ][13379] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-06-25 02:51:47,518 [salt.loaded.ext.module.maasng:1011][WARNING ][13379] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-06-25 02:51:47,588 [salt.state       :300 ][INFO    ][13379] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-06-25 02:51:47,588 [salt.state       :1951][INFO    ][13379] Completed state [pxe_admin] at time 02:51:47.588716 duration_in_ms=207.565
2019-06-25 02:51:47,589 [salt.state       :1780][INFO    ][13379] Running state [vlan 0] at time 02:51:47.589100
2019-06-25 02:51:47,589 [salt.state       :1813][INFO    ][13379] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-06-25 02:51:47,643 [salt.loaded.ext.module.maasng:945 ][INFO    ][13379] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'awmegn', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-06-25 02:51:47,739 [salt.loaded.ext.module.maasng:945 ][INFO    ][13379] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'awmegn', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-06-25 02:51:48,003 [salt.loaded.ext.module.maasng:945 ][INFO    ][13379] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'awmegn', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-06-25 02:51:48,114 [salt.state       :300 ][INFO    ][13379] {'new': 'Vlan untagged was updated'}
2019-06-25 02:51:48,115 [salt.state       :1951][INFO    ][13379] Completed state [vlan 0] at time 02:51:48.115127 duration_in_ms=526.026
2019-06-25 02:51:48,116 [salt.state       :1780][INFO    ][13379] Running state [192.168.11.0/24] at time 02:51:48.116672
2019-06-25 02:51:48,117 [salt.state       :1813][INFO    ][13379] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-06-25 02:51:48,301 [salt.loaded.ext.module.maasng:945 ][INFO    ][13379] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'awmegn', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-06-25 02:51:48,303 [salt.loaded.ext.module.maasng:1235][WARNING ][13379] Ignoring parameter vlan:0
2019-06-25 02:51:48,380 [salt.state       :300 ][INFO    ][13379] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-06-25 02:51:48,380 [salt.state       :1951][INFO    ][13379] Completed state [192.168.11.0/24] at time 02:51:48.380275 duration_in_ms=263.604
2019-06-25 02:51:48,381 [salt.state       :1780][INFO    ][13379] Running state [maas_create_iprange_1] at time 02:51:48.381103
2019-06-25 02:51:48,381 [salt.state       :1813][INFO    ][13379] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-06-25 02:51:48,435 [salt.state       :300 ][INFO    ][13379] Iprange maas_create_iprange_1 already exist.
2019-06-25 02:51:48,435 [salt.state       :1951][INFO    ][13379] Completed state [maas_create_iprange_1] at time 02:51:48.435734 duration_in_ms=54.631
2019-06-25 02:51:48,436 [salt.state       :1780][INFO    ][13379] Running state [vlan 0] at time 02:51:48.436044
2019-06-25 02:51:48,436 [salt.state       :1813][INFO    ][13379] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-06-25 02:51:48,518 [salt.loaded.ext.module.maasng:945 ][INFO    ][13379] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'awmegn', u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}]
2019-06-25 02:51:48,611 [salt.loaded.ext.module.maasng:945 ][INFO    ][13379] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'awmegn', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-06-25 02:51:48,853 [salt.loaded.ext.module.maasng:945 ][INFO    ][13379] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'awmegn', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-06-25 02:51:48,953 [salt.state       :300 ][INFO    ][13379] {'new': 'Vlan untagged was updated'}
2019-06-25 02:51:48,954 [salt.state       :1951][INFO    ][13379] Completed state [vlan 0] at time 02:51:48.954725 duration_in_ms=518.68
2019-06-25 02:51:48,955 [salt.state       :1780][INFO    ][13379] Running state [opnfv] at time 02:51:48.955675
2019-06-25 02:51:48,956 [salt.state       :1813][INFO    ][13379] Executing state maasng.sshkey_present for [opnfv]
2019-06-25 02:51:49,008 [salt.loaded.ext.module.maasng:1903][INFO    ][13379] [{u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b', u'keysource': u''}]
2019-06-25 02:51:49,009 [salt.state       :300 ][INFO    ][13379] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-06-25 02:51:49,009 [salt.state       :1951][INFO    ][13379] Completed state [opnfv] at time 02:51:49.009339 duration_in_ms=53.664
2019-06-25 02:51:49,014 [salt.minion      :1711][INFO    ][13379] Returning information for job: 20190625025125100649
2019-06-25 02:51:49,781 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command state.apply with jid 20190625025149769067
2019-06-25 02:51:49,796 [salt.minion      :1432][INFO    ][13838] Starting a new job with PID 13838
2019-06-25 02:51:55,852 [salt.state       :915 ][INFO    ][13838] Loading fresh modules for state activity
2019-06-25 02:51:55,963 [salt.state       :1780][INFO    ][13838] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:51:55.963801
2019-06-25 02:51:55,964 [salt.state       :1813][INFO    ][13838] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-25 02:51:55,966 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13838] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-25 02:51:57,914 [salt.state       :300 ][INFO    ][13838] {'pid': 13862, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-25 02:51:57,916 [salt.state       :1951][INFO    ][13838] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:51:57.915873 duration_in_ms=1952.071
2019-06-25 02:51:57,919 [salt.state       :1780][INFO    ][13838] Running state [maas.process_machines] at time 02:51:57.919404
2019-06-25 02:51:57,920 [salt.state       :1813][INFO    ][13838] Executing state module.run for [maas.process_machines]
2019-06-25 02:51:57,922 [salt.utils.decorators:613 ][WARNING ][13838] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-25 02:51:58,519 [salt.loaded.ext.module.maas:412 ][WARNING ][13838] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-25 02:51:58,520 [salt.loaded.ext.module.maas:92  ][INFO    ][13838] 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=ydp8df architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-25 02:51:59,670 [salt.loaded.ext.module.maas:412 ][WARNING ][13838] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-25 02:51:59,671 [salt.loaded.ext.module.maas:92  ][INFO    ][13838] 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=n8r8tb architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-25 02:52:00,923 [salt.loaded.ext.module.maas:412 ][WARNING ][13838] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-25 02:52:00,924 [salt.loaded.ext.module.maas:92  ][INFO    ][13838] machine hostname=kvm01 power_type=ipmi mac_addresses=['14:58:d0:54:e7:88'] power_parameters_power_address=172.16.1.16 power_parameters_power_pass=Winter2017 system_id=q4xqxs architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-25 02:52:02,002 [salt.loaded.ext.module.maas:412 ][WARNING ][13838] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-25 02:52:02,003 [salt.loaded.ext.module.maas:92  ][INFO    ][13838] machine hostname=kvm03 power_type=ipmi mac_addresses=['14:58:d0:54:7a:28'] power_parameters_power_address=172.16.1.18 power_parameters_power_pass=Winter2017 system_id=qxcxdx architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-25 02:52:03,133 [salt.loaded.ext.module.maas:412 ][WARNING ][13838] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-25 02:52:03,134 [salt.loaded.ext.module.maas:92  ][INFO    ][13838] machine hostname=kvm02 power_type=ipmi mac_addresses=['14:58:d0:54:6a:60'] power_parameters_power_address=172.16.1.17 power_parameters_power_pass=Winter2017 system_id=pqh7ct architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-25 02:52:04,215 [salt.state       :300 ][INFO    ][13838] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-06-25 02:52:04,216 [salt.state       :1951][INFO    ][13838] Completed state [maas.process_machines] at time 02:52:04.216008 duration_in_ms=6296.602
2019-06-25 02:52:04,220 [salt.minion      :1711][INFO    ][13838] Returning information for job: 20190625025149769067
2019-06-25 02:52:38,192 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command state.apply with jid 20190625025238182472
2019-06-25 02:52:38,224 [salt.minion      :1432][INFO    ][14129] Starting a new job with PID 14129
2019-06-25 02:52:44,235 [salt.state       :915 ][INFO    ][14129] Loading fresh modules for state activity
2019-06-25 02:52:44,343 [salt.state       :1780][INFO    ][14129] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:52:44.343265
2019-06-25 02:52:44,343 [salt.state       :1813][INFO    ][14129] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-25 02:52:44,345 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14129] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-25 02:52:46,195 [salt.state       :300 ][INFO    ][14129] {'pid': 14136, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-25 02:52:46,197 [salt.state       :1951][INFO    ][14129] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:52:46.196890 duration_in_ms=1853.625
2019-06-25 02:52:46,203 [salt.state       :1780][INFO    ][14129] Running state [maas.wait_for_machine_status] at time 02:52:46.203474
2019-06-25 02:52:46,203 [salt.state       :1813][INFO    ][14129] Executing state module.run for [maas.wait_for_machine_status]
2019-06-25 02:52:46,204 [salt.utils.decorators:613 ][WARNING ][14129] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-25 02:52:49,285 [salt.state       :300 ][INFO    ][14129] {'ret': True}
2019-06-25 02:52:49,287 [salt.state       :1951][INFO    ][14129] Completed state [maas.wait_for_machine_status] at time 02:52:49.285600 duration_in_ms=3082.123
2019-06-25 02:52:49,290 [salt.minion      :1711][INFO    ][14129] Returning information for job: 20190625025238182472
2019-06-25 02:52:49,997 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command state.apply with jid 20190625025249987644
2019-06-25 02:52:50,022 [salt.minion      :1432][INFO    ][14162] Starting a new job with PID 14162
2019-06-25 02:52:51,031 [salt.state       :915 ][INFO    ][14162] Loading fresh modules for state activity
2019-06-25 02:52:51,188 [salt.state       :1780][INFO    ][14162] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:52:51.188540
2019-06-25 02:52:51,188 [salt.state       :1813][INFO    ][14162] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-25 02:52:51,191 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14162] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-25 02:52:53,087 [salt.state       :300 ][INFO    ][14162] {'pid': 14169, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-25 02:52:53,088 [salt.state       :1951][INFO    ][14162] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:52:53.087985 duration_in_ms=1899.445
2019-06-25 02:52:53,092 [salt.state       :1780][INFO    ][14162] Running state [maas_machines_storage_cmp002_lvm] at time 02:52:53.092722
2019-06-25 02:52:53,093 [salt.state       :1813][INFO    ][14162] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-06-25 02:52:53,649 [salt.state       :300 ][INFO    ][14162] Machine cmp002 is not in Ready state.
2019-06-25 02:52:53,650 [salt.state       :1951][INFO    ][14162] Completed state [maas_machines_storage_cmp002_lvm] at time 02:52:53.649907 duration_in_ms=557.184
2019-06-25 02:52:53,650 [salt.state       :1780][INFO    ][14162] Running state [maas_machines_storage_cmp001_lvm] at time 02:52:53.650636
2019-06-25 02:52:53,651 [salt.state       :1813][INFO    ][14162] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-06-25 02:52:54,187 [salt.state       :300 ][INFO    ][14162] Machine cmp001 is not in Ready state.
2019-06-25 02:52:54,187 [salt.state       :1951][INFO    ][14162] Completed state [maas_machines_storage_cmp001_lvm] at time 02:52:54.187643 duration_in_ms=537.006
2019-06-25 02:52:54,192 [salt.minion      :1711][INFO    ][14162] Returning information for job: 20190625025249987644
2019-06-25 02:52:54,896 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command state.apply with jid 20190625025254883389
2019-06-25 02:52:54,927 [salt.minion      :1432][INFO    ][14179] Starting a new job with PID 14179
2019-06-25 02:52:55,961 [salt.state       :915 ][INFO    ][14179] Loading fresh modules for state activity
2019-06-25 02:52:56,075 [salt.state       :1780][INFO    ][14179] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:52:56.075748
2019-06-25 02:52:56,076 [salt.state       :1813][INFO    ][14179] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-25 02:52:56,078 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14179] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-25 02:52:58,067 [salt.state       :300 ][INFO    ][14179] {'pid': 14187, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-25 02:52:58,067 [salt.state       :1951][INFO    ][14179] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:52:58.067836 duration_in_ms=1992.087
2019-06-25 02:52:58,070 [salt.state       :1780][INFO    ][14179] Running state [maas.deploy_machines] at time 02:52:58.070823
2019-06-25 02:52:58,071 [salt.state       :1813][INFO    ][14179] Executing state module.run for [maas.deploy_machines]
2019-06-25 02:52:58,071 [salt.utils.decorators:613 ][WARNING ][14179] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-25 02:52:58,707 [salt.loaded.ext.module.maas:684 ][INFO    ][14179] deploymachines hwe_kernel=ga-16.04 system_id=pqh7ct distro_series=xenial
2019-06-25 02:53:01,228 [salt.state       :300 ][INFO    ][14179] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-06-25 02:53:01,228 [salt.state       :1951][INFO    ][14179] Completed state [maas.deploy_machines] at time 02:53:01.228810 duration_in_ms=3157.985
2019-06-25 02:53:01,232 [salt.minion      :1711][INFO    ][14179] Returning information for job: 20190625025254883389
2019-06-25 02:53:01,956 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command state.apply with jid 20190625025301944438
2019-06-25 02:53:01,979 [salt.minion      :1432][INFO    ][14274] Starting a new job with PID 14274
2019-06-25 02:53:07,995 [salt.state       :915 ][INFO    ][14274] Loading fresh modules for state activity
2019-06-25 02:53:08,098 [salt.state       :1780][INFO    ][14274] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:08.098465
2019-06-25 02:53:08,098 [salt.state       :1813][INFO    ][14274] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-25 02:53:08,100 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14274] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-25 02:53:09,954 [salt.state       :300 ][INFO    ][14274] {'pid': 14285, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-25 02:53:09,955 [salt.state       :1951][INFO    ][14274] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:09.955364 duration_in_ms=1856.898
2019-06-25 02:53:09,958 [salt.state       :1780][INFO    ][14274] Running state [maas.wait_for_machine_status] at time 02:53:09.958268
2019-06-25 02:53:09,959 [salt.state       :1813][INFO    ][14274] Executing state module.run for [maas.wait_for_machine_status]
2019-06-25 02:53:09,959 [salt.utils.decorators:613 ][WARNING ][14274] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-25 02:53:12,711 [salt.loaded.ext.module.maas:1023][INFO    ][14274] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.26376009s left)
2019-06-25 02:53:17,082 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625025317023019
2019-06-25 02:53:17,115 [salt.minion      :1432][INFO    ][14303] Starting a new job with PID 14303
2019-06-25 02:53:17,144 [salt.minion      :1711][INFO    ][14303] Returning information for job: 20190625025317023019
2019-06-25 02:53:45,628 [salt.loaded.ext.module.maas:1023][INFO    ][14274] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.34650898s left)
2019-06-25 02:53:47,179 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625025347167195
2019-06-25 02:53:47,207 [salt.minion      :1432][INFO    ][14338] Starting a new job with PID 14338
2019-06-25 02:53:47,236 [salt.minion      :1711][INFO    ][14338] Returning information for job: 20190625025347167195
2019-06-25 02:54:17,300 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625025417289199
2019-06-25 02:54:17,323 [salt.minion      :1432][INFO    ][14380] Starting a new job with PID 14380
2019-06-25 02:54:17,353 [salt.minion      :1711][INFO    ][14380] Returning information for job: 20190625025417289199
2019-06-25 02:54:18,512 [salt.loaded.ext.module.maas:1023][INFO    ][14274] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2181.46301889s left)
2019-06-25 02:54:47,388 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625025447375327
2019-06-25 02:54:47,413 [salt.minion      :1432][INFO    ][14414] Starting a new job with PID 14414
2019-06-25 02:54:47,449 [salt.minion      :1711][INFO    ][14414] Returning information for job: 20190625025447375327
2019-06-25 02:54:51,438 [salt.loaded.ext.module.maas:1023][INFO    ][14274] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2148.536551s left)
2019-06-25 02:55:17,480 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625025517467415
2019-06-25 02:55:17,504 [salt.minion      :1432][INFO    ][14475] Starting a new job with PID 14475
2019-06-25 02:55:17,532 [salt.minion      :1711][INFO    ][14475] Returning information for job: 20190625025517467415
2019-06-25 02:55:24,465 [salt.loaded.ext.module.maas:1023][INFO    ][14274] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2115.51031089s left)
2019-06-25 02:55:47,556 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625025547544719
2019-06-25 02:55:47,585 [salt.minion      :1432][INFO    ][14510] Starting a new job with PID 14510
2019-06-25 02:55:47,615 [salt.minion      :1711][INFO    ][14510] Returning information for job: 20190625025547544719
2019-06-25 02:55:57,213 [salt.loaded.ext.module.maas:1023][INFO    ][14274] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2082.76174498s left)
2019-06-25 02:56:17,659 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625025617639786
2019-06-25 02:56:17,686 [salt.minion      :1432][INFO    ][14560] Starting a new job with PID 14560
2019-06-25 02:56:17,715 [salt.minion      :1711][INFO    ][14560] Returning information for job: 20190625025617639786
2019-06-25 02:56:30,057 [salt.loaded.ext.module.maas:1023][INFO    ][14274] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2049.91833711s left)
2019-06-25 02:56:47,747 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625025647735154
2019-06-25 02:56:47,773 [salt.minion      :1432][INFO    ][14593] Starting a new job with PID 14593
2019-06-25 02:56:47,805 [salt.minion      :1711][INFO    ][14593] Returning information for job: 20190625025647735154
2019-06-25 02:57:03,096 [salt.loaded.ext.module.maas:1023][INFO    ][14274] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2016.87890697s left)
2019-06-25 02:57:17,847 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625025717836389
2019-06-25 02:57:17,868 [salt.minion      :1432][INFO    ][14662] Starting a new job with PID 14662
2019-06-25 02:57:17,900 [salt.minion      :1711][INFO    ][14662] Returning information for job: 20190625025717836389
2019-06-25 02:57:36,281 [salt.loaded.ext.module.maas:1023][INFO    ][14274] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1983.69399595s left)
2019-06-25 02:57:47,935 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625025747922668
2019-06-25 02:57:47,963 [salt.minion      :1432][INFO    ][14729] Starting a new job with PID 14729
2019-06-25 02:57:47,992 [salt.minion      :1711][INFO    ][14729] Returning information for job: 20190625025747922668
2019-06-25 02:58:09,216 [salt.loaded.ext.module.maas:1023][INFO    ][14274] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1950.75898004s left)
2019-06-25 02:58:18,076 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625025818059580
2019-06-25 02:58:18,103 [salt.minion      :1432][INFO    ][14835] Starting a new job with PID 14835
2019-06-25 02:58:18,137 [salt.minion      :1711][INFO    ][14835] Returning information for job: 20190625025818059580
2019-06-25 02:58:42,279 [salt.loaded.ext.module.maas:1023][INFO    ][14274] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1917.69567204s left)
2019-06-25 02:58:48,186 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625025848172946
2019-06-25 02:58:48,212 [salt.minion      :1432][INFO    ][14901] Starting a new job with PID 14901
2019-06-25 02:58:48,247 [salt.minion      :1711][INFO    ][14901] Returning information for job: 20190625025848172946
2019-06-25 02:59:15,294 [salt.loaded.ext.module.maas:1023][INFO    ][14274] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1884.68097901s left)
2019-06-25 02:59:18,332 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625025918317615
2019-06-25 02:59:18,360 [salt.minion      :1432][INFO    ][15016] Starting a new job with PID 15016
2019-06-25 02:59:18,391 [salt.minion      :1711][INFO    ][15016] Returning information for job: 20190625025918317615
2019-06-25 02:59:48,344 [salt.loaded.ext.module.maas:1023][INFO    ][14274] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1851.63067007s left)
2019-06-25 02:59:48,471 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625025948454837
2019-06-25 02:59:48,498 [salt.minion      :1432][INFO    ][15066] Starting a new job with PID 15066
2019-06-25 02:59:48,532 [salt.minion      :1711][INFO    ][15066] Returning information for job: 20190625025948454837
2019-06-25 03:00:18,636 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625030018624265
2019-06-25 03:00:18,655 [salt.minion      :1432][INFO    ][15171] Starting a new job with PID 15171
2019-06-25 03:00:18,692 [salt.minion      :1711][INFO    ][15171] Returning information for job: 20190625030018624265
2019-06-25 03:00:21,255 [salt.loaded.ext.module.maas:1023][INFO    ][14274] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1818.71978307s left)
2019-06-25 03:00:48,786 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625030048774983
2019-06-25 03:00:48,810 [salt.minion      :1432][INFO    ][15246] Starting a new job with PID 15246
2019-06-25 03:00:48,848 [salt.minion      :1711][INFO    ][15246] Returning information for job: 20190625030048774983
2019-06-25 03:00:54,147 [salt.loaded.ext.module.maas:1023][INFO    ][14274] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1785.82774711s left)
2019-06-25 03:01:18,957 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625030118941572
2019-06-25 03:01:18,992 [salt.minion      :1432][INFO    ][15360] Starting a new job with PID 15360
2019-06-25 03:01:19,024 [salt.minion      :1711][INFO    ][15360] Returning information for job: 20190625030118941572
2019-06-25 03:01:27,100 [salt.loaded.ext.module.maas:1023][INFO    ][14274] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1752.87532306s left)
2019-06-25 03:01:49,133 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625030149115494
2019-06-25 03:01:49,163 [salt.minion      :1432][INFO    ][15393] Starting a new job with PID 15393
2019-06-25 03:01:49,194 [salt.minion      :1711][INFO    ][15393] Returning information for job: 20190625030149115494
2019-06-25 03:01:59,963 [salt.loaded.ext.module.maas:1023][INFO    ][14274] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1720.01189899s left)
2019-06-25 03:02:19,303 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625030219283134
2019-06-25 03:02:19,338 [salt.minion      :1432][INFO    ][15437] Starting a new job with PID 15437
2019-06-25 03:02:19,369 [salt.minion      :1711][INFO    ][15437] Returning information for job: 20190625030219283134
2019-06-25 03:02:32,972 [salt.loaded.ext.module.maas:1023][INFO    ][14274] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1687.003407s left)
2019-06-25 03:02:49,480 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625030249461622
2019-06-25 03:02:49,511 [salt.minion      :1432][INFO    ][15474] Starting a new job with PID 15474
2019-06-25 03:02:49,541 [salt.minion      :1711][INFO    ][15474] Returning information for job: 20190625030249461622
2019-06-25 03:03:05,865 [salt.loaded.ext.module.maas:1023][INFO    ][14274] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1654.11019993s left)
2019-06-25 03:03:19,665 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625030319650945
2019-06-25 03:03:19,693 [salt.minion      :1432][INFO    ][15515] Starting a new job with PID 15515
2019-06-25 03:03:19,725 [salt.minion      :1711][INFO    ][15515] Returning information for job: 20190625030319650945
2019-06-25 03:03:38,691 [salt.loaded.ext.module.maas:1023][INFO    ][14274] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1621.28341794s left)
2019-06-25 03:03:49,847 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625030349835159
2019-06-25 03:03:49,875 [salt.minion      :1432][INFO    ][15549] Starting a new job with PID 15549
2019-06-25 03:03:49,914 [salt.minion      :1711][INFO    ][15549] Returning information for job: 20190625030349835159
2019-06-25 03:04:11,713 [salt.loaded.ext.module.maas:1023][INFO    ][14274] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1588.26208496s left)
2019-06-25 03:04:20,045 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625030420029251
2019-06-25 03:04:20,075 [salt.minion      :1432][INFO    ][15591] Starting a new job with PID 15591
2019-06-25 03:04:20,108 [salt.minion      :1711][INFO    ][15591] Returning information for job: 20190625030420029251
2019-06-25 03:04:44,578 [salt.loaded.ext.module.maas:1023][INFO    ][14274] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1555.39661694s left)
2019-06-25 03:04:50,250 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command saltutil.find_job with jid 20190625030450236318
2019-06-25 03:04:50,277 [salt.minion      :1432][INFO    ][15649] Starting a new job with PID 15649
2019-06-25 03:04:50,313 [salt.minion      :1711][INFO    ][15649] Returning information for job: 20190625030450236318
2019-06-25 03:05:17,836 [salt.state       :300 ][INFO    ][14274] {'ret': True}
2019-06-25 03:05:17,836 [salt.state       :1951][INFO    ][14274] Completed state [maas.wait_for_machine_status] at time 03:05:17.836831 duration_in_ms=727878.561
2019-06-25 03:05:17,840 [salt.minion      :1711][INFO    ][14274] Returning information for job: 20190625025301944438
2019-06-25 03:05:50,506 [salt.utils.schedule:1377][INFO    ][5369] Running scheduled job: __mine_interval
2019-06-25 04:05:50,506 [salt.utils.schedule:1377][INFO    ][5369] Running scheduled job: __mine_interval
2019-06-25 04:34:41,352 [salt.minion      :1308][INFO    ][5369] User sudo_ubuntu Executing command cp.push_dir with jid 20190625043441339855
2019-06-25 04:34:41,381 [salt.minion      :1432][INFO    ][22176] Starting a new job with PID 22176
