CUPC CSF in HVD log Parsed

[Yufang Zhou]

! CUPC started and CSF is started by CUPC:

2012-05-28 14:31:03,286 DEBUG [main     ] [FileUtilities]  [FileUtilities.getOperatingSystemFamily(206)] - Operating System Family:  WINDOWS_7

2012-05-28 14:31:03,422 INFO  [main-0   ] [Controller] [Controller.printArguments(248)] -    Launching CSF with 1 arguments

2012-05-28 14:31:03,423 INFO  [main-0   ] [Controller]  [Controller.printArguments(252)] -      #1 =  [client.startup.callback=http://127.0.0.1:44442/v2/]

!  Reading local user config file for startup preference and setting  Config Registry Key, if required data not existed in policy folder, then  read it from native path:

2012-05-28 14:31:05,808 DEBUG [main-0   ] [LocalFileConfigProvider]  [LocalFileConfigProviderImpl.readUserConfigFile(121)] - Reading existing  user Config File: C:\Users\administrator\AppData\Roaming\Cisco\Unified  Communications\Client Services Framework\Config\userData.properties

2012-05-28 14:31:05,870 INFO  [main-0   ] [RegistryConfigProvider]  [RegistryConfigProviderImpl.setAdminRegistryKey(198)] - Setting Config  Registry key to : Software\Policies\Cisco Systems, Inc.\Client Services Framework\AdminData

2012-05-28 14:31:06,154 DEBUG [main-0   ] [ConfigService]  [ConfigServiceCoreImpl.getRegistryDataForSpecificTree(702)] - Did not  find config data for Registry Tree: Software\Policies\Cisco Systems, Inc.\Client Services Framework\AdminData

2012-05-28 14:31:06,156 DEBUG [main-0   ] [RegistryConfigProvider]  [RegistryConfigProviderImpl.retrieveNativeRegistryValues(85)] - Entering  retrieveNativeRegistryValues for Registry Tree Software\Cisco Systems,  Inc.\Client Services Framework\AdminData

! CUPC read tftp address from registry key

2012-05-28 14:31:06,271 INFO  [main-0   ] [ConfigService]  [TFTPConfigProviderImpl.onAdminConfigDataChange(643)] - TFTP primary  address 10.67.82.15

!  Before CUPC communicate with device switcher, it pre-selected the  default sound device emulated by Citrix VDA in HVD Win7 OS, but this is  un-desired in virtualisation environment as the audio will be transport  in display protocol.

2012-05-28 14:31:08,368 DEBUG [Thread-9 ] [AUDIO (C++)]  [JavaTracer.NativeTraceCallback(64)] - 04:31:08.367 [0d0c] MTDLL:  SetAudioInputDevice( Microphone (Citrix HDX Audio) ), ret = 0

!  It is ok for CUPC failed to fetch Directory Lookup rule and Application  Dial rule via TFTP because it will use the rule sets downloaded from  SOAP request to CUP server.

2012-05-28 14:31:08,864 ERROR [tftp-config-pool-thread-2]  [ConfigService] [TFTPConfigReader.getFile(213)] - Error retrieving TFTP  file [CUPC\DirLookupDialRules.xml] from [10.67.82.15]: Error code 1  received: File not found

2012-05-28 14:31:09,409 ERROR  [tftp-config-pool-thread-1] [ConfigService]  [TFTPConfigReader.getFile(213)] - Error retrieving TFTP file  [CUPC\AppDialRules.xml] from [10.67.82.15]: Error code 1 received: File  not found

! CSF  is started successfully and established socket connection with CUPC  application for SOAP call back (CSF is SOAP provider to CUPC and SOAP  client to CUP server)

2012-05-28 14:31:11,721 INFO  [main-0   ] [Controller]  [Controller.init(429)] - =======================  CSF STARTED  ==================================

2012-05-28  14:31:12,258 DEBUG [main-0   ] [Controller]  [Controller.publishOnCsfStartedMessage(575)] - Exiting  publishOnCsfStartedMessage. callBack URL: http://127.0.0.1:44442/v2/

! CSF read local registry key to find out the pre-configured CUP server address, or it will request user input for CUP address:

2012-05-28 14:31:13,502 DEBUG [ws-pool-thread-1] [ConfigService]  [ConfigServiceCoreImpl.readLocalValue(614)] - read local user data using  key=CUPServer returned value= 10.67.82.17

! CSF initiated SOAP login request to CUP server and read CUP config response

2012-05-28 14:31:16,115 DEBUG [ws-pool-thread-1] [CUPSoapProvider]  [CUPSoapProviderImpl.login(269)] - Entering connect. serverName:  10.67.82.17 majorVersion: 8.5.x, minorVersion: null

2012-05-28 14:31:16,142 DEBUG [ws-pool-thread-1] [CUPSoapProvider]  [CUPSoapProviderImpl.login(292)] - About to login  https://10.67.82.17/EPASSoap/service

2012-05-28  14:31:21,370 WARN  [ws-pool-thread-1] [Provider]  [AllCertX509TrustManager.checkServerTrusted(103)] - Failed to validate  certificate.   Accepting an untrusted certificate.   PKIX path building  failed: sun.security.provider.certpath.SunCertPathBuilderException:  unable to find valid certification path to requested target

2012-05-28 14:31:21,716 DEBUG [ws-pool-thread-1] [CUPSoapProvider]  [CUPSoapProviderImpl.login(329)] - Successfully logged in: abcdef

2012-05-28 14:31:31,028 DEBUG [ws-pool-thread-1] [CUPSoapProvider]  [ConfigDataManagerImpl.getCDSConfigDataFromResponse(510)] - Extracting  CDSConfifData from CUP Config response

! CSF is connected to XCP connection manager for IM and Presence service:

2012-05-28 14:31:31,261 DEBUG [ws-pool-thread-1]  [com.cisco.uc.core.provider.cup.connection.ConnectionManagerImpl]  [ConnectionManagerImpl.connect(118)] - Entering connect, serverAddress:  10.67.82.17, portNumber: 5222, username: abcdef, serverDomain:  vxi.cisco.com

! CSF is connected to LDAP (Microsoft AD) according to CUP configuration response for user search:

2012-05-28 14:31:50,766 DEBUG [ws-pool-thread-1] [ContactService]  [ContactServiceCoreImpl.initialize(1661)] - Initializing contact service  for Client CcClient-1

2012-05-28 14:31:50,769 DEBUG  [ws-pool-thread-1] [ContactLdapProvider]  [LDAPContactDAO.setCredentials(829)] - Setting LDAP credentials:  username='CN=Administrator,CN=Users,DC=vxi,DC=cisco,DC=com'

2012-05-28 14:31:51,179 DEBUG [ws-pool-thread-1] [ContactService]  [CapabilitiesManager.updateCapabilities(90)] - Updating Contact Service  Capabilities for USER_AUTHENTICATED_WITH_LDAP

2012-05-28 14:31:51,184 DEBUG [ws-pool-thread-1] [ContactService]  [CapabilitiesManager.updateCapabilities(90)] - Updating Contact Service  Capabilities for LDAP_AVAILABLE_FOR_SEARCHING

! CUPC remembers the previous device selection and device name from local user config data:

2012-05-28 14:31:53,382 DEBUG [ws-pool-thread-1] [ConfigService]  [ConfigServiceCoreImpl.readAllLocalValues(636)] - readAllLocalValues:  LocalUserConfigDataCore

   phone.mode=deskphone

   phone.VXCABCDEF.lineId=1000

   phone.deskphone.device=VXCABCDEF

2012-05-28 14:31:53,382 INFO  [ws-pool-thread-1] [DeviceService]  [DeviceServiceCoreImpl.processProperties(1405)] - Stored Deskphone  Device VXCabcdef

! CSF is connected to CCMCIP server and retrieve updated list of devices that associated with the user:

2012-05-28 14:31:53,444 DEBUG [ws-pool-thread-1] [DeviceProvider]  [DeviceProviderHttpUtils.doHttpRequest(170)] - URL is :  https://10.67.82.15:8443/ccmcip/Personalization

2012-05-28 14:31:58,318 DEBUG [ws-pool-thread-1] [DeviceService]  [DeviceServiceCoreImpl.populateDeviceList(2036)] - Devices in device  list are : [name=[VXCABCDEF] description=[

     ] model=[Cisco Virtualization Experience Client (VXC 6215)]

! CSF verified the device type of the retrieved device is a virtual device:

2012-05-28 14:31:58,321 DEBUG [ws-pool-thread-1] [DeviceService]  [DeviceServiceCoreImpl.isVirtualisationDevice(2272)] - Cisco  Virtualization Experience Client (VXC 6215) is a virtual device

! CSF initialise the cti library and verified with CM for the version check:

2012-05-28 14:31:59,245 INFO  [ReinitializeThread] [PhoneService]  [CtiLibLoader.doLoadVersionSpecificCtiLib(167)] - Sever jar jtapi.jar  8.6(1.10000) elease @ http://10.67.82.15:80/plugins/

2012-05-28 14:31:59,248 DEBUG [ReinitializeThread] [PhoneService]  [CtiComponentUpdaterImpl$ComponentImpl.compareTo(318)] - ComponentImpl:  Entering compareTo (jtapi.jar 8.6(1.10000) elease @  C:\Users\administrator\AppData\Local\Cisco\Unified Communications\Client  Services Framework\Jars\)

2012-05-28 14:31:59,249 INFO   [ReinitializeThread] [PhoneService]  [CtiLibLoader.doLoadVersionSpecificCtiLib(185)] - Sever and local jars  match.  Do not need to download jar from server.

! CSF is connected to CTIManager for phone control and downloaded phone configuration file from tftp:

2012-05-28 14:31:59,306 DEBUG [ReinitializeThread] [DeviceService]  [DeviceServiceCoreImpl.startProvider(349)] - Connecting with userName:  'abcdef', and CTI Gateway: 10.67.82.15 and CTI Gateway

Backup: null

2012-05-28 14:31:59,355 INFO  [ReinitializeThread] [DeskphoneProvider]  [PCProvider.<init>(94)] - Using Cisco Jtapi version 8.6(1.10000)-1  Release

2012-05-28 14:31:59,355 INFO  [ReinitializeThread]  [DeskphoneProvider] [PCProvider.init(102)] - Phone control provider  initializing...

2012-05-28 14:31:59,439 INFO   [ws-pool-thread-1] [ConfigService] [TFTPConfigReader.getFile(179)] -  Downloading VXC

ABCDEF

.cnf.xml, 10199 bytes received from server  10.67.82.15

2012-05-28 14:32:07,120 WARN   [PCProvider-null] [DeskphoneProvider]  [DeskphoneProviderImpl.inService(335)] - inService - 10.67.82.15

! vxcc device on VXC 6215 registered the callmanager and this event is notified to CUPC/CSF via CTIManager

2012-05-28 14:32:07,225 DEBUG  [ObserverThread(com.cisco.uc.core.provider.deskphone.phonecontrol.PCDevice@735a55)]  [DeskphoneProvider] [PCDevice.terminalChangedEvent(316)] - DEVICE EVENT  com.cisco.jtapi.CiscoTermInServiceEvImpl VXC

ABCDEF

; Cause: CAUSE_NORMAL

2012-05-28 14:32:07,271 DEBUG  [ObserverThread(com.cisco.uc.core.provider.deskphone.phonecontrol.PCLine@1125fac)]  [DeskphoneProvider] [PCLine.addressChangedEvent(1058)] - ADDRESS EVENT  com.cisco.jtapi.CiscoAddrInServiceEvImpl 1000; Cause: CAUSE_NORMAL

! CUPC gained control of device, and dial pad should be available for using

2012-05-28 14:32:07,275 DEBUG [PCLine-VXCABCDEF/1000] [PhoneService]  [PhoneServiceCoreImpl.onLineStatusUpdate(2674)] - Line Update, LineId =  1000, Phone number = 1000, partition = , Display Name = , deviceName =  VXCJEZHOU, lineLabel = null, buttonPosition = 1, messagesWaiting =  UNKNOWN, Capabilities[], messagesWaitingUNKNOWN, CallForwardDataState =  UNKNOWN, number = , contactId =

ABCEEF