UCCE 10.5: MR PG can not connect to SIP Dialer

[Ion Cristian Radescu]

Hello,

I have a strange problem with a SIP Dialer installation.

The MR PIM can not connect to SIP Dialer with the following error:

14:32:34:621 pg2a-pim1 Attempting to connect to MR application at IP address: 172.19.10.31, port: 38001.
14:32:34:621 pg2a-pim1 Trace: Transport: Making connection attempt to host1: 172.19.10.31 port: 38001.
14:32:35:713 pg2a-pim1 Trace: Transport: Connection to host1 attempt failed; error 10061..
14:32:35:713 pg2a-pim1 Trace: Transport: The attempt to connect was rejected..
14:32:35:713 pg2a-pim1 Trace: Transport: Make sure that the MR-PIM setup has the correct MR application hostname and port number..
14:32:35:713 pg2a-pim1 Trace: Transport: Make sure that the application is listening for connection at the correct port number..
14:32:35:713 pg2a-pim1 Trace: Transport: Connection closed..

I configured two duplexed SIP Dialers without Port Map. In baDialer logs I can see it can connect to Campaign Manager, CTI Servers, but it received "Port Map With ZERO Ports". Judging by what is written in SRND, this behavior is normal.

14:38:51:052 dialer-baDialer Trace: DialerMetrics::reset(), Dialer Metric subsystem was reset..
14:38:51:350 dialer-baDialer Initializing Event Management System (EMS) library.
14:38:51:350 dialer-baDialer EMS library initialized with write thread.
14:38:51:350 dialer-baDialer Trace: EMS Server pipe cce\Dialer\baDialerEMSPipe enabled for cce\Dialer\baDialer.
14:38:51:350 dialer-baDialer Trace: Release 10.5.1.0 , Build 2572.
14:38:51:350 dialer-baDialer Trace: Received dialer startup request..
14:38:51:350 dialer-baDialer Trace: Monitor Server pipe cce\Dialer\baDialerCmdPipe enabled for cce\Dialer\baDialer.
14:38:51:351 dialer-baDialer Trace: Dialer starting...ICM\cce\Dialer.
14:38:51:351 dialer-baDialer Trace: Using registry key: Software\Cisco Systems, Inc.\ICM\cce\Dialer.
14:38:51:351 dialer-baDialer Trace: Using registry key: Software\Cisco Systems, Inc.\ICM\cce\CurrentVersion.
14:38:51:354 dialer-baDialer Trace: EMT I/O completion ports: max threads=4, concurent threads=0.
14:38:51:355 dialer-baDialer Trace: Current Directory (C:\icm\cce\dialer).
14:38:51:355 dialer-baDialer Trace: Size of call result mapping table: 46.
14:38:51:355 dialer-baDialer Trace: DialerMetrics::Instance, Dialer Manager Creating New instance..
14:38:51:355 dialer-baDialer Trace: The performance monitor object instance (cce) was created successfully..
14:38:51:359 dialer-baDialer Trace: Attempting EMT connection to computer [172.19.10.30/172.19.10.30], port [40032].
14:38:51:611 dialer-baDialer Trace:     EMT connection established.
14:38:52:368 dialer-baDialer Trace: Registering with Campaign Manager.
14:38:52:370 dialer-baDialer Trace: Config received: [1] dialer PortThrottle:15.000000.
14:38:52:370 dialer-baDialer Trace: Trying to connect to server: 172.19.10.31 on port: 42027.
14:38:52:370 dialer-baDialer Trace: socket open on Server=172.19.10.31 port=42027.
14:38:52:370 dialer-baDialer Trace: (CTIPROXY)    Sending CTI OpenRequest for this Dialer.
14:38:52:372 dialer-baDialer Trace: CallListener creating mutex - Global\CCallListener8536.
14:38:52:372 dialer-baDialer Trace: (PM) Update Port Throttle. lPortThrottle=15.000000, dwPortThrottleCount=30, dwPortThrottleTime=2.
14:38:52:372 dialer-baDialer Trace: Received Port Map With ZERO Ports.
14:38:52:372 dialer-baDialer Trace: Received Port Map With ZERO Ports.
14:38:52:372 dialer-baDialer Trace: Received Port Map With ZERO Ports.
14:38:52:372 dialer-baDialer Trace: Received Port Map With ZERO Ports.
14:38:52:372 dialer-baDialer Trace: (CLMGR)    CTI Client Event Report Request, invoke: 1, clients: [1].
14:38:53:382 dialer-baDialer Trace: Dailer Status Change,Old Status:cce-Dialer BADialer_SIP -X  [CM-X]  [CTI-U]  [Ports-X]  [MR-X]  [SIP-U] 0x0.
14:38:53:382 dialer-baDialer Trace: Dailer Status Change,New Status:cce-Dialer BADialer_SIP -X  [CM-A]  [CTI-A]  [Ports C:0,R:0,B:0]  [MR-X]  [SIP-U] 0x3.
14:38:53:382 dialer-baDialer Trace: (DD)    **** Media Routing PIM is not Connected ****.
14:38:53:382 dialer-baDialer Trace: (DD)    **** All SoftPhone Channels not Initialized ****.
14:38:53:382 dialer-baDialer Trace: (DD)    **** Configured SoftPhone Channels: [0], Initialized: [0] ****.
14:38:54:396 dialer-baDialer Trace: (DD)    **** Media Routing PIM is not Connected ****.
14:38:54:396 dialer-baDialer Trace: (DD)    **** All SoftPhone Channels not Initialized ****.
14:38:54:396 dialer-baDialer Trace: (DD)    **** Configured SoftPhone Channels: [0], Initialized: [0] ****.
14:38:55:410 dialer-baDialer Trace: (DD)    **** Media Routing PIM is not Connected ****.
14:38:55:410 dialer-baDialer Trace: (DD)    **** All SoftPhone Channels not Initialized ****.

I double checked the IP Addresses, MR port(38001), network interfaces order in Windows and everything looks ok.

A possible problem could be that I see no application listening on port 38001 with netstat -na.

Does anyone have any idea what else should I check?

Many thanks in advance,

Cristian

[Gergely Szabo]

M'kay, can you also post the baDialer logs? Thanks. G.

[Ion Cristian Radescu]

8:07:16:260 dialer-baDialer Trace: Received dialer shutdown request..
18:07:16:261 dialer-baDialer Trace: (CM)    Release records from all skill groups.
18:07:16:261 dialer-baDialer Trace: PortManager: Clearing all ports.
18:07:16:266 dialer-baDialer Trace: (SipServer) Stop called before Start!.
18:07:16:266 dialer-baDialer Trace: DialerMetrics::reset(), Dialer Metric subsystem was reset..
18:07:16:583 dialer-baDialer Initializing Event Management System (EMS) library.
18:07:16:583 dialer-baDialer EMS library initialized with write thread.
18:07:16:583 dialer-baDialer Trace: EMS Server pipe cce\Dialer\baDialerEMSPipe enabled for cce\Dialer\baDialer.
18:07:16:583 dialer-baDialer Trace: Release 10.5.1.0 , Build 2572.
18:07:16:583 dialer-baDialer Trace: Received dialer startup request..
18:07:16:583 dialer-baDialer Trace: Monitor Server pipe cce\Dialer\baDialerCmdPipe enabled for cce\Dialer\baDialer.
18:07:16:585 dialer-baDialer Trace: Dialer starting...ICM\cce\Dialer.
18:07:16:585 dialer-baDialer Trace: Using registry key: Software\Cisco Systems, Inc.\ICM\cce\Dialer.
18:07:16:585 dialer-baDialer Trace: Using registry key: Software\Cisco Systems, Inc.\ICM\cce\CurrentVersion.
18:07:16:590 dialer-baDialer Trace: EMT I/O completion ports: max threads=4, concurent threads=0.
18:07:16:592 dialer-baDialer Trace: Current Directory (C:\icm\cce\dialer).
18:07:16:592 dialer-baDialer Trace: Size of call result mapping table: 46.
18:07:16:593 dialer-baDialer Trace: DialerMetrics::Instance, Dialer Manager Creating New instance..
18:07:16:593 dialer-baDialer Trace: The performance monitor object instance (cce) was created successfully..
18:07:16:601 dialer-baDialer Trace: Attempting EMT connection to computer [172.19.10.30/172.19.10.30], port [40032].
18:07:16:855 dialer-baDialer Trace:     EMT connection established.
18:07:17:607 dialer-baDialer Trace: Registering with Campaign Manager.
18:07:17:609 dialer-baDialer Trace: Config received: [1] dialer PortThrottle:15.000000.
18:07:17:609 dialer-baDialer Trace: Trying to connect to server: 172.19.10.31 on port: 42027.
18:07:17:609 dialer-baDialer Trace: socket open on Server=172.19.10.31 port=42027.
18:07:17:609 dialer-baDialer Trace: (CTIPROXY)    Sending CTI OpenRequest for this Dialer.
18:07:17:611 dialer-baDialer Trace: CallListener creating mutex - Global\CCallListener8652.
18:07:17:617 dialer-baDialer Trace: (PM) Update Port Throttle. lPortThrottle=15.000000, dwPortThrottleCount=30, dwPortThrottleTime=2.
18:07:17:617 dialer-baDialer Trace: Received Port Map With ZERO Ports.
18:07:17:617 dialer-baDialer Trace: Received Port Map With ZERO Ports.
18:07:17:617 dialer-baDialer Trace: Received Port Map With ZERO Ports.
18:07:17:617 dialer-baDialer Trace: Received Port Map With ZERO Ports.
18:07:17:617 dialer-baDialer Trace: (CLMGR)    CTI Client Event Report Request, invoke: 1, clients: [1].
18:07:18:621 dialer-baDialer Trace: Dailer Status Change,Old Status:cce-Dialer BADialer_SIP -X  [CM-X]  [CTI-U]  [Ports-X]  [MR-X]  [SIP-U] 0x0.
18:07:18:621 dialer-baDialer Trace: Dailer Status Change,New Status:cce-Dialer BADialer_SIP -X  [CM-A]  [CTI-A]  [Ports C:0,R:0,B:0]  [MR-X]  [SIP-U] 0x3.
18:07:18:621 dialer-baDialer Trace: (DD)    **** Media Routing PIM is not Connected ****.
18:07:18:621 dialer-baDialer Trace: (DD)    **** All SoftPhone Channels not Initialized ****.
18:07:18:621 dialer-baDialer Trace: (DD)    **** Configured SoftPhone Channels: [0], Initialized: [0] ****.
18:07:19:634 dialer-baDialer Trace: (DD)    **** Media Routing PIM is not Connected ****.
18:07:19:634 dialer-baDialer Trace: (DD)    **** All SoftPhone Channels not Initialized ****.
18:07:19:634 dialer-baDialer Trace: (DD)    **** Configured SoftPhone Channels: [0], Initialized: [0] ****.
18:07:20:648 dialer-baDialer Trace: (DD)    **** Media Routing PIM is not Connected ****.
18:07:20:648 dialer-baDialer Trace: (DD)    **** All SoftPhone Channels not Initialized ****.
18:07:20:648 dialer-baDialer Trace: (DD)    **** Configured SoftPhone Channels: [0], Initialized: [0] ****.
18:07:21:662 dialer-baDialer Trace: (DD)    **** Media Routing PIM is not Connected ****.

[dchumbley]

I'm running into the same issue as Ion.  My dialer has ports configured though.  The pim log shows this:

15:38:53:284 PG4A-pim1 Trace: MR_Dialogue Init Complete
15:38:53:284 PG4A-pim1 Trace: EMT I/O completion ports: max threads=4, concurent threads=0
15:38:53:299 PG4A-pim1 Trace: MBufLimitMgr OpenMutex Global\MBUF Limit Table Mutex
15:38:53:721 PG4A-pim1 Connection to MDS process established.
15:39:23:381 PG4A-pim1 MDS now in service.
15:39:34:384 PG4A-pim1 ProcessPIMSetIdleReq: Peripheral 5005 going idle.
15:39:34:384 PG4A-pim1 Peripheral 5005 sending OPC PIM_OK_ACK acknowledgment for command PIM_SET_IDLE_REQ (TransID=1).
15:39:34:384 PG4A-pim1 ADDED\UPDATED 32 AgentDeskSettings on Peripheral 5005.
15:39:34:384 PG4A-pim1 ADDED\UPDATED 32 AgentDeskSettings on Peripheral 5005.
15:39:34:384 PG4A-pim1 ADDED\UPDATED 32 AgentDeskSettings on Peripheral 5005.
15:39:34:384 PG4A-pim1 ADDED\UPDATED 32 AgentDeskSettings on Peripheral 5005.
15:39:34:384 PG4A-pim1 ADDED\UPDATED 32 AgentDeskSettings on Peripheral 5005.
15:39:34:384 PG4A-pim1 ADDED\UPDATED 32 AgentDeskSettings on Peripheral 5005.
15:39:34:384 PG4A-pim1 ADDED\UPDATED 32 AgentDeskSettings on Peripheral 5005.
15:39:34:384 PG4A-pim1 ADDED\UPDATED 11 AgentDeskSettings on Peripheral 5005.
15:39:34:384 PG4A-pim1 ADDED\UPDATED 0 DeviceTargets on Peripheral 5005.
15:39:34:384 PG4A-pim1 ADDED\UPDATED 45 ECCVariables on Peripheral 5005.
15:39:34:384 PG4A-pim1 ADDED\UPDATED 3 AgentGroups on Peripheral 5005.
15:39:34:384 PG4A-pim1 ADDED\UPDATED 0 Agents on Peripheral 5005.
15:39:34:384 PG4A-pim1 ADDED\UPDATED 0 AgentTeams on Peripheral 5005.
15:39:34:384 PG4A-pim1 ADDED\UPDATED 0 AgentTeamMembers on Peripheral 5005.
15:39:34:384 PG4A-pim1 ADDED\UPDATED 0 AgentTeamSupervisorMembers on Peripheral 5005.
15:39:34:384 PG4A-pim1 ADDED\UPDATED 0 Services on Peripheral 5005.
15:39:34:384 PG4A-pim1 ADDED\UPDATED 0 TrunkGroups on Peripheral 5005.
15:39:34:384 PG4A-pim1 ADDED\UPDATED 0 Trunks on Peripheral 5005.
15:39:34:384 PG4A-pim1 ADDED\UPDATED 0 DialedNumbers on Peripheral 5005.
15:39:34:384 PG4A-pim1 ADDED\UPDATED 0 DialedNumberPlans on Peripheral 5005.
15:39:34:384 PG4A-pim1 ADDED\UPDATED 0 PeripheralTargets on Peripheral 5005.
15:39:34:384 PG4A-pim1 ADDED\UPDATED 0 PeripheralMonitors on Peripheral 5005.
15:39:34:384 PG4A-pim1 ADDED\UPDATED 4 MediaRoutingDomain on Peripheral 5005.
15:39:34:384 PG4A-pim1 Trace: Transport: appServerHostName1 = 10.157.1.57
15:39:34:384 PG4A-pim1 Trace: Transport: appTcpServiceName1 = 38001
15:39:34:384 PG4A-pim1 Trace: Transport: appServerHostName2 =  
15:39:34:384 PG4A-pim1 Trace: Transport: appTcpServiceName2 =  
15:39:34:384 PG4A-pim1 Peripheral 5005 sending OPC PIM_OK_ACK acknowledgment for command PIM_CONFIGURE_REQ (TransID=0).
15:39:34:384 PG4A-pim1 Attempting to connect to MR application at IP address 10.157.1.57 port 38001.
15:39:34:384 PG4A-pim1 Trace: Transport: Making connection attempt to host1: 10.157.1.57 port: 38001
15:39:35:401 PG4A-pim1 Trace: Transport: Connection to host1 attempt failed; error 10061.
15:39:35:401 PG4A-pim1 Trace: Transport: The attempt to connect was rejected.
15:39:35:401 PG4A-pim1 Trace: Transport: Make sure that the MR-PIM setup has the correct MR application hostname and port number.
15:39:35:401 PG4A-pim1 Trace: Transport: Make sure that the application is listening for connection at the correct port number.
15:39:35:401 PG4A-pim1 Trace: Transport: Connection closed.
15:39:45:406 PG4A-pim1 Attempting to connect to MR application at IP address 10.157.1.57 port 38001.
15:39:45:406 PG4A-pim1 Trace: Transport: Making connection attempt to host1: 10.157.1.57 port: 38001
15:39:46:420 PG4A-pim1 Trace: Transport: Connection to host1 attempt failed; error 10061.
15:39:46:420 PG4A-pim1 Trace: Transport: The attempt to connect was rejected.
15:39:46:420 PG4A-pim1 Trace: Transport: Make sure that the MR-PIM setup has the correct MR application hostname and port number.
15:39:46:420 PG4A-pim1 Trace: Transport: Make sure that the application is listening for connection at the correct port number.
15:39:46:420 PG4A-pim1 Trace: Transport: Connection closed.
15:39:56:435 PG4A-pim1 Attempting to connect to MR application at IP address 10.157.1.57 port 38001.
15:39:56:435 PG4A-pim1 Trace: Transport: Making connection attempt to host1: 10.157.1.57 port: 38001
15:39:57:449 PG4A-pim1 Trace: Transport: Connection to host1 attempt failed; error 10061.
15:39:57:449 PG4A-pim1 Trace: Transport: The attempt to connect was rejected.
15:39:57:449 PG4A-pim1 Trace: Transport: Make sure that the MR-PIM setup has the correct MR application hostname and port number.
15:39:57:449 PG4A-pim1 Trace: Transport: Make sure that the application is listening for connection at the correct port number.
15:39:57:449 PG4A-pim1 Trace: Transport: Connection closed.
15:40:05:380 PG4A-pim1 ProcessPIMSetIdleReq: Peripheral 5005 going idle.
15:40:07:455 PG4A-pim1 Trace: Transport: Connection closed.
15:40:08:422 PG4A-pim1 Peripheral 5005 sending OPC PIM_OK_ACK acknowledgment for command PIM_SET_IDLE_REQ (TransID=2).

The dialer log shows this:

15:39:11:254 dialer-baDialer Initializing Event Management System (EMS) Library.
15:39:11:254 dialer-baDialer EMS library initialized with Write Thread.
15:39:11:254 dialer-baDialer Trace: EMS Server pipe fhs\Dialer\baDialerEMSPipe enabled for fhs\Dialer\baDialer
15:39:11:254 dialer-baDialer Trace: Release 10.0.1.0 , Build 1662
15:39:11:254 dialer-baDialer Trace: Received dialer startup request.
15:39:11:254 dialer-baDialer Trace: Monitor Server pipe fhs\Dialer\baDialerCmdPipe enabled for fhs\Dialer\baDialer
15:39:11:254 dialer-baDialer Trace: Dialer starting...ICM\fhs\Dialer
15:39:11:254 dialer-baDialer Trace: Using registry key: Software\Cisco Systems, Inc.\ICM\fhs\Dialer
15:39:11:254 dialer-baDialer Trace: Using registry key: Software\Cisco Systems, Inc.\ICM\fhs\CurrentVersion
15:39:11:254 dialer-baDialer Trace: EMT I/O completion ports: max threads=4, concurent threads=0
15:39:11:254 dialer-baDialer Trace: Current Directory (C:\icm\fhs\dialer)
15:39:11:254 dialer-baDialer Trace: Size of call result mapping table: 46
15:39:11:254 dialer-baDialer Trace: DialerMetrics::Instance, Dialer Manager Creating New instance.
15:39:11:254 dialer-baDialer Trace: The performance monitor object instance (fhs) was created successfully.
15:39:11:254 dialer-baDialer Trace: Attempting EMT connection to computer [10.157.1.49/10.157.1.49], port [40032]
15:39:11:519 dialer-baDialer Trace:     EMT connection established
15:39:12:268 dialer-baDialer Trace: Registering with Campaign Manager
15:39:12:283 dialer-baDialer Trace: Config received: [1] dialer PortThrottle:15.000000
15:39:12:283 dialer-baDialer Trace: Trying to connect to server: 10.157.1.57 on port: 44027
15:39:12:283 dialer-baDialer Trace: socket open on Server=10.157.1.57 port=44027
15:39:12:283 dialer-baDialer Trace: (CTIPROXY)    Sending CTI OpenRequest for this Dialer
15:39:12:283 dialer-baDialer Trace: CallListener creating mutex - Global\CCallListener4016
15:39:12:283 dialer-baDialer Trace: (PM) Update Port Throttle. lPortThrottle=15.000000, dwPortThrottleCount=30, dwPortThrottleTime=2
15:39:12:283 dialer-baDialer Trace: Received Port Map With 30 Ports
15:39:12:283 dialer-baDialer Trace: Received Port Map With ZERO Ports
15:39:12:283 dialer-baDialer Trace: Received portmap configuration; DialerID: [5000], Number of Ports [30]
15:39:12:283 dialer-baDialer Trace: Configured Port Numbers [0;1;2;3;4;5;6;7;8;9;10;11;12;13;14;15;16;17;18;19;20;21;22;23;24;25;26;27;28;29;]
15:39:12:283 dialer-baDialer Trace: CSipUAReader::Initialize, m_SIPServerTransportType=5060 SIPServerTransportType=1 SIPServerType=0
15:39:12:283 dialer-baDialer Trace: (SipServer) start the call throttle timer, m_fPortThrottle=15.000000 m_dwTickTimeout=66
15:39:12:283 dialer-baDialer Trace: (SipServer)1
15:39:12:283 dialer-baDialer Trace: Start to load the local static route file.
15:39:12:283 dialer-baDialer Trace: Loading the local static route file ends.
15:39:12:283 dialer-baDialer Trace: The local static route file has no valid entry.
15:39:12:283 dialer-baDialer Missing\incorrect local Static Route File is detected on [TCOM-PG3A] in the directory, ..\icm\[fhs]\Dialer.].
15:39:12:283 dialer-baDialer Trace: m_SIPServerHBenabled -1 lVal 1
15:39:12:283 dialer-baDialer Trace: m_SIPServerHBenabled 1 lVal 1 starttimer
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (PM)    Init port: 000, station: 6121402900
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (PM)    Init port: 001, station: 6121402901
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (PM)    Init port: 002, station: 6121402902
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (PM)    Init port: 003, station: 6121402903
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (PM)    Init port: 004, station: 6121402904
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (PM)    Init port: 005, station: 6121402905
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (PM)    Init port: 006, station: 6121402906
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (PM)    Init port: 007, station: 6121402907
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (PM)    Init port: 008, station: 6121402908
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (PM)    Init port: 009, station: 6121402909
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (PM)    Init port: 010, station: 6121402910
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (PM)    Init port: 011, station: 6121402911
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (PM)    Init port: 012, station: 6121402912
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (PM)    Init port: 013, station: 6121402913
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (PM)    Init port: 014, station: 6121402914
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (PM)    Init port: 015, station: 6121402915
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (PM)    Init port: 016, station: 6121402916
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (PM)    Init port: 017, station: 6121402917
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (PM)    Init port: 018, station: 6121402918
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (PM)    Init port: 019, station: 6121402919
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (PM)    Init port: 020, station: 6121402920
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (PM)    Init port: 021, station: 6121402921
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (PM)    Init port: 022, station: 6121402922
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (PM)    Init port: 023, station: 6121402923
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (PM)    Init port: 024, station: 6121402924
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (PM)    Init port: 025, station: 6121402925
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (PM)    Init port: 026, station: 6121402926
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (PM)    Init port: 027, station: 6121402927
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (PM)    Init port: 028, station: 6121402928
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (PM)    Init port: 029, station: 6121402929
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 004, state: IDLE
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (CPORT)    SetState, port: 000, state: IDLE
15:39:12:283 dialer-baDialer Trace: (CLMGR)    CTI Client Event Report Request, invoke: 1, clients: [1]
15:39:13:282 dialer-baDialer Trace: Dailer Status Change,Old Status:fhs-Dialer BADialer_SIP -X  [CM-X]  [CTI-U]  [Ports-X]  [MR-X]  [SIP-U] 0x0
15:39:13:282 dialer-baDialer Trace: Dailer Status Change,New Status:fhs-Dialer BADialer_SIP -X  [CM-A]  [CTI-A]  [Ports C:30,R:0,B:0]  [MR-X]  [SIP-U] 0x3
15:39:13:282 dialer-baDialer Trace: (PMC)    PortMap Self-Check Initialized; [30] ports configured
15:39:13:282 dialer-baDialer Trace: (DD)    **** Media Routing PIM is not Connected ****
15:39:13:282 dialer-baDialer Trace: (DD)    **** All SoftPhone Channels not Initialized ****
15:39:13:282 dialer-baDialer Trace: (DD)    **** Configured SoftPhone Channels: [30], Initialized: [0] ****
15:39:13:313 dialer-baDialer Trace: CSipUATelephonyServer::HandleKeepAliveTimer
15:39:13:843 dialer-baDialer Trace: CSipUATelephonyServer::HandleKeepAliveTimeoutTimer: Missing HB ACK 0
15:39:14:296 dialer-baDialer Trace: (DD)    **** Media Routing PIM is not Connected ****
15:39:14:296 dialer-baDialer Trace: (DD)    **** All SoftPhone Channels not Initialized ****
15:39:14:296 dialer-baDialer Trace: (DD)    **** Configured SoftPhone Channels: [30], Initialized: [0] ****
15:39:15:312 dialer-baDialer Trace: (DD)    **** Media Routing PIM is not Connected ****
15:39:15:312 dialer-baDialer Trace: (DD)    **** All SoftPhone Channels not Initialized ****
15:39:15:312 dialer-baDialer Trace: (DD)    **** Configured SoftPhone Channels: [30], Initialized: [0] ****
15:39:16:326 dialer-baDialer Trace: (DD)    **** Media Routing PIM is not Connected ****
15:39:16:326 dialer-baDialer Trace: (DD)    **** All SoftPhone Channels not Initialized ****
15:39:16:326 dialer-baDialer Trace: (DD)    **** Configured SoftPhone Channels: [30], Initialized: [0] ****
15:39:17:341 dialer-baDialer Trace: (DD)    **** Media Routing PIM is not Connected ****
15:39:17:341 dialer-baDialer Trace: (DD)    **** All SoftPhone Channels not Initialized ****
15:39:17:341 dialer-baDialer Trace: (DD)    **** Configured SoftPhone Channels: [30], Initialized: [0] ****
15:39:18:355 dialer-baDialer Trace: (DD)    **** Media Routing PIM is not Connected ****
15:39:18:355 dialer-baDialer Trace: (DD)    **** All SoftPhone Channels not Initialized ****
15:39:18:355 dialer-baDialer Trace: (DD)    **** Configured SoftPhone Channels: [30], Initialized: [0] ****
15:39:18:355 dialer-baDialer Trace: (FAILOVER)    SENT:HeartBeatRequest to CTI Server; InvokeId[2]
15:39:18:355 dialer-baDialer Trace: (FAILOVER)    Received:HeartBeatConf from CTI Server; InvokeId[2]
15:39:18:370 dialer-baDialer Trace: CSipUATelephonyServer::HandleKeepAliveTimer
15:39:18:901 dialer-baDialer Trace: CSipUATelephonyServer::HandleKeepAliveTimeoutTimer: Missing HB ACK 1
15:39:19:369 dialer-baDialer Trace: (DD)    **** Media Routing PIM is not Connected ****
15:39:19:369 dialer-baDialer Trace: (DD)    **** All SoftPhone Channels not Initialized ****
15:39:19:369 dialer-baDialer Trace: (DD)    **** Configured SoftPhone Channels: [30], Initialized: [0] ****
15:39:20:385 dialer-baDialer Trace: (DD)    **** Media Routing PIM is not Connected ****
15:39:20:385 dialer-baDialer Trace: (DD)    **** All SoftPhone Channels not Initialized ****
15:39:20:385 dialer-baDialer Trace: (DD)    **** Configured SoftPhone Channels: [30], Initialized: [0] ****
15:39:21:399 dialer-baDialer Trace: (DD)    **** Media Routing PIM is not Connected ****
15:39:21:399 dialer-baDialer Trace: (DD)    **** All SoftPhone Channels not Initialized ****
15:39:21:399 dialer-baDialer Trace: (DD)    **** Configured SoftPhone Channels: [30], Initialized: [0] ****
15:39:22:413 dialer-baDialer Trace: (DD)    **** Media Routing PIM is not Connected ****
15:39:22:413 dialer-baDialer Trace: (DD)    **** All SoftPhone Channels not Initialized ****
15:39:22:413 dialer-baDialer Trace: (DD)    **** Configured SoftPhone Channels: [30], Initialized: [0] ****
15:39:23:412 dialer-baDialer Trace: CSipUATelephonyServer::HandleKeepAliveTimer
15:39:23:428 dialer-baDialer Trace: (DD)    **** Media Routing PIM is not Connected ****
15:39:23:428 dialer-baDialer Trace: (DD)    **** All SoftPhone Channels not Initialized ****
15:39:23:428 dialer-baDialer Trace: (DD)    **** Configured SoftPhone Channels: [30], Initialized: [0] ****
15:39:23:942 dialer-baDialer Trace: CSipUATelephonyServer::HandleKeepAliveTimeoutTimer: Missing HB ACK 2
15:39:24:442 dialer-baDialer Trace: (DD)    **** Media Routing PIM is not Connected ****
15:39:24:442 dialer-baDialer Trace: (DD)    **** All SoftPhone Channels not Initialized ****
15:39:24:442 dialer-baDialer Trace: (DD)    **** Configured SoftPhone Channels: [30], Initialized: [0] ****
15:39:24:442 dialer-baDialer Trace: (FAILOVER)    SENT:HeartBeatRequest to CTI Server; InvokeId[3]
15:39:24:442 dialer-baDialer Trace: (FAILOVER)    Received:HeartBeatConf from CTI Server; InvokeId[3]
15:39:25:458 dialer-baDialer Trace: (DD)    **** Media Routing PIM is not Connected ****
15:39:25:458 dialer-baDialer Trace: (DD)    **** All SoftPhone Channels not Initialized ****
15:39:25:458 dialer-baDialer Trace: (DD)    **** Configured SoftPhone Channels: [30], Initialized: [0] ****
15:39:26:472 dialer-baDialer Trace: (DD)    **** Media Routing PIM is not Connected ****
15:39:26:472 dialer-baDialer Trace: (DD)    **** All SoftPhone Channels not Initialized ****
15:39:26:472 dialer-baDialer Trace: (DD)    **** Configured SoftPhone Channels: [30], Initialized: [0] ****
15:39:27:486 dialer-baDialer Trace: (DD)    **** Media Routing PIM is not Connected ****
15:39:27:486 dialer-baDialer Trace: (DD)    **** All SoftPhone Channels not Initialized ****
15:39:27:486 dialer-baDialer Trace: (DD)    **** Configured SoftPhone Channels: [30], Initialized: [0] ****
15:39:28:469 dialer-baDialer Trace: CSipUATelephonyServer::HandleKeepAliveTimer
15:39:28:500 dialer-baDialer Trace: (DD)    **** Media Routing PIM is not Connected ****
15:39:28:500 dialer-baDialer Trace: (DD)    **** All SoftPhone Channels not Initialized ****
15:39:28:500 dialer-baDialer Trace: (DD)    **** Configured SoftPhone Channels: [30], Initialized: [0] ****
15:39:28:999 dialer-baDialer Trace: CSipUATelephonyServer::HandleKeepAliveTimeoutTimer: Missing HB ACK 3
15:39:29:514 dialer-baDialer Trace: (DD)    **** Media Routing PIM is not Connected ****
15:39:29:514 dialer-baDialer Trace: (DD)    **** All SoftPhone Channels not Initialized ****
15:39:29:514 dialer-baDialer Trace: (DD)    **** Configured SoftPhone Channels: [30], Initialized: [0] ****
15:39:30:531 dialer-baDialer Trace: (DD)    **** Media Routing PIM is not Connected ****
15:39:30:531 dialer-baDialer Trace: (DD)    **** All SoftPhone Channels not Initialized ****
15:39:30:531 dialer-baDialer Trace: (DD)    **** Configured SoftPhone Channels: [30], Initialized: [0] ****
15:39:30:531 dialer-baDialer Trace: (FAILOVER)    SENT:HeartBeatRequest to CTI Server; InvokeId[4]
15:39:30:531 dialer-baDialer Trace: (FAILOVER)    Received:HeartBeatConf from CTI Server; InvokeId[4]
15:39:31:545 dialer-baDialer Trace: (DD)    **** Media Routing PIM is not Connected ****
15:39:31:545 dialer-baDialer Trace: (DD)    **** All SoftPhone Channels not Initialized ****
15:39:31:545 dialer-baDialer Trace: (DD)    **** Configured SoftPhone Channels: [30], Initialized: [0] ****
15:39:32:559 dialer-baDialer Trace: (DD)    **** Media Routing PIM is not Connected ****
15:39:32:559 dialer-baDialer Trace: (DD)    **** All SoftPhone Channels not Initialized ****
15:39:32:559 dialer-baDialer Trace: (DD)    **** Configured SoftPhone Channels: [30], Initialized: [0] ****
15:39:33:510 dialer-baDialer Trace: CSipUATelephonyServer::HandleKeepAliveTimer
15:39:33:573 dialer-baDialer Trace: (DD)    **** Media Routing PIM is not Connected ****
15:39:33:573 dialer-baDialer Trace: (DD)    **** All SoftPhone Channels not Initialized ****
15:39:33:573 dialer-baDialer Trace: (DD)    **** Configured SoftPhone Channels: [30], Initialized: [0] ****
15:39:34:025 dialer-baDialer Trace: CSipUATelephonyServer::HandleKeepAliveTimeoutTimer: Missing HB ACK 4
15:39:34:587 dialer-baDialer Trace: (DD)    **** Media Routing PIM is not Connected ****
15:39:34:587 dialer-baDialer Trace: (DD)    **** All SoftPhone Channels not Initialized ****
15:39:34:587 dialer-baDialer Trace: (DD)    **** Configured SoftPhone Channels: [30], Initialized: [0] ****
15:39:35:588 dialer-baDialer Trace: (DD)    **** Media Routing PIM is not Connected ****
15:39:35:588 dialer-baDialer Trace: (DD)    **** All SoftPhone Channels not Initialized ****
15:39:35:588 dialer-baDialer Trace: (DD)    **** Configured SoftPhone Channels: [30], Initialized: [0] ****
15:39:36:602 dialer-baDialer Trace: (DD)    **** Media Routing PIM is not Connected ****
15:39:36:602 dialer-baDialer Trace: (DD)    **** All SoftPhone Channels not Initialized ****
15:39:36:602 dialer-baDialer Trace: (DD)    **** Configured SoftPhone Channels: [30], Initialized: [0] ****
15:39:36:602 dialer-baDialer Trace: (FAILOVER)    SENT:HeartBeatRequest to CTI Server; InvokeId[5]
15:39:36:602 dialer-baDialer Trace: (FAILOVER)    Received:HeartBeatConf from CTI Server; InvokeId[5]
15:39:37:616 dialer-baDialer Trace: (DD)    **** Media Routing PIM is not Connected ****
15:39:37:616 dialer-baDialer Trace: (DD)    **** All SoftPhone Channels not Initialized ****
15:39:37:616 dialer-baDialer Trace: (DD)    **** Configured SoftPhone Channels: [30], Initialized: [0] ****
15:39:38:537 dialer-baDialer Trace: CSipUATelephonyServer::HandleKeepAliveTimer
15:39:38:630 dialer-baDialer Trace: (DD)    **** Media Routing PIM is not Connected ****
15:39:38:630 dialer-baDialer Trace: (DD)    **** All SoftPhone Channels not Initialized ****
15:39:38:630 dialer-baDialer Trace: (DD)    **** Configured SoftPhone Channels: [30], Initialized: [0] ****
15:39:39:067 dialer-baDialer Trace: CSipUATelephonyServer::HandleKeepAliveTimeoutTimer: Missing HB ACK 5
15:39:39:067 dialer-baDialer Blended Agent connection to SIP Server [172.21.0.10] on computer [TCOM-PG3A] is down, heart beat failure detected

172.21.0.10 is the voice gateway.  The dialer is connecting to the Campaign Manager and the CTI server, but can't connect to the PG or the Voice Gateway.  The Dialer and PG are on the same server.  I'm stumped.  Would appreciate any advice.  Thanks

[Ion Cristian Radescu]

Hi,

Double check your IP Addresses. 10.157.1.57 is your dialer's public address? Do you have your public network interface with highest priority in Windows? Otherwise the dialer will bind to your private IP Address on port 38001. What do you see in "netstat -na" output? Do you have any firewall on that machine?

C.

[dchumbley]

Thanks Ion.  The public address was correct in the binding order.  I changed the PG configuration to use all ip addresses instead of hostnames and it connects now.  I can ping the hostnames and it resolves to the correct addresses which is odd.  It's Active now though.  Thanks

[Ion Cristian Radescu]

Hi,

I have another problem now with this SIP Dialer. It seems it sends CANCEL immediately after Telco sends "CALL_PROC" message:

002016: Feb 23 18:06:05.675 EST: ISDN Se0/1/1:15 Q931: Sending SETUP  callref = 0x00A0 callID = 0x8021 switch = primary-net5 interface = User
002017: Feb 23 18:06:05.675 EST: ISDN Se0/1/1:15 Q931: TX -> SETUP pd = 8  callref = 0x00A0
        Bearer Capability i = 0x8090A3
                Standard = CCITT
                Transfer Capability = Speech
                Transfer Mode = Circuit
                Transfer Rate = 64 kbit/s
        Channel ID i = 0xA9839F
                Exclusive, Channel 31
        Called Party Number i = 0x81, MOBILE_NUMBER
                Plan:ISDN, Type:Unknown
002018: Feb 23 18:06:05.891 EST: ISDN Se0/1/1:15 Q931: RX <- SETUP_ACK pd = 8  callref = 0x80A0
        Channel ID i = 0xA9839F
                Exclusive, Channel 31
002019: Feb 23 18:06:06.011 EST: ISDN Se0/1/1:15 Q931: RX <- CALL_PROC pd = 8  callref = 0x80A0
002020: Feb 23 18:06:09.827 EST: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
CANCEL sip:MOBILE_NUMBER@172.18.10.11 SIP/2.0
Via: SIP/2.0/UDP 172.18.10.31:58800;branch=z9hG4bK-d8754z-0308637c7044b07d-1---d8754z-;rport
Max-Forwards: 70
To: <sip:MOBILE_NUMBER@172.18.10.11>
From: <sip:7014@172.18.10.31>;tag=5a067767
Call-ID: 7970b11a-0f75da62-5f15b959-e95c0814
CSeq: 1 CANCEL
User-Agent: Cisco-SIPDialer/UCCE10.0
Content-Length: 0

002021: Feb 23 18:06:09.831 EST: ISDN Se0/1/1:15 Q931: TX -> DISCONNECT pd = 8  callref = 0x00A0
        Cause i = 0x8090 - Normal call clearing

Any idea why this happens?

C.

[Ion Cristian Radescu]

I found the answer here: http://www.cisco.com/c/en/us/support/docs/voice-unified-communications/outbound-option/116105-problem-ucce-sip-dialer-00.html

Thanks,

C.