Cisco Unified Communications Manager 12 Install Errors on Proxmox/KVM

The strange thing is that usually the first install or two will work on any new machine but then it suddenly won't. I had this experience on QEMU 2.13 on a different machine. There is something finicky or buggy about the CUCM installer even when choosing the same virtual hardware specs.

qemu-kvm command:

/usr/libexec/qemu-kvm -version
QEMU PC emulator version 0.12.1 (qemu-kvm-0.12.1.2-2.506.el6_10.1), Copyright (c) 2003-2008 Fabrice Bellard

/usr/libexec/qemu-kvm -smp 6 -cdrom /mnt/vms/iso/Bootable_UCSInstall_UCOS_12.iso -boot once=d -vnc :9 -m 4096 -drive file=cucm-second-again.img,if=virtio -net nic,macaddr=ea:d9:1b:6d:e8:a3,model=virtio -net tap,ifname=tap49,script=no,downscript=no

This is the critical part and it is found by mounting /dev/sda6 using libguestfs:

guestmount -a cucm-second-again.img -m /dev/sda6 mount/

cat /log/install/install.log

05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 01:33:49 2019 cm-dbl-install DEBUG: <--|<LVL::Debug> 05/01/2019 01:34:07 component_install|File:/opt/cisco/install/bin/component_install:821, Function: exec_progmeter(), /opt/cisco/install/bin/progmeter failed (1)|<LVL::Error> 05/01/2019 01:34:07 appmanager.sh|Internal Error, File:/usr/local/bin/base_scripts/appmanager.sh:153, Function: install(), failed to install infrastructure_post components|<LVL::Critical> 05/01/2019 01:34:07 post_install|File:/opt/cisco/install/bin/post_install:1030, Function: install_applications(), /usr/local/bin/base_scripts/appmanager.sh -install failed (1)|<LVL::Error> 05/01/2019 01:34:07 post_install|Exiting with result 1|<LVL::Info> 05/01/2019 01:34:07 post_install|INSTALL_TYPE="Basic Install"|<LVL::Debug> 05/01/2019 01:34:07 post_install|File:/opt/cisco/install/bin/post_install:721, Function: check_for_critical_error(), check_for_critical_error, found /common/log/install/critical.log, exiting|<LVL::Error> 05/01/2019 01:34:07 post_install|(CAPTURE) Mail notification cancelled - smtp server address for email not found! [/usr/local/platform/conf/platformConfig.xml]|<LVL::Debug> 05/01/2019 01:34:07 display_screen|Arguments: "Critical Error" "The installation has encountered a unrecoverable internal error. For further assistance report the following information to your support provider.

hssi_api_state_file

cat hssi_api_state_file hardware_implementation_paths=../../server_implementation/KVM/RHEV/shared/bin/api_implementation.sh HWModel=KVM Vendor= Red Hat CPUSpeed=(cpu64-rhel6) CPUCount=6 MEMSize=4096 getDiskSize 1=128 getDiskSize 2=0 isPlatformVMware=1 getNumberOfDisks=

cat HSSI_LOG.txt (05/01/2019 04:11:06 AM) IN INITIALIZE model = KVM statefile= /tmp/hssi_api_state_file (05/01/2019 04:11:06 AM) DEBUG:[hssiInitialize] Current Directory=/mnt/source/Cisco/hssi/shared/bin (Wed May 1 04:11:06 UTC 2019) - No State file - sam_init sam_mode=HSSI_Mode sam_hssi_path=/mnt/source/Cisco/hssi/shared/bin sam_path=/mnt/source/Cisco/base_scripts (05/01/2019 04:11:06 AM) IN INITIALIZE model = KVM statefile= /tmp/hssi_api_state_file (05/01/2019 04:11:06 AM) DEBUG:[hardware isHardwareValid] Current Directory=/mnt/source/Cisco/hssi/shared/bin (05/01/2019 04:11:06 AM) IN INITIALIZE model = KVM statefile= /tmp/hssi_api_state_file (05/01/2019 04:11:06 AM) DEBUG:[getHardwareAttribute HWModel] Current Directory=/mnt/source/Cisco/hssi/shared/bin (05/01/2019 04:11:06 AM) [KVM] retrieved from cache for key -[HWModel]- (05/01/2019 04:11:06 AM) IN INITIALIZE model = KVM statefile= /tmp/hssi_api_state_file (05/01/2019 04:11:06 AM) DEBUG:[getHardwareAttribute Vendor] Current Directory=/mnt/source/Cisco/hssi/shared/bin (05/01/2019 04:11:06 AM) In runtime of KVM args [HssiDefines] (05/01/2019 04:11:06 AM) In runtime of server_implementation args [HssiDefines] (05/01/2019 04:11:07 AM) server_implementation shared_implementation.sh HssiDefines returns (0) (05/01/2019 04:11:07 AM) KVM shared_implementation.sh HssiDefines returns (0) (05/01/2019 04:11:07 AM) In runtime of RHEV args [Vendor] (05/01/2019 04:11:07 AM) RHEV api_implementation.sh Vendor returns (0) (05/01/2019 04:11:07 AM) HSSI set_inDataFile_forKey_value /tmp/hssi_api_state_file -> Vendor = Red Hat (05/01/2019 04:11:07 AM) IN INITIALIZE model = KVM statefile= /tmp/hssi_api_state_file (05/01/2019 04:11:07 AM) DEBUG:[preBootHardwareSetup /tmp/hw_setup_log.txt /tmp/hw_setup_err.txt] Current Directory=/mnt/source/Cisco/hssi/shared/bin (05/01/2019 04:11:07 AM) In runtime of KVM args [HssiDefines] (05/01/2019 04:11:07 AM) In runtime of server_implementation args [HssiDefines] (05/01/2019 04:11:07 AM) server_implementation shared_implementation.sh HssiDefines returns (0) (05/01/2019 04:11:07 AM) KVM shared_implementation.sh HssiDefines returns (0) (05/01/2019 04:11:07 AM) In runtime of RHEV args [preBootHardwareSetup /tmp/hw_setup_log.txt /tmp/hw_setup_err.txt] (05/01/2019 04:11:07 AM) In runtime of KVM args [preBootHardwareSetup /tmp/hw_setup_log.txt /tmp/hw_setup_err.txt] (05/01/2019 04:11:07 AM) In runtime of server_implementation args [preBootHardwareSetup /tmp/hw_setup_log.txt /tmp/hw_setup_err.txt] (05/01/2019 04:11:07 AM) DEBUG: nothing to be setup for generic implementation (05/01/2019 04:11:07 AM) server_implementation shared_implementation.sh preBootHardwareSetup /tmp/hw_setup_log.txt /tmp/hw_setup_err.txt returns (0) (05/01/2019 04:11:07 AM) KVM shared_implementation.sh preBootHardwareSetup /tmp/hw_setup_log.txt /tmp/hw_setup_err.txt returns (0) (05/01/2019 04:11:07 AM) RHEV api_implementation.sh preBootHardwareSetup /tmp/hw_setup_log.txt /tmp/hw_setup_err.txt returns (0) (05/01/2019 04:11:07 AM) IN INITIALIZE model = KVM statefile= /tmp/hssi_api_state_file (05/01/2019 04:11:07 AM) DEBUG:[getMountedDiskPartitions] Current Directory=/mnt/source/Cisco/hssi/shared/bin (05/01/2019 04:11:07 AM) In runtime of KVM args [HssiDefines] (05/01/2019 04:11:07 AM) In runtime of server_implementation args [HssiDefines] (05/01/2019 04:11:08 AM) server_implementation shared_implementation.sh HssiDefines returns (0) (05/01/2019 04:11:08 AM) KVM shared_implementation.sh HssiDefines returns (0) (05/01/2019 04:11:08 AM) In runtime of RHEV args [getMountedDiskPartitions] (05/01/2019 04:11:08 AM) In runtime of KVM args [getMountedDiskPartitions] (05/01/2019 04:11:08 AM) In runtime of server_implementation args [getMountedDiskPartitions] (05/01/2019 04:11:08 AM) server_implementation shared_implementation.sh getMountedDiskPartitions returns (0) (05/01/2019 04:11:08 AM) KVM shared_implementation.sh getMountedDiskPartitions returns (0) (05/01/2019 04:11:08 AM) RHEV api_implementation.sh getMountedDiskPartitions returns (0) (05/01/2019 04:11:08 AM) IN INITIALIZE model = KVM statefile= /tmp/hssi_api_state_file (05/01/2019 04:11:08 AM) DEBUG:[isMotorizedDVD] Current Directory=/mnt/source/Cisco/hssi/shared/bin (05/01/2019 04:11:08 AM) In runtime of KVM args [HssiDefines] (05/01/2019 04:11:08 AM) In runtime of server_implementation args [HssiDefines] (05/01/2019 04:11:08 AM) server_implementation shared_implementation.sh HssiDefines returns (0) (05/01/2019 04:11:08 AM) KVM shared_implementation.sh HssiDefines returns (0) (05/01/2019 04:11:08 AM) In runtime of RHEV args [isMotorizedDVD] (05/01/2019 04:11:08 AM) In runtime of KVM args [isMotorizedDVD] (05/01/2019 04:11:08 AM) In runtime of server_implementation args [isMotorizedDVD] (05/01/2019 04:11:08 AM) server_implementation shared_implementation.sh isMotorizedDVD returns (1) (05/01/2019 04:11:08 AM) KVM shared_implementation.sh isMotorizedDVD returns (1) (05/01/2019 04:11:08 AM) RHEV api_implementation.sh isMotorizedDVD returns (1) (05/01/2019 04:11:09 AM) IN INITIALIZE model = KVM statefile= /tmp/hssi_api_state_file (05/01/2019 04:11:09 AM) DEBUG:[getHardwareAttribute CPUSpeed] Current Directory=/mnt/source/Cisco/hssi/shared/bin (05/01/2019 04:11:09 AM) In runtime of KVM args [HssiDefines] (05/01/2019 04:11:09 AM) In runtime of server_implementation args [HssiDefines] (05/01/2019 04:11:09 AM) server_implementation shared_implementation.sh HssiDefines returns (0) (05/01/2019 04:11:09 AM) KVM shared_implementation.sh HssiDefines returns (0) (05/01/2019 04:11:09 AM) In runtime of RHEV args [getHardwareAttribute CPUSpeedInMHz] (05/01/2019 04:11:09 AM) In runtime of RHEV args [CPUSpeedInMHz] (05/01/2019 04:11:09 AM) In runtime of KVM args [CPUSpeedInMHz] (05/01/2019 04:11:09 AM) In runtime of server_implementation args [CPUSpeedInMHz] (05/01/2019 04:11:09 AM) server_implementation shared_implementation.sh CPUSpeedInMHz returns (0) (05/01/2019 04:11:10 AM) KVM shared_implementation.sh CPUSpeedInMHz returns (0) (05/01/2019 04:11:10 AM) RHEV api_implementation.sh CPUSpeedInMHz returns (0) (05/01/2019 04:11:10 AM) RHEV api_implementation.sh getHardwareAttribute CPUSpeedInMHz returns (0) (05/01/2019 04:11:10 AM) HSSI set_inDataFile_forKey_value /tmp/hssi_api_state_file -> CPUSpeed = (cpu64-rhel6) (05/01/2019 04:11:10 AM) IN INITIALIZE model = KVM statefile= /tmp/hssi_api_state_file (05/01/2019 04:11:10 AM) DEBUG:[getHardwareAttribute CPUCount] Current Directory=/mnt/source/Cisco/hssi/shared/bin (05/01/2019 04:11:10 AM) In runtime of KVM args [HssiDefines] (05/01/2019 04:11:10 AM) In runtime of server_implementation args [HssiDefines] (05/01/2019 04:11:10 AM) server_implementation shared_implementation.sh HssiDefines returns (0) (05/01/2019 04:11:10 AM) KVM shared_implementation.sh HssiDefines returns (0) (05/01/2019 04:11:10 AM) In runtime of RHEV args [CPUCount] (05/01/2019 04:11:10 AM) In runtime of KVM args [CPUCount] (05/01/2019 04:11:10 AM) In runtime of server_implementation args [CPUCount] (05/01/2019 04:11:10 AM) server_implementation shared_implementation.sh CPUCount returns (0) (05/01/2019 04:11:10 AM) KVM shared_implementation.sh CPUCount returns (0) (05/01/2019 04:11:10 AM) RHEV api_implementation.sh CPUCount returns (0) (05/01/2019 04:11:10 AM) HSSI set_inDataFile_forKey_value /tmp/hssi_api_state_file -> CPUCount = 6 (05/01/2019 04:11:10 AM) IN INITIALIZE model = KVM statefile= /tmp/hssi_api_state_file (05/01/2019 04:11:10 AM) DEBUG:[getHardwareAttribute MEMSize] Current Directory=/mnt/source/Cisco/hssi/shared/bin (05/01/2019 04:11:10 AM) In runtime of KVM args [HssiDefines] (05/01/2019 04:11:10 AM) In runtime of server_implementation args [HssiDefines] (05/01/2019 04:11:11 AM) server_implementation shared_implementation.sh HssiDefines returns (0) (05/01/2019 04:11:11 AM) KVM shared_implementation.sh HssiDefines returns (0) (05/01/2019 04:11:11 AM) In runtime of RHEV args [MemUnits] (05/01/2019 04:11:11 AM) In runtime of KVM args [MemUnits] (05/01/2019 04:11:11 AM) In runtime of server_implementation args [MemUnits] (05/01/2019 04:11:11 AM) server_implementation shared_implementation.sh MemUnits returns (0) (05/01/2019 04:11:11 AM) KVM shared_implementation.sh MemUnits returns (0) (05/01/2019 04:11:11 AM) RHEV api_implementation.sh MemUnits returns (0) (05/01/2019 04:11:11 AM) In runtime of KVM args [HssiDefines] (05/01/2019 04:11:11 AM) In runtime of server_implementation args [HssiDefines] (05/01/2019 04:11:11 AM) server_implementation shared_implementation.sh HssiDefines returns (0) (05/01/2019 04:11:11 AM) KVM shared_implementation.sh HssiDefines returns (0) (05/01/2019 04:11:11 AM) In runtime of RHEV args [getHardwareAttribute MEMSize] (05/01/2019 04:11:11 AM) In runtime of RHEV args [MEMSize] (05/01/2019 04:11:11 AM) In runtime of KVM args [MEMSize] (05/01/2019 04:11:11 AM) In runtime of server_implementation args [MEMSize] (05/01/2019 04:11:11 AM) server_implementation shared_implementation.sh MEMSize returns (0) (05/01/2019 04:11:11 AM) KVM shared_implementation.sh MEMSize returns (0) (05/01/2019 04:11:11 AM) RHEV api_implementation.sh MEMSize returns (0) (05/01/2019 04:11:11 AM) RHEV api_implementation.sh getHardwareAttribute MEMSize returns (0) (05/01/2019 04:11:11 AM) HSSI set_inDataFile_forKey_value /tmp/hssi_api_state_file -> MEMSize = 4096 (05/01/2019 04:11:11 AM) IN INITIALIZE model = KVM statefile= /tmp/hssi_api_state_file (05/01/2019 04:11:11 AM) DEBUG:[hardware getDiskSize 1] Current Directory=/mnt/source/Cisco/hssi/shared/bin (05/01/2019 04:11:11 AM) In runtime of KVM args [HssiDefines] (05/01/2019 04:11:11 AM) In runtime of server_implementation args [HssiDefines] (05/01/2019 04:11:12 AM) server_implementation shared_implementation.sh HssiDefines returns (0) (05/01/2019 04:11:12 AM) KVM shared_implementation.sh HssiDefines returns (0) (05/01/2019 04:11:12 AM) In runtime of RHEV args [getDiskSize 1] (05/01/2019 04:11:12 AM) In runtime of RHEV args [disk_access] (05/01/2019 04:11:12 AM) RHEV api_implementation.sh disk_access returns (0) (05/01/2019 04:11:12 AM) In runtime of KVM args [getProcDiskSize vda] (05/01/2019 04:11:12 AM) In runtime of server_implementation args [getProcDiskSize vda] (05/01/2019 04:11:12 AM) server_implementation shared_implementation.sh getProcDiskSize vda returns (0) (05/01/2019 04:11:12 AM) KVM shared_implementation.sh getProcDiskSize vda returns (0) (05/01/2019 04:11:12 AM) RHEV api_implementation.sh getDiskSize 1 returns (0) (05/01/2019 04:11:12 AM) HSSI set_inDataFile_forKey_value /tmp/hssi_api_state_file -> getDiskSize 1 = 128 (05/01/2019 04:11:12 AM) IN INITIALIZE model = KVM statefile= /tmp/hssi_api_state_file (05/01/2019 04:11:12 AM) DEBUG:[hardware getDiskSize 2] Current Directory=/mnt/source/Cisco/hssi/shared/bin (05/01/2019 04:11:12 AM) In runtime of KVM args [HssiDefines] (05/01/2019 04:11:12 AM) In runtime of server_implementation args [HssiDefines] (05/01/2019 04:11:12 AM) server_implementation shared_implementation.sh HssiDefines returns (0) (05/01/2019 04:11:12 AM) KVM shared_implementation.sh HssiDefines returns (0) (05/01/2019 04:11:12 AM) In runtime of RHEV args [getDiskSize 2] (05/01/2019 04:11:12 AM) In runtime of RHEV args [disk_access] (05/01/2019 04:11:12 AM) RHEV api_implementation.sh disk_access returns (0) (05/01/2019 04:11:12 AM) In runtime of KVM args [getProcDiskSize vdb] (05/01/2019 04:11:12 AM) In runtime of server_implementation args [getProcDiskSize vdb] (05/01/2019 04:11:12 AM) server_implementation shared_implementation.sh getProcDiskSize vdb returns (0) (05/01/2019 04:11:12 AM) KVM shared_implementation.sh getProcDiskSize vdb returns (0) (05/01/2019 04:11:12 AM) RHEV api_implementation.sh getDiskSize 2 returns (0) (05/01/2019 04:11:12 AM) HSSI set_inDataFile_forKey_value /tmp/hssi_api_state_file -> getDiskSize 2 = 0 (05/01/2019 04:11:13 AM) IN INITIALIZE model = KVM statefile= /tmp/hssi_api_state_file (05/01/2019 04:11:13 AM) DEBUG:[isPlatformVMware] Current Directory=/mnt/source/Cisco/hssi/shared/bin (05/01/2019 04:11:13 AM) In runtime of KVM args [HssiDefines] (05/01/2019 04:11:13 AM) In runtime of server_implementation args [HssiDefines] (05/01/2019 04:11:13 AM) server_implementation shared_implementation.sh HssiDefines returns (0) (05/01/2019 04:11:13 AM) KVM shared_implementation.sh HssiDefines returns (0) (05/01/2019 04:11:13 AM) In runtime of RHEV args [isPlatformVMware] (05/01/2019 04:11:13 AM) In runtime of KVM args [isPlatformVMware] (05/01/2019 04:11:13 AM) In runtime of server_implementation args [isPlatformVMware] (05/01/2019 04:11:13 AM) server_implementation shared_implementation.sh isPlatformVMware returns (1) (05/01/2019 04:11:13 AM) KVM shared_implementation.sh isPlatformVMware returns (1) (05/01/2019 04:11:13 AM) RHEV api_implementation.sh isPlatformVMware returns (1) (05/01/2019 04:11:13 AM) HSSI set_inDataFile_forKey_value /tmp/hssi_api_state_file -> isPlatformVMware = 1 (05/01/2019 04:11:21 AM) IN INITIALIZE model = KVM statefile= /tmp/hssi_api_state_file (05/01/2019 04:11:21 AM) DEBUG:[hardware getNumberOfDisks] Current Directory=/mnt/source/Cisco/hssi/shared/bin (05/01/2019 04:11:21 AM) In runtime of KVM args [HssiDefines] (05/01/2019 04:11:21 AM) In runtime of server_implementation args [HssiDefines] (05/01/2019 04:11:21 AM) server_implementation shared_implementation.sh HssiDefines returns (0) (05/01/2019 04:11:21 AM) KVM shared_implementation.sh HssiDefines returns (0) (05/01/2019 04:11:21 AM) In runtime of RHEV args [getNumberOfDisks] (05/01/2019 04:11:21 AM) In runtime of RHEV args [disk_access] (05/01/2019 04:11:22 AM) RHEV api_implementation.sh disk_access returns (0) (05/01/2019 04:11:22 AM) RHEV api_implementation.sh getNumberOfDisks returns (1) (05/01/2019 04:11:22 AM) HSSI set_inDataFile_forKey_value /tmp/hssi_api_state_file -> getNumberOfDisks = (05/01/2019 04:11:22 AM) IN INITIALIZE model = KVM statefile= /tmp/hssi_api_state_file (05/01/2019 04:11:22 AM) DEBUG:[hardware isHardwareValid] Current Directory=/mnt/source/Cisco/hssi/shared/bin (05/01/2019 04:11:22 AM) IN INITIALIZE model = KVM statefile= /tmp/hssi_api_state_file (05/01/2019 04:11:22 AM) DEBUG:[hardware isPlatformVMware] Current Directory=/mnt/source/Cisco/hssi/shared/bin (05/01/2019 04:11:22 AM) [1] retrieved from cache for key -[isPlatformVMware]- (05/01/2019 04:11:24 AM) IN INITIALIZE model = KVM statefile= /tmp/hssi_api_state_file (05/01/2019 04:11:24 AM) DEBUG:[getHardwareAttribute CPUSpeed] Current Directory=/mnt/source/Cisco/hssi/shared/bin (05/01/2019 04:11:24 AM) [(cpu64-rhel6)] retrieved from cache for key -[CPUSpeed]- (05/01/2019 04:11:24 AM) IN INITIALIZE model = KVM statefile= /tmp/hssi_api_state_file (05/01/2019 04:11:24 AM) DEBUG:[getHardwareAttribute CPUCount] Current Directory=/mnt/source/Cisco/hssi/shared/bin (05/01/2019 04:11:24 AM) [6] retrieved from cache for key -[CPUCount]- (05/01/2019 04:11:24 AM) IN INITIALIZE model = KVM statefile= /tmp/hssi_api_state_file (05/01/2019 04:11:24 AM) DEBUG:[getHardwareAttribute MEMSize] Current Directory=/mnt/source/Cisco/hssi/shared/bin (05/01/2019 04:11:24 AM) [4096] retrieved from cache for key -[MEMSize]- (05/01/2019 04:11:24 AM) IN INITIALIZE model = KVM statefile= /tmp/hssi_api_state_file (05/01/2019 04:11:24 AM) DEBUG:[hardware getDiskSize 1] Current Directory=/mnt/source/Cisco/hssi/shared/bin (05/01/2019 04:11:24 AM) [128] retrieved from cache for key -[getDiskSize 1]- (05/01/2019 04:11:24 AM) IN INITIALIZE model = KVM statefile= /tmp/hssi_api_state_file (05/01/2019 04:11:24 AM) DEBUG:[hardware getDiskSize 2] Current Directory=/mnt/source/Cisco/hssi/shared/bin (05/01/2019 04:11:24 AM) [0] retrieved from cache for key -[getDiskSize 2]- (05/01/19 04:12:46) IN INITIALIZE model = KVM statefile= /tmp/hssi_api_state_file (05/01/19 04:12:46) DEBUG:[hardware getNumberOfDisks] Current Directory=/mnt/source/Cisco/hssi/shared/bin (05/01/19 04:12:46) In runtime of KVM args [HssiDefines] (05/01/19 04:12:47) In runtime of server_implementation args [HssiDefines] (05/01/19 04:12:47) server_implementation shared_implementation.sh HssiDefines returns (0) (05/01/19 04:12:47) KVM shared_implementation.sh HssiDefines returns (0) (05/01/19 04:12:47) In runtime of RHEV args [getNumberOfDisks] (05/01/19 04:12:47) In runtime of RHEV args [disk_access] (05/01/19 04:12:47) RHEV api_implementation.sh disk_access returns (0) (05/01/19 04:12:47) RHEV api_implementation.sh getNumberOfDisks returns (1) (05/01/19 04:12:47) HSSI set_inDataFile_forKey_value /tmp/hssi_api_state_file -> getNumberOfDisks = (05/01/19 04:12:47) IN INITIALIZE model = KVM statefile= /tmp/hssi_api_state_file (05/01/19 04:12:47) DEBUG:[hardware getDiskSize 1] Current Directory=/mnt/source/Cisco/hssi/shared/bin (05/01/19 04:12:47) [128] retrieved from cache for key -[getDiskSize 1]- (05/01/19 04:12:47) IN INITIALIZE model = KVM statefile= /tmp/hssi_api_state_file (05/01/19 04:12:47) DEBUG:[hardware getDiskBlockSize 1] Current Directory=/mnt/source/Cisco/hssi/shared/bin (05/01/19 04:12:47) In runtime of KVM args [HssiDefines] (05/01/19 04:12:47) In runtime of server_implementation args [HssiDefines] (05/01/19 04:12:47) server_implementation shared_implementation.sh HssiDefines returns (0) (05/01/19 04:12:47) KVM shared_implementation.sh HssiDefines returns (0) (05/01/19 04:12:47) In runtime of RHEV args [getHardwareAttribute getDiskBlockSize 1] (05/01/19 04:12:47) In runtime of RHEV args [getDiskBlockSize 1] (05/01/19 04:12:47) In runtime of RHEV args [disk_access] (05/01/19 04:12:47) RHEV api_implementation.sh disk_access returns (0) (05/01/19 04:12:47) In runtime of KVM args [getProcDiskBlockSize vda] (05/01/19 04:12:47) In runtime of server_implementation args [getProcDiskBlockSize vda] (05/01/19 04:12:47) server_implementation shared_implementation.sh getProcDiskBlockSize vda returns (0) (05/01/19 04:12:47) KVM shared_implementation.sh getProcDiskBlockSize vda returns (0) (05/01/19 04:12:47) RHEV api_implementation.sh getDiskBlockSize 1 returns (0) (05/01/19 04:12:47) RHEV api_implementation.sh getHardwareAttribute getDiskBlockSize 1 returns (0) (05/01/19 04:12:50) IN INITIALIZE model = KVM statefile= /tmp/hssi_api_state_file (05/01/19 04:12:50) DEBUG:[hardware getDiskName 1] Current Directory=/mnt/source/Cisco/hssi/shared/bin (05/01/19 04:12:50) In runtime of KVM args [HssiDefines] (05/01/19 04:12:50) In runtime of server_implementation args [HssiDefines] (05/01/19 04:12:50) server_implementation shared_implementation.sh HssiDefines returns (0) (05/01/19 04:12:50) KVM shared_implementation.sh HssiDefines returns (0) (05/01/19 04:12:50) In runtime of RHEV args [getHardwareAttribute getDiskName 1] (05/01/19 04:12:50) In runtime of RHEV args [getDiskName 1] (05/01/19 04:12:51) In runtime of RHEV args [disk_access] (05/01/19 04:12:51) RHEV api_implementation.sh disk_access returns (0) (05/01/19 04:12:51) In runtime of KVM args [getProcDiskName vda] (05/01/19 04:12:51) In runtime of server_implementation args [getProcDiskName vda] (05/01/19 04:12:51) server_implementation shared_implementation.sh getProcDiskName vda returns (0) (05/01/19 04:12:51) KVM shared_implementation.sh getProcDiskName vda returns (0) (05/01/19 04:12:51) RHEV api_implementation.sh getDiskName 1 returns (0) (05/01/19 04:12:51) RHEV api_implementation.sh getHardwareAttribute getDiskName 1 returns (0) (05/01/19 04:35:32) IN INITIALIZE model = KVM statefile= /tmp/hssi_api_state_file (05/01/19 04:35:32) DEBUG:[getHwRpmList] Current Directory=/mnt/source/Cisco/hssi/shared/bin (05/01/19 04:35:33) In runtime of KVM args [HssiDefines] (05/01/19 04:35:33) In runtime of server_implementation args [HssiDefines] (05/01/19 04:35:33) server_implementation shared_implementation.sh HssiDefines returns (0) (05/01/19 04:35:33) KVM shared_implementation.sh HssiDefines returns (0) (05/01/19 04:35:33) In runtime of RHEV args [getHwRpmList] (05/01/19 04:35:33) In runtime of KVM args [getHwRpmList] (05/01/19 04:35:33) In runtime of server_implementation args [getHwRpmList] (05/01/19 04:35:33) server_implementation shared_implementation.sh getHwRpmList returns (1) (05/01/19 04:35:33) KVM shared_implementation.sh getHwRpmList returns (1) (05/01/19 04:35:33) RHEV api_implementation.sh getHwRpmList returns (1)

cat initial_hardware_info Vendor= Red Hat HWModel=KVM CPUCount=6 CPUType= QEMU Virtual CPU version (cpu64-rhel6) CPUSpeed=(cpu64-rhel6) MEMSize=4096 BIOSVer=Seabios 0.5.1 01/01/2007 ObjectId=KVM OSVersion=UCOS 6.0.0.0-2.i386 SerialNumber= Not Specified VendorOID= areDisksAligned=0 isHardwareUnsupported=1

cat partAlloc clearpart --drives=vda --all --initlabel part swap --size 4000 --ondisk vda part / --fstype ext4 --size 21648 --asprimary --ondisk vda part /partB --fstype ext4 --size 21648 --asprimary --ondisk vda part /grub --fstype ext4 --size 256 --asprimary --ondisk vda part /common --fstype ext4 --size 1 --grow --ondisk vda

install.log (most of it)

04/30/2019 22:47:34 InstallWizard|newtFormRun exited. reason=1|<LVL::Info> 04/30/2019 22:47:34 InstallWizard|installWizard service deactivated|<LVL::Info> 04/30/2019 22:47:34 InstallWizard|validateNetworking Failed, user requested CONTINUE of the Install|<LVL::Warn> 04/30/2019 22:47:34 InstallWizard|validateNetworking Failed, validateNetworkingBypassEnabled set to TRUE|<LVL::Warn> 04/30/2019 22:47:34 InstallWizard|adminAcctConfigEnabled: admin='optional'.|<LVL::Debug> 04/30/2019 22:47:34 InstallWizard|validateAdminCredentials: valid administrator id admin|<LVL::Info> 04/30/2019 22:47:34 InstallWizard|multideployConfigEnabled: multideploy='optional'.|<LVL::Debug> 04/30/2019 22:47:34 InstallWizard|Updating /usr/local/platform/conf/platformConfig.xml|<LVL::Info> 04/30/2019 22:47:34 InstallWizard|6f e2 98 18|<LVL::Debug> 04/30/2019 22:47:34 InstallWizard|written 6267 bytes to /usr/local/platform/conf/platformConfig.xml|<LVL::Info> 04/30/2019 22:47:34 InstallWizard|finally salt = $5$wD3LN74K$|<LVL::Info> 04/30/2019 22:47:34 InstallWizard|runScript: Closing inewt|<LVL::Debug> 04/30/2019 22:47:34 InstallWizard|runScript: Calling system("/root/.security/unimmunize.sh >/common/log/install/capture.txt 2>&1")|<LVL::Debug> 04/30/2019 22:47:34 InstallWizard|runScript: 0 = system("/root/.security/unimmunize.sh >/common/log/install/capture.txt 2>&1")|<LVL::Debug> 04/30/2019 22:47:34 InstallWizard|runScript: Opening inewt|<LVL::Debug> 04/30/2019 22:47:34 InstallWizard|runScript: Closing inewt|<LVL::Debug> 04/30/2019 22:47:34 InstallWizard|runScript: Calling system("/usr/sbin/groupadd administrator >/common/log/install/capture.txt 2>&1")|<LVL::Debug> 04/30/2019 22:47:35 InstallWizard|(CAPTURE) groupadd: group 'administrator' already exists|<LVL::Debug> 04/30/2019 22:47:35 InstallWizard|runScript: 2304 = system("/usr/sbin/groupadd administrator >/common/log/install/capture.txt 2>&1")|<LVL::Debug> 04/30/2019 22:47:35 InstallWizard|runScript: Opening inewt|<LVL::Debug> 04/30/2019 22:47:35 InstallWizard|Created selinux security directory|<LVL::Info> 04/30/2019 22:47:35 InstallWizard|Opened selinux admin role shell script|<LVL::Info> 04/30/2019 22:47:35 InstallWizard|Added selinux admin role to shell script|<LVL::Info> 04/30/2019 22:47:35 InstallWizard|Added selinux admin login to shell script|<LVL::Info> 04/30/2019 22:47:35 InstallWizard|runScript: Closing inewt|<LVL::Debug> 04/30/2019 22:47:35 InstallWizard|runScript: Calling system("/usr/sbin/adduser -s /usr/local/platform/bin/startcliscript.sh -g administrator -p '$5$wD3LN74K$RZ8V/o3t5o0r0h0aexUL03/k3XsmCvQEsOFOC/2oT41' admin >/common/log/install/capture.txt 2>&1")|<LVL::Debug> 04/30/2019 22:47:36 InstallWizard|runScript: 0 = system("/usr/sbin/adduser -s /usr/local/platform/bin/startcliscript.sh -g administrator -p '$5$wD3LN74K$RZ8V/o3t5o0r0h0aexUL03/k3XsmCvQEsOFOC/2oT41' admin >/common/log/install/capture.txt 2>&1")|<LVL::Debug> 04/30/2019 22:47:36 InstallWizard|runScript: Opening inewt|<LVL::Debug> 04/30/2019 22:47:36 InstallWizard|runScript: Closing inewt|<LVL::Debug> 04/30/2019 22:47:36 InstallWizard|runScript: Calling system("/usr/sbin/usermod -p '$5$wD3LN74K$RZ8V/o3t5o0r0h0aexUL03/k3XsmCvQEsOFOC/2oT41' adminsftp >/common/log/install/capture.txt 2>&1")|<LVL::Debug> 04/30/2019 22:47:37 InstallWizard|runScript: 0 = system("/usr/sbin/usermod -p '$5$wD3LN74K$RZ8V/o3t5o0r0h0aexUL03/k3XsmCvQEsOFOC/2oT41' adminsftp >/common/log/install/capture.txt 2>&1")|<LVL::Debug> 04/30/2019 22:47:37 InstallWizard|runScript: Opening inewt|<LVL::Debug> 04/30/2019 22:47:37 InstallWizard|runScript: Closing inewt|<LVL::Debug> 04/30/2019 22:47:37 InstallWizard|runScript: Calling system("/root/.security/immunize.sh >/common/log/install/capture.txt 2>&1")|<LVL::Debug> 04/30/2019 22:47:37 InstallWizard|runScript: 0 = system("/root/.security/immunize.sh >/common/log/install/capture.txt 2>&1")|<LVL::Debug> 04/30/2019 22:47:37 InstallWizard|runScript: Opening inewt|<LVL::Debug> 04/30/2019 22:47:37 InstallWizard|csrConfigEnabled: cert='optional'.|<LVL::Debug> 04/30/2019 22:47:37 InstallWizard|Organziation = "aasdfas "|<LVL::Info> 04/30/2019 22:47:37 InstallWizard|Unit = "sdafasd "|<LVL::Info> 04/30/2019 22:47:37 InstallWizard|Location = "fsdf "|<LVL::Info> 04/30/2019 22:47:37 InstallWizard|State = "sfas "|<LVL::Info> 04/30/2019 22:47:37 InstallWizard|Country = "US "|<LVL::Info> 04/30/2019 22:47:37 InstallWizard|runScript: Closing inewt|<LVL::Debug> 04/30/2019 22:47:37 InstallWizard|runScript: Calling system("/usr/local/bin/base_scripts/sd_zoneinfo America/Los_Angeles >/common/log/install/capture.txt 2>&1")|<LVL::Debug> 04/30/2019 22:47:37 InstallWizard|runScript: 0 = system("/usr/local/bin/base_scripts/sd_zoneinfo America/Los_Angeles >/common/log/install/capture.txt 2>&1")|<LVL::Debug> 04/30/2019 22:47:37 InstallWizard|runScript: Opening inewt|<LVL::Debug> 04/30/2019 22:47:37 InstallWizard|set TZ='America/Los_Angeles' succeeded|<LVL::Info> 04/30/2019 22:47:37 InstallWizard|The time zone has been set. Updating the system clock|<LVL::Info> 04/30/2019 22:47:37 InstallWizard|runScript: Closing inewt|<LVL::Debug> 04/30/2019 22:47:37 InstallWizard|runScript: Calling system("/usr/sbin/hwclock --systz >/common/log/install/capture.txt 2>&1")|<LVL::Debug> 04/30/2019 22:47:37 InstallWizard|runScript: 0 = system("/usr/sbin/hwclock --systz >/common/log/install/capture.txt 2>&1")|<LVL::Debug> 04/30/2019 22:47:37 InstallWizard|runScript: Opening inewt|<LVL::Debug> 04/30/2019 22:47:37 InstallWizard|ntpConfigEnabled: ntp='optional'.|<LVL::Debug> 04/30/2019 22:47:37 InstallWizard|clusterConfigEnabled: cluster='optional'.|<LVL::Debug> 04/30/2019 22:47:37 InstallWizard|Calling /usr/local/bin/base_scripts/ipprefs --portTag ntp --enable |<LVL::Info> 04/30/2019 22:47:37 InstallWizard|runScriptPassive: Calling system("/usr/local/bin/base_scripts/ipprefs --portTag ntp --enable >/common/log/install/capture.txt 2>&1")|<LVL::Debug> 04/30/2019 22:47:47 InstallWizard|runScriptPassive: 0 = system("/usr/local/bin/base_scripts/ipprefs --portTag ntp --enable >/common/log/install/capture.txt 2>&1")|<LVL::Debug> 04/30/2019 22:47:47 InstallWizard|runScriptPassive: correcting rc per system/script call; returning rc=0|<LVL::Debug> 04/30/2019 22:47:47 InstallWizard|Relying on system/HW clock.|<LVL::Info> 04/30/2019 22:47:47 InstallWizard|ipmScriptListInit|<LVL::Debug> 04/30/2019 22:47:47 InstallWizard|ipmScriptListAdd: /usr/local/bin/base_scripts/sd_ntp -defaultConf|<LVL::Debug> 04/30/2019 22:47:47 InstallWizard|ipmScriptListRun: desc="Setting up NTP server to use hardware clock as time source ...", title="NTP Configuration", items=1|<LVL::Debug> 04/30/2019 22:47:47 IPM|Open progress meter "NTP Configuration"|<LVL::Info> 04/30/2019 22:47:47 IPM| begin-of-session "Setting up NTP server to use hardware clock as time source ...", 1 items|<LVL::Info> 04/30/2019 22:47:47 IPM| ipmDoTimedCommand: cmd="/usr/local/bin/base_scripts/sd_ntp -defaultConf", est(sec)=60, max(sec)=300|<LVL::Debug> 04/30/2019 22:47:47 IPM| begin-of-work: [cmd="/usr/local/bin/base_scripts/sd_ntp -defaultConf"]|<LVL::Info> 04/30/2019 22:47:47 IPM-Child|execlp(/tmp/.ipmbzd4Pg) for cmd "/usr/local/bin/base_scripts/sd_ntp -defaultConf"|<LVL::Debug> 04/30/2019 22:47:47 IPM|(CAPTURE) rc=0|<LVL::Debug> 04/30/2019 22:47:47 IPM| end-of-work: [cmd="/usr/local/bin/base_scripts/sd_ntp -defaultConf"]|<LVL::Info> 04/30/2019 22:47:47 IPM|Child's return-status = 0x00000000|<LVL::Debug> 04/30/2019 22:47:50 IPM| end-of-session "Setting up NTP server to use hardware clock as time source ...": 3.253 secs.|<LVL::Info> 04/30/2019 22:47:50 IPM|Close progress meter "NTP Configuration"|<LVL::Info> 04/30/2019 22:47:50 InstallWizard|Security password = Passed|<LVL::Info> 04/30/2019 22:47:50 InstallWizard|6f e2 98 18|<LVL::Debug> 04/30/2019 22:47:50 InstallWizard|Updating /usr/local/platform/conf/platformConfig.xml|<LVL::Info> 04/30/2019 22:47:50 InstallWizard|openPlatformXml: Parse existing xml document /usr/local/platform/conf/platformConfig.xml.|<LVL::Info> 04/30/2019 22:47:50 InstallWizard|6f e2 98 18|<LVL::Debug> 04/30/2019 22:47:50 InstallWizard|written 6267 bytes to /usr/local/platform/conf/platformConfig.xml|<LVL::Info> 04/30/2019 22:47:50 InstallWizard|ipmScriptListInit|<LVL::Debug> 04/30/2019 22:47:50 InstallWizard|ipmScriptListAdd: /root/.security/sftp/update_sftpuser_pwd.sh|<LVL::Debug> 04/30/2019 22:47:50 InstallWizard|ipmScriptListRun: desc="Running Security Configuration", title="Security Configuration", items=1|<LVL::Debug> 04/30/2019 22:47:50 IPM|Open progress meter "Security Configuration"|<LVL::Info> 04/30/2019 22:47:50 IPM| begin-of-session "Running Security Configuration", 1 items|<LVL::Info> 04/30/2019 22:47:50 IPM| ipmDoTimedCommand: cmd="/root/.security/sftp/update_sftpuser_pwd.sh", est(sec)=60, max(sec)=300|<LVL::Debug> 04/30/2019 22:47:50 IPM| begin-of-work: [cmd="/root/.security/sftp/update_sftpuser_pwd.sh"]|<LVL::Info> 04/30/2019 22:47:50 IPM-Child|execlp(/tmp/.ipmenpo1t) for cmd "/root/.security/sftp/update_sftpuser_pwd.sh"|<LVL::Debug> 04/30/2019 22:47:51 IPM|(CAPTURE) Changing password for user sftpuser.|<LVL::Debug> 04/30/2019 22:47:51 IPM|(CAPTURE) passwd: all authentication tokens updated successfully.|<LVL::Debug> 04/30/2019 22:47:51 IPM|(CAPTURE) Changing password for user drfuser.|<LVL::Debug> 04/30/2019 22:47:51 IPM|(CAPTURE) passwd: all authentication tokens updated successfully.|<LVL::Debug> 04/30/2019 22:47:51 IPM|(CAPTURE) Changing password for user drfkeys.|<LVL::Debug> 04/30/2019 22:47:51 IPM|(CAPTURE) passwd: all authentication tokens updated successfully.|<LVL::Debug> 04/30/2019 22:47:51 IPM|(CAPTURE) Set user-specified password for sftpuser, drfuser and drfkeys|<LVL::Debug> 04/30/2019 22:47:51 IPM|(CAPTURE) rc=0|<LVL::Debug> 04/30/2019 22:47:51 IPM| end-of-work: [cmd="/root/.security/sftp/update_sftpuser_pwd.sh"]|<LVL::Info> 04/30/2019 22:47:51 IPM|Child's return-status = 0x00000000|<LVL::Debug> 04/30/2019 22:47:55 IPM| end-of-session "Running Security Configuration": 4.352 secs.|<LVL::Info> 04/30/2019 22:47:55 IPM|Close progress meter "Security Configuration"|<LVL::Info> 04/30/2019 22:47:55 InstallWizard|Security configuration OK|<LVL::Info> 04/30/2019 22:47:55 InstallWizard|smtpConfigMandatory: smtp='optional'.|<LVL::Debug> 04/30/2019 22:47:55 InstallWizard|smtpConfigOptional: smtp='optional'.|<LVL::Debug> 04/30/2019 22:47:55 InstallWizard|applAcctConfigEnabled: appusr='optional'.|<LVL::Debug> 04/30/2019 22:47:55 InstallWizard|Validating Application User Credentials|<LVL::Info> 04/30/2019 22:47:55 InstallWizard|validateApplUserCredentials: valid application username id app|<LVL::Info> 04/30/2019 22:47:55 InstallWizard|validateData() status 1|<LVL::Info> 04/30/2019 22:47:55 InstallWizard|Data validation complete.|<LVL::Info> 04/30/2019 22:47:55 InstallWizard|Getting CallManager wizard|<LVL::Info> 04/30/2019 22:47:55 InstallWizard|Wizard not found for CallManager|<LVL::Info> 04/30/2019 22:47:55 InstallWizard|Getting Selfcare wizard|<LVL::Info> 04/30/2019 22:47:55 InstallWizard|Wizard not found for Selfcare|<LVL::Info> 04/30/2019 22:47:55 InstallWizard|multideployConfigEnabled: multideploy='optional'.|<LVL::Debug> 04/30/2019 22:47:55 InstallWizard|Updating /usr/local/platform/conf/platformConfig.xml|<LVL::Info> 04/30/2019 22:47:55 InstallWizard|openPlatformXml: Parse existing xml document /usr/local/platform/conf/platformConfig.xml.|<LVL::Info> 04/30/2019 22:47:56 InstallWizard|clusterConfigEnabled: cluster='optional'.|<LVL::Debug> 04/30/2019 22:47:56 InstallWizard|'/usr/local/platform/bin/clm/clm_ctl set install_configuration_complete yes' executed successfully|<LVL::Info> 04/30/2019 22:47:56 InstallWizard|6f e2 98 18|<LVL::Debug> 04/30/2019 22:47:56 InstallWizard|written 6268 bytes to /usr/local/platform/conf/platformConfig.xml|<LVL::Info> 04/30/2019 22:47:56 InstallWizard|Exiting InstallWizard with return code 0|<LVL::Info> 04/30/2019 22:47:56 platform-events.sh|Received request for platform-event (platform-configuration-complete)|<LVL::Info> 04/30/2019 22:47:56 platform-events.sh|emitting platform-event (platform-configuration-complete)|<LVL::Info> 04/30/2019 22:47:56 platform-events.sh|emitted platform-event (platform-configuration-complete)|<LVL::Info> 04/30/2019 22:47:56 post_install|Changing reserved block percentage on root from 5% to 1%|<LVL::Debug> 04/30/2019 22:47:56 post_install|Reserved block percentage on root changed to 1%|<LVL::Debug> 04/30/2019 22:47:56 post_install|Installing platform prefs files|<LVL::Info> 04/30/2019 22:47:56 post_install|Calling "/usr/local/bin/base_scripts/sd_prefs -set ALL"|<LVL::Debug> 04/30/2019 22:47:57 post_install|Calling "/usr/local/bin/base_scripts/sd_installprefs -set ALL"|<LVL::Debug> 04/30/2019 22:47:58 post_install|Platform prefs files install complete|<LVL::Info> 04/30/2019 22:47:58 post_install|Checking if CLI disabling is supported ..|<LVL::Info> 04/30/2019 22:48:02 removeCliAccess.sh|The product Cisco Unified Communications Manager supports CLI (Command Line) access|<LVL::Info> 04/30/2019 22:48:02 post_install|Install applications|<LVL::Info> 04/30/2019 22:48:02 post_install|Calling /usr/local/bin/base_scripts/appmanager.sh -install|<LVL::Debug> 04/30/2019 22:48:04 appmanager.sh|Appliation install RTMTStart|<LVL::Notice> 04/30/2019 22:48:04 appmanager.sh|Parse argument -install|<LVL::Debug> 04/30/2019 22:48:04 appmanager.sh|action type is install|<LVL::Debug> 04/30/2019 22:48:04 appmanager.sh|Application install starting|<LVL::Info> 04/30/2019 22:48:04 InstallWizard|Platform Install: installWizMain.c@@/main/161|<LVL::Info> 04/30/2019 22:48:04 InstallWizard|Reencrypt password|<LVL::Info> 04/30/2019 22:48:04 InstallWizard|Running from local disk|<LVL::Info> 04/30/2019 22:48:07 InstallWizard|fipsAESAlgoConfig: fipsAES='optional'.|<LVL::Debug> 04/30/2019 22:48:07 InstallWizard|applAcctConfigEnabled: appusr='optional'.|<LVL::Debug> 04/30/2019 22:48:07 InstallWizard|ccmEncryptionConfigOptional: dynamicKeyCCMEncryption='enable'.|<LVL::Debug> 04/30/2019 22:48:07 InstallWizard|Populating config from file /usr/local/platform/conf/platformConfig.xml|<LVL::Info> 04/30/2019 22:48:07 InstallWizard|openPlatformXml: Parse existing xml document /usr/local/platform/conf/platformConfig.xml.|<LVL::Info> 04/30/2019 22:48:07 InstallWizard|ProductDeployment 'callmanager'|<LVL::Info> 04/30/2019 22:48:07 InstallWizard|Install or upgrade type 'Basic Install'|<LVL::Info> 04/30/2019 22:48:07 InstallWizard|timezoneConfigEnabled: timezone='optional'.|<LVL::Debug> 04/30/2019 22:48:07 InstallWizard|ApplUserUsername 'app'|<LVL::Info> 04/30/2019 22:48:07 InstallWizard|Tag ApplUserDbPwCrypt not found.|<LVL::Warn> 04/30/2019 22:48:08 InstallWizard|ReencryptFlag set to FALSE|<LVL::Info> 04/30/2019 22:48:08 InstallWizard|NIC Auto Negotiate set to TRUE|<LVL::Info> 04/30/2019 22:48:08 InstallWizard|Host Name for this machine 'PubSecond'|<LVL::Info> 04/30/2019 22:48:08 InstallWizard|Change MTU set to FALSE|<LVL::Info> 04/30/2019 22:48:08 InstallWizard|Is DHCP enabled for this machine 'no'|<LVL::Info> 04/30/2019 22:48:08 InstallWizard|Host IP0 addr for this node '192.168.1.106'|<LVL::Info> 04/30/2019 22:48:08 InstallWizard|Host IP0 mask for this node '255.255.255.0'|<LVL::Info> 04/30/2019 22:48:08 InstallWizard|Gateway for this node '192.168.1.1'|<LVL::Info> 04/30/2019 22:48:08 InstallWizard|Primary DNS server IP address '192.168.1.1'|<LVL::Info> 04/30/2019 22:48:08 InstallWizard|Tag LocalHostDnsSecondary not found.|<LVL::Warn> 04/30/2019 22:48:08 InstallWizard|Domain name for this machine 'techrich.hk'|<LVL::Info> 04/30/2019 22:48:08 InstallWizard|First node in the cluster 'yes'|<LVL::Info> 04/30/2019 22:48:08 InstallWizard|node does NOT have external NTP server(s)|<LVL::Debug> 04/30/2019 22:48:08 InstallWizard|populateFromPlatformXml: IS first node.|<LVL::Info> 04/30/2019 22:48:08 InstallWizard|Timezone for this node 'America/Los_Angeles'|<LVL::Info> 04/30/2019 22:48:08 InstallWizard|Administrator name for this node 'admin'|<LVL::Info> 04/30/2019 22:48:08 InstallWizard|Administrator UID Value for this node 'admin'|<LVL::Info> 04/30/2019 22:48:08 InstallWizard|Old decrypt method|<LVL::Info> 04/30/2019 22:48:08 InstallWizard|Certification Signing Request Organization 'aasdfas'|<LVL::Info> 04/30/2019 22:48:08 InstallWizard|Certification Signing Request Unit 'sdafasd'|<LVL::Info> 04/30/2019 22:48:08 InstallWizard|Certification Signing Request Location 'fsdf'|<LVL::Info> 04/30/2019 22:48:08 InstallWizard|Certification Signing Request State 'sfas'|<LVL::Info> 04/30/2019 22:48:08 InstallWizard|Certification Signing Request Country 'US'|<LVL::Info> 04/30/2019 22:48:08 InstallWizard|Tag SMTPHostName not found.|<LVL::Warn> 04/30/2019 22:48:08 InstallWizard|A Special Install Mode for Testers which will ignore networking errors in the install process 'yes'|<LVL::Info> 04/30/2019 22:48:08 InstallWizard|Network Validation Bypassed|<LVL::Info> 04/30/2019 22:48:08 InstallWizard|ccmEncryptionConfigOptional: dynamicKeyCCMEncryption='enable'.|<LVL::Debug> 04/30/2019 22:48:08 InstallWizard|Generating Dynamic Encryption Key with config of CCM Encryption enable|<LVL::Info> 04/30/2019 22:48:08 InstallWizard|Dynamic key file is not present|<LVL::Info> 04/30/2019 22:48:08 InstallWizard|File read successful|<LVL::Info> 04/30/2019 22:48:08 InstallWizard|encryptData : enter|<LVL::Info> 04/30/2019 22:48:08 InstallWizard|encryptData (Success)|<LVL::Info> 04/30/2019 22:48:08 InstallWizard|Dynamic Encryption Key generation Successful for fresh install|<LVL::Info> 04/30/2019 22:48:08 InstallWizard|fipsAESAlgoConfig: fipsAES='optional'.|<LVL::Debug> 04/30/2019 22:48:09 InstallWizard|Updating /usr/local/platform/conf/platformConfig.xml|<LVL::Info> 04/30/2019 22:48:09 InstallWizard|clusterConfigEnabled: cluster='optional'.|<LVL::Debug> 04/30/2019 22:48:09 InstallWizard|'/usr/local/platform/bin/clm/clm_ctl set install_configuration_complete yes' executed successfully|<LVL::Info> 04/30/2019 22:48:09 InstallWizard|6f e2 98 18|<LVL::Debug> 04/30/2019 22:48:09 InstallWizard|written 6502 bytes to /usr/local/platform/conf/platformConfig.xml|<LVL::Info> 04/30/2019 22:48:09 InstallWizard|ccmEncryptionConfigOptional: dynamicKeyCCMEncryption='enable'.|<LVL::Debug> 04/30/2019 22:48:09 InstallWizard|encryptData : enter|<LVL::Info> 04/30/2019 22:48:09 InstallWizard|encryptData (Success)|<LVL::Info> 04/30/2019 22:48:09 InstallWizard|Updating /usr/local/platform/conf/platformConfig.xml|<LVL::Info> 04/30/2019 22:48:09 InstallWizard|openPlatformXml: Parse existing xml document /usr/local/platform/conf/platformConfig.xml.|<LVL::Info> 04/30/2019 22:48:09 InstallWizard|clusterConfigEnabled: cluster='optional'.|<LVL::Debug> 04/30/2019 22:48:09 InstallWizard|'/usr/local/platform/bin/clm/clm_ctl set install_configuration_complete yes' executed successfully|<LVL::Info> 04/30/2019 22:48:09 InstallWizard|written 6522 bytes to /usr/local/platform/conf/platformConfig.xml|<LVL::Info> 04/30/2019 22:48:09 InstallWizard|re encrypted file stored in platformConfig file|<LVL::Info> 04/30/2019 22:48:09 appmanager.sh|/usr/local/bin/base_scripts/InstallWizard returned 0|<LVL::Info> 04/30/2019 22:48:09 component_install|Initializing xproduct.sh API|<LVL::Debug> 04/30/2019 22:48:09 component_install|Initializing xcomponent.sh API|<LVL::Debug> 04/30/2019 22:48:09 component_install|Initializing xplatformconfig.sh API|<LVL::Debug> 04/30/2019 22:48:09 component_install|PATH=/usr/local/cm/db/informix:/usr/local/cm/db/informix/bin:/usr/local/cm/bin:/usr/local//thirdparty/java/j2sdk/bin:/usr/local/sbin:/usr/local/bin:/sbin:/bin:/usr/sbin:/usr/bin:/sbin:/home/sftpuser:/root/.security:/root/bin:/usr/local/sbin:/sbin:/usr/sbin:/bin:/usr/bin:/sbin:/home/sftpuser:/root/.security:/sbin:/home/sftpuser:/root/.security|<LVL::Info> 04/30/2019 22:48:09 component_install|LD_LIBRARY_PATH=/usr/local/lib:/usr/local/thirdparty/java/j2sdk/jre/lib/i386:/usr/local/thirdparty/java/j2sdk/jre/lib/i386/server:/usr/lib/pgsql:/usr/lib:/usr/local/cm/lib:/usr/local/platform/lib:/usr/local/lib:/usr/local/thirdparty/java/j2sdk/jre/lib/i386:/usr/local/thirdparty/java/j2sdk/jre/lib/i386/server:/usr/lib/pgsql:/usr/lib:/usr/local/cm/lib:/usr/local/lib:/usr/local/thirdparty/java/j2sdk/jre/lib/i386:/usr/local/thirdparty/java/j2sdk/jre/lib/i386/server:/usr/lib/pgsql:/usr/lib:/usr/local/cm/lib::/usr/local/platform/lib:/usr/local/platform/lib|<LVL::Info> 04/30/2019 22:48:09 component_install|Parse argument type=infrastructure_post|<LVL::Debug> 04/30/2019 22:48:09 component_install|Parse argument mode=install|<LVL::Debug> 04/30/2019 22:48:09 component_install|Install infrastructure_post component, mode install|<LVL::Info> 04/30/2019 22:48:09 component_install|Initialize product data, mode install|<LVL::Info> 04/30/2019 22:48:09 component_install|Initialize "to" side product version|<LVL::Info> 04/30/2019 22:48:09 component_install|Access "to" side API|<LVL::Debug> 04/30/2019 22:48:10 component_install|Got version 12.0.1.10000-10|<LVL::Debug> 04/30/2019 22:48:10 component_install|Initialize "to" side product version complete|<LVL::Info> 04/30/2019 22:48:10 component_install|Initialize product data complete|<LVL::Info> 04/30/2019 22:48:10 component_install|Initialize component data, mode install|<LVL::Info> 04/30/2019 22:48:10 component_install|Initialize ucplatform_post "to" side version|<LVL::Info> 04/30/2019 22:48:10 component_install|Access "to" side API|<LVL::Debug> 04/30/2019 22:48:10 component_install|Got version 12.0.1.10000-10|<LVL::Debug> 04/30/2019 22:48:10 component_install|Initialize ucplatform_post "to" side version complete|<LVL::Info> 04/30/2019 22:48:10 component_install|Initialize global data complete|<LVL::Info> 04/30/2019 22:48:10 component_install|Build the command list for ucplatform_post|<LVL::Info> 04/30/2019 22:48:10 component_install|Initialize command list|<LVL::Debug> 04/30/2019 22:48:10 component_install|Build ucplatform_post install phase commands|<LVL::Debug> 04/30/2019 22:48:10 component_install|Processing ucplatform_post install element|<LVL::Debug> 04/30/2019 22:48:10 component_install|Processing ucplatform_post phase element|<LVL::Debug> 04/30/2019 22:48:10 component_install|Processing ucplatform_post import element|<LVL::Debug> 04/30/2019 22:48:10 component_install|Building the command list for ucplatform_post is complete|<LVL::Info> 04/30/2019 22:48:10 component_install|Command execution for ucplatform_post component RTMTStart|<LVL::Notice> 04/30/2019 22:48:10 component_install|Execute commands in the command list for ucplatform_post component|<LVL::Info> 04/30/2019 22:48:11 component_install|No commands to execute for ucplatform_post component|<LVL::Info> 04/30/2019 22:48:11 component_install|Command execution for ucplatform_post component is complete|<LVL::Info> 04/30/2019 22:48:11 component_install|Command execution for ucplatform_post component RTMTFinish|<LVL::Notice> 04/30/2019 22:48:11 component_install|Initialize component data, mode install|<LVL::Info> 04/30/2019 22:48:11 component_install|Initialize thirdparty "to" side version|<LVL::Info> 04/30/2019 22:48:11 component_install|Access "to" side API|<LVL::Debug> 04/30/2019 22:48:11 component_install|Got version 12.0.1.10000-10|<LVL::Debug> 04/30/2019 22:48:11 component_install|Initialize thirdparty "to" side version complete|<LVL::Info> 04/30/2019 22:48:11 component_install|Initialize global data complete|<LVL::Info> 04/30/2019 22:48:11 component_install|Build the command list for thirdparty|<LVL::Info> 04/30/2019 22:48:11 component_install|Initialize command list|<LVL::Debug> 04/30/2019 22:48:11 component_install|Build thirdparty install phase commands|<LVL::Debug> 04/30/2019 22:48:11 component_install|Processing thirdparty install element|<LVL::Debug> 04/30/2019 22:48:11 component_install|Processing thirdparty package element|<LVL::Debug> 04/30/2019 22:48:11 component_install|Checking if RPM /common/rpm-archive/12.0.1.10000-10/RPMS/thirdparty/cm-lib-1.0.0.0-1.i386.rpm exists|<LVL::Debug> 04/30/2019 22:48:11 component_install|Add "rpm -iv --replacefiles /common/rpm-archive/12.0.1.10000-10/RPMS/thirdparty/cm-lib-1.0.0.0-1.i386.rpm" to command list|<LVL::Debug> 04/30/2019 22:48:11 component_install|Processing thirdparty package element|<LVL::Debug> 04/30/2019 22:48:11 component_install|Checking if RPM /common/rpm-archive/12.0.1.10000-10/RPMS/thirdparty/cm-jar-lib-1.0.0.0-1.i386.rpm exists|<LVL::Debug> 04/30/2019 22:48:11 component_install|Add "rpm -iv --replacefiles /common/rpm-archive/12.0.1.10000-10/RPMS/thirdparty/cm-jar-lib-1.0.0.0-1.i386.rpm" to command list|<LVL::Debug> 04/30/2019 22:48:11 component_install|Building the command list for thirdparty is complete|<LVL::Info> 04/30/2019 22:48:11 component_install|Command execution for thirdparty component RTMTStart|<LVL::Notice> 04/30/2019 22:48:11 component_install|Execute commands in the command list for thirdparty component|<LVL::Info> 04/30/2019 22:48:12 IPM|Normalizer search path is /opt/cisco/install/bin|<LVL::Debug> 04/30/2019 22:48:12 IPM|Checking normalizer /opt/cisco/install/bin/pmNormalizeGeneric|<LVL::Debug> 04/30/2019 22:48:12 IPM|Returning normalizer /opt/cisco/install/bin/pmNormalizeGeneric|<LVL::Debug> 04/30/2019 22:48:13 IPM|Open progress meter "Component Install"|<LVL::Info> 04/30/2019 22:48:13 IPM| begin-of-session "Installing thirdparty component", 2 items|<LVL::Info> 04/30/2019 22:48:13 IPM| begin-analysis: [2 commands]|<LVL::Info> 04/30/2019 22:48:13 IPM| command #1: 3973452 bytes, 0 seconds: rpm -iv --replacefiles /common/rpm-archive/12.0.1.10000-10/RPMS/thirdparty/cm-lib-1.0.0.0-1.i386.rpm|<LVL::Info> 04/30/2019 22:48:13 IPM| command #2: 6237296 bytes, 0 seconds: rpm -iv --replacefiles /common/rpm-archive/12.0.1.10000-10/RPMS/thirdparty/cm-jar-lib-1.0.0.0-1.i386.rpm|<LVL::Info> 04/30/2019 22:48:13 IPM| end-analysis: 2 total objects, 10210748 total bytes, 0 total seconds, 0.051 secs.|<LVL::Info> 04/30/2019 22:48:13 IPM| begin-work: [2 items]|<LVL::Info> 04/30/2019 22:48:13 IPM|Execute rpm command "rpm --percent -iv --replacefiles /common/rpm-archive/12.0.1.10000-10/RPMS/thirdparty/cm-lib-1.0.0.0-1.i386.rpm "|<LVL::Debug> 04/30/2019 22:48:26 IPM| item 1: rpm-install 3973452 bytes (13.020 secs.)|<LVL::Info> 04/30/2019 22:48:26 IPM|Execute rpm command "rpm --percent -iv --replacefiles /common/rpm-archive/12.0.1.10000-10/RPMS/thirdparty/cm-jar-lib-1.0.0.0-1.i386.rpm "|<LVL::Debug> 04/30/2019 22:48:35 IPM| item 2: rpm-install 6237296 bytes (9.336 secs.)|<LVL::Info> 04/30/2019 22:48:35 IPM| end-work: 2 items 22.367 secs. total|<LVL::Info> 04/30/2019 22:48:37 IPM| end-of-session "Installing thirdparty component": 24.377 secs.|<LVL::Info> 04/30/2019 22:48:37 IPM|Close progress meter "Component Install"|<LVL::Info> 04/30/2019 22:48:37 component_install|Command execution for thirdparty component is complete|<LVL::Info> 04/30/2019 22:48:37 component_install|Command execution for thirdparty component RTMTFinish|<LVL::Notice> 04/30/2019 22:48:37 component_install|Initialize component data, mode install|<LVL::Info> 04/30/2019 22:48:37 component_install|Initialize serviceability_core "to" side version|<LVL::Info> 04/30/2019 22:48:37 component_install|Access "to" side API|<LVL::Debug> 04/30/2019 22:48:37 component_install|Got version 12.0.1.10000-10|<LVL::Debug> 04/30/2019 22:48:37 component_install|Initialize serviceability_core "to" side version complete|<LVL::Info> 04/30/2019 22:48:37 component_install|Initialize global data complete|<LVL::Info> 04/30/2019 22:48:37 component_install|Build the command list for serviceability_core|<LVL::Info> 04/30/2019 22:48:37 component_install|Initialize command list|<LVL::Debug> 04/30/2019 22:48:37 component_install|Build serviceability_core install phase commands|<LVL::Debug> 04/30/2019 22:48:37 component_install|Processing serviceability_core install element|<LVL::Debug> 04/30/2019 22:48:38 component_install|Processing serviceability_core package element|<LVL::Debug> 04/30/2019 22:48:38 component_install|Checking if RPM /common/rpm-archive/12.0.1.10000-10/RPMS/serviceability_core/cm-pi-0.0.0.1-0.i386.rpm exists|<LVL::Debug> 04/30/2019 22:48:38 component_install|Add "rpm -iv --replacefiles /common/rpm-archive/12.0.1.10000-10/RPMS/serviceability_core/cm-pi-0.0.0.1-0.i386.rpm" to command list|<LVL::Debug> 04/30/2019 22:48:38 component_install|Building the command list for serviceability_core is complete|<LVL::Info> 04/30/2019 22:48:38 component_install|Command execution for serviceability_core component RTMTStart|<LVL::Notice> 04/30/2019 22:48:38 component_install|Execute commands in the command list for serviceability_core component|<LVL::Info> 04/30/2019 22:48:38 IPM|Normalizer search path is /opt/cisco/install/bin|<LVL::Debug> 04/30/2019 22:48:38 IPM|Checking normalizer /opt/cisco/install/bin/pmNormalizeGeneric|<LVL::Debug> 04/30/2019 22:48:38 IPM|Returning normalizer /opt/cisco/install/bin/pmNormalizeGeneric|<LVL::Debug> 04/30/2019 22:48:39 IPM|Open progress meter "Component Install"|<LVL::Info> 04/30/2019 22:48:39 IPM| begin-of-session "Installing core serviceability component", 1 items|<LVL::Info> 04/30/2019 22:48:39 IPM| begin-analysis: [1 commands]|<LVL::Info> 04/30/2019 22:48:39 IPM| command #1: 4318680 bytes, 0 seconds: rpm -iv --replacefiles /common/rpm-archive/12.0.1.10000-10/RPMS/serviceability_core/cm-pi-0.0.0.1-0.i386.rpm|<LVL::Info> 04/30/2019 22:48:39 IPM| end-analysis: 1 total objects, 4318680 total bytes, 0 total seconds, 0.045 secs.|<LVL::Info> 04/30/2019 22:48:39 IPM| begin-work: [1 items]|<LVL::Info> 04/30/2019 22:48:39 IPM|Execute rpm command "rpm --percent -iv --replacefiles /common/rpm-archive/12.0.1.10000-10/RPMS/serviceability_core/cm-pi-0.0.0.1-0.i386.rpm "|<LVL::Debug> 04/30/2019 22:48:48 IPM| item 1: rpm-install 4318680 bytes (9.029 secs.)|<LVL::Info> 04/30/2019 22:48:48 IPM| end-work: 1 items 9.156 secs. total|<LVL::Info> 04/30/2019 22:48:50 IPM| end-of-session "Installing core serviceability component": 11.167 secs.|<LVL::Info> 04/30/2019 22:48:50 IPM|Close progress meter "Component Install"|<LVL::Info> 04/30/2019 22:48:50 component_install|Command execution for serviceability_core component is complete|<LVL::Info> 04/30/2019 22:48:50 component_install|Command execution for serviceability_core component RTMTFinish|<LVL::Notice> 04/30/2019 22:48:50 component_install|Initialize component data, mode install|<LVL::Info> 04/30/2019 22:48:50 component_install|Initialize database "to" side version|<LVL::Info> 04/30/2019 22:48:50 component_install|Access "to" side API|<LVL::Debug> 04/30/2019 22:48:51 component_install|Got version 12.0.1.10000-10|<LVL::Debug> 04/30/2019 22:48:51 component_install|Initialize database "to" side version complete|<LVL::Info> 04/30/2019 22:48:51 component_install|Initialize global data complete|<LVL::Info> 04/30/2019 22:48:51 component_install|Build the command list for database|<LVL::Info> 04/30/2019 22:48:51 component_install|Initialize command list|<LVL::Debug> 04/30/2019 22:48:51 component_install|Build database install phase commands|<LVL::Debug> 04/30/2019 22:48:51 component_install|Processing database install element|<LVL::Debug> 04/30/2019 22:48:51 component_install|Processing database package element|<LVL::Debug> 04/30/2019 22:48:51 component_install|Checking if RPM /common/rpm-archive/12.0.1.10000-10/RPMS/database/cm-dbms-1.0.0.0-1.i386.rpm exists|<LVL::Debug> 04/30/2019 22:48:51 component_install|Add "rpm -iv --replacefiles /common/rpm-archive/12.0.1.10000-10/RPMS/database/cm-dbms-1.0.0.0-1.i386.rpm" to command list|<LVL::Debug> 04/30/2019 22:48:51 component_install|Processing database package element|<LVL::Debug> 04/30/2019 22:48:51 component_install|Checking if RPM /common/rpm-archive/12.0.1.10000-10/RPMS/database/cm-dbl-1.0.0.0-1.i386.rpm exists|<LVL::Debug> 04/30/2019 22:48:51 component_install|Add "rpm -iv --replacefiles /common/rpm-archive/12.0.1.10000-10/RPMS/database/cm-dbl-1.0.0.0-1.i386.rpm" to command list|<LVL::Debug> 04/30/2019 22:48:51 component_install|Processing database phase element|<LVL::Debug> 04/30/2019 22:48:51 component_install|Processing database import element|<LVL::Debug> 04/30/2019 22:48:51 component_install|Processing database phase element|<LVL::Debug> 04/30/2019 22:48:51 component_install|Processing database migration element|<LVL::Debug> 04/30/2019 22:48:51 component_install|Processing database phase element|<LVL::Debug> 04/30/2019 22:48:51 component_install|Processing database postinstall element|<LVL::Debug> 04/30/2019 22:48:51 component_install|Processing database script element|<LVL::Debug> 04/30/2019 22:48:51 component_install|Add "cm-dbms-install install PostInstall 12.0.1.10000-10 12.0.1.10000-10 /usr/local/cm/ /usr/local/cm/ /common/log/install/capture.txt" to command list|<LVL::Debug> 04/30/2019 22:48:52 component_install|Processing database script element|<LVL::Debug> 04/30/2019 22:48:52 component_install|Add "cm-dbl-install install PostInstall 12.0.1.10000-10 12.0.1.10000-10 /usr/local/cm/ /usr/local/cm/ /common/log/install/capture.txt" to command list|<LVL::Debug> 04/30/2019 22:48:52 component_install|Building the command list for database is complete|<LVL::Info> 04/30/2019 22:48:52 component_install|Command execution for database component RTMTStart|<LVL::Notice> 04/30/2019 22:48:52 component_install|Execute commands in the command list for database component|<LVL::Info> 04/30/2019 22:48:52 IPM|Normalizer search path is /opt/cisco/install/bin|<LVL::Debug> 04/30/2019 22:48:52 IPM|Checking normalizer /opt/cisco/install/bin/pmNormalizeGeneric|<LVL::Debug> 04/30/2019 22:48:52 IPM|Returning normalizer /opt/cisco/install/bin/pmNormalizeGeneric|<LVL::Debug> 04/30/2019 22:48:53 IPM|Open progress meter "Component Install"|<LVL::Info> 04/30/2019 22:48:53 IPM| begin-of-session "Installing database component", 4 items|<LVL::Info> 04/30/2019 22:48:53 IPM| begin-analysis: [4 commands]|<LVL::Info> 04/30/2019 22:48:53 IPM| command #1: 423348056 bytes, 0 seconds: rpm -iv --replacefiles /common/rpm-archive/12.0.1.10000-10/RPMS/database/cm-dbms-1.0.0.0-1.i386.rpm|<LVL::Info> 04/30/2019 22:48:53 IPM| command #2: 12611328 bytes, 0 seconds: rpm -iv --replacefiles /common/rpm-archive/12.0.1.10000-10/RPMS/database/cm-dbl-1.0.0.0-1.i386.rpm|<LVL::Info> 04/30/2019 22:48:53 IPM| command #3: 0 bytes, 4800 seconds: sh -path /opt/cisco/install/database/scripts:/usr/local/cm/script -t 4800 -c cm-dbms-install install PostInstall 12.0.1.10000-10 12.0.1.10000-10 /usr/local/cm/ /usr/local/cm/ /common/log/install/capture.txt|<LVL::Info> 04/30/2019 22:48:53 IPM| command #4: 0 bytes, 21600 seconds: sh -path /opt/cisco/install/database/scripts:/usr/local/cm/script -t 21600 -c cm-dbl-install install PostInstall 12.0.1.10000-10 12.0.1.10000-10 /usr/local/cm/ /usr/local/cm/ /common/log/install/capture.txt|<LVL::Info> 04/30/2019 22:48:53 IPM| end-analysis: 4 total objects, 435959384 total bytes, 26400 total seconds, 0.045 secs.|<LVL::Info> 04/30/2019 22:48:53 IPM| begin-work: [4 items]|<LVL::Info> 04/30/2019 22:48:53 IPM|Execute rpm command "rpm --percent -iv --replacefiles /common/rpm-archive/12.0.1.10000-10/RPMS/database/cm-dbms-1.0.0.0-1.i386.rpm "|<LVL::Debug> 04/30/2019 22:50:34 IPM| item 1: rpm-install 423348056 bytes (101.099 secs.)|<LVL::Info> 04/30/2019 22:50:34 IPM|Execute rpm command "rpm --percent -iv --replacefiles /common/rpm-archive/12.0.1.10000-10/RPMS/database/cm-dbl-1.0.0.0-1.i386.rpm "|<LVL::Debug> 04/30/2019 22:51:16 IPM| item 2: rpm-install 12611328 bytes (42.076 secs.)|<LVL::Info> 04/30/2019 22:51:16 IPM|Execute shell command "/usr/local/cm/script/cm-dbms-install install PostInstall 12.0.1.10000-10 12.0.1.10000-10 /usr/local/cm/ /usr/local/cm/ /common/log/install/capture.txt "|<LVL::Debug> 05/01/2019 00:11:17 IPM|Command exceeded maximum time (4800). Extending time by 24000 seconds.|<LVL::Warn> 05/01/2019 00:20:03 IPM| item 3: shell-script 0 bytes (5327.358 secs.)|<LVL::Info> 05/01/2019 00:20:03 IPM|Execute shell command "/usr/local/cm/script/cm-dbl-install install PostInstall 12.0.1.10000-10 12.0.1.10000-10 /usr/local/cm/ /usr/local/cm/ /common/log/install/capture.txt "|<LVL::Debug> 05/01/2019 01:33:50 IPM|Internal Error, File:ipm.c:2011, Function: ipmReadNormalizedInputLine(), "/usr/local/cm/script/cm-dbl-install install PostInstall 12.0.1.10000-10 12.0.1.10000-10 /usr/local/cm/ /usr/local/cm/ /common/log/install/capture.txt " failed (1)|<LVL::Critical> 05/01/2019 01:33:53 IPM| end-of-session "Installing database component": 9899.732 secs.|<LVL::Info> 05/01/2019 01:34:06 IPM|Close progress meter "Component Install"|<LVL::Info> 05/01/2019 01:34:06 component_install|Writing database into /common/log/install/component_failed.xml file.|<LVL::Info> 05/01/2019 01:34:07 component_install|/common/log/install/component_failed.xml created : 0|<LVL::Info> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:19:46 2019 cm-dbms-install DEBUG: <--|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:03 2019 cm-dbl-install DEBUG: -->|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:03 2019 cm-dbl-install.ParamObject.validate DEBUG: -->|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:03 2019 cm-dbl-install.ParamObject.validate DEBUG: <--|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:03 2019 cm-dbl-install.doActionInstall DEBUG: -->|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:03 2019 cm-dbl-install.doActionInstall DEBUG: Inside doActionInstall...|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:03 2019 cm-dbl-install.doInstallPost_installDB DEBUG: -->|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:03 2019 cm-dbl-install.doInstallPost_installDB DEBUG: Inside doInstallPost...|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:03 2019 cm-dbl-install.doCreateLogDirectory DEBUG: -->|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:03 2019 cm-dbl-install.doCreateLogDirectory DEBUG: Verifying SDI directory [/var/log/active/cm/trace/dbl/sdi]|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:03 2019 cm-dbl-install.doCreateLogDirectory DEBUG: chown database.ccmbase /var/log/active/cm/trace/dbl/sdi|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:03 2019 cm-dbl-install.doCreateLogDirectory DEBUG: chmod 775 /var/log/active/cm/trace/dbl/sdi|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:03 2019 cm-dbl-install.doCreateLogDirectory DEBUG: <--|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:03 2019 cm-dbl-install.setupPreferences DEBUG: -->|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:03 2019 cm-dbl-install.setupPreferences DEBUG: Inside setupPreferences...|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:03 2019 cm-dbl-install.setupPreferences DEBUG: current_db [ccm12_0_1_10000_10]|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:03 2019 cm-dbl-install.setupPreferences DEBUG: chown database.ccmbase /usr/local/cm/conf/dbl/prefs.xml ; chmod 644 /usr/local/cm/conf/dbl/prefs.xml|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:03 2019 cm-dbl-install.setupPreferences DEBUG: Updating prefs [/usr/local/cm/conf/dbl/prefs.xml]|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:03 2019 cm-dbl-install.shortHostName DEBUG: -->|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:03 2019 cm-dbl-install.shortHostName DEBUG: <--|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:03 2019 cm-dbl-install.shortHostName DEBUG: -->|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:03 2019 cm-dbl-install.shortHostName DEBUG: <--|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:03 2019 cm-dbl-install.shortHostName DEBUG: -->|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:03 2019 cm-dbl-install.shortHostName DEBUG: <--|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:03 2019 cm-dbl-install.shortHostName DEBUG: -->|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:03 2019 cm-dbl-install.shortHostName DEBUG: <--|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:03 2019 cm-dbl-install.setupPreferences DEBUG: chown database.ccmbase /usr/local/cm/conf/dbl/sdi/prefs.xml ; chmod 664 /usr/local/cm/conf/dbl/sdi/prefs.xml|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:03 2019 cm-dbl-install.setupPreferences DEBUG: Updating prefs [/usr/local/cm/conf/dbl/sdi/prefs.xml]|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:03 2019 cm-dbl-install.setupPreferences DEBUG: chown database.ccmbase /usr/local/cm/conf/dbnotify/sdi/prefs.xml ; chmod 664 /usr/local/cm/conf/dbnotify/sdi/prefs.xml|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:03 2019 cm-dbl-install.setupPreferences DEBUG: Updating prefs [/usr/local/cm/conf/dbnotify/sdi/prefs.xml]|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:03 2019 cm-dbl-install.setupPreferences DEBUG: python "/usr/local/cm/bin/dbl" setprefs /common/log/install/capture.txt|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:03 2019 dblsetprefs-plugin.run DEBUG: -->|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:03 2019 dblsetprefs-plugin.run DEBUG: Create Preferences entries for various database components|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:03 2019 dblsetprefs-plugin.run DEBUG: <--|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:03 2019 cm-dbl-install.setupPreferences DEBUG: <--|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:03 2019 cm-dbl-install.getPublisherFromInstallPrefs DEBUG: -->|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:03 2019 cm-dbl-install.getPublisherFromInstallPrefs DEBUG: Inside getPublisherFromInstallPrefs...|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:03 2019 cm-dbl-install.getPublisherFromInstallPrefs DEBUG: <--|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:03 2019 cm-dbl-install.doInstallPost_installDB DEBUG: (odbc setup moved to dbl init)|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:03 2019 cm-dbl-install.fixSQLLibPaths DEBUG: -->|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:03 2019 cm-dbl-install.fixSQLLibPaths DEBUG: Inside fixSQLLibPaths....|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:03 2019 cm-dbl-install.fixSQLLibPaths DEBUG: <--|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:03 2019 cm-dbl-install.fixSQLLibPaths DEBUG: -->|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:03 2019 cm-dbl-install.fixSQLLibPaths DEBUG: Inside fixSQLLibPaths....|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:04 2019 cm-dbl-install.fixSQLLibPaths DEBUG: <--|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:04 2019 cm-dbl-install.doInstallPost_installDB DEBUG: -->|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:04 2019 cm-dbl-install.doInstallPost_installDB DEBUG: Inside doInstallPost_installDB ...|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:04 2019 cm-dbl-install.installdb DEBUG: -->|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:04 2019 cm-dbl-install.installdb DEBUG: Inside installdb ...|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:04 2019 cm-dbl-install.installdb DEBUG: python "/usr/local/cm/bin/dbl" start --safe --nopreflight --log=/common/log/install/capture.txt|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:04 2019 dblstart-plugin.run DEBUG: -->|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:04 2019 dblstart-plugin.run DEBUG: Start DBMS|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:20 2019 dblstart-plugin.run DEBUG: do preflight|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:20 2019 dblstart-plugin.run DEBUG: <--|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:20 2019 cm-dbl-install.installdb DEBUG: rm -f /tmp/*.txt /tmp/*.log /tmp/*.err /var/log/active/cm/trace/dbl/sdi/psqlx /tmp/*.bin /tmp/af* /tmp/shmem*|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:20 2019 cm-dbl-install.installdb DEBUG: Disk size present in dbl/prefs is 128|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:20 2019 cm-dbl-install.getPublisherFromPrefs DEBUG: -->|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:20 2019 cm-dbl-install.getPublisherFromPrefs DEBUG: Inside getPublisherFromPrefs...|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:20 2019 cm-dbl-install.getPublisherFromPrefs DEBUG: <--|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 00:20:20 2019 cm-dbl-install.installdb DEBUG: su -l informix -s /bin/sh -c 'source /usr/local/cm/db/dblenv.bash /usr/local/cm ; source /usr/local/cm/db/informix/local/ids.env ; "/usr/local/cm/bin/installdb" -f IN "/usr/local/cm" "/usr/local/cm" 12.0.1.10000-10 12.0.1.10000-10 installdb_in.log'|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 01:33:49 2019 cm-dbl-install.installdb DEBUG: <--|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 01:33:49 2019 cm-dbl-install.doInstallPost_installDB DEBUG: <--|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 01:33:49 2019 cm-dbl-install.doInstallPost_installDB DEBUG: <--|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 01:33:49 2019 cm-dbl-install.doActionInstall DEBUG: <--|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 01:33:49 2019 cm-dbl-install DEBUG: cm-dbl-install [1]|<LVL::Debug> 05/01/2019 01:34:07 component_install|(CAPTURE) Wed May 1 01:33:49 2019 cm-dbl-install DEBUG: <--|<LVL::Debug> 05/01/2019 01:34:07 component_install|File:/opt/cisco/install/bin/component_install:821, Function: exec_progmeter(), /opt/cisco/install/bin/progmeter failed (1)|<LVL::Error> 05/01/2019 01:34:07 appmanager.sh|Internal Error, File:/usr/local/bin/base_scripts/appmanager.sh:153, Function: install(), failed to install infrastructure_post components|<LVL::Critical> 05/01/2019 01:34:07 post_install|File:/opt/cisco/install/bin/post_install:1030, Function: install_applications(), /usr/local/bin/base_scripts/appmanager.sh -install failed (1)|<LVL::Error> 05/01/2019 01:34:07 post_install|Exiting with result 1|<LVL::Info> 05/01/2019 01:34:07 post_install|INSTALL_TYPE="Basic Install"|<LVL::Debug> 05/01/2019 01:34:07 post_install|File:/opt/cisco/install/bin/post_install:721, Function: check_for_critical_error(), check_for_critical_error, found /common/log/install/critical.log, exiting|<LVL::Error> 05/01/2019 01:34:07 post_install|(CAPTURE) Mail notification cancelled - smtp server address for email not found! [/usr/local/platform/conf/platformConfig.xml]|<LVL::Debug> 05/01/2019 01:34:07 display_screen|Arguments: "Critical Error" "The installation has encountered a unrecoverable internal error. For further assistance report the following information to your support provider. "/usr/local/cm/script/cm-dbl-install install PostInstall 12.0.1.10000-10 12.0.1.10000-10 /usr/local/cm/ /usr/local/cm/ /common/log/install/capture.txt " failed (1) The system will now halt. Continuing will allow you to dump diagnostic information before halting." "Continue"|<LVL::Debug> 05/01/2019 01:34:07 display_screen|Button label size information 66, 1, 66|<LVL::Debug> 05/01/2019 01:34:07 platform-events.sh|Received request for platform-event (platform-install-paused-for-input TYPE=INSTALL_PAUSED)|<LVL::Info> 05/01/2019 01:34:07 platform-events.sh|emitting platform-event (platform-install-paused-for-input TYPE=INSTALL_PAUSED)|<LVL::Info> 05/01/2019 01:34:07 platform-events.sh|emitted platform-event (platform-install-paused-for-input TYPE=INSTALL_PAUSED)|<LVL::Info> 05/01/2019 01:34:07 display_screen|installWizard service activated|<LVL::Info> 05/01/2019 08:32:50 display_screen|newtFormRun exited. reason=1|<LVL::Info> 05/01/2019 08:32:50 display_screen|installWizard service deactivated|<LVL::Info> 05/01/2019 08:32:50 post_install|Dumping diagnostic information to USB|<LVL::Info> 05/01/2019 08:32:50 display_screen|Arguments: "INSTALLATION FAILED" "Would you like to dump diagnostic information?" "Yes" "No"|<LVL::Debug> 05/01/2019 08:32:50 display_screen|Button label size information 66, 2, 33|<LVL::Debug> 05/01/2019 08:32:50 platform-events.sh|Received request for platform-event (platform-install-paused-for-input TYPE=INSTALL_PAUSED)|<LVL::Info> 05/01/2019 08:32:50 platform-events.sh|emitting platform-event (platform-install-paused-for-input TYPE=INSTALL_PAUSED)|<LVL::Info> 05/01/2019 08:32:50 platform-events.sh|emitted platform-event (platform-install-paused-for-input TYPE=INSTALL_PAUSED)|<LVL::Info> 05/01/2019 08:32:50 display_screen|installWizard service activated|<LVL::Info> 05/01/2019 08:33:01 display_screen|newtFormRun exited. reason=1|<LVL::Info> 05/01/2019 08:33:01 display_screen|installWizard service deactivated|<LVL::Info> 05/01/2019 08:33:01 post_install|Installer selected not to dump diagnostics|<LVL::Debug> 05/01/2019 08:33:01 post_install|========================= Installation failed. =========================|<LVL::Info> 05/01/2019 08:33:01 post_install|Copy /usr/local/bin/base_scripts/INSTALLATION_FAILED to /etc/rc.d/init.d/|<LVL::Info> 05/01/2019 08:33:01 post_install|Calling "cp /usr/local/bin/base_scripts/INSTALLATION_FAILED /etc/rc.d/init.d"|<LVL::Debug>