2019-07-15 02:06:39,088 [salt.utils.decorators:613 ][WARNING ][2033] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-15 02:06:40,196 [salt.utils.decorators:613 ][WARNING ][2033] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-15 02:06:43,808 [salt.loaded.int.states.file:2298][WARNING ][2455] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-07-15 02:07:13,626 [salt.state       :2022][WARNING ][2854] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-07-15 02:07:16,775 [salt.utils.decorators:613 ][WARNING ][2854] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-15 02:07:37,820 [salt.utils.decorators:613 ][WARNING ][2854] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-15 02:08:04,686 [salt.utils.decorators:613 ][WARNING ][2854] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-15 02:08:05,600 [salt.utils.decorators:613 ][WARNING ][2854] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-15 02:08:07,748 [salt.loaded.ext.module.maasng:1008][WARNING ][2854] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-07-15 02:08:07,748 [salt.loaded.ext.module.maasng:1011][WARNING ][2854] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-07-15 02:08:08,511 [salt.loaded.ext.module.maasng:1235][WARNING ][2854] Ignoring parameter vlan:0
2019-07-15 02:08:21,176 [salt.utils.decorators:613 ][WARNING ][5205] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-15 02:08:21,228 [salt.loaded.ext.module.maas:412 ][WARNING ][5205] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-15 02:08:22,487 [salt.loaded.ext.module.maas:412 ][WARNING ][5205] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-15 02:08:23,710 [salt.loaded.ext.module.maas:412 ][WARNING ][5205] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-15 02:08:24,797 [salt.loaded.ext.module.maas:412 ][WARNING ][5205] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-15 02:08:25,881 [salt.loaded.ext.module.maas:412 ][WARNING ][5205] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-15 02:08:30,296 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5795] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-07-15 02:08:30,330 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5795] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-07-15 02:08:30,378 [salt.utils.parsers:1051][WARNING ][387] Minion received a SIGTERM. Exiting.
2019-07-15 02:08:31,384 [salt.cli.daemons :293 ][INFO    ][5852] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-07-15 02:08:31,502 [salt.cli.daemons :82  ][INFO    ][5852] Starting up the Salt Minion
2019-07-15 02:08:31,503 [salt.utils.event :1017][INFO    ][5852] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-07-15 02:08:32,675 [salt.minion      :976 ][INFO    ][5852] Creating minion process manager
2019-07-15 02:08:34,703 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][5852] Executing command ['date', '+%z'] in directory '/root'
2019-07-15 02:08:34,731 [salt.utils.schedule:568 ][INFO    ][5852] Updating job settings for scheduled job: __mine_interval
2019-07-15 02:08:34,733 [salt.minion      :1108][INFO    ][5852] Added mine.update to scheduler
2019-07-15 02:08:34,737 [salt.minion      :1975][INFO    ][5852] Minion is starting as user 'root'
2019-07-15 02:08:34,753 [salt.minion      :2336][INFO    ][5852] Minion is ready to receive requests!
2019-07-15 02:08:58,296 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command state.apply with jid 20190715020858280174
2019-07-15 02:08:58,320 [salt.minion      :1432][INFO    ][5956] Starting a new job with PID 5956
2019-07-15 02:09:07,338 [salt.state       :915 ][INFO    ][5956] Loading fresh modules for state activity
2019-07-15 02:09:07,388 [salt.fileclient  :1219][INFO    ][5956] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-07-15 02:09:07,436 [salt.state       :1780][INFO    ][5956] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:09:07.436621
2019-07-15 02:09:07,436 [salt.state       :1813][INFO    ][5956] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-15 02:09:07,438 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5956] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-15 02:09:09,195 [salt.state       :300 ][INFO    ][5956] {'pid': 5977, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-15 02:09:09,196 [salt.state       :1951][INFO    ][5956] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:09:09.196123 duration_in_ms=1759.502
2019-07-15 02:09:09,198 [salt.state       :1780][INFO    ][5956] Running state [maas.wait_for_machine_status] at time 02:09:09.198738
2019-07-15 02:09:09,199 [salt.state       :1813][INFO    ][5956] Executing state module.run for [maas.wait_for_machine_status]
2019-07-15 02:09:09,199 [salt.utils.decorators:613 ][WARNING ][5956] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-15 02:09:09,935 [salt.loaded.ext.module.maas:1023][INFO    ][5956] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.27673602s left)
2019-07-15 02:09:13,427 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715020913411239
2019-07-15 02:09:13,449 [salt.minion      :1432][INFO    ][5988] Starting a new job with PID 5988
2019-07-15 02:09:13,471 [salt.minion      :1711][INFO    ][5988] Returning information for job: 20190715020913411239
2019-07-15 02:09:40,872 [salt.loaded.ext.module.maas:1023][INFO    ][5956] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.33960199s left)
2019-07-15 02:09:43,496 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715020943484162
2019-07-15 02:09:43,524 [salt.minion      :1432][INFO    ][6035] Starting a new job with PID 6035
2019-07-15 02:09:43,542 [salt.minion      :1711][INFO    ][6035] Returning information for job: 20190715020943484162
2019-07-15 02:10:11,702 [salt.loaded.ext.module.maas:1023][INFO    ][5956] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.51001s left)
2019-07-15 02:10:13,612 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715021013593638
2019-07-15 02:10:13,637 [salt.minion      :1432][INFO    ][6065] Starting a new job with PID 6065
2019-07-15 02:10:13,656 [salt.minion      :1711][INFO    ][6065] Returning information for job: 20190715021013593638
2019-07-15 02:10:42,581 [salt.loaded.ext.module.maas:1023][INFO    ][5956] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.630759s left)
2019-07-15 02:10:43,674 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715021043659315
2019-07-15 02:10:43,697 [salt.minion      :1432][INFO    ][6113] Starting a new job with PID 6113
2019-07-15 02:10:43,717 [salt.minion      :1711][INFO    ][6113] Returning information for job: 20190715021043659315
2019-07-15 02:11:13,683 [salt.loaded.ext.module.maas:1023][INFO    ][5956] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.52857614s left)
2019-07-15 02:11:13,744 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715021113728381
2019-07-15 02:11:13,765 [salt.minion      :1432][INFO    ][6170] Starting a new job with PID 6170
2019-07-15 02:11:13,788 [salt.minion      :1711][INFO    ][6170] Returning information for job: 20190715021113728381
2019-07-15 02:11:43,824 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715021143814688
2019-07-15 02:11:43,841 [salt.minion      :1432][INFO    ][6298] Starting a new job with PID 6298
2019-07-15 02:11:43,865 [salt.minion      :1711][INFO    ][6298] Returning information for job: 20190715021143814688
2019-07-15 02:11:44,860 [salt.loaded.ext.module.maas:1023][INFO    ][5956] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.35100818s left)
2019-07-15 02:12:13,932 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715021213914912
2019-07-15 02:12:13,961 [salt.minion      :1432][INFO    ][6384] Starting a new job with PID 6384
2019-07-15 02:12:13,981 [salt.minion      :1711][INFO    ][6384] Returning information for job: 20190715021213914912
2019-07-15 02:12:16,152 [salt.loaded.ext.module.maas:1023][INFO    ][5956] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1313.05959511s left)
2019-07-15 02:12:44,099 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715021244088142
2019-07-15 02:12:44,121 [salt.minion      :1432][INFO    ][6716] Starting a new job with PID 6716
2019-07-15 02:12:44,143 [salt.minion      :1711][INFO    ][6716] Returning information for job: 20190715021244088142
2019-07-15 02:12:47,536 [salt.loaded.ext.module.maas:1023][INFO    ][5956] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1281.67553401s left)
2019-07-15 02:13:14,213 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715021314199443
2019-07-15 02:13:14,238 [salt.minion      :1432][INFO    ][6827] Starting a new job with PID 6827
2019-07-15 02:13:14,261 [salt.minion      :1711][INFO    ][6827] Returning information for job: 20190715021314199443
2019-07-15 02:13:19,944 [salt.loaded.ext.module.maas:1023][INFO    ][5956] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1249.267699s left)
2019-07-15 02:13:44,368 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715021344352192
2019-07-15 02:13:44,393 [salt.minion      :1432][INFO    ][7247] Starting a new job with PID 7247
2019-07-15 02:13:44,415 [salt.minion      :1711][INFO    ][7247] Returning information for job: 20190715021344352192
2019-07-15 02:13:52,164 [salt.loaded.ext.module.maas:1023][INFO    ][5956] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1217.04719019s left)
2019-07-15 02:14:14,493 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715021414480305
2019-07-15 02:14:14,519 [salt.minion      :1432][INFO    ][7309] Starting a new job with PID 7309
2019-07-15 02:14:14,543 [salt.minion      :1711][INFO    ][7309] Returning information for job: 20190715021414480305
2019-07-15 02:14:24,549 [salt.loaded.ext.module.maas:1023][INFO    ][5956] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1184.661937s left)
2019-07-15 02:14:44,634 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715021444625005
2019-07-15 02:14:44,651 [salt.minion      :1432][INFO    ][7547] Starting a new job with PID 7547
2019-07-15 02:14:44,675 [salt.minion      :1711][INFO    ][7547] Returning information for job: 20190715021444625005
2019-07-15 02:14:57,368 [salt.state       :300 ][INFO    ][5956] {'ret': True}
2019-07-15 02:14:57,369 [salt.state       :1951][INFO    ][5956] Completed state [maas.wait_for_machine_status] at time 02:14:57.369447 duration_in_ms=348170.707
2019-07-15 02:14:57,376 [salt.minion      :1711][INFO    ][5956] Returning information for job: 20190715020858280174
2019-07-15 02:14:57,955 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command state.apply with jid 20190715021457939624
2019-07-15 02:14:57,982 [salt.minion      :1432][INFO    ][7621] Starting a new job with PID 7621
2019-07-15 02:15:06,311 [salt.state       :915 ][INFO    ][7621] Loading fresh modules for state activity
2019-07-15 02:15:06,379 [salt.fileclient  :1219][INFO    ][7621] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-07-15 02:15:06,483 [salt.state       :1780][INFO    ][7621] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:15:06.483525
2019-07-15 02:15:06,483 [salt.state       :1813][INFO    ][7621] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-15 02:15:06,485 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7621] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-15 02:15:08,277 [salt.state       :300 ][INFO    ][7621] {'pid': 7651, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-15 02:15:08,278 [salt.state       :1951][INFO    ][7621] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:15:08.278800 duration_in_ms=1795.275
2019-07-15 02:15:08,282 [salt.state       :1780][INFO    ][7621] Running state [maas_machines_storage_cmp002_lvm] at time 02:15:08.282672
2019-07-15 02:15:08,283 [salt.state       :1813][INFO    ][7621] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-07-15 02:15:09,504 [salt.loaded.ext.module.maasng:610 ][INFO    ][7621] c3gk8k
2019-07-15 02:15:09,504 [salt.loaded.ext.module.maasng:626 ][INFO    ][7621] sda
2019-07-15 02:15:10,108 [salt.loaded.ext.module.maasng:361 ][INFO    ][7621] c3gk8k
2019-07-15 02:15:10,212 [salt.loaded.ext.module.maasng:367 ][INFO    ][7621] [{u'size': 800109715456, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'168ff750-fd2b-44d0-aebf-a448e07afc69', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'c3gk8k', u'device_id': 1, u'filesystem': {u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'uuid': u'fcec2be9-40d8-4a33-a3b8-21ffa3dab670'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/c3gk8k/blockdevices/1/partition/1'}], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'c3gk8k', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/c3gk8k/blockdevices/1/'}, {u'size': 800097042432, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'uuid': u'1ea5d1f6-3b58-4342-b5c2-115e31cdb171'}, u'uuid': u'24cf800a-d062-44f6-af8f-74c1ccef5c98', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'c3gk8k', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 800097042432, u'id': 3, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/c3gk8k/blockdevices/3/'}]
2019-07-15 02:15:10,212 [salt.loaded.ext.module.maasng:632 ][INFO    ][7621] vgroot
2019-07-15 02:15:10,212 [salt.loaded.ext.module.maasng:635 ][INFO    ][7621] lvroot
2019-07-15 02:15:10,213 [salt.loaded.ext.module.maasng:639 ][INFO    ][7621] 107374182400
2019-07-15 02:15:10,821 [salt.loaded.ext.module.maasng:645 ][INFO    ][7621] {u'hwe_kernel': u'', u'swap_size': None, u'disable_ipv4': False, u'cpu_count': 40, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'block_size': 4096, u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'uuid': u'8b73d44b-f2ad-4633-8746-8fc9f11ebe61', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'c3gk8k', u'device_id': 1, u'filesystem': {u'label': None, u'uuid': u'2ef75742-ec4e-4302-aa9d-426ba79c1be2', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/c3gk8k/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'size': 800101236736}], u'name': u'sda', u'system_id': u'c3gk8k', 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/c3gk8k/blockdevices/1/', u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}, u'blockdevice_set': [{u'block_size': 4096, u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'uuid': u'8b73d44b-f2ad-4633-8746-8fc9f11ebe61', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'c3gk8k', u'device_id': 1, u'filesystem': {u'label': None, u'uuid': u'2ef75742-ec4e-4302-aa9d-426ba79c1be2', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/c3gk8k/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'size': 800101236736}], u'name': u'sda', u'system_id': u'c3gk8k', 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/c3gk8k/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'uuid': u'68dbe6b0-147e-426d-a02d-c7eaa4d6ccfc', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'partitions': [], u'name': u'vgroot-lvroot', u'system_id': u'c3gk8k', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'uuid': u'35193f93-d5cd-4f0c-8c94-da706b876dc2', 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'resource_uri': u'/MAAS/api/2.0/nodes/c3gk8k/blockdevices/11/', u'used_size': 107374182400, u'id': 11, u'serial': None, u'size': 107374182400}], u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default'}, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'c3gk8k', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'block_size': 4096, u'available_size': 0, u'uuid': u'68dbe6b0-147e-426d-a02d-c7eaa4d6ccfc', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'partitions': [], u'name': u'vgroot-lvroot', u'system_id': u'c3gk8k', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'uuid': u'35193f93-d5cd-4f0c-8c94-da706b876dc2', 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'resource_uri': u'/MAAS/api/2.0/nodes/c3gk8k/blockdevices/11/', u'used_size': 107374182400, u'id': 11, u'serial': None, u'size': 107374182400}], u'architecture': u'amd64/generic', u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'physicalblockdevice_set': [{u'block_size': 4096, u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'uuid': u'8b73d44b-f2ad-4633-8746-8fc9f11ebe61', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'c3gk8k', u'device_id': 1, u'filesystem': {u'label': None, u'uuid': u'2ef75742-ec4e-4302-aa9d-426ba79c1be2', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/c3gk8k/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'size': 800101236736}], u'name': u'sda', u'system_id': u'c3gk8k', 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/c3gk8k/blockdevices/1/', u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}], u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'c3gk8k', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_interface': {u'vlan': {u'name': u'untagged', 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'ed7sbp', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, 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'name': u'untagged', 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'ed7sbp', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 18}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:10:18', 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'name': u'untagged', 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'ed7sbp', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, 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'system_id': u'c3gk8k', 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/c3gk8k/interfaces/4/'}, u'interface_set': [{u'vlan': {u'name': u'untagged', 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'ed7sbp', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, 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'name': u'untagged', 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'ed7sbp', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 18}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:10:18', 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'name': u'untagged', 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'ed7sbp', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, 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'system_id': u'c3gk8k', 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/c3gk8k/interfaces/4/'}, {u'vlan': {u'name': u'untagged', 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'ed7sbp', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, 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'name': u'untagged', 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'ed7sbp', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'mode': u'link_up', u'id': 19}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:10:1c', 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'name': u'untagged', 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'ed7sbp', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, 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'system_id': u'c3gk8k', 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/c3gk8k/interfaces/13/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 20}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:12:49', u'enabled': True, u'children': [], u'discovered': None, u'system_id': u'c3gk8k', 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/c3gk8k/interfaces/14/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 21}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:12:48', u'enabled': True, u'children': [], u'discovered': None, u'system_id': u'c3gk8k', 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/c3gk8k/interfaces/15/'}, {u'vlan': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:07:51', u'enabled': True, u'children': [], u'discovered': None, u'system_id': u'c3gk8k', 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/c3gk8k/interfaces/11/'}, {u'vlan': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:07:50', u'enabled': True, u'children': [], u'discovered': None, u'system_id': u'c3gk8k', 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/c3gk8k/interfaces/12/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'node_type': 0, u'commissioning_status': 2, u'min_hwe_kernel': u'ga-16.04', u'commissioning_status_name': u'Passed', u'current_commissioning_result_id': 2, u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/c3gk8k/', u'distro_series': u'', u'memory_test_status': -1}
2019-07-15 02:15:10,823 [salt.state       :300 ][INFO    ][7621] {'new': {'storage_layout': 'lvm'}}
2019-07-15 02:15:10,823 [salt.state       :1951][INFO    ][7621] Completed state [maas_machines_storage_cmp002_lvm] at time 02:15:10.823763 duration_in_ms=2541.09
2019-07-15 02:15:10,824 [salt.state       :1780][INFO    ][7621] Running state [maas_machines_storage_cmp001_lvm] at time 02:15:10.824324
2019-07-15 02:15:10,824 [salt.state       :1813][INFO    ][7621] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-07-15 02:15:11,987 [salt.loaded.ext.module.maasng:610 ][INFO    ][7621] qf7e3w
2019-07-15 02:15:11,988 [salt.loaded.ext.module.maasng:626 ][INFO    ][7621] sda
2019-07-15 02:15:12,558 [salt.loaded.ext.module.maasng:361 ][INFO    ][7621] qf7e3w
2019-07-15 02:15:12,651 [salt.loaded.ext.module.maasng:367 ][INFO    ][7621] [{u'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/qf7e3w/blockdevices/2/', u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'b176ff9e-2379-4a52-bc34-eb59b327a8a6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qf7e3w', u'filesystem': {u'mount_options': None, u'uuid': u'780fbb6a-9b34-429c-ad25-3e6fd080404b', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/qf7e3w/blockdevices/2/partition/2'}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'qf7e3w', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'tags': [u'ssd'], u'size': 800109715456}, {u'uuid': u'0b3810a5-bc5c-436f-9294-4c37c40cd3dd', u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/qf7e3w/blockdevices/4/', u'type': u'virtual', u'partitions': [], u'filesystem': {u'mount_options': None, u'uuid': u'b30165f9-c0a3-4ac3-8e60-47f060c2a9ee', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'qf7e3w', u'serial': None, u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 800097042432, u'id': 4, u'tags': [], u'size': 800097042432}]
2019-07-15 02:15:12,651 [salt.loaded.ext.module.maasng:632 ][INFO    ][7621] vgroot
2019-07-15 02:15:12,651 [salt.loaded.ext.module.maasng:635 ][INFO    ][7621] lvroot
2019-07-15 02:15:12,652 [salt.loaded.ext.module.maasng:639 ][INFO    ][7621] 107374182400
2019-07-15 02:15:13,075 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715021513062937
2019-07-15 02:15:13,100 [salt.minion      :1432][INFO    ][7683] Starting a new job with PID 7683
2019-07-15 02:15:13,122 [salt.minion      :1711][INFO    ][7683] Returning information for job: 20190715021513062937
2019-07-15 02:15:13,271 [salt.loaded.ext.module.maasng:645 ][INFO    ][7621] {u'hwe_kernel': u'', u'swap_size': None, u'disable_ipv4': False, u'cpu_count': 40, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'block_size': 4096, u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'uuid': u'884f31b9-312c-4a3c-b4ae-93c16292a6af', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qf7e3w', u'device_id': 2, u'filesystem': {u'label': None, u'uuid': u'772f0ebd-ed38-4633-b7e2-27bc3132af63', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/qf7e3w/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}], u'name': u'sda', u'system_id': u'qf7e3w', 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'resource_uri': u'/MAAS/api/2.0/nodes/qf7e3w/blockdevices/2/', u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, u'blockdevice_set': [{u'block_size': 4096, u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'uuid': u'884f31b9-312c-4a3c-b4ae-93c16292a6af', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qf7e3w', u'device_id': 2, u'filesystem': {u'label': None, u'uuid': u'772f0ebd-ed38-4633-b7e2-27bc3132af63', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/qf7e3w/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}], u'name': u'sda', u'system_id': u'qf7e3w', 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'resource_uri': u'/MAAS/api/2.0/nodes/qf7e3w/blockdevices/2/', u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, {u'block_size': 4096, u'available_size': 0, u'uuid': u'e37f6aff-eca1-498c-b593-c1e25112deed', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'partitions': [], u'name': u'vgroot-lvroot', u'system_id': u'qf7e3w', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'uuid': u'a0aeff69-e64e-43eb-8b94-ae9dec3f6a3d', 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'resource_uri': u'/MAAS/api/2.0/nodes/qf7e3w/blockdevices/12/', u'used_size': 107374182400, u'id': 12, u'serial': None, u'size': 107374182400}], u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default'}, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'qf7e3w', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'block_size': 4096, u'available_size': 0, u'uuid': u'e37f6aff-eca1-498c-b593-c1e25112deed', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'partitions': [], u'name': u'vgroot-lvroot', u'system_id': u'qf7e3w', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'uuid': u'a0aeff69-e64e-43eb-8b94-ae9dec3f6a3d', 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'resource_uri': u'/MAAS/api/2.0/nodes/qf7e3w/blockdevices/12/', u'used_size': 107374182400, u'id': 12, u'serial': None, u'size': 107374182400}], u'architecture': u'amd64/generic', u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'physicalblockdevice_set': [{u'block_size': 4096, u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'uuid': u'884f31b9-312c-4a3c-b4ae-93c16292a6af', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qf7e3w', u'device_id': 2, u'filesystem': {u'label': None, u'uuid': u'772f0ebd-ed38-4633-b7e2-27bc3132af63', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/qf7e3w/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}], u'name': u'sda', u'system_id': u'qf7e3w', 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'resource_uri': u'/MAAS/api/2.0/nodes/qf7e3w/blockdevices/2/', u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}], u'ip_addresses': [u'192.168.11.39', u'192.168.11.41'], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'qf7e3w', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_interface': {u'vlan': {u'name': u'untagged', 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'ed7sbp', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, 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'name': u'untagged', 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'ed7sbp', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 24}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:95:a0', 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'name': u'untagged', 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'ed7sbp', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'system_id': u'qf7e3w', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/qf7e3w/interfaces/5/'}, u'interface_set': [{u'vlan': {u'name': u'untagged', 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'ed7sbp', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, 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'name': u'untagged', 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'ed7sbp', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 24}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:95:a0', 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'name': u'untagged', 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'ed7sbp', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'system_id': u'qf7e3w', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/qf7e3w/interfaces/5/'}, {u'vlan': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:1f:d5', u'enabled': True, u'children': [], u'discovered': None, u'system_id': u'qf7e3w', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/qf7e3w/interfaces/16/'}, {u'vlan': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:1f:d4', u'enabled': True, u'children': [], u'discovered': None, u'system_id': u'qf7e3w', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/qf7e3w/interfaces/17/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 25}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:52:cc', u'enabled': True, u'children': [], u'discovered': None, u'system_id': u'qf7e3w', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/qf7e3w/interfaces/18/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 26}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:52:cd', u'enabled': True, u'children': [], u'discovered': None, u'system_id': u'qf7e3w', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/qf7e3w/interfaces/19/'}, {u'vlan': {u'name': u'untagged', 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'ed7sbp', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, 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'name': u'untagged', 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'ed7sbp', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'mode': u'link_up', u'id': 27}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:95:a4', 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'name': u'untagged', 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'ed7sbp', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, 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'system_id': u'qf7e3w', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/qf7e3w/interfaces/20/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'node_type': 0, u'commissioning_status': 2, u'min_hwe_kernel': u'ga-16.04', u'commissioning_status_name': u'Passed', u'current_commissioning_result_id': 4, u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/qf7e3w/', u'distro_series': u'', u'memory_test_status': -1}
2019-07-15 02:15:13,276 [salt.state       :300 ][INFO    ][7621] {'new': {'storage_layout': 'lvm'}}
2019-07-15 02:15:13,277 [salt.state       :1951][INFO    ][7621] Completed state [maas_machines_storage_cmp001_lvm] at time 02:15:13.277096 duration_in_ms=2452.772
2019-07-15 02:15:13,280 [salt.minion      :1711][INFO    ][7621] Returning information for job: 20190715021457939624
2019-07-15 02:15:13,870 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command state.apply with jid 20190715021513855883
2019-07-15 02:15:13,904 [salt.minion      :1432][INFO    ][7690] Starting a new job with PID 7690
2019-07-15 02:15:15,114 [salt.state       :915 ][INFO    ][7690] Loading fresh modules for state activity
2019-07-15 02:15:15,183 [salt.fileclient  :1219][INFO    ][7690] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-07-15 02:15:15,228 [salt.state       :1780][INFO    ][7690] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:15:15.228315
2019-07-15 02:15:15,228 [salt.state       :1813][INFO    ][7690] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-15 02:15:15,230 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7690] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-15 02:15:16,948 [salt.state       :300 ][INFO    ][7690] {'pid': 7697, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-15 02:15:16,949 [salt.state       :1951][INFO    ][7690] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:15:16.949446 duration_in_ms=1721.13
2019-07-15 02:15:16,953 [salt.state       :1780][INFO    ][7690] Running state [maas.deploy_machines] at time 02:15:16.952943
2019-07-15 02:15:16,953 [salt.state       :1813][INFO    ][7690] Executing state module.run for [maas.deploy_machines]
2019-07-15 02:15:16,954 [salt.utils.decorators:613 ][WARNING ][7690] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-15 02:15:17,579 [salt.loaded.ext.module.maas:684 ][INFO    ][7690] deploymachines hwe_kernel=ga-16.04 system_id=c3gk8k distro_series=xenial
2019-07-15 02:15:20,073 [salt.loaded.ext.module.maas:684 ][INFO    ][7690] deploymachines hwe_kernel=ga-16.04 system_id=qf7e3w distro_series=xenial
2019-07-15 02:15:22,632 [salt.loaded.ext.module.maas:684 ][INFO    ][7690] deploymachines hwe_kernel=ga-16.04 system_id=nm6qwy distro_series=xenial
2019-07-15 02:15:25,367 [salt.loaded.ext.module.maas:684 ][INFO    ][7690] deploymachines hwe_kernel=ga-16.04 system_id=8aqaa7 distro_series=xenial
2019-07-15 02:15:27,960 [salt.loaded.ext.module.maas:684 ][INFO    ][7690] deploymachines hwe_kernel=ga-16.04 system_id=xkh4qc distro_series=xenial
2019-07-15 02:15:28,978 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715021528963812
2019-07-15 02:15:29,011 [salt.minion      :1432][INFO    ][7949] Starting a new job with PID 7949
2019-07-15 02:15:29,037 [salt.minion      :1711][INFO    ][7949] Returning information for job: 20190715021528963812
2019-07-15 02:15:30,575 [salt.state       :300 ][INFO    ][7690] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-07-15 02:15:30,575 [salt.state       :1951][INFO    ][7690] Completed state [maas.deploy_machines] at time 02:15:30.575544 duration_in_ms=13622.6
2019-07-15 02:15:30,581 [salt.minion      :1711][INFO    ][7690] Returning information for job: 20190715021513855883
2019-07-15 02:15:31,171 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command state.apply with jid 20190715021531159148
2019-07-15 02:15:31,200 [salt.minion      :1432][INFO    ][7981] Starting a new job with PID 7981
2019-07-15 02:15:39,495 [salt.state       :915 ][INFO    ][7981] Loading fresh modules for state activity
2019-07-15 02:15:39,558 [salt.fileclient  :1219][INFO    ][7981] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-07-15 02:15:39,609 [salt.state       :1780][INFO    ][7981] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:15:39.609532
2019-07-15 02:15:39,609 [salt.state       :1813][INFO    ][7981] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-15 02:15:39,611 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7981] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-15 02:15:41,517 [salt.state       :300 ][INFO    ][7981] {'pid': 7999, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-15 02:15:41,519 [salt.state       :1951][INFO    ][7981] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:15:41.519050 duration_in_ms=1909.518
2019-07-15 02:15:41,523 [salt.state       :1780][INFO    ][7981] Running state [maas.wait_for_machine_status] at time 02:15:41.523642
2019-07-15 02:15:41,524 [salt.state       :1813][INFO    ][7981] Executing state module.run for [maas.wait_for_machine_status]
2019-07-15 02:15:41,524 [salt.utils.decorators:613 ][WARNING ][7981] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-15 02:15:44,441 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.09478402s left)
2019-07-15 02:15:46,253 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715021546241675
2019-07-15 02:15:46,273 [salt.minion      :1432][INFO    ][8035] Starting a new job with PID 8035
2019-07-15 02:15:46,302 [salt.minion      :1711][INFO    ][8035] Returning information for job: 20190715021546241675
2019-07-15 02:16:16,343 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715021616328223
2019-07-15 02:16:16,369 [salt.minion      :1432][INFO    ][8065] Starting a new job with PID 8065
2019-07-15 02:16:16,393 [salt.minion      :1711][INFO    ][8065] Returning information for job: 20190715021616328223
2019-07-15 02:16:17,800 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.735919s left)
2019-07-15 02:16:46,465 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715021646453029
2019-07-15 02:16:46,492 [salt.minion      :1432][INFO    ][8107] Starting a new job with PID 8107
2019-07-15 02:16:46,512 [salt.minion      :1711][INFO    ][8107] Returning information for job: 20190715021646453029
2019-07-15 02:16:50,704 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2180.83166099s left)
2019-07-15 02:17:16,544 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715021716528009
2019-07-15 02:17:16,570 [salt.minion      :1432][INFO    ][8150] Starting a new job with PID 8150
2019-07-15 02:17:16,591 [salt.minion      :1711][INFO    ][8150] Returning information for job: 20190715021716528009
2019-07-15 02:17:23,656 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2147.87921095s left)
2019-07-15 02:17:46,627 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715021746611968
2019-07-15 02:17:46,656 [salt.minion      :1432][INFO    ][8206] Starting a new job with PID 8206
2019-07-15 02:17:46,681 [salt.minion      :1711][INFO    ][8206] Returning information for job: 20190715021746611968
2019-07-15 02:17:56,388 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2115.14748216s left)
2019-07-15 02:18:16,722 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715021816710169
2019-07-15 02:18:16,745 [salt.minion      :1432][INFO    ][8271] Starting a new job with PID 8271
2019-07-15 02:18:16,767 [salt.minion      :1711][INFO    ][8271] Returning information for job: 20190715021816710169
2019-07-15 02:18:29,316 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2082.21987796s left)
2019-07-15 02:18:46,834 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715021846820743
2019-07-15 02:18:46,861 [salt.minion      :1432][INFO    ][8433] Starting a new job with PID 8433
2019-07-15 02:18:46,890 [salt.minion      :1711][INFO    ][8433] Returning information for job: 20190715021846820743
2019-07-15 02:19:02,543 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2048.99271417s left)
2019-07-15 02:19:16,950 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715021916933269
2019-07-15 02:19:16,972 [salt.minion      :1432][INFO    ][8478] Starting a new job with PID 8478
2019-07-15 02:19:17,000 [salt.minion      :1711][INFO    ][8478] Returning information for job: 20190715021916933269
2019-07-15 02:19:35,571 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2015.96424913s left)
2019-07-15 02:19:47,073 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715021947064090
2019-07-15 02:19:47,095 [salt.minion      :1432][INFO    ][8824] Starting a new job with PID 8824
2019-07-15 02:19:47,115 [salt.minion      :1711][INFO    ][8824] Returning information for job: 20190715021947064090
2019-07-15 02:20:08,587 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1982.94812298s left)
2019-07-15 02:20:17,170 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715022017154744
2019-07-15 02:20:17,197 [salt.minion      :1432][INFO    ][8966] Starting a new job with PID 8966
2019-07-15 02:20:17,231 [salt.minion      :1711][INFO    ][8966] Returning information for job: 20190715022017154744
2019-07-15 02:20:41,980 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1949.5553s left)
2019-07-15 02:20:47,349 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715022047337340
2019-07-15 02:20:47,380 [salt.minion      :1432][INFO    ][9246] Starting a new job with PID 9246
2019-07-15 02:20:47,401 [salt.minion      :1711][INFO    ][9246] Returning information for job: 20190715022047337340
2019-07-15 02:21:14,871 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1916.66469812s left)
2019-07-15 02:21:17,504 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715022117490285
2019-07-15 02:21:17,530 [salt.minion      :1432][INFO    ][9345] Starting a new job with PID 9345
2019-07-15 02:21:17,564 [salt.minion      :1711][INFO    ][9345] Returning information for job: 20190715022117490285
2019-07-15 02:21:47,635 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715022147624774
2019-07-15 02:21:47,658 [salt.minion      :1432][INFO    ][9716] Starting a new job with PID 9716
2019-07-15 02:21:47,680 [salt.minion      :1711][INFO    ][9716] Returning information for job: 20190715022147624774
2019-07-15 02:21:47,937 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1883.59890199s left)
2019-07-15 02:22:17,784 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715022217767588
2019-07-15 02:22:17,819 [salt.minion      :1432][INFO    ][9844] Starting a new job with PID 9844
2019-07-15 02:22:17,855 [salt.minion      :1711][INFO    ][9844] Returning information for job: 20190715022217767588
2019-07-15 02:22:21,416 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1850.11968303s left)
2019-07-15 02:22:47,963 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715022247949193
2019-07-15 02:22:47,987 [salt.minion      :1432][INFO    ][10059] Starting a new job with PID 10059
2019-07-15 02:22:48,010 [salt.minion      :1711][INFO    ][10059] Returning information for job: 20190715022247949193
2019-07-15 02:22:54,597 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1816.93893003s left)
2019-07-15 02:23:18,149 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715022318132478
2019-07-15 02:23:18,175 [salt.minion      :1432][INFO    ][10209] Starting a new job with PID 10209
2019-07-15 02:23:18,201 [salt.minion      :1711][INFO    ][10209] Returning information for job: 20190715022318132478
2019-07-15 02:23:27,713 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1783.82234216s left)
2019-07-15 02:23:48,317 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715022348297510
2019-07-15 02:23:48,341 [salt.minion      :1432][INFO    ][10284] Starting a new job with PID 10284
2019-07-15 02:23:48,364 [salt.minion      :1711][INFO    ][10284] Returning information for job: 20190715022348297510
2019-07-15 02:24:00,631 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1750.9044621s left)
2019-07-15 02:24:18,505 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715022418492160
2019-07-15 02:24:18,534 [salt.minion      :1432][INFO    ][10479] Starting a new job with PID 10479
2019-07-15 02:24:18,557 [salt.minion      :1711][INFO    ][10479] Returning information for job: 20190715022418492160
2019-07-15 02:24:33,492 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1718.04348302s left)
2019-07-15 02:24:48,673 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715022448665215
2019-07-15 02:24:48,694 [salt.minion      :1432][INFO    ][10565] Starting a new job with PID 10565
2019-07-15 02:24:48,715 [salt.minion      :1711][INFO    ][10565] Returning information for job: 20190715022448665215
2019-07-15 02:25:06,492 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1685.04388213s left)
2019-07-15 02:25:18,852 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715022518840616
2019-07-15 02:25:18,876 [salt.minion      :1432][INFO    ][10722] Starting a new job with PID 10722
2019-07-15 02:25:18,898 [salt.minion      :1711][INFO    ][10722] Returning information for job: 20190715022518840616
2019-07-15 02:25:39,694 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1651.84169412s left)
2019-07-15 02:25:49,051 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715022549038884
2019-07-15 02:25:49,077 [salt.minion      :1432][INFO    ][10846] Starting a new job with PID 10846
2019-07-15 02:25:49,099 [salt.minion      :1711][INFO    ][10846] Returning information for job: 20190715022549038884
2019-07-15 02:26:12,675 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1618.86069012s left)
2019-07-15 02:26:19,244 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715022619222952
2019-07-15 02:26:19,276 [salt.minion      :1432][INFO    ][10946] Starting a new job with PID 10946
2019-07-15 02:26:19,297 [salt.minion      :1711][INFO    ][10946] Returning information for job: 20190715022619222952
2019-07-15 02:26:45,718 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1585.81713605s left)
2019-07-15 02:26:49,434 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715022649421826
2019-07-15 02:26:49,453 [salt.minion      :1432][INFO    ][10981] Starting a new job with PID 10981
2019-07-15 02:26:49,477 [salt.minion      :1711][INFO    ][10981] Returning information for job: 20190715022649421826
2019-07-15 02:27:18,992 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1552.54392695s left)
2019-07-15 02:27:19,615 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715022719602736
2019-07-15 02:27:19,644 [salt.minion      :1432][INFO    ][11158] Starting a new job with PID 11158
2019-07-15 02:27:19,667 [salt.minion      :1711][INFO    ][11158] Returning information for job: 20190715022719602736
2019-07-15 02:27:49,818 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715022749806178
2019-07-15 02:27:49,841 [salt.minion      :1432][INFO    ][11199] Starting a new job with PID 11199
2019-07-15 02:27:49,863 [salt.minion      :1711][INFO    ][11199] Returning information for job: 20190715022749806178
2019-07-15 02:27:51,891 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1519.644449s left)
2019-07-15 02:28:19,931 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715022819921589
2019-07-15 02:28:19,961 [salt.minion      :1432][INFO    ][11359] Starting a new job with PID 11359
2019-07-15 02:28:19,986 [salt.minion      :1711][INFO    ][11359] Returning information for job: 20190715022819921589
2019-07-15 02:28:24,941 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1486.59482312s left)
2019-07-15 02:28:49,978 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715022849968067
2019-07-15 02:28:50,010 [salt.minion      :1432][INFO    ][11450] Starting a new job with PID 11450
2019-07-15 02:28:50,041 [salt.minion      :1711][INFO    ][11450] Returning information for job: 20190715022849968067
2019-07-15 02:28:57,993 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1453.54250407s left)
2019-07-15 02:29:20,021 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715022920003522
2019-07-15 02:29:20,051 [salt.minion      :1432][INFO    ][11590] Starting a new job with PID 11590
2019-07-15 02:29:20,080 [salt.minion      :1711][INFO    ][11590] Returning information for job: 20190715022920003522
2019-07-15 02:29:31,004 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1420.53154898s left)
2019-07-15 02:29:50,063 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715022950048322
2019-07-15 02:29:50,093 [salt.minion      :1432][INFO    ][11625] Starting a new job with PID 11625
2019-07-15 02:29:50,118 [salt.minion      :1711][INFO    ][11625] Returning information for job: 20190715022950048322
2019-07-15 02:30:03,896 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1387.63969612s left)
2019-07-15 02:30:20,083 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715023020069567
2019-07-15 02:30:20,118 [salt.minion      :1432][INFO    ][11665] Starting a new job with PID 11665
2019-07-15 02:30:20,140 [salt.minion      :1711][INFO    ][11665] Returning information for job: 20190715023020069567
2019-07-15 02:30:36,789 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1354.74710894s left)
2019-07-15 02:30:50,168 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715023050152295
2019-07-15 02:30:50,197 [salt.minion      :1432][INFO    ][11706] Starting a new job with PID 11706
2019-07-15 02:30:50,220 [salt.minion      :1711][INFO    ][11706] Returning information for job: 20190715023050152295
2019-07-15 02:31:09,634 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1321.90193415s left)
2019-07-15 02:31:20,236 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715023120216800
2019-07-15 02:31:20,266 [salt.minion      :1432][INFO    ][11746] Starting a new job with PID 11746
2019-07-15 02:31:20,289 [salt.minion      :1711][INFO    ][11746] Returning information for job: 20190715023120216800
2019-07-15 02:31:42,599 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1288.93673897s left)
2019-07-15 02:31:50,328 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715023150311799
2019-07-15 02:31:50,353 [salt.minion      :1432][INFO    ][11785] Starting a new job with PID 11785
2019-07-15 02:31:50,375 [salt.minion      :1711][INFO    ][11785] Returning information for job: 20190715023150311799
2019-07-15 02:32:15,504 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1256.03206897s left)
2019-07-15 02:32:20,419 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715023220405694
2019-07-15 02:32:20,443 [salt.minion      :1432][INFO    ][11834] Starting a new job with PID 11834
2019-07-15 02:32:20,464 [salt.minion      :1711][INFO    ][11834] Returning information for job: 20190715023220405694
2019-07-15 02:32:48,365 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1223.17087603s left)
2019-07-15 02:32:50,517 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715023250501563
2019-07-15 02:32:50,544 [salt.minion      :1432][INFO    ][11871] Starting a new job with PID 11871
2019-07-15 02:32:50,571 [salt.minion      :1711][INFO    ][11871] Returning information for job: 20190715023250501563
2019-07-15 02:33:20,648 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715023320634670
2019-07-15 02:33:20,672 [salt.minion      :1432][INFO    ][11914] Starting a new job with PID 11914
2019-07-15 02:33:20,694 [salt.minion      :1711][INFO    ][11914] Returning information for job: 20190715023320634670
2019-07-15 02:33:21,160 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1190.37519217s left)
2019-07-15 02:33:50,781 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715023350764831
2019-07-15 02:33:50,807 [salt.minion      :1432][INFO    ][11949] Starting a new job with PID 11949
2019-07-15 02:33:50,828 [salt.minion      :1711][INFO    ][11949] Returning information for job: 20190715023350764831
2019-07-15 02:33:54,064 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1157.47140002s left)
2019-07-15 02:34:20,900 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715023420888571
2019-07-15 02:34:20,924 [salt.minion      :1432][INFO    ][11990] Starting a new job with PID 11990
2019-07-15 02:34:20,951 [salt.minion      :1711][INFO    ][11990] Returning information for job: 20190715023420888571
2019-07-15 02:34:26,950 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1124.58631206s left)
2019-07-15 02:34:51,032 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715023451018500
2019-07-15 02:34:51,057 [salt.minion      :1432][INFO    ][12028] Starting a new job with PID 12028
2019-07-15 02:34:51,080 [salt.minion      :1711][INFO    ][12028] Returning information for job: 20190715023451018500
2019-07-15 02:34:59,683 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1091.85209894s left)
2019-07-15 02:35:21,217 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715023521199565
2019-07-15 02:35:21,247 [salt.minion      :1432][INFO    ][12069] Starting a new job with PID 12069
2019-07-15 02:35:21,269 [salt.minion      :1711][INFO    ][12069] Returning information for job: 20190715023521199565
2019-07-15 02:35:32,484 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1059.05207205s left)
2019-07-15 02:35:51,380 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715023551365243
2019-07-15 02:35:51,406 [salt.minion      :1432][INFO    ][12105] Starting a new job with PID 12105
2019-07-15 02:35:51,427 [salt.minion      :1711][INFO    ][12105] Returning information for job: 20190715023551365243
2019-07-15 02:36:05,407 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1026.12854195s left)
2019-07-15 02:36:21,583 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715023621568018
2019-07-15 02:36:21,612 [salt.minion      :1432][INFO    ][12150] Starting a new job with PID 12150
2019-07-15 02:36:21,634 [salt.minion      :1711][INFO    ][12150] Returning information for job: 20190715023621568018
2019-07-15 02:36:38,297 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (993.238981009s left)
2019-07-15 02:36:51,789 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715023651772352
2019-07-15 02:36:51,820 [salt.minion      :1432][INFO    ][12187] Starting a new job with PID 12187
2019-07-15 02:36:51,841 [salt.minion      :1711][INFO    ][12187] Returning information for job: 20190715023651772352
2019-07-15 02:37:11,080 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (960.455132961s left)
2019-07-15 02:37:21,832 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715023721818529
2019-07-15 02:37:21,855 [salt.minion      :1432][INFO    ][12247] Starting a new job with PID 12247
2019-07-15 02:37:21,875 [salt.minion      :1711][INFO    ][12247] Returning information for job: 20190715023721818529
2019-07-15 02:37:43,976 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (927.559313059s left)
2019-07-15 02:37:52,040 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715023752030885
2019-07-15 02:37:52,068 [salt.minion      :1432][INFO    ][12292] Starting a new job with PID 12292
2019-07-15 02:37:52,090 [salt.minion      :1711][INFO    ][12292] Returning information for job: 20190715023752030885
2019-07-15 02:38:17,095 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (894.441271067s left)
2019-07-15 02:38:22,096 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715023822080702
2019-07-15 02:38:22,122 [salt.minion      :1432][INFO    ][12337] Starting a new job with PID 12337
2019-07-15 02:38:22,144 [salt.minion      :1711][INFO    ][12337] Returning information for job: 20190715023822080702
2019-07-15 02:38:49,955 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (861.580533028s left)
2019-07-15 02:38:52,161 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715023852153415
2019-07-15 02:38:52,182 [salt.minion      :1432][INFO    ][12374] Starting a new job with PID 12374
2019-07-15 02:38:52,206 [salt.minion      :1711][INFO    ][12374] Returning information for job: 20190715023852153415
2019-07-15 02:39:22,213 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715023922200994
2019-07-15 02:39:22,236 [salt.minion      :1432][INFO    ][12415] Starting a new job with PID 12415
2019-07-15 02:39:22,261 [salt.minion      :1711][INFO    ][12415] Returning information for job: 20190715023922200994
2019-07-15 02:39:22,760 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (828.775202036s left)
2019-07-15 02:39:52,340 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715023952323974
2019-07-15 02:39:52,367 [salt.minion      :1432][INFO    ][12444] Starting a new job with PID 12444
2019-07-15 02:39:52,388 [salt.minion      :1711][INFO    ][12444] Returning information for job: 20190715023952323974
2019-07-15 02:39:55,494 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (796.042263031s left)
2019-07-15 02:40:22,452 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715024022438146
2019-07-15 02:40:22,479 [salt.minion      :1432][INFO    ][12484] Starting a new job with PID 12484
2019-07-15 02:40:22,503 [salt.minion      :1711][INFO    ][12484] Returning information for job: 20190715024022438146
2019-07-15 02:40:28,495 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (763.040506124s left)
2019-07-15 02:40:52,613 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715024052599574
2019-07-15 02:40:52,640 [salt.minion      :1432][INFO    ][12522] Starting a new job with PID 12522
2019-07-15 02:40:52,661 [salt.minion      :1711][INFO    ][12522] Returning information for job: 20190715024052599574
2019-07-15 02:41:01,285 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (730.250731945s left)
2019-07-15 02:41:22,749 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715024122734833
2019-07-15 02:41:22,780 [salt.minion      :1432][INFO    ][12566] Starting a new job with PID 12566
2019-07-15 02:41:22,799 [salt.minion      :1711][INFO    ][12566] Returning information for job: 20190715024122734833
2019-07-15 02:41:34,175 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (697.360782146s left)
2019-07-15 02:41:52,961 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715024152945000
2019-07-15 02:41:52,988 [salt.minion      :1432][INFO    ][12603] Starting a new job with PID 12603
2019-07-15 02:41:53,015 [salt.minion      :1711][INFO    ][12603] Returning information for job: 20190715024152945000
2019-07-15 02:42:07,087 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (664.448117971s left)
2019-07-15 02:42:23,159 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715024223142180
2019-07-15 02:42:23,187 [salt.minion      :1432][INFO    ][12645] Starting a new job with PID 12645
2019-07-15 02:42:23,212 [salt.minion      :1711][INFO    ][12645] Returning information for job: 20190715024223142180
2019-07-15 02:42:39,843 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (631.69224906s left)
2019-07-15 02:42:53,192 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715024253177084
2019-07-15 02:42:53,213 [salt.minion      :1432][INFO    ][12681] Starting a new job with PID 12681
2019-07-15 02:42:53,236 [salt.minion      :1711][INFO    ][12681] Returning information for job: 20190715024253177084
2019-07-15 02:43:12,600 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (598.935738087s left)
2019-07-15 02:43:23,415 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715024323400329
2019-07-15 02:43:23,445 [salt.minion      :1432][INFO    ][12724] Starting a new job with PID 12724
2019-07-15 02:43:23,467 [salt.minion      :1711][INFO    ][12724] Returning information for job: 20190715024323400329
2019-07-15 02:43:45,344 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (566.191753149s left)
2019-07-15 02:43:53,504 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715024353492016
2019-07-15 02:43:53,532 [salt.minion      :1432][INFO    ][12759] Starting a new job with PID 12759
2019-07-15 02:43:53,561 [salt.minion      :1711][INFO    ][12759] Returning information for job: 20190715024353492016
2019-07-15 02:44:18,509 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (533.026958942s left)
2019-07-15 02:44:23,729 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715024423715867
2019-07-15 02:44:23,753 [salt.minion      :1432][INFO    ][12802] Starting a new job with PID 12802
2019-07-15 02:44:23,775 [salt.minion      :1711][INFO    ][12802] Returning information for job: 20190715024423715867
2019-07-15 02:44:51,256 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (500.279257059s left)
2019-07-15 02:44:53,815 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715024453806120
2019-07-15 02:44:53,842 [salt.minion      :1432][INFO    ][12836] Starting a new job with PID 12836
2019-07-15 02:44:53,866 [salt.minion      :1711][INFO    ][12836] Returning information for job: 20190715024453806120
2019-07-15 02:45:23,936 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715024523925437
2019-07-15 02:45:23,957 [salt.minion      :1432][INFO    ][12877] Starting a new job with PID 12877
2019-07-15 02:45:23,980 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (467.55539012s left)
2019-07-15 02:45:23,982 [salt.minion      :1711][INFO    ][12877] Returning information for job: 20190715024523925437
2019-07-15 02:45:54,083 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715024554068515
2019-07-15 02:45:54,108 [salt.minion      :1432][INFO    ][12908] Starting a new job with PID 12908
2019-07-15 02:45:54,133 [salt.minion      :1711][INFO    ][12908] Returning information for job: 20190715024554068515
2019-07-15 02:45:57,012 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (434.523169994s left)
2019-07-15 02:46:24,277 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715024624259894
2019-07-15 02:46:24,308 [salt.minion      :1432][INFO    ][12950] Starting a new job with PID 12950
2019-07-15 02:46:24,335 [salt.minion      :1711][INFO    ][12950] Returning information for job: 20190715024624259894
2019-07-15 02:46:29,813 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (401.72213006s left)
2019-07-15 02:46:54,404 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715024654388981
2019-07-15 02:46:54,431 [salt.minion      :1432][INFO    ][12983] Starting a new job with PID 12983
2019-07-15 02:46:54,473 [salt.minion      :1711][INFO    ][12983] Returning information for job: 20190715024654388981
2019-07-15 02:47:03,042 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (368.493841171s left)
2019-07-15 02:47:24,439 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715024724426866
2019-07-15 02:47:24,468 [salt.minion      :1432][INFO    ][13169] Starting a new job with PID 13169
2019-07-15 02:47:24,490 [salt.minion      :1711][INFO    ][13169] Returning information for job: 20190715024724426866
2019-07-15 02:47:35,802 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (335.73367095s left)
2019-07-15 02:47:54,462 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715024754447138
2019-07-15 02:47:54,492 [salt.minion      :1432][INFO    ][13206] Starting a new job with PID 13206
2019-07-15 02:47:54,518 [salt.minion      :1711][INFO    ][13206] Returning information for job: 20190715024754447138
2019-07-15 02:48:08,568 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (302.967396021s left)
2019-07-15 02:48:24,524 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715024824508501
2019-07-15 02:48:24,548 [salt.minion      :1432][INFO    ][13248] Starting a new job with PID 13248
2019-07-15 02:48:24,574 [salt.minion      :1711][INFO    ][13248] Returning information for job: 20190715024824508501
2019-07-15 02:48:41,663 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (269.872295141s left)
2019-07-15 02:48:54,737 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715024854723079
2019-07-15 02:48:54,755 [salt.minion      :1432][INFO    ][13285] Starting a new job with PID 13285
2019-07-15 02:48:54,778 [salt.minion      :1711][INFO    ][13285] Returning information for job: 20190715024854723079
2019-07-15 02:49:14,434 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (237.101669073s left)
2019-07-15 02:49:24,790 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715024924774507
2019-07-15 02:49:24,820 [salt.minion      :1432][INFO    ][13333] Starting a new job with PID 13333
2019-07-15 02:49:24,843 [salt.minion      :1711][INFO    ][13333] Returning information for job: 20190715024924774507
2019-07-15 02:49:47,287 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (204.249022007s left)
2019-07-15 02:49:54,872 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715024954859874
2019-07-15 02:49:54,898 [salt.minion      :1432][INFO    ][13368] Starting a new job with PID 13368
2019-07-15 02:49:54,923 [salt.minion      :1711][INFO    ][13368] Returning information for job: 20190715024954859874
2019-07-15 02:50:20,295 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (171.240257025s left)
2019-07-15 02:50:25,004 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715025024987102
2019-07-15 02:50:25,036 [salt.minion      :1432][INFO    ][13410] Starting a new job with PID 13410
2019-07-15 02:50:25,062 [salt.minion      :1711][INFO    ][13410] Returning information for job: 20190715025024987102
2019-07-15 02:50:53,035 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (138.500812054s left)
2019-07-15 02:50:55,135 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715025055121912
2019-07-15 02:50:55,163 [salt.minion      :1432][INFO    ][13444] Starting a new job with PID 13444
2019-07-15 02:50:55,191 [salt.minion      :1711][INFO    ][13444] Returning information for job: 20190715025055121912
2019-07-15 02:51:25,205 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715025125191564
2019-07-15 02:51:25,224 [salt.minion      :1432][INFO    ][13485] Starting a new job with PID 13485
2019-07-15 02:51:25,251 [salt.minion      :1711][INFO    ][13485] Returning information for job: 20190715025125191564
2019-07-15 02:51:25,999 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (105.536198139s left)
2019-07-15 02:51:55,381 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715025155370752
2019-07-15 02:51:55,411 [salt.minion      :1432][INFO    ][13521] Starting a new job with PID 13521
2019-07-15 02:51:55,435 [salt.minion      :1711][INFO    ][13521] Returning information for job: 20190715025155370752
2019-07-15 02:51:58,728 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (72.807320118s left)
2019-07-15 02:52:25,581 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715025225567026
2019-07-15 02:52:25,609 [salt.minion      :1432][INFO    ][13563] Starting a new job with PID 13563
2019-07-15 02:52:25,630 [salt.minion      :1711][INFO    ][13563] Returning information for job: 20190715025225567026
2019-07-15 02:52:31,696 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (39.8395841122s left)
2019-07-15 02:52:55,732 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715025255715888
2019-07-15 02:52:55,762 [salt.minion      :1432][INFO    ][13599] Starting a new job with PID 13599
2019-07-15 02:52:55,784 [salt.minion      :1711][INFO    ][13599] Returning information for job: 20190715025255715888
2019-07-15 02:53:04,575 [salt.loaded.ext.module.maas:1023][INFO    ][7981] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (6.96237802505s left)
2019-07-15 02:53:25,884 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715025325868629
2019-07-15 02:53:25,912 [salt.minion      :1432][INFO    ][13642] Starting a new job with PID 13642
2019-07-15 02:53:25,938 [salt.minion      :1711][INFO    ][13642] Returning information for job: 20190715025325868629
2019-07-15 02:53:37,339 [salt.state       :302 ][ERROR   ][7981] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-07-15 02:53:37,340 [salt.state       :1951][INFO    ][7981] Completed state [maas.wait_for_machine_status] at time 02:53:37.340191 duration_in_ms=2275816.543
2019-07-15 02:53:37,348 [salt.minion      :1711][INFO    ][7981] Returning information for job: 20190715021531159148
2019-07-15 02:53:48,209 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command pillar.get with jid 20190715025348195165
2019-07-15 02:53:48,235 [salt.minion      :1432][INFO    ][13682] Starting a new job with PID 13682
2019-07-15 02:53:48,246 [salt.minion      :1711][INFO    ][13682] Returning information for job: 20190715025348195165
2019-07-15 02:53:48,814 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command service.status with jid 20190715025348800326
2019-07-15 02:53:48,840 [salt.minion      :1432][INFO    ][13688] Starting a new job with PID 13688
2019-07-15 02:53:49,655 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13688] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-07-15 02:53:49,703 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13688] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-07-15 02:53:49,726 [salt.minion      :1711][INFO    ][13688] Returning information for job: 20190715025348800326
2019-07-15 02:53:50,309 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command state.apply with jid 20190715025350297336
2019-07-15 02:53:50,336 [salt.minion      :1432][INFO    ][13699] Starting a new job with PID 13699
2019-07-15 02:53:58,592 [salt.state       :915 ][INFO    ][13699] Loading fresh modules for state activity
2019-07-15 02:53:59,223 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13699] Executing command 'salt-minion --version' in directory '/root'
2019-07-15 02:53:59,563 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13699] Executing command 'salt-minion --version' in directory '/root'
2019-07-15 02:54:00,636 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13699] Executing command 'salt-minion --version' in directory '/root'
2019-07-15 02:54:00,927 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13699] Executing command 'salt-minion --version' in directory '/root'
2019-07-15 02:54:02,900 [salt.state       :1780][INFO    ][13699] Running state [salt-minion] at time 02:54:02.900718
2019-07-15 02:54:02,901 [salt.state       :1813][INFO    ][13699] Executing state pkg.installed for [salt-minion]
2019-07-15 02:54:02,902 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13699] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-07-15 02:54:03,019 [salt.state       :300 ][INFO    ][13699] All specified packages are already installed
2019-07-15 02:54:03,019 [salt.state       :1951][INFO    ][13699] Completed state [salt-minion] at time 02:54:03.019512 duration_in_ms=118.794
2019-07-15 02:54:03,019 [salt.state       :1780][INFO    ][13699] Running state [salt_minion_dependency_packages] at time 02:54:03.019873
2019-07-15 02:54:03,020 [salt.state       :1813][INFO    ][13699] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-07-15 02:54:03,030 [salt.state       :300 ][INFO    ][13699] All specified packages are already installed
2019-07-15 02:54:03,030 [salt.state       :1951][INFO    ][13699] Completed state [salt_minion_dependency_packages] at time 02:54:03.030585 duration_in_ms=10.712
2019-07-15 02:54:03,034 [salt.state       :1780][INFO    ][13699] Running state [/etc/salt/minion.d/minion.conf] at time 02:54:03.034817
2019-07-15 02:54:03,035 [salt.state       :1813][INFO    ][13699] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-07-15 02:54:03,321 [salt.state       :300 ][INFO    ][13699] File /etc/salt/minion.d/minion.conf is in the correct state
2019-07-15 02:54:03,322 [salt.state       :1951][INFO    ][13699] Completed state [/etc/salt/minion.d/minion.conf] at time 02:54:03.322646 duration_in_ms=286.886
2019-07-15 02:54:03,323 [salt.state       :1780][INFO    ][13699] Running state [python-netaddr] at time 02:54:03.323008
2019-07-15 02:54:03,323 [salt.state       :1813][INFO    ][13699] Executing state pkg.installed for [python-netaddr]
2019-07-15 02:54:03,335 [salt.state       :300 ][INFO    ][13699] All specified packages are already installed
2019-07-15 02:54:03,335 [salt.state       :1951][INFO    ][13699] Completed state [python-netaddr] at time 02:54:03.335275 duration_in_ms=12.268
2019-07-15 02:54:03,341 [salt.state       :1780][INFO    ][13699] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:54:03.341326
2019-07-15 02:54:03,342 [salt.state       :1813][INFO    ][13699] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-07-15 02:54:03,361 [salt.state       :300 ][INFO    ][13699] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-07-15 02:54:03,363 [salt.state       :1951][INFO    ][13699] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:54:03.361729 duration_in_ms=20.402
2019-07-15 02:54:03,365 [salt.state       :1780][INFO    ][13699] Running state [salt-minion] at time 02:54:03.365194
2019-07-15 02:54:03,366 [salt.state       :1813][INFO    ][13699] Executing state service.running for [salt-minion]
2019-07-15 02:54:03,367 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13699] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-07-15 02:54:03,412 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13699] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-07-15 02:54:03,435 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13699] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-07-15 02:54:03,461 [salt.state       :300 ][INFO    ][13699] The service salt-minion is already running
2019-07-15 02:54:03,462 [salt.state       :1951][INFO    ][13699] Completed state [salt-minion] at time 02:54:03.462213 duration_in_ms=97.019
2019-07-15 02:54:03,465 [salt.state       :1780][INFO    ][13699] Running state [/etc/salt/grains.d] at time 02:54:03.465289
2019-07-15 02:54:03,465 [salt.state       :1813][INFO    ][13699] Executing state file.directory for [/etc/salt/grains.d]
2019-07-15 02:54:03,467 [salt.state       :300 ][INFO    ][13699] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-07-15 02:54:03,468 [salt.state       :1951][INFO    ][13699] Completed state [/etc/salt/grains.d] at time 02:54:03.468067 duration_in_ms=2.779
2019-07-15 02:54:03,469 [salt.state       :1780][INFO    ][13699] Running state [/etc/salt/grains] at time 02:54:03.469401
2019-07-15 02:54:03,472 [salt.state       :1813][INFO    ][13699] Executing state file.managed for [/etc/salt/grains]
2019-07-15 02:54:03,472 [salt.state       :300 ][INFO    ][13699] File /etc/salt/grains exists with proper permissions. No changes made.
2019-07-15 02:54:03,473 [salt.state       :1951][INFO    ][13699] Completed state [/etc/salt/grains] at time 02:54:03.472978 duration_in_ms=3.578
2019-07-15 02:54:03,473 [salt.state       :1780][INFO    ][13699] Running state [/etc/salt/grains.d/placeholder] at time 02:54:03.473474
2019-07-15 02:54:03,473 [salt.state       :1813][INFO    ][13699] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-07-15 02:54:03,474 [salt.state       :300 ][INFO    ][13699] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-07-15 02:54:03,474 [salt.state       :1951][INFO    ][13699] Completed state [/etc/salt/grains.d/placeholder] at time 02:54:03.474455 duration_in_ms=0.981
2019-07-15 02:54:03,475 [salt.state       :1780][INFO    ][13699] Running state [/etc/salt/grains.d/sphinx] at time 02:54:03.474954
2019-07-15 02:54:03,475 [salt.state       :1813][INFO    ][13699] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-07-15 02:54:03,476 [salt.state       :300 ][INFO    ][13699] File /etc/salt/grains.d/sphinx is in the correct state
2019-07-15 02:54:03,476 [salt.state       :1951][INFO    ][13699] Completed state [/etc/salt/grains.d/sphinx] at time 02:54:03.476808 duration_in_ms=1.854
2019-07-15 02:54:03,479 [salt.state       :1780][INFO    ][13699] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:54:03.479001
2019-07-15 02:54:03,479 [salt.state       :1813][INFO    ][13699] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-07-15 02:54:03,479 [salt.state       :300 ][INFO    ][13699] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-07-15 02:54:03,479 [salt.state       :1951][INFO    ][13699] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:54:03.479799 duration_in_ms=0.798
2019-07-15 02:54:03,480 [salt.state       :1780][INFO    ][13699] Running state [/etc/salt/grains.d/dns_records] at time 02:54:03.480306
2019-07-15 02:54:03,480 [salt.state       :1813][INFO    ][13699] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-07-15 02:54:03,481 [salt.state       :300 ][INFO    ][13699] File /etc/salt/grains.d/dns_records is in the correct state
2019-07-15 02:54:03,483 [salt.state       :1951][INFO    ][13699] Completed state [/etc/salt/grains.d/dns_records] at time 02:54:03.481705 duration_in_ms=1.399
2019-07-15 02:54:03,484 [salt.state       :1780][INFO    ][13699] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:54:03.484144
2019-07-15 02:54:03,484 [salt.state       :1813][INFO    ][13699] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-07-15 02:54:03,484 [salt.state       :300 ][INFO    ][13699] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-07-15 02:54:03,484 [salt.state       :1951][INFO    ][13699] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:54:03.484901 duration_in_ms=0.758
2019-07-15 02:54:03,485 [salt.state       :1780][INFO    ][13699] Running state [/etc/salt/grains.d/salt] at time 02:54:03.485390
2019-07-15 02:54:03,485 [salt.state       :1813][INFO    ][13699] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-07-15 02:54:03,486 [salt.state       :300 ][INFO    ][13699] File /etc/salt/grains.d/salt is in the correct state
2019-07-15 02:54:03,486 [salt.state       :1951][INFO    ][13699] Completed state [/etc/salt/grains.d/salt] at time 02:54:03.486764 duration_in_ms=1.375
2019-07-15 02:54:03,487 [salt.state       :1780][INFO    ][13699] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:54:03.487657
2019-07-15 02:54:03,487 [salt.state       :1813][INFO    ][13699] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-07-15 02:54:03,488 [salt.state       :300 ][INFO    ][13699] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-07-15 02:54:03,488 [salt.state       :1951][INFO    ][13699] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:54:03.488400 duration_in_ms=0.743
2019-07-15 02:54:03,490 [salt.state       :1780][INFO    ][13699] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:54:03.490373
2019-07-15 02:54:03,490 [salt.state       :1813][INFO    ][13699] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-07-15 02:54:03,490 [salt.state       :300 ][INFO    ][13699] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-07-15 02:54:03,491 [salt.state       :1951][INFO    ][13699] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:54:03.491149 duration_in_ms=0.776
2019-07-15 02:54:03,491 [salt.state       :1780][INFO    ][13699] Running state [mine.update] at time 02:54:03.491818
2019-07-15 02:54:03,492 [salt.state       :1813][INFO    ][13699] Executing state module.wait for [mine.update]
2019-07-15 02:54:03,492 [salt.state       :300 ][INFO    ][13699] No changes made for mine.update
2019-07-15 02:54:03,492 [salt.state       :1951][INFO    ][13699] Completed state [mine.update] at time 02:54:03.492519 duration_in_ms=0.701
2019-07-15 02:54:03,492 [salt.state       :1780][INFO    ][13699] Running state [ca-certificates] at time 02:54:03.492757
2019-07-15 02:54:03,493 [salt.state       :1813][INFO    ][13699] Executing state pkg.installed for [ca-certificates]
2019-07-15 02:54:03,502 [salt.state       :300 ][INFO    ][13699] All specified packages are already installed
2019-07-15 02:54:03,503 [salt.state       :1951][INFO    ][13699] Completed state [ca-certificates] at time 02:54:03.503059 duration_in_ms=10.302
2019-07-15 02:54:03,503 [salt.state       :1780][INFO    ][13699] Running state [update-ca-certificates] at time 02:54:03.503763
2019-07-15 02:54:03,504 [salt.state       :1813][INFO    ][13699] Executing state cmd.wait for [update-ca-certificates]
2019-07-15 02:54:03,504 [salt.state       :300 ][INFO    ][13699] No changes made for update-ca-certificates
2019-07-15 02:54:03,504 [salt.state       :1951][INFO    ][13699] Completed state [update-ca-certificates] at time 02:54:03.504492 duration_in_ms=0.729
2019-07-15 02:54:03,504 [salt.state       :1780][INFO    ][13699] Running state [iptables] at time 02:54:03.504727
2019-07-15 02:54:03,504 [salt.state       :1813][INFO    ][13699] Executing state pkg.installed for [iptables]
2019-07-15 02:54:03,514 [salt.state       :300 ][INFO    ][13699] All specified packages are already installed
2019-07-15 02:54:03,514 [salt.state       :1951][INFO    ][13699] Completed state [iptables] at time 02:54:03.514604 duration_in_ms=9.877
2019-07-15 02:54:03,514 [salt.state       :1780][INFO    ][13699] Running state [iptables-persistent] at time 02:54:03.514850
2019-07-15 02:54:03,515 [salt.state       :1813][INFO    ][13699] Executing state pkg.installed for [iptables-persistent]
2019-07-15 02:54:03,524 [salt.state       :300 ][INFO    ][13699] All specified packages are already installed
2019-07-15 02:54:03,524 [salt.state       :1951][INFO    ][13699] Completed state [iptables-persistent] at time 02:54:03.524783 duration_in_ms=9.933
2019-07-15 02:54:03,525 [salt.state       :1780][INFO    ][13699] Running state [iptables_modules_v4_load] at time 02:54:03.525769
2019-07-15 02:54:03,526 [salt.state       :1813][INFO    ][13699] Executing state kmod.present for [iptables_modules_v4_load]
2019-07-15 02:54:03,526 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13699] Executing command 'lsmod' in directory '/root'
2019-07-15 02:54:03,547 [salt.state       :300 ][INFO    ][13699] Kernel modules iptable_filter, ip_tables are already present
2019-07-15 02:54:03,547 [salt.state       :1951][INFO    ][13699] Completed state [iptables_modules_v4_load] at time 02:54:03.547666 duration_in_ms=21.896
2019-07-15 02:54:03,549 [salt.state       :1780][INFO    ][13699] Running state [/etc/iptables/rules.v4] at time 02:54:03.548954
2019-07-15 02:54:03,549 [salt.state       :1813][INFO    ][13699] Executing state file.managed for [/etc/iptables/rules.v4]
2019-07-15 02:54:03,660 [salt.state       :300 ][INFO    ][13699] File /etc/iptables/rules.v4 is in the correct state
2019-07-15 02:54:03,661 [salt.state       :1951][INFO    ][13699] Completed state [/etc/iptables/rules.v4] at time 02:54:03.660995 duration_in_ms=112.042
2019-07-15 02:54:03,662 [salt.state       :1780][INFO    ][13699] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:54:03.662471
2019-07-15 02:54:03,662 [salt.state       :1813][INFO    ][13699] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-07-15 02:54:03,663 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13699] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-07-15 02:54:03,684 [salt.state       :300 ][INFO    ][13699] onlyif execution failed
2019-07-15 02:54:03,685 [salt.state       :1951][INFO    ][13699] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:54:03.685276 duration_in_ms=22.804
2019-07-15 02:54:03,689 [salt.state       :1780][INFO    ][13699] Running state [netfilter-persistent] at time 02:54:03.689467
2019-07-15 02:54:03,690 [salt.state       :1813][INFO    ][13699] Executing state service.running for [netfilter-persistent]
2019-07-15 02:54:03,691 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13699] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-07-15 02:54:03,716 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13699] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-07-15 02:54:03,739 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13699] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-07-15 02:54:03,762 [salt.state       :300 ][INFO    ][13699] The service netfilter-persistent is already running
2019-07-15 02:54:03,763 [salt.state       :1951][INFO    ][13699] Completed state [netfilter-persistent] at time 02:54:03.763126 duration_in_ms=73.66
2019-07-15 02:54:03,764 [salt.state       :1780][INFO    ][13699] Running state [iptables_extra.remove_stale_tables] at time 02:54:03.764574
2019-07-15 02:54:03,765 [salt.state       :1813][INFO    ][13699] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-07-15 02:54:03,765 [salt.state       :300 ][INFO    ][13699] No changes made for iptables_extra.remove_stale_tables
2019-07-15 02:54:03,765 [salt.state       :1951][INFO    ][13699] Completed state [iptables_extra.remove_stale_tables] at time 02:54:03.765861 duration_in_ms=1.287
2019-07-15 02:54:03,766 [salt.state       :1780][INFO    ][13699] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:54:03.766178
2019-07-15 02:54:03,766 [salt.state       :1813][INFO    ][13699] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-07-15 02:54:03,767 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13699] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-07-15 02:54:03,784 [salt.state       :300 ][INFO    ][13699] onlyif execution failed
2019-07-15 02:54:03,785 [salt.state       :1951][INFO    ][13699] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:54:03.785064 duration_in_ms=18.885
2019-07-15 02:54:03,786 [salt.state       :1780][INFO    ][13699] Running state [/etc/iptables/rules.v6] at time 02:54:03.786734
2019-07-15 02:54:03,787 [salt.state       :1813][INFO    ][13699] Executing state file.absent for [/etc/iptables/rules.v6]
2019-07-15 02:54:03,788 [salt.state       :300 ][INFO    ][13699] File /etc/iptables/rules.v6 is not present
2019-07-15 02:54:03,788 [salt.state       :1951][INFO    ][13699] Completed state [/etc/iptables/rules.v6] at time 02:54:03.788343 duration_in_ms=1.61
2019-07-15 02:54:03,789 [salt.state       :1780][INFO    ][13699] Running state [iptables_extra.flush_all] at time 02:54:03.789562
2019-07-15 02:54:03,790 [salt.state       :1813][INFO    ][13699] Executing state module.wait for [iptables_extra.flush_all]
2019-07-15 02:54:03,790 [salt.state       :300 ][INFO    ][13699] No changes made for iptables_extra.flush_all
2019-07-15 02:54:03,790 [salt.state       :1951][INFO    ][13699] Completed state [iptables_extra.flush_all] at time 02:54:03.790860 duration_in_ms=1.299
2019-07-15 02:54:03,798 [salt.minion      :1711][INFO    ][13699] Returning information for job: 20190715025350297336
2019-07-15 02:54:04,362 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command state.apply with jid 20190715025404345370
2019-07-15 02:54:04,390 [salt.minion      :1432][INFO    ][13788] Starting a new job with PID 13788
2019-07-15 02:54:05,549 [salt.state       :915 ][INFO    ][13788] Loading fresh modules for state activity
2019-07-15 02:54:06,501 [salt.state       :1780][INFO    ][13788] Running state [maas-rack-controller] at time 02:54:06.501425
2019-07-15 02:54:06,502 [salt.state       :1813][INFO    ][13788] Executing state pkg.installed for [maas-rack-controller]
2019-07-15 02:54:06,503 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13788] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-07-15 02:54:06,624 [salt.state       :300 ][INFO    ][13788] All specified packages are already installed
2019-07-15 02:54:06,624 [salt.state       :1951][INFO    ][13788] Completed state [maas-rack-controller] at time 02:54:06.624468 duration_in_ms=123.044
2019-07-15 02:54:06,624 [salt.state       :1780][INFO    ][13788] Running state [ipmitool] at time 02:54:06.624783
2019-07-15 02:54:06,625 [salt.state       :1813][INFO    ][13788] Executing state pkg.installed for [ipmitool]
2019-07-15 02:54:06,634 [salt.state       :300 ][INFO    ][13788] All specified packages are already installed
2019-07-15 02:54:06,634 [salt.state       :1951][INFO    ][13788] Completed state [ipmitool] at time 02:54:06.634753 duration_in_ms=9.97
2019-07-15 02:54:06,638 [salt.state       :1780][INFO    ][13788] Running state [/etc/maas/rackd.conf] at time 02:54:06.638918
2019-07-15 02:54:06,639 [salt.state       :1813][INFO    ][13788] Executing state file.line for [/etc/maas/rackd.conf]
2019-07-15 02:54:06,640 [salt.state       :300 ][INFO    ][13788] No changes needed to be made
2019-07-15 02:54:06,640 [salt.state       :1951][INFO    ][13788] Completed state [/etc/maas/rackd.conf] at time 02:54:06.640276 duration_in_ms=1.358
2019-07-15 02:54:06,640 [salt.state       :1780][INFO    ][13788] Running state [/etc/maas/rackd.conf] at time 02:54:06.640528
2019-07-15 02:54:06,640 [salt.state       :1813][INFO    ][13788] Executing state file.managed for [/etc/maas/rackd.conf]
2019-07-15 02:54:06,641 [salt.loaded.int.states.file:2298][WARNING ][13788] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-07-15 02:54:06,641 [salt.state       :300 ][INFO    ][13788] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-07-15 02:54:06,642 [salt.state       :1951][INFO    ][13788] Completed state [/etc/maas/rackd.conf] at time 02:54:06.642014 duration_in_ms=1.486
2019-07-15 02:54:06,643 [salt.state       :1780][INFO    ][13788] Running state [maas-rackd] at time 02:54:06.643057
2019-07-15 02:54:06,643 [salt.state       :1813][INFO    ][13788] Executing state service.running for [maas-rackd]
2019-07-15 02:54:06,643 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13788] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-07-15 02:54:06,684 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13788] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-07-15 02:54:06,707 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13788] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-07-15 02:54:06,733 [salt.state       :300 ][INFO    ][13788] The service maas-rackd is already running
2019-07-15 02:54:06,736 [salt.state       :1951][INFO    ][13788] Completed state [maas-rackd] at time 02:54:06.735212 duration_in_ms=92.153
2019-07-15 02:54:06,738 [salt.minion      :1711][INFO    ][13788] Returning information for job: 20190715025404345370
2019-07-15 02:54:07,303 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command state.apply with jid 20190715025407284787
2019-07-15 02:54:07,334 [salt.minion      :1432][INFO    ][13813] Starting a new job with PID 13813
2019-07-15 02:54:08,468 [salt.state       :915 ][INFO    ][13813] Loading fresh modules for state activity
2019-07-15 02:54:09,544 [salt.state       :1780][INFO    ][13813] Running state [maas-region-controller] at time 02:54:09.544254
2019-07-15 02:54:09,544 [salt.state       :1813][INFO    ][13813] Executing state pkg.installed for [maas-region-controller]
2019-07-15 02:54:09,545 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13813] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-07-15 02:54:09,657 [salt.state       :300 ][INFO    ][13813] All specified packages are already installed
2019-07-15 02:54:09,658 [salt.state       :1951][INFO    ][13813] Completed state [maas-region-controller] at time 02:54:09.658028 duration_in_ms=113.774
2019-07-15 02:54:09,658 [salt.state       :1780][INFO    ][13813] Running state [python-oauth] at time 02:54:09.658355
2019-07-15 02:54:09,658 [salt.state       :1813][INFO    ][13813] Executing state pkg.installed for [python-oauth]
2019-07-15 02:54:09,667 [salt.state       :300 ][INFO    ][13813] All specified packages are already installed
2019-07-15 02:54:09,668 [salt.state       :1951][INFO    ][13813] Completed state [python-oauth] at time 02:54:09.668016 duration_in_ms=9.661
2019-07-15 02:54:09,671 [salt.state       :1780][INFO    ][13813] Running state [/etc/maas/regiond.conf] at time 02:54:09.671192
2019-07-15 02:54:09,671 [salt.state       :1813][INFO    ][13813] Executing state file.replace for [/etc/maas/regiond.conf]
2019-07-15 02:54:09,676 [salt.state       :300 ][INFO    ][13813] No changes needed to be made
2019-07-15 02:54:09,677 [salt.state       :1951][INFO    ][13813] Completed state [/etc/maas/regiond.conf] at time 02:54:09.677044 duration_in_ms=5.852
2019-07-15 02:54:09,677 [salt.state       :1780][INFO    ][13813] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:54:09.677541
2019-07-15 02:54:09,677 [salt.state       :1813][INFO    ][13813] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-07-15 02:54:09,735 [salt.state       :300 ][INFO    ][13813] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-07-15 02:54:09,735 [salt.state       :1951][INFO    ][13813] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:54:09.735921 duration_in_ms=58.379
2019-07-15 02:54:09,736 [salt.state       :1780][INFO    ][13813] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:54:09.736411
2019-07-15 02:54:09,736 [salt.state       :1813][INFO    ][13813] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-07-15 02:54:09,740 [salt.state       :300 ][INFO    ][13813] No changes needed to be made
2019-07-15 02:54:09,740 [salt.state       :1951][INFO    ][13813] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:54:09.740370 duration_in_ms=3.959
2019-07-15 02:54:09,740 [salt.state       :1780][INFO    ][13813] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:54:09.740862
2019-07-15 02:54:09,741 [salt.state       :1813][INFO    ][13813] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-07-15 02:54:09,744 [salt.state       :300 ][INFO    ][13813] No changes needed to be made
2019-07-15 02:54:09,744 [salt.state       :1951][INFO    ][13813] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:54:09.744702 duration_in_ms=3.84
2019-07-15 02:54:09,745 [salt.state       :1780][INFO    ][13813] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:54:09.745190
2019-07-15 02:54:09,745 [salt.state       :1813][INFO    ][13813] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-07-15 02:54:09,775 [salt.state       :300 ][INFO    ][13813] No changes needed to be made
2019-07-15 02:54:09,775 [salt.state       :1951][INFO    ][13813] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:54:09.775828 duration_in_ms=30.637
2019-07-15 02:54:09,776 [salt.state       :1780][INFO    ][13813] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:54:09.776389
2019-07-15 02:54:09,776 [salt.state       :1813][INFO    ][13813] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-07-15 02:54:09,797 [salt.state       :300 ][INFO    ][13813] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-07-15 02:54:09,797 [salt.state       :1951][INFO    ][13813] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:54:09.797781 duration_in_ms=21.39
2019-07-15 02:54:09,799 [salt.state       :1780][INFO    ][13813] Running state [a2enmod headers] at time 02:54:09.799672
2019-07-15 02:54:09,800 [salt.state       :1813][INFO    ][13813] Executing state cmd.run for [a2enmod headers]
2019-07-15 02:54:09,801 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13813] Executing command 'a2enmod headers' in directory '/root'
2019-07-15 02:54:09,863 [salt.state       :300 ][INFO    ][13813] {'pid': 13832, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-07-15 02:54:09,864 [salt.state       :1951][INFO    ][13813] Completed state [a2enmod headers] at time 02:54:09.864067 duration_in_ms=64.396
2019-07-15 02:54:09,864 [salt.state       :1780][INFO    ][13813] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:54:09.864408
2019-07-15 02:54:09,864 [salt.state       :1813][INFO    ][13813] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-07-15 02:54:09,887 [salt.state       :300 ][INFO    ][13813] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-07-15 02:54:09,887 [salt.state       :1951][INFO    ][13813] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:54:09.887796 duration_in_ms=23.386
2019-07-15 02:54:09,889 [salt.state       :1780][INFO    ][13813] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:54:09.889080
2019-07-15 02:54:09,892 [salt.state       :1813][INFO    ][13813] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-07-15 02:54:09,953 [salt.state       :300 ][INFO    ][13813] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-07-15 02:54:09,954 [salt.state       :1951][INFO    ][13813] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:54:09.954139 duration_in_ms=65.06
2019-07-15 02:54:09,954 [salt.state       :1780][INFO    ][13813] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:54:09.954652
2019-07-15 02:54:09,954 [salt.state       :1813][INFO    ][13813] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-07-15 02:54:10,016 [salt.state       :300 ][INFO    ][13813] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-07-15 02:54:10,016 [salt.state       :1951][INFO    ][13813] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:54:10.016659 duration_in_ms=62.007
2019-07-15 02:54:10,017 [salt.state       :1780][INFO    ][13813] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:54:10.017159
2019-07-15 02:54:10,017 [salt.state       :1813][INFO    ][13813] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-07-15 02:54:10,088 [salt.state       :300 ][INFO    ][13813] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-07-15 02:54:10,088 [salt.state       :1951][INFO    ][13813] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:54:10.088238 duration_in_ms=71.079
2019-07-15 02:54:10,088 [salt.state       :1780][INFO    ][13813] Running state [/root/.pgpass] at time 02:54:10.088507
2019-07-15 02:54:10,088 [salt.state       :1813][INFO    ][13813] Executing state file.managed for [/root/.pgpass]
2019-07-15 02:54:10,138 [salt.state       :300 ][INFO    ][13813] File /root/.pgpass is in the correct state
2019-07-15 02:54:10,138 [salt.state       :1951][INFO    ][13813] Completed state [/root/.pgpass] at time 02:54:10.138604 duration_in_ms=50.096
2019-07-15 02:54:10,145 [salt.state       :1780][INFO    ][13813] Running state [maas-region syncdb --noinput] at time 02:54:10.145634
2019-07-15 02:54:10,145 [salt.state       :1813][INFO    ][13813] Executing state cmd.run for [maas-region syncdb --noinput]
2019-07-15 02:54:10,146 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13813] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-07-15 02:54:12,638 [salt.state       :300 ][INFO    ][13813] {'pid': 13846, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: metadataserver, contenttypes, sites, auth, maasserver, piston3, sessions\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-07-15 02:54:12,639 [salt.state       :1951][INFO    ][13813] Completed state [maas-region syncdb --noinput] at time 02:54:12.639084 duration_in_ms=2493.45
2019-07-15 02:54:12,639 [salt.state       :2022][WARNING ][13813] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-07-15 02:54:12,641 [salt.state       :1780][INFO    ][13813] Running state [maas-regiond] at time 02:54:12.641199
2019-07-15 02:54:12,641 [salt.state       :1813][INFO    ][13813] Executing state service.running for [maas-regiond]
2019-07-15 02:54:12,643 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13813] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-07-15 02:54:12,690 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13813] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-07-15 02:54:12,705 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13813] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-07-15 02:54:12,721 [salt.state       :300 ][INFO    ][13813] The service maas-regiond is already running
2019-07-15 02:54:12,723 [salt.state       :1951][INFO    ][13813] Completed state [maas-regiond] at time 02:54:12.723806 duration_in_ms=82.606
2019-07-15 02:54:12,726 [salt.state       :1780][INFO    ][13813] Running state [bind9] at time 02:54:12.726876
2019-07-15 02:54:12,727 [salt.state       :1813][INFO    ][13813] Executing state service.running for [bind9]
2019-07-15 02:54:12,728 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13813] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-07-15 02:54:12,750 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13813] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-07-15 02:54:12,767 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13813] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-07-15 02:54:12,787 [salt.state       :300 ][INFO    ][13813] The service bind9 is already running
2019-07-15 02:54:12,787 [salt.state       :1951][INFO    ][13813] Completed state [bind9] at time 02:54:12.787746 duration_in_ms=60.87
2019-07-15 02:54:12,790 [salt.state       :1780][INFO    ][13813] Running state [apache2] at time 02:54:12.790520
2019-07-15 02:54:12,791 [salt.state       :1813][INFO    ][13813] Executing state service.running for [apache2]
2019-07-15 02:54:12,792 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13813] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-07-15 02:54:12,813 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13813] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-07-15 02:54:12,833 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13813] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-07-15 02:54:12,852 [salt.state       :300 ][INFO    ][13813] The service apache2 is already running
2019-07-15 02:54:12,853 [salt.state       :1951][INFO    ][13813] Completed state [apache2] at time 02:54:12.853318 duration_in_ms=62.798
2019-07-15 02:54:12,857 [salt.state       :1780][INFO    ][13813] Running state [maasng.wait_for_http_code] at time 02:54:12.857530
2019-07-15 02:54:12,858 [salt.state       :1813][INFO    ][13813] Executing state module.run for [maasng.wait_for_http_code]
2019-07-15 02:54:12,858 [salt.utils.decorators:613 ][WARNING ][13813] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-15 02:54:13,003 [salt.state       :300 ][INFO    ][13813] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-07-15 02:54:13,003 [salt.state       :1951][INFO    ][13813] Completed state [maasng.wait_for_http_code] at time 02:54:13.003813 duration_in_ms=146.283
2019-07-15 02:54:13,004 [salt.state       :1780][INFO    ][13813] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:54:13.004898
2019-07-15 02:54:13,005 [salt.state       :1813][INFO    ][13813] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-07-15 02:54:13,006 [salt.state       :300 ][INFO    ][13813] /var/lib/maas/.setup_admin exists
2019-07-15 02:54:13,006 [salt.state       :1951][INFO    ][13813] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:54:13.006484 duration_in_ms=1.586
2019-07-15 02:54:13,007 [salt.state       :1780][INFO    ][13813] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:13.007798
2019-07-15 02:54:13,008 [salt.state       :1813][INFO    ][13813] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-15 02:54:13,009 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13813] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-15 02:54:14,716 [salt.state       :300 ][INFO    ][13813] {'pid': 13867, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-15 02:54:14,718 [salt.state       :1951][INFO    ][13813] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:14.717706 duration_in_ms=1709.906
2019-07-15 02:54:14,728 [salt.state       :1780][INFO    ][13813] Running state [maas_region_boot_source_resources_mirror] at time 02:54:14.728172
2019-07-15 02:54:14,728 [salt.state       :1813][INFO    ][13813] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-07-15 02:54:14,814 [salt.state       :300 ][INFO    ][13813] {'changes': {}}
2019-07-15 02:54:14,815 [salt.state       :1951][INFO    ][13813] Completed state [maas_region_boot_source_resources_mirror] at time 02:54:14.815361 duration_in_ms=87.188
2019-07-15 02:54:14,817 [salt.state       :1780][INFO    ][13813] Running state [maasng.boot_resources_import] at time 02:54:14.817197
2019-07-15 02:54:14,818 [salt.state       :1813][INFO    ][13813] Executing state module.run for [maasng.boot_resources_import]
2019-07-15 02:54:14,818 [salt.utils.decorators:613 ][WARNING ][13813] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-15 02:54:14,902 [salt.loaded.ext.module.maasng:1600][INFO    ][13813] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-07-15 02:54:20,159 [salt.loaded.ext.module.maasng:1600][INFO    ][13813] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-07-15 02:54:22,412 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715025422400580
2019-07-15 02:54:22,441 [salt.minion      :1432][INFO    ][14035] Starting a new job with PID 14035
2019-07-15 02:54:22,470 [salt.minion      :1711][INFO    ][14035] Returning information for job: 20190715025422400580
2019-07-15 02:54:25,255 [salt.state       :300 ][INFO    ][13813] {'ret': True}
2019-07-15 02:54:25,255 [salt.state       :1951][INFO    ][13813] Completed state [maasng.boot_resources_import] at time 02:54:25.255849 duration_in_ms=10438.652
2019-07-15 02:54:25,257 [salt.state       :1780][INFO    ][13813] Running state [maas_region_boot_sources_selection_xenial] at time 02:54:25.257122
2019-07-15 02:54:25,257 [salt.state       :1813][INFO    ][13813] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-07-15 02:54:25,449 [salt.state       :300 ][INFO    ][13813] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-07-15 02:54:25,449 [salt.state       :1951][INFO    ][13813] Completed state [maas_region_boot_sources_selection_xenial] at time 02:54:25.449412 duration_in_ms=192.288
2019-07-15 02:54:25,451 [salt.state       :1780][INFO    ][13813] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 02:54:25.451687
2019-07-15 02:54:25,452 [salt.state       :1813][INFO    ][13813] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-07-15 02:54:25,452 [salt.utils.decorators:613 ][WARNING ][13813] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-15 02:54:25,453 [salt.loaded.ext.module.maasng:1771][INFO    ][13813] boot-sources sync initiated for ALL Rack's
2019-07-15 02:54:26,519 [salt.state       :300 ][INFO    ][13813] {'ret': True}
2019-07-15 02:54:26,519 [salt.state       :1951][INFO    ][13813] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 02:54:26.519637 duration_in_ms=1067.949
2019-07-15 02:54:26,523 [salt.state       :1780][INFO    ][13813] Running state [maas.process_maas_config] at time 02:54:26.523876
2019-07-15 02:54:26,524 [salt.state       :1813][INFO    ][13813] Executing state module.run for [maas.process_maas_config]
2019-07-15 02:54:26,524 [salt.utils.decorators:613 ][WARNING ][13813] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-15 02:54:26,525 [salt.loaded.ext.module.maas:92  ][INFO    ][13813] maasconfig name=enable_http_proxy value=True
2019-07-15 02:54:26,578 [salt.loaded.ext.module.maas:92  ][INFO    ][13813] maasconfig name=upstream_dns value=8.8.8.8
2019-07-15 02:54:26,640 [salt.loaded.ext.module.maas:92  ][INFO    ][13813] maasconfig name=commissioning_distro_series value=xenial
2019-07-15 02:54:26,700 [salt.loaded.ext.module.maas:92  ][INFO    ][13813] maasconfig name=default_osystem value=ubuntu
2019-07-15 02:54:28,055 [salt.loaded.ext.module.maas:92  ][INFO    ][13813] maasconfig name=active_discovery_interval value=600
2019-07-15 02:54:28,110 [salt.loaded.ext.module.maas:92  ][INFO    ][13813] maasconfig name=dnssec_validation value=no
2019-07-15 02:54:28,169 [salt.loaded.ext.module.maas:92  ][INFO    ][13813] maasconfig name=maas_name value=mas01
2019-07-15 02:54:28,231 [salt.loaded.ext.module.maas:92  ][INFO    ][13813] maasconfig name=network_discovery value=enabled
2019-07-15 02:54:28,338 [salt.loaded.ext.module.maas:92  ][INFO    ][13813] maasconfig name=enable_third_party_drivers value=True
2019-07-15 02:54:28,393 [salt.loaded.ext.module.maas:92  ][INFO    ][13813] maasconfig name=default_storage_layout value=lvm
2019-07-15 02:54:28,450 [salt.loaded.ext.module.maas:92  ][INFO    ][13813] maasconfig name=ntp_external_only value=True
2019-07-15 02:54:28,494 [salt.loaded.ext.module.maas:92  ][INFO    ][13813] maasconfig name=disk_erase_with_secure_erase value=False
2019-07-15 02:54:28,547 [salt.loaded.ext.module.maas:92  ][INFO    ][13813] maasconfig name=default_distro_series value=xenial
2019-07-15 02:54:28,602 [salt.loaded.ext.module.maas:92  ][INFO    ][13813] maasconfig name=default_min_hwe_kernel value=ga-16.04
2019-07-15 02:54:28,748 [salt.state       :300 ][INFO    ][13813] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-07-15 02:54:28,749 [salt.state       :1951][INFO    ][13813] Completed state [maas.process_maas_config] at time 02:54:28.748966 duration_in_ms=2225.089
2019-07-15 02:54:28,751 [salt.state       :1780][INFO    ][13813] Running state [pxe_admin] at time 02:54:28.751298
2019-07-15 02:54:28,751 [salt.state       :1813][INFO    ][13813] Executing state maasng.fabric_present for [pxe_admin]
2019-07-15 02:54:28,839 [salt.loaded.ext.module.maasng:945 ][INFO    ][13813] [{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'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'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'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'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': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ed7sbp', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}]
2019-07-15 02:54:28,900 [salt.loaded.ext.module.maasng:1008][WARNING ][13813] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-07-15 02:54:28,900 [salt.loaded.ext.module.maasng:1011][WARNING ][13813] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-07-15 02:54:28,974 [salt.state       :300 ][INFO    ][13813] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-07-15 02:54:28,974 [salt.state       :1951][INFO    ][13813] Completed state [pxe_admin] at time 02:54:28.974680 duration_in_ms=223.383
2019-07-15 02:54:28,975 [salt.state       :1780][INFO    ][13813] Running state [vlan 0] at time 02:54:28.974992
2019-07-15 02:54:28,975 [salt.state       :1813][INFO    ][13813] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-07-15 02:54:29,031 [salt.loaded.ext.module.maasng:945 ][INFO    ][13813] [{u'name': u'fabric-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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'name': u'fabric-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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}, {u'name': u'pxe_admin', 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'primary_rack': u'ed7sbp', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}]
2019-07-15 02:54:29,152 [salt.loaded.ext.module.maasng:945 ][INFO    ][13813] [{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'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'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'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'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': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ed7sbp', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}]
2019-07-15 02:54:29,417 [salt.loaded.ext.module.maasng:945 ][INFO    ][13813] [{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'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'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'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'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': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ed7sbp', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}]
2019-07-15 02:54:29,501 [salt.state       :300 ][INFO    ][13813] {'new': 'Vlan untagged was updated'}
2019-07-15 02:54:29,501 [salt.state       :1951][INFO    ][13813] Completed state [vlan 0] at time 02:54:29.501563 duration_in_ms=526.57
2019-07-15 02:54:29,503 [salt.state       :1780][INFO    ][13813] Running state [192.168.11.0/24] at time 02:54:29.503542
2019-07-15 02:54:29,504 [salt.state       :1813][INFO    ][13813] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-07-15 02:54:29,708 [salt.loaded.ext.module.maasng:945 ][INFO    ][13813] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'id': 1, u'vlans': [{u'name': u'untagged', 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'ed7sbp', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-07-15 02:54:29,709 [salt.loaded.ext.module.maasng:1235][WARNING ][13813] Ignoring parameter vlan:0
2019-07-15 02:54:29,792 [salt.state       :300 ][INFO    ][13813] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-07-15 02:54:29,792 [salt.state       :1951][INFO    ][13813] Completed state [192.168.11.0/24] at time 02:54:29.792568 duration_in_ms=289.025
2019-07-15 02:54:29,793 [salt.state       :1780][INFO    ][13813] Running state [maas_create_iprange_1] at time 02:54:29.793347
2019-07-15 02:54:29,793 [salt.state       :1813][INFO    ][13813] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-07-15 02:54:29,849 [salt.state       :300 ][INFO    ][13813] Iprange maas_create_iprange_1 already exist.
2019-07-15 02:54:29,850 [salt.state       :1951][INFO    ][13813] Completed state [maas_create_iprange_1] at time 02:54:29.850111 duration_in_ms=56.764
2019-07-15 02:54:29,850 [salt.state       :1780][INFO    ][13813] Running state [vlan 0] at time 02:54:29.850363
2019-07-15 02:54:29,850 [salt.state       :1813][INFO    ][13813] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-07-15 02:54:29,898 [salt.loaded.ext.module.maasng:945 ][INFO    ][13813] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'id': 1, u'vlans': [{u'name': u'untagged', 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'ed7sbp', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-07-15 02:54:29,995 [salt.loaded.ext.module.maasng:945 ][INFO    ][13813] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'id': 1, u'vlans': [{u'name': u'untagged', 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'ed7sbp', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-07-15 02:54:30,394 [salt.loaded.ext.module.maasng:945 ][INFO    ][13813] [{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'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'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'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'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'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ed7sbp', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}]
2019-07-15 02:54:30,488 [salt.state       :300 ][INFO    ][13813] {'new': 'Vlan untagged was updated'}
2019-07-15 02:54:30,488 [salt.state       :1951][INFO    ][13813] Completed state [vlan 0] at time 02:54:30.488801 duration_in_ms=638.437
2019-07-15 02:54:30,489 [salt.state       :1780][INFO    ][13813] Running state [opnfv] at time 02:54:30.489470
2019-07-15 02:54:30,490 [salt.state       :1813][INFO    ][13813] Executing state maasng.sshkey_present for [opnfv]
2019-07-15 02:54:30,534 [salt.loaded.ext.module.maasng:1903][INFO    ][13813] [{u'keysource': u'', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b', u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/'}]
2019-07-15 02:54:30,534 [salt.state       :300 ][INFO    ][13813] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-07-15 02:54:30,534 [salt.state       :1951][INFO    ][13813] Completed state [opnfv] at time 02:54:30.534901 duration_in_ms=45.43
2019-07-15 02:54:30,538 [salt.minion      :1711][INFO    ][13813] Returning information for job: 20190715025407284787
2019-07-15 02:54:31,146 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command state.apply with jid 20190715025431131676
2019-07-15 02:54:31,172 [salt.minion      :1432][INFO    ][14264] Starting a new job with PID 14264
2019-07-15 02:54:39,516 [salt.state       :915 ][INFO    ][14264] Loading fresh modules for state activity
2019-07-15 02:54:39,628 [salt.state       :1780][INFO    ][14264] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:39.628598
2019-07-15 02:54:39,628 [salt.state       :1813][INFO    ][14264] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-15 02:54:39,630 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14264] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-15 02:54:41,434 [salt.state       :300 ][INFO    ][14264] {'pid': 14290, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-15 02:54:41,435 [salt.state       :1951][INFO    ][14264] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:41.435299 duration_in_ms=1806.7
2019-07-15 02:54:41,440 [salt.state       :1780][INFO    ][14264] Running state [maas.process_machines] at time 02:54:41.440111
2019-07-15 02:54:41,440 [salt.state       :1813][INFO    ][14264] Executing state module.run for [maas.process_machines]
2019-07-15 02:54:41,441 [salt.utils.decorators:613 ][WARNING ][14264] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-15 02:54:42,008 [salt.loaded.ext.module.maas:412 ][WARNING ][14264] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-15 02:54:42,009 [salt.loaded.ext.module.maas:92  ][INFO    ][14264] 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=c3gk8k architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-15 02:54:43,343 [salt.loaded.ext.module.maas:412 ][WARNING ][14264] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-15 02:54:43,344 [salt.loaded.ext.module.maas:92  ][INFO    ][14264] 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=qf7e3w architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-15 02:54:44,549 [salt.loaded.ext.module.maas:412 ][WARNING ][14264] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-15 02:54:44,550 [salt.loaded.ext.module.maas:92  ][INFO    ][14264] 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=nm6qwy architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-15 02:54:45,811 [salt.loaded.ext.module.maas:412 ][WARNING ][14264] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-15 02:54:45,812 [salt.loaded.ext.module.maas:92  ][INFO    ][14264] 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=8aqaa7 architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-15 02:54:46,277 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715025446259023
2019-07-15 02:54:46,308 [salt.minion      :1432][INFO    ][14470] Starting a new job with PID 14470
2019-07-15 02:54:46,337 [salt.minion      :1711][INFO    ][14470] Returning information for job: 20190715025446259023
2019-07-15 02:54:47,104 [salt.loaded.ext.module.maas:412 ][WARNING ][14264] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-15 02:54:47,107 [salt.loaded.ext.module.maas:92  ][INFO    ][14264] 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=xkh4qc architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-15 02:54:48,275 [salt.state       :300 ][INFO    ][14264] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-07-15 02:54:48,276 [salt.state       :1951][INFO    ][14264] Completed state [maas.process_machines] at time 02:54:48.276253 duration_in_ms=6836.139
2019-07-15 02:54:48,281 [salt.minion      :1711][INFO    ][14264] Returning information for job: 20190715025431131676
2019-07-15 02:55:21,236 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command state.apply with jid 20190715025521220779
2019-07-15 02:55:21,253 [salt.minion      :1432][INFO    ][14562] Starting a new job with PID 14562
2019-07-15 02:55:29,597 [salt.state       :915 ][INFO    ][14562] Loading fresh modules for state activity
2019-07-15 02:55:29,692 [salt.state       :1780][INFO    ][14562] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:55:29.692601
2019-07-15 02:55:29,692 [salt.state       :1813][INFO    ][14562] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-15 02:55:29,694 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14562] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-15 02:55:31,421 [salt.state       :300 ][INFO    ][14562] {'pid': 14570, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-15 02:55:31,422 [salt.state       :1951][INFO    ][14562] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:55:31.422818 duration_in_ms=1730.216
2019-07-15 02:55:31,426 [salt.state       :1780][INFO    ][14562] Running state [maas.wait_for_machine_status] at time 02:55:31.426215
2019-07-15 02:55:31,426 [salt.state       :1813][INFO    ][14562] Executing state module.run for [maas.wait_for_machine_status]
2019-07-15 02:55:31,427 [salt.utils.decorators:613 ][WARNING ][14562] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-15 02:55:34,350 [salt.state       :300 ][INFO    ][14562] {'ret': True}
2019-07-15 02:55:34,351 [salt.state       :1951][INFO    ][14562] Completed state [maas.wait_for_machine_status] at time 02:55:34.350896 duration_in_ms=2924.672
2019-07-15 02:55:34,355 [salt.minion      :1711][INFO    ][14562] Returning information for job: 20190715025521220779
2019-07-15 02:55:34,957 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command state.apply with jid 20190715025534892755
2019-07-15 02:55:34,986 [salt.minion      :1432][INFO    ][14589] Starting a new job with PID 14589
2019-07-15 02:55:36,150 [salt.state       :915 ][INFO    ][14589] Loading fresh modules for state activity
2019-07-15 02:55:36,314 [salt.state       :1780][INFO    ][14589] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:55:36.314510
2019-07-15 02:55:36,314 [salt.state       :1813][INFO    ][14589] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-15 02:55:36,316 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14589] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-15 02:55:38,011 [salt.state       :300 ][INFO    ][14589] {'pid': 14596, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-15 02:55:38,012 [salt.state       :1951][INFO    ][14589] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:55:38.012371 duration_in_ms=1697.862
2019-07-15 02:55:38,016 [salt.state       :1780][INFO    ][14589] Running state [maas_machines_storage_cmp002_lvm] at time 02:55:38.016089
2019-07-15 02:55:38,016 [salt.state       :1813][INFO    ][14589] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-07-15 02:55:38,569 [salt.state       :300 ][INFO    ][14589] Machine cmp002 is not in Ready state.
2019-07-15 02:55:38,572 [salt.state       :1951][INFO    ][14589] Completed state [maas_machines_storage_cmp002_lvm] at time 02:55:38.572496 duration_in_ms=556.409
2019-07-15 02:55:38,572 [salt.state       :1780][INFO    ][14589] Running state [maas_machines_storage_cmp001_lvm] at time 02:55:38.572808
2019-07-15 02:55:38,573 [salt.state       :1813][INFO    ][14589] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-07-15 02:55:39,283 [salt.state       :300 ][INFO    ][14589] Machine cmp001 is not in Ready state.
2019-07-15 02:55:39,284 [salt.state       :1951][INFO    ][14589] Completed state [maas_machines_storage_cmp001_lvm] at time 02:55:39.283886 duration_in_ms=711.076
2019-07-15 02:55:39,288 [salt.minion      :1711][INFO    ][14589] Returning information for job: 20190715025534892755
2019-07-15 02:55:39,839 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command state.apply with jid 20190715025539826801
2019-07-15 02:55:39,864 [salt.minion      :1432][INFO    ][14606] Starting a new job with PID 14606
2019-07-15 02:55:41,112 [salt.state       :915 ][INFO    ][14606] Loading fresh modules for state activity
2019-07-15 02:55:41,214 [salt.state       :1780][INFO    ][14606] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:55:41.214898
2019-07-15 02:55:41,215 [salt.state       :1813][INFO    ][14606] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-15 02:55:41,217 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14606] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-15 02:55:42,931 [salt.state       :300 ][INFO    ][14606] {'pid': 14632, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-15 02:55:42,932 [salt.state       :1951][INFO    ][14606] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:55:42.932667 duration_in_ms=1717.769
2019-07-15 02:55:42,935 [salt.state       :1780][INFO    ][14606] Running state [maas.deploy_machines] at time 02:55:42.935809
2019-07-15 02:55:42,936 [salt.state       :1813][INFO    ][14606] Executing state module.run for [maas.deploy_machines]
2019-07-15 02:55:42,936 [salt.utils.decorators:613 ][WARNING ][14606] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-15 02:55:43,519 [salt.loaded.ext.module.maas:684 ][INFO    ][14606] deploymachines hwe_kernel=ga-16.04 system_id=xkh4qc distro_series=xenial
2019-07-15 02:55:45,993 [salt.state       :300 ][INFO    ][14606] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-07-15 02:55:45,994 [salt.state       :1951][INFO    ][14606] Completed state [maas.deploy_machines] at time 02:55:45.994487 duration_in_ms=3058.678
2019-07-15 02:55:46,000 [salt.minion      :1711][INFO    ][14606] Returning information for job: 20190715025539826801
2019-07-15 02:55:46,591 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command state.apply with jid 20190715025546578312
2019-07-15 02:55:46,619 [salt.minion      :1432][INFO    ][14694] Starting a new job with PID 14694
2019-07-15 02:55:54,789 [salt.state       :915 ][INFO    ][14694] Loading fresh modules for state activity
2019-07-15 02:55:54,892 [salt.state       :1780][INFO    ][14694] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:55:54.892188
2019-07-15 02:55:54,892 [salt.state       :1813][INFO    ][14694] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-15 02:55:54,894 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14694] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-15 02:55:56,621 [salt.state       :300 ][INFO    ][14694] {'pid': 14708, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-15 02:55:56,622 [salt.state       :1951][INFO    ][14694] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:55:56.622768 duration_in_ms=1730.579
2019-07-15 02:55:56,626 [salt.state       :1780][INFO    ][14694] Running state [maas.wait_for_machine_status] at time 02:55:56.626192
2019-07-15 02:55:56,626 [salt.state       :1813][INFO    ][14694] Executing state module.run for [maas.wait_for_machine_status]
2019-07-15 02:55:56,627 [salt.utils.decorators:613 ][WARNING ][14694] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-15 02:55:59,376 [salt.loaded.ext.module.maas:1023][INFO    ][14694] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.27062607s left)
2019-07-15 02:56:01,661 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715025601644712
2019-07-15 02:56:01,687 [salt.minion      :1432][INFO    ][14733] Starting a new job with PID 14733
2019-07-15 02:56:01,712 [salt.minion      :1711][INFO    ][14733] Returning information for job: 20190715025601644712
2019-07-15 02:56:31,740 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715025631731951
2019-07-15 02:56:31,768 [salt.minion      :1432][INFO    ][14768] Starting a new job with PID 14768
2019-07-15 02:56:31,792 [salt.minion      :1711][INFO    ][14768] Returning information for job: 20190715025631731951
2019-07-15 02:56:32,190 [salt.loaded.ext.module.maas:1023][INFO    ][14694] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.45729113s left)
2019-07-15 02:57:01,856 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715025701845410
2019-07-15 02:57:01,884 [salt.minion      :1432][INFO    ][14811] Starting a new job with PID 14811
2019-07-15 02:57:01,908 [salt.minion      :1711][INFO    ][14811] Returning information for job: 20190715025701845410
2019-07-15 02:57:05,044 [salt.loaded.ext.module.maas:1023][INFO    ][14694] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2181.60330296s left)
2019-07-15 02:57:31,924 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715025731914444
2019-07-15 02:57:31,943 [salt.minion      :1432][INFO    ][14860] Starting a new job with PID 14860
2019-07-15 02:57:31,967 [salt.minion      :1711][INFO    ][14860] Returning information for job: 20190715025731914444
2019-07-15 02:57:37,868 [salt.loaded.ext.module.maas:1023][INFO    ][14694] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2148.77879s left)
2019-07-15 02:58:02,000 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715025801987753
2019-07-15 02:58:02,028 [salt.minion      :1432][INFO    ][14905] Starting a new job with PID 14905
2019-07-15 02:58:02,057 [salt.minion      :1711][INFO    ][14905] Returning information for job: 20190715025801987753
2019-07-15 02:58:11,127 [salt.loaded.ext.module.maas:1023][INFO    ][14694] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2115.51993799s left)
2019-07-15 02:58:32,085 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715025832073203
2019-07-15 02:58:32,112 [salt.minion      :1432][INFO    ][14942] Starting a new job with PID 14942
2019-07-15 02:58:32,135 [salt.minion      :1711][INFO    ][14942] Returning information for job: 20190715025832073203
2019-07-15 02:58:43,900 [salt.loaded.ext.module.maas:1023][INFO    ][14694] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2082.74711609s left)
2019-07-15 02:59:02,170 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715025902159874
2019-07-15 02:59:02,195 [salt.minion      :1432][INFO    ][14995] Starting a new job with PID 14995
2019-07-15 02:59:02,219 [salt.minion      :1711][INFO    ][14995] Returning information for job: 20190715025902159874
2019-07-15 02:59:16,726 [salt.loaded.ext.module.maas:1023][INFO    ][14694] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2049.92098808s left)
2019-07-15 02:59:32,272 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715025932257328
2019-07-15 02:59:32,302 [salt.minion      :1432][INFO    ][15032] Starting a new job with PID 15032
2019-07-15 02:59:32,326 [salt.minion      :1711][INFO    ][15032] Returning information for job: 20190715025932257328
2019-07-15 02:59:49,491 [salt.loaded.ext.module.maas:1023][INFO    ][14694] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2017.1559391s left)
2019-07-15 03:00:02,361 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715030002349783
2019-07-15 03:00:02,385 [salt.minion      :1432][INFO    ][15095] Starting a new job with PID 15095
2019-07-15 03:00:02,409 [salt.minion      :1711][INFO    ][15095] Returning information for job: 20190715030002349783
2019-07-15 03:00:22,351 [salt.loaded.ext.module.maas:1023][INFO    ][14694] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1984.29586911s left)
2019-07-15 03:00:32,469 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715030032453173
2019-07-15 03:00:32,500 [salt.minion      :1432][INFO    ][15153] Starting a new job with PID 15153
2019-07-15 03:00:32,523 [salt.minion      :1711][INFO    ][15153] Returning information for job: 20190715030032453173
2019-07-15 03:00:55,433 [salt.loaded.ext.module.maas:1023][INFO    ][14694] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1951.21417212s left)
2019-07-15 03:01:02,573 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715030102558744
2019-07-15 03:01:02,604 [salt.minion      :1432][INFO    ][15211] Starting a new job with PID 15211
2019-07-15 03:01:02,625 [salt.minion      :1711][INFO    ][15211] Returning information for job: 20190715030102558744
2019-07-15 03:01:28,423 [salt.loaded.ext.module.maas:1023][INFO    ][14694] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1918.22409201s left)
2019-07-15 03:01:32,709 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715030132697909
2019-07-15 03:01:32,736 [salt.minion      :1432][INFO    ][15367] Starting a new job with PID 15367
2019-07-15 03:01:32,760 [salt.minion      :1711][INFO    ][15367] Returning information for job: 20190715030132697909
2019-07-15 03:02:01,300 [salt.loaded.ext.module.maas:1023][INFO    ][14694] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1885.34706807s left)
2019-07-15 03:02:02,820 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715030202811170
2019-07-15 03:02:02,844 [salt.minion      :1432][INFO    ][15435] Starting a new job with PID 15435
2019-07-15 03:02:02,867 [salt.minion      :1711][INFO    ][15435] Returning information for job: 20190715030202811170
2019-07-15 03:02:32,964 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715030232949950
2019-07-15 03:02:32,987 [salt.minion      :1432][INFO    ][15504] Starting a new job with PID 15504
2019-07-15 03:02:33,013 [salt.minion      :1711][INFO    ][15504] Returning information for job: 20190715030232949950
2019-07-15 03:02:34,400 [salt.loaded.ext.module.maas:1023][INFO    ][14694] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1852.24739814s left)
2019-07-15 03:03:03,104 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715030303090857
2019-07-15 03:03:03,130 [salt.minion      :1432][INFO    ][15565] Starting a new job with PID 15565
2019-07-15 03:03:03,152 [salt.minion      :1711][INFO    ][15565] Returning information for job: 20190715030303090857
2019-07-15 03:03:07,317 [salt.loaded.ext.module.maas:1023][INFO    ][14694] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1819.32957792s left)
2019-07-15 03:03:33,275 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715030333262422
2019-07-15 03:03:33,306 [salt.minion      :1432][INFO    ][15659] Starting a new job with PID 15659
2019-07-15 03:03:33,338 [salt.minion      :1711][INFO    ][15659] Returning information for job: 20190715030333262422
2019-07-15 03:03:40,363 [salt.loaded.ext.module.maas:1023][INFO    ][14694] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1786.28384709s left)
2019-07-15 03:04:03,435 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715030403421155
2019-07-15 03:04:03,456 [salt.minion      :1432][INFO    ][15727] Starting a new job with PID 15727
2019-07-15 03:04:03,492 [salt.minion      :1711][INFO    ][15727] Returning information for job: 20190715030403421155
2019-07-15 03:04:13,371 [salt.loaded.ext.module.maas:1023][INFO    ][14694] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1753.27594495s left)
2019-07-15 03:04:33,601 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715030433587187
2019-07-15 03:04:33,630 [salt.minion      :1432][INFO    ][15810] Starting a new job with PID 15810
2019-07-15 03:04:33,659 [salt.minion      :1711][INFO    ][15810] Returning information for job: 20190715030433587187
2019-07-15 03:04:46,328 [salt.loaded.ext.module.maas:1023][INFO    ][14694] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1720.31874514s left)
2019-07-15 03:05:03,780 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715030503771066
2019-07-15 03:05:03,809 [salt.minion      :1432][INFO    ][15855] Starting a new job with PID 15855
2019-07-15 03:05:03,833 [salt.minion      :1711][INFO    ][15855] Returning information for job: 20190715030503771066
2019-07-15 03:05:19,464 [salt.loaded.ext.module.maas:1023][INFO    ][14694] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1687.1836009s left)
2019-07-15 03:05:33,937 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715030533923181
2019-07-15 03:05:33,970 [salt.minion      :1432][INFO    ][15887] Starting a new job with PID 15887
2019-07-15 03:05:34,007 [salt.minion      :1711][INFO    ][15887] Returning information for job: 20190715030533923181
2019-07-15 03:05:52,332 [salt.loaded.ext.module.maas:1023][INFO    ][14694] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1654.31479812s left)
2019-07-15 03:06:04,141 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715030604124040
2019-07-15 03:06:04,169 [salt.minion      :1432][INFO    ][15930] Starting a new job with PID 15930
2019-07-15 03:06:04,195 [salt.minion      :1711][INFO    ][15930] Returning information for job: 20190715030604124040
2019-07-15 03:06:25,177 [salt.loaded.ext.module.maas:1023][INFO    ][14694] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1621.47002912s left)
2019-07-15 03:06:34,327 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715030634310763
2019-07-15 03:06:34,356 [salt.minion      :1432][INFO    ][15964] Starting a new job with PID 15964
2019-07-15 03:06:34,378 [salt.minion      :1711][INFO    ][15964] Returning information for job: 20190715030634310763
2019-07-15 03:06:58,169 [salt.loaded.ext.module.maas:1023][INFO    ][14694] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1588.47833395s left)
2019-07-15 03:07:04,515 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715030704498823
2019-07-15 03:07:04,540 [salt.minion      :1432][INFO    ][16014] Starting a new job with PID 16014
2019-07-15 03:07:04,563 [salt.minion      :1711][INFO    ][16014] Returning information for job: 20190715030704498823
2019-07-15 03:07:31,220 [salt.loaded.ext.module.maas:1023][INFO    ][14694] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1555.42716908s left)
2019-07-15 03:07:34,687 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715030734675132
2019-07-15 03:07:34,715 [salt.minion      :1432][INFO    ][16063] Starting a new job with PID 16063
2019-07-15 03:07:34,739 [salt.minion      :1711][INFO    ][16063] Returning information for job: 20190715030734675132
2019-07-15 03:08:04,136 [salt.loaded.ext.module.maas:1023][INFO    ][14694] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1522.51111794s left)
2019-07-15 03:08:04,879 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715030804869696
2019-07-15 03:08:04,906 [salt.minion      :1432][INFO    ][16255] Starting a new job with PID 16255
2019-07-15 03:08:04,930 [salt.minion      :1711][INFO    ][16255] Returning information for job: 20190715030804869696
2019-07-15 03:08:35,095 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715030835083223
2019-07-15 03:08:35,121 [salt.minion      :1432][INFO    ][16284] Starting a new job with PID 16284
2019-07-15 03:08:35,147 [salt.minion      :1711][INFO    ][16284] Returning information for job: 20190715030835083223
2019-07-15 03:08:35,759 [salt.utils.schedule:1377][INFO    ][5852] Running scheduled job: __mine_interval
2019-07-15 03:08:37,019 [salt.loaded.ext.module.maas:1023][INFO    ][14694] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1489.62750006s left)
2019-07-15 03:09:05,116 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715030905100311
2019-07-15 03:09:05,148 [salt.minion      :1432][INFO    ][16361] Starting a new job with PID 16361
2019-07-15 03:09:05,179 [salt.minion      :1711][INFO    ][16361] Returning information for job: 20190715030905100311
2019-07-15 03:09:10,087 [salt.loaded.ext.module.maas:1023][INFO    ][14694] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1456.55985308s left)
2019-07-15 03:09:35,153 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command saltutil.find_job with jid 20190715030935139932
2019-07-15 03:09:35,176 [salt.minion      :1432][INFO    ][16416] Starting a new job with PID 16416
2019-07-15 03:09:35,196 [salt.minion      :1711][INFO    ][16416] Returning information for job: 20190715030935139932
2019-07-15 03:09:43,164 [salt.state       :300 ][INFO    ][14694] {'ret': True}
2019-07-15 03:09:43,165 [salt.state       :1951][INFO    ][14694] Completed state [maas.wait_for_machine_status] at time 03:09:43.165362 duration_in_ms=826539.164
2019-07-15 03:09:43,172 [salt.minion      :1711][INFO    ][14694] Returning information for job: 20190715025546578312
2019-07-15 04:08:35,758 [salt.utils.schedule:1377][INFO    ][5852] Running scheduled job: __mine_interval
2019-07-15 04:40:42,968 [salt.minion      :1308][INFO    ][5852] User sudo_ubuntu Executing command cp.push_dir with jid 20190715044042957849
2019-07-15 04:40:42,994 [salt.minion      :1432][INFO    ][22951] Starting a new job with PID 22951
