Hello,
I am completely stumped out here. I configured PG2 in duplexed mode and i am unable to get ctisvr/JGW1/EAGPIM to go active. I have installed the JTAPI from the respective call manager for the JGW and i am able to see the phone associated to my pguser on the JGW1 logs. But the status still shows idle on both Side A and Side B..
22:12:08 Trace: EMT Creating Mutex Global\IMTConnect_DisconnectLock
22:12:08 Initializing Node Manager Library.
22:12:08 Initializing Event Management System (EMS) Library.
22:12:08 Trace: EMS Server pipe cca\PG2A\jgw1EMSPipe enabled for cca\PG2A\jgw1
22:12:08 Trace: Release 8.5.4.0 , Build 275
22:12:08 Trace: JGW arg 0, jgw1
22:12:08 Trace: JGW arg 1, CLEAN
22:12:08 Trace: JGW arg 2, DUPLEX
22:12:08 Trace: JGW arg 3, A
22:12:08 Trace: JGW arg 4, C:\icm\cca\pg2a
22:12:08 Trace: JGW arg 5, 2
22:12:08 Trace: JGW arg 6, ICM\cca\PG2A
22:12:08 Trace: starting java program main method...
EMSInitialize failed.
22:12:08 Trace: 0: 10.112.1.46: Jan 18 2016 22:12:08.936 -0800: %_Infrastructure-5-AUDIT_LOG_LEVEL: %[ss_log_level=INFO]: Log Level now set [id:9002]
22:12:08 Trace: 0: 10.112.1.46: Jan 18 2016 22:12:08.936 -0800: %_apache-5-AUDIT_LOG_LEVEL: %[ss_log_level=INFO]: Log Level now set [id:9002]
22:12:08 Trace: 1: 10.112.1.46: Jan 18 2016 22:12:08.936 -0800: %_apache-5-AUDIT_LOG_LEVEL: %[ss_log_level=WARN]: Log Level now set [id:9002]
22:12:08 Trace: 0: 10.112.1.46: Jan 18 2016 22:12:08.936 -0800: %_springframework-5-AUDIT_LOG_LEVEL: %[ss_log_level=INFO]: Log Level now set [id:9002]
22:12:08 Trace: 1: 10.112.1.46: Jan 18 2016 22:12:08.936 -0800: %_springframework-5-AUDIT_LOG_LEVEL: %[ss_log_level=WARN]: Log Level now set [id:9002]
22:12:08 Trace: 1: 10.112.1.46: Jan 18 2016 22:12:08.951 -0800: %_Infrastructure-6-AUDIT_LOG_LEVEL_MASK: %[svcmgr_log_level=WARN][svcmgr_name=org.apache][svcmgr_trace_mask=0]: ServiceabilityManager lo
g level and trace mask [id:9018]
22:12:08 Trace: 2: 10.112.1.46: Jan 18 2016 22:12:08.951 -0800: %_Infrastructure-6-AUDIT_LOG_LEVEL_MASK: %[svcmgr_log_level=INFO][svcmgr_name=Infrastructure][svcmgr_trace_mask=0]: ServiceabilityManage
r log level and trace mask [id:9018]
22:12:08 Trace: 3: 10.112.1.46: Jan 18 2016 22:12:08.951 -0800: %_Infrastructure-6-AUDIT_LOG_LEVEL_MASK: %[svcmgr_log_level=WARN][svcmgr_name=org.springframework][svcmgr_trace_mask=0]: ServiceabilityM
anager log level and trace mask [id:9018]
22:12:08 Trace: 4: 10.112.1.46: Jan 18 2016 22:12:08.951 -0800: %_Infrastructure-6-AUDIT_LOG_SIZE: %[log_limit_governor_name=null][max_dir_size=5000][max_file_size=50]: Maximum log file size and maxim
um logging directory size in MB [id:9017]
22:12:08 Trace: 5: 10.112.1.46: Jan 18 2016 22:12:08.951 -0800: %_Infrastructure-5-VERSION: %[build_date=Dec 31, 1969 3:59 PM][build_type=rel][product_name=Unknown product][version_number=Unknown prod
uct_-1_-1_-1_-1_-1_-1_-1]: Product version information. [id:9058]
22:12:08 Trace: 6: 10.112.1.46: Jan 18 2016 22:12:08.951 -0800: %_Infrastructure-6-AUDIT_LOG_CONFIG_FILE: %[file_name=C:\icm\conf\log4j.xml]: Logging configuration file [id:9003]
22:12:08 Trace: 7: 10.112.1.46: Jan 18 2016 22:12:08.967 -0800: %_Infrastructure-6-THREAD_POOL_MANAGER_SET_MAX_THREADS: %[id=JTAPIGW][thread_count=500][thread_pool_weight=5.0]: Set ThreadPoolManager's
max number of allowed threads based on its weight relative to other manage
22:12:08 Trace: Monitor Server pipe cca\PG2A\jgw1CmdPipe enabled for cca\PG2A\jgw1
22:12:08 Trace: JVM Total Memory: 32440320 JVM Free Memory: 31092680 JVM Heap in Use: 1347640 JVM has 95.84579% free memory
22:12:08 Trace: Configuring JTAPI Object
22:12:08 Trace: Calling getJtapiPeer for peer: com.cisco.jtapi.CiscoJtapiPeerImpl
22:12:09 Trace: [pool-1-thread-1-JTAPIGW-1 - ThreadAddressManager]ThreadAddressManager starts
22:12:09 Trace: ThreadAddressManager::Waiting for next retry
22:12:09 Trace: getJtapiPeer returned successfully. JTapiPeer class name: com.cisco.jtapi.CiscoJtapiPeerImpl
22:12:09 Trace: JTapiObj:initializeJTapi: Values for UseProgressAsDisconnectedCauses are: 0
22:12:09 Trace: CiscoJtapiVersion: Cisco Jtapi version 9.1(1.10000)-4 Release
22:12:09 Trace: BuildDescription: Release BuildNumber: 10000 RevisionNumber: 1 MajorVersion: 9 MinorVersion: 1 ExtendedBuildNumber: 4
22:12:09 Trace: Not using CiscoSynchronousObserver interface.
22:12:09 Trace: Initializing JTAPI TraceManager.
22:12:09 Trace: disableAll() TraceManager for CTICLIENT
22:12:09 Trace: Calling getProvider() 10.111.11.11;login=pguser;passwd=<***edited***>
22:12:10 Trace: Returned successfully from getProvider()
22:12:10 Trace: disableAll() TraceManager for CTICLIENT
22:12:10 Trace: Waiting for the provider to be in service
22:12:10 Trace: ProvOutOfServiceEv
22:12:10 Trace: ProvInServiceEv
22:12:10 Trace: Provider is in service
22:12:10 Trace: Creating Instruments for 2 addresses
22:12:10 Trace: JTapiServer: Wait for adding CallObservers to 2 addresses
22:12:10 Trace: ThreadAddressManager: Adding AddressObservers to 2 addresses
22:12:10 Trace: CiscoAddrOutOfServiceEv Addr: 3809 Cause: NORMAL
22:12:10 Trace: Call to address.addObserver for 3809 returned in 16 milsecs
22:12:10 Trace: CiscoAddrInServiceEv Addr: 3809 Cause: NORMAL
22:12:10 Trace: CiscoAddrOutOfServiceEv Addr: 1566 Cause: NORMAL
22:12:10 Trace: Call to address.addObserver for 1566 returned in 0 milsecs
22:12:10 Trace: [pool-1-thread-1-JTAPIGW-1 - ThreadAddressManager]ThreadAddressManager ends adding observers after 16 milsecs
22:12:10 Trace: ThreadAddressManager::processNextQueuedMsg: msgHashtable.size = 0
22:12:10 Trace: ThreadAddressManager::Waiting for next retry
22:12:10 Trace: CiscoAddrInServiceEv Addr: 1566 Cause: NORMAL
22:12:10 Trace: JTapiServer: Complete waiting for adding CallObservers to 2 addresses
22:12:10 Trace: Successfully configured JTAPI Object.
22:12:10 Trace: Creating server socket on port 40029 to listen for PIM connections.
22:22:08 Trace: JVM Total Memory: 32636928 JVM Free Memory: 31016024 JVM Heap in Use: 1620904 JVM has 95.03352% free memory
CTISvr is the same it shows Idle and i have verified the IP communication both for the private and visible network. I am able to ping between both networks. My Windows firewall is off on the Windows machine so i know nothing should be blocking it.
CTISvr event:
22:12:27 Trace: EMT Creating Mutex Global\IMTConnect_DisconnectLock
22:12:27 Trace: Main: argc = 8
22:12:27 Trace: inside main
22:12:27 Trace: Main: argv[0] = CTISrvr
22:12:27 Trace: Main: argv[1] = ctisvr
22:12:27 Trace: Main: argv[2] = CLEAN
22:12:27 Trace: Main: argv[3] = DUPLEX
22:12:27 Trace: Main: argv[4] = A
22:12:27 Trace: Main: argv[5] = C:\icm\cca\cg2a
22:12:27 Trace: Main: argv[6] = 2
22:12:27 Trace: Main: argv[7] = ICM\cca\CG2A
22:12:27 Trace: CTIServerMain::Execute
22:12:27 Initializing Event Management System (EMS) Library.
22:12:27 Trace: EMS Server pipe cca\CG2A\ctisvrEMSPipe enabled for cca\CG2A\ctisvr
22:12:27 Trace: Monitor Server pipe cca\CG2A\ctisvrCmdPipe enabled for cca\CG2A\ctisvr
22:12:27 Initializing Node Manager Library.
22:12:27 CTIServer Release 8.5.4.0 , Build 275, Built 09/05/12 09:57:10
22:12:27 Trace: Perfmon Initialization for the instanceName = cca CG2A
22:12:27 Trace: Initialize of Perfmon manager successful
22:12:27 Trace: EMT I/O completion ports: max threads=2, concurent threads=0
22:12:27 Trace: MBufLimitMgr OpenMutex Global\MBUF Limit Table Mutex
22:12:27 Connection to MDS process established.
22:12:38 MDS now in service.
22:12:42 ProcessSetIdleReq: Enterprise CTI Server going idle.
22:12:42 PG System Event: IdleCTIServer, PG Status: PERIPHERAL(S) OFFLINE
22:12:42 Enterprise CTI Server sending OPC CTI_OK_ACK acknowledgment for command CTI_SET_IDLE_REQ (TransID=1).
22:12:42 ICM Central Controller time adjusted by 0 second(s) to 06:12:42 UTC.
22:12:45 Trace: Ignoring idle request for other side=0
My EAGPIM also shows IDLE while i have everything associated to this all configured. I have ran through setup over and over again and i ensured that my Logical controller ID (5001) and Peripheral ID (5003) are both the same on the ICM side.
22:12:09 Trace: EMT Creating Mutex Global\IMTConnect_DisconnectLock
22:12:09 Trace: PimConfig::ConfigLock: ChkDdLck=1 TrkLck=1 Grp=0 Ord=0 MxHldTcks=3000
22:12:09 Trace: PimConfig::CallLock: ChkDdLck=0 TrkLck=0 Grp=0 Ord=10 MxHldTcks=100
22:12:09 Initializing Event Management System (EMS) Library.
22:12:09 Trace: EMS Server pipe cca\PG2A\pim1EMSPipe enabled for cca\PG2A\pim1
22:12:09 Trace: Monitor Server pipe cca\PG2A\pim1CmdPipe enabled for cca\PG2A\pim1
22:12:09 Initializing Node Manager Library.
22:12:09 EAGTPIM Release 8.5.4.0 , Build 275
22:12:09 Failed to read AutoCfgErrorFileMaxBytes value in the SOFTWARE\CISCO SYSTEMS, INC.\ICM\cca\PG2A\PG\CurrentVersion\PIMS\pim1\EAgentData\Dynamic key in the registry.
22:12:09 Failed to read AutoCfgErrorFileName value in the SOFTWARE\CISCO SYSTEMS, INC.\ICM\cca\PG2A\PG\CurrentVersion\PIMS\pim1\EAgentData\Dynamic key in the registry.
22:12:09 Trace: PimConfig::TPServices: ChkDdLck=1 TrkLck=1 Grp=0 Ord=10 MxHldTcks=3000
22:12:09 Trace: DeskLinkDeviceTarget::SetNumExtensionDigits: Number of extension digits set to 4
22:12:09 Trace: Perfmon Initialization for the instanceName = cca PG2A pim1
22:12:09 Trace: Entering init()
22:12:09 Trace: Resetting Permanent Counters
22:12:09 Trace: EAPIMMetrics::init(), Permanent metric counter object was successfully created.
22:12:09 Trace: Resetting Optional Counters
22:12:09 Trace: EAPIMMetrics::init(), Optional metric counter object was successfully created.
22:12:09 Trace: EAPIMMetrics::init(), EAPIM Metric subsystem initialized for permanent metrics and optional metrics.
22:12:09 Trace: EMT I/O completion ports: max threads=2, concurent threads=0
22:12:09 Trace: MBufLimitMgr OpenMutex Global\MBUF Limit Table Mutex
22:12:09 Connection to MDS process established.
22:12:38 MDS now in service.
my MDSproc and PGAgent are both Active and duplexed. On my Router, i am able to see that PG2 on the CCAgent but when i run a RTTest, i don't see the PG2 showing on the controller or peripheral. I have also ran Websetup on the router to ensure the PG is 1-2 (PG1 and PG2).
I am completely stomped and don't know what else to look at.
