Softphone 1.3.1

[Helmut.schranz]

hi

We have Call Manager 3.2.2a

We want to use Softphone 1.3.1.0 on Win XP

We have done Following Configuration Settings for an Softphone User:

add an CTI Port using notebooks DNS name with right partition and Calling Searchbase, we add an line with right configuration.

In the User Directory (we have an MS-AD integration) we enable CTI Application, and assign to the user in the "Device Association" the notebook

and we assign in the "SoftPhone" the DNS Name of the notebook.

We have done that twice for two users completly parallel and then following occured :

User A is working fine, the User B does not see any line within Softphone Startupwindow.

When we configure on Users B notebook the user credentials from User A everything is working fine.

USER notebook constellations:

User A: Compaq Armada 7800 with XP professional

Softphone 1.3.1.0

User B: Compaq Armada Evo N600c with XP professional

Softphone 1.3.1.0

We do not understand at all why the user A is working and User B not.

Could it be that there is one Parameter difference within AD,

or where is the problem ?

Please Help

Ing. Helmut Schranz

mii ag, lerchenfelder straße 2/203, a-1080 Vienna

tel: (+43) (0)1 4070587-1251, fax: (+43) (0)1 4070587-7125

mobile: (+43) (0)699 12826005

mailto:helmut.schranz@mii.at

http://www.mii.at

[amishak]

Try restarting CTI manager service.

If possible, delete the CTI port for User B and readd it.

-Amisha

Cisco Systems

[Helmut.schranz]

hi Amisha

I was able to remover User B CTI Port.

Then i stopped CTI Manager and restarted him again.

Then i added the Notebook using DNS-Name again added the extension

(also checked from Callmanager Server with ping and nslookup if DNS is responding properly, was OK)

Then i added to the User B again the "Device Association" and "Softphone"

then i stopped and started CTI Man. again.

And it happened again that i get no line to control on startup of softphone.

When User A ist using his credentials it worked fine, so from my point of view the problem

can only be from Callmanager or AD !!

HERE IS THE SOFTPHONE TRACE may be it helps you to identify detailed problems :

07/17/2002 20:06:39.328 ciscotsp001.tsp|<--CTspTrace::Init()

07/17/2002 20:06:39.328 ciscotsp001.tsp|-->TSPI_providerUIIdentify()

07/17/2002 20:06:39.328 ciscotsp001.tsp|-->SelsiusTSP::TSPI_providerUIIdentify()

07/17/2002 20:06:39.328 ciscotsp001.tsp| SelsiusTSP::TSPI_providerUIIdentify() TUISPDllPath=C:\WINNT\System32\CiscoTUISP001.dll

07/17/2002 20:06:39.328 ciscotsp001.tsp| SelsiusTSP::TSPI_providerUIIdentify() lpszUIDLLName=C

07/17/2002 20:06:39.328 ciscotsp001.tsp|<--SelsiusTSP::TSPI_providerUIIdentify()

07/17/2002 20:06:39.328 ciscotsp001.tsp| TSPI_providerUIIdentify()

TSPI_providerEnumDevices returns = 0x00000000

lpszUIDLLName = C

07/17/2002 20:06:39.328 ciscotsp001.tsp|<--TSPI_providerUIIdentify()

07/17/2002 20:06:39.338 ciscotsp001.tsp|-->TSPI_lineNegotiateTSPIVersion()

07/17/2002 20:06:39.338 ciscotsp001.tsp| TSPI_lineNegotiateTSPIVersion()

dwDeviceID =0xFFFFFFFF

dwLowVersion =0x00010003

dwHighVersion =0x00030001

lpdwTSPIVersion =0x030C5760

07/17/2002 20:06:39.338 ciscotsp001.tsp|-->InitGlobalTSP()

07/17/2002 20:06:39.338 ciscotsp001.tsp|-->CSelsiusTSP::CSelsiusTSP()

07/17/2002 20:06:39.338 ciscotsp001.tsp| CSelsiusTSP::CSelsiusTSP() TSP Product Version 3.2(0.24)

07/17/2002 20:06:39.338 ciscotsp001.tsp| CSelsiusTSP::CSelsiusTSP() trace mask=0x007F0601

07/17/2002 20:06:39.338 ciscotsp001.tsp| CSelsiusTSP::CSelsiusTSP() m_EnumAutomatedVoiceOnly=0

07/17/2002 20:06:39.338 ciscotsp001.tsp| CSelsiusTSP::CSelsiusTSP() m_SyncMsgTimeout=15000

07/17/2002 20:06:39.338 ciscotsp001.tsp| CSelsiusTSP::CSelsiusTSP() m_ProviderOpenTimeout=30000

07/17/2002 20:06:39.338 ciscotsp001.tsp| CSelsiusTSP::CSelsiusTSP() m_HeartbeatInterval=30

07/17/2002 20:06:39.338 ciscotsp001.tsp| CSelsiusTSP::CSelsiusTSP() m_ConnectRetryInterval=30000

07/17/2002 20:06:39.338 ciscotsp001.tsp|-->CSelsiusTSP::CreateCtiInterface()

07/17/2002 20:06:39.338 ciscotsp001.tsp|-->CCtiInterface::CCtiInterface()

07/17/2002 20:06:39.338 ciscotsp001.tsp|<--CCtiInterface::CCtiInterface()

07/17/2002 20:06:39.338 ciscotsp001.tsp|-->CCtiInterface::~CCtiInterface()

07/17/2002 20:06:39.338 ciscotsp001.tsp|-->CSelsiusTCPLink()

07/17/2002 20:06:39.338 ciscotsp001.tsp|<--CSelsiusTCPLink()

07/17/2002 20:06:39.338 ciscotsp001.tsp|<--CCtiInterface::~CCtiInterface()

07/17/2002 20:06:39.338 ciscotsp001.tsp|<--CSelsiusTSP::CreateCtiInterface()

07/17/2002 20:06:39.338 ciscotsp001.tsp|-->CSelsiusTSP::StartConnectThread()

07/17/2002 20:06:39.338 ciscotsp001.tsp|-->CSelsiusTSP::StartEventThread()

07/17/2002 20:06:39.338 ciscotsp001.tsp| CSelsiusTSP::StartEventThread() Created event thread.

07/17/2002 20:06:39.338 ciscotsp001.tsp|<--CSelsiusTSP::StartEventThread()

07/17/2002 20:06:39.338 ciscotsp001.tsp|-->CSelsiusTSP::StartWorkerThread()

07/17/2002 20:06:39.338 ciscotsp001.tsp| CSelsiusTSP::StartWorkerThread() Created worker thread.

07/17/2002 20:06:39.338 ciscotsp001.tsp|<--CSelsiusTSP::StartWorkerThread()

07/17/2002 20:06:39.338 ciscotsp001.tsp|-->SelsiusTSP::SetupConnectionAndInitializeTSP()

07/17/2002 20:06:39.338 ciscotsp001.tsp| SelsiusTSP::SetupConnectionAndInitializeTSP() TSPConnectState = 0 initFrom = 0

07/17/2002 20:06:39.338 ciscotsp001.tsp|-->CSelsiusTSP::ReadCtiMgrLocationFromRegistry()

07/17/2002 20:06:39.338 ciscotsp001.tsp| CSelsiusTSP::ReadCtiMgrLocationFromRegistry() Primary CTI Manager is IP address [10.67.51.29]

07/17/2002 20:06:39.338 ciscotsp001.tsp| CSelsiusTSP::ReadCtiMgrLocationFromRegistry() Primary CTI Manager address: 0x1D33430A

07/17/2002 20:06:39.338 ciscotsp001.tsp| CSelsiusTSP::ReadCtiMgrLocationFromRegistry() Backup CTI Manager is not configured

07/17/2002 20:06:39.338 ciscotsp001.tsp| CSelsiusTSP::ReadCtiMgrLocationFromRegistry() m_SyncMsgTimeout=15000

07/17/2002 20:06:39.338 ciscotsp001.tsp| CSelsiusTSP::ReadCtiMgrLocationFromRegistry() m_ProviderOpenTimeout=30000

07/17/2002 20:06:39.338 ciscotsp001.tsp| CSelsiusTSP::ReadCtiMgrLocationFromRegistry() m_HeartbeatInterval=30

07/17/2002 20:06:39.338 ciscotsp001.tsp| CSelsiusTSP::ReadCtiMgrLocationFromRegistry() m_ConnectRetryInterval=30000

07/17/2002 20:06:39.338 ciscotsp001.tsp|<--CSelsiusTSP::ReadCtiMgrLocationFromRegistry()

07/17/2002 20:06:39.338 ciscotsp001.tsp| SelsiusTSP::SetupConnectionAndInitializeTSP() ReadCtiMgrLocationFromRegistry() returned 0

07/17/2002 20:06:39.338 ciscotsp001.tsp|-->CCtiInterface::ConnectLink()

07/17/2002 20:06:39.338 ciscotsp001.tsp|-->CSelsiusTSPLink::Socket()

07/17/2002 20:06:39.338 ciscotsp001.tsp|-->SelsiusTSP::EventThreadEntry()

07/17/2002 20:06:39.338 ciscotsp001.tsp|-->SelsiusTSP::InitializeComObjects()

07/17/2002 20:06:39.338 ciscotsp001.tsp| SelsiusTSP::InitializeComObjects() trace mask=0x007F0601

07/17/2002 20:06:39.338 ciscotsp001.tsp| SelsiusTSP::InitializeComObjects() CoInitializeEx(0, COINIT_MULTITHREADED) OK

07/17/2002 20:06:39.338 ciscotsp001.tsp| SelsiusTSP::InitializeComObjects() *ERROR* CoInitializeSecurity() return 0x80010119

07/17/2002 20:06:39.338 ciscotsp001.tsp| SelsiusTSP::InitializeComObjects() GITInitialize() OK

07/17/2002 20:06:39.338 ciscotsp001.tsp|-->CSelsiusTSPWaveList::CSelsiusTSPWaveList()

07/17/2002 20:06:39.338 ciscotsp001.tsp|-->SelsiusTSP::WorkerThreadEntry()

07/17/2002 20:06:39.338 ciscotsp001.tsp|-->SelsiusTSP::WorkerLoop()

07/17/2002 20:06:39.338 ciscotsp001.tsp| SelsiusTSP::WorkerLoop() worker thread waiting for signal from main thread that there is a request

07/17/2002 20:06:39.338 ciscotsp001.tsp|<--CSelsiusTSPLink::Socket()

07/17/2002 20:06:39.338 ciscotsp001.tsp|-->CSelsiusTSPLink::Connect()

07/17/2002 20:06:39.338 ciscotsp001.tsp|<--CSelsiusTSPWaveList::CSelsiusTSPWaveList()

07/17/2002 20:06:39.338 ciscotsp001.tsp|-->CSelsiusTSPUIMsgGetInfo::ProcessTSPUIMsgGetInfo()

07/17/2002 20:06:39.338 ciscotsp001.tsp| CSelsiusTSPUIMsgGetInfo::ProcessTSPUIMsgGetInfo() user name USERB

07/17/2002 20:06:39.338 ciscotsp001.tsp| CSelsiusTSPUIMsgGetInfo::ProcessTSPUIMsgGetInfo() Primary CTI Manager Address Type: 2

07/17/2002 20:06:39.338 ciscotsp001.tsp| CSelsiusTSPUIMsgGetInfo::ProcessTSPUIMsgGetInfo() Primary CTI Manager Address: 10.67.51.29

07/17/2002 20:06:39.338 ciscotsp001.tsp| CSelsiusTSPUIMsgGetInfo::ProcessTSPUIMsgGetInfo() Backup CTI Manager Address Type: 0

07/17/2002 20:06:39.338 ciscotsp001.tsp| CSelsiusTSPUIMsgGetInfo::ProcessTSPUIMsgGetInfo() Backup CTI Manager Address:

07/17/2002 20:06:39.338 ciscotsp001.tsp| CSelsiusTSPUIMsgGetInfo::ProcessTSPUIMsgGetInfo() Synchronous message timeout 15000

07/17/2002 20:06:39.338 ciscotsp001.tsp| CSelsiusTSPUIMsgGetInfo::ProcessTSPUIMsgGetInfo() ProviderOpen Synchronous message timeout 30000

07/17/2002 20:06:39.338 ciscotsp001.tsp| CSelsiusTSPUIMsgGetInfo::ProcessTSPUIMsgGetInfo() Heartbeat interval 30

07/17/2002 20:06:39.338 ciscotsp001.tsp| CSelsiusTSPUIMsgGetInfo::ProcessTSPUIMsgGetInfo() Connect retry interval 30000

07/17/2002 20:06:39.338 ciscotsp001.tsp| CSelsiusTSPUIMsgGetInfo::ProcessTSPUIMsgGetInfo() TraceOn flag 1

07/17/2002 20:06:39.338 ciscotsp001.tsp| CSelsiusTSPUIMsgGetInfo::ProcessTSPUIMsgGetInfo() TraceMask =0xC07F0601

07/17/2002 20:06:39.338 ciscotsp001.tsp| CSelsiusTSPUIMsgGetInfo::ProcessTSPUIMsgGetInfo() TraceMaxLines =0x00007530

07/17/2002 20:06:39.338 ciscotsp001.tsp| CSelsiusTSPUIMsgGetInfo::ProcessTSPUIMsgGetInfo() TraceNumFiles =10

07/17/2002 20:06:39.338 ciscotsp001.tsp| CSelsiusTSPUIMsgGetInfo::ProcessTSPUIMsgGetInfo() Call TraceFileName c:\Temp

07/17/2002 20:06:39.338 ciscotsp001.tsp| CSelsiusTSPUIMsgGetInfo::ProcessTSPUIMsgGetInfo() LangId = 0x00000009

07/17/2002 20:06:39.338 ciscotsp001.tsp| CSelsiusTSPUIMsgGetInfo::ProcessTSPUIMsgGetInfo() ResourceDirPath C:\Program Files\Cisco

07/17/2002 20:06:39.338 ciscotsp001.tsp|<--CSelsiusTSPUIMsgGetInfo::ProcessTSPUIMsgGetInfo()

07/17/2002 20:06:39.338 ciscotsp001.tsp|-->CSelsiusTSPWaveList::Init()

07/17/2002 20:06:39.338 ciscotsp001.tsp| CSelsiusTSPWaveList::Init() ISelsiusNTWaveCtl Created

07/17/2002 20:06:39.338 ciscotsp001.tsp| CSelsiusTSPWaveList::Init() GetNumDevice() returned 0

07/17/2002 20:06:39.338 ciscotsp001.tsp| CSelsiusTSPWaveList::Init() pWaveCtl->AddTSPSinkToMap done

07/17/2002 20:06:39.338 ciscotsp001.tsp|-->CSelsiusTSPWaveList::SetSilenceDetectionParameters()

07/17/2002 20:06:39.338 ciscotsp001.tsp|<--CSelsiusTSPWaveList::SetSilenceDetectionParameters()

07/17/2002 20:06:39.338 ciscotsp001.tsp|<--CSelsiusTSPWaveList::Init()

07/17/2002 20:06:39.338 ciscotsp001.tsp|<--SelsiusTSP::InitializeComObjects()

07/17/2002 20:06:39.338 ciscotsp001.tsp|-->SelsiusTSP::SignalingLoop()

07/17/2002 20:06:39.338 ciscotsp001.tsp| SelsiusTSP::SignalingLoop() event thread waiting for signal from main thread that there is a request

07/17/2002 20:06:39.348 ciscotsp001.tsp|-->Connect()

07/17/2002 20:06:39.348 ciscotsp001.tsp| Connect() Connected to Call Manager

07/17/2002 20:06:39.348 ciscotsp001.tsp|<--Connect()

07/17/2002 20:06:39.348 ciscotsp001.tsp|<--CSelsiusTSPLink::Connect()

07/17/2002 20:06:39.348 ciscotsp001.tsp| CCtiInterface::ConnectLink() Connected to CTI Manager

07/17/2002 20:06:39.348 ciscotsp001.tsp|<--CCtiInterface::ConnectLink()

07/17/2002 20:06:39.348 ciscotsp001.tsp| SelsiusTSP::SetupConnectionAndInitializeTSP() Connected to CTI Manager: [0] address: 0x1D33430A, port: 0xabc

07/17/2002 20:06:39.348 ciscotsp001.tsp| SelsiusTSP::SetupConnectionAndInitializeTSP() TSPConnectState = 2 initFrom = 0

07/17/2002 20:06:39.348 ciscotsp001.tsp|-->CSelsiusTSP::ReadUserNamePasswordFromRegistry()

07/17/2002 20:06:39.348 ciscotsp001.tsp| CSelsiusTSP::ReadUserNamePasswordFromRegistry() user name=USERB

07/17/2002 20:06:39.348 ciscotsp001.tsp|<--CSelsiusTSP::ReadUserNamePasswordFromRegistry()

07/17/2002 20:06:39.348 ciscotsp001.tsp| SelsiusTSP::SetupConnectionAndInitializeTSP() TSPConnectState = 3 initFrom = 0

07/17/2002 20:06:39.348 ciscotsp001.tsp|-->SelsiusTSP::ProviderOpen()

07/17/2002 20:06:39.348 ciscotsp001.tsp|-->CSelsiusTSP::SetApplicationID()

07/17/2002 20:06:39.348 ciscotsp001.tsp|-->CCtiInterface::ReceiveThreadEntry()

07/17/2002 20:06:39.348 ciscotsp001.tsp|-->CCtiInterface::ReceiveLoop()

07/17/2002 20:06:39.348 ciscotsp001.tsp| CCtiInterface::ReceiveLoop() receive thread: Waiting for messages

07/17/2002 20:06:39.348 ciscotsp001.tsp|-->CSelsiusTSPLink::GetMessage()

07/17/2002 20:06:39.348 ciscotsp001.tsp| CSelsiusTSPLink::GetMessage() Receive thread waiting on select()

07/17/2002 20:06:39.348 ciscotsp001.tsp| CSelsiusTSP::SetApplicationID() ApplicationID=CiscoTSP001-10.67.11.77

07/17/2002 20:06:39.348 ciscotsp001.tsp|<--CSelsiusTSP::SetApplicationID()

07/17/2002 20:06:39.348 ciscotsp001.tsp|-->CCtiInterface::SendProviderOpenRequest()

07/17/2002 20:06:39.348 ciscotsp001.tsp|-->CCtiInterface::SendAndProcessProviderOpenRequest()

07/17/2002 20:06:39.348 ciscotsp001.tsp|-->CCtiInterface::AddToSequenceNumberMap()

07/17/2002 20:06:39.348 ciscotsp001.tsp| CCtiInterface::AddToSequenceNumberMap() Adding map entry for sequenceNumber(0x00000000) to responseObject(0x024A2318)

07/17/2002 20:06:39.348 ciscotsp001.tsp|<--CCtiInterface::AddToSequenceNumberMap()

07/17/2002 20:06:39.348 ciscotsp001.tsp|-->SendMessage()

07/17/2002 20:06:39.348 ciscotsp001.tsp|-->ProviderOpenRequest::Trace

07/17/2002 20:06:39.348 ciscotsp001.tsp| ProviderOpenRequest::Trace :dwSequenceNumber = 0x00000000

07/17/2002 20:06:39.348 ciscotsp001.tsp| ProviderOpenRequest::Trace

dummyHeader.dwLen =173

dummyHeader.dwType =0

dwMagicCookie =0xFFFFDDDD

dwProtocolVersion =0x00030000

dwPDUNumber =3

dwHeaderSize =32

dwFixedSize =52

dwVariableSize =97

07/17/2002 20:06:39.348 ciscotsp001.tsp| ProviderOpenRequest::Trace

dwProviderNameOffset =0x00000054

dwProviderNameSize =19

ProviderName =CiscoTSP 3.2(0.24)

dwLoginOffset =0x00000067

dwLoginSize =9

Login =USERB

dwPasswordOffset =0x00000070

dwPasswordSize =33

dwApplicationIDOffset =0x0000009D

dwApplicationIDSize =24

ApplicationID = CiscoTSP001-10.67.11.77

07/17/2002 20:06:39.348 ciscotsp001.tsp| ProviderOpenRequest::Trace

dwProviderEventFilterOffset =0x00000091

dwProviderEventFilterSize =12

dwProviderEventFilterFixedSize =12

ProviderEventFilter_t.bDeviceRegistered =0xFFFFFFFF

ProviderEventFilter_t.bDeviceUnRegistered =0xFFFFFFFF

ProviderEventFilter_t.bDirectoryChangeNotify =0xFFFFFFFF

dwWantServerHeartbeat =0x0000001E

07/17/2002 20:06:39.348 ciscotsp001.tsp|<--ProviderOpenRequest::Trace

07/17/2002 20:06:39.348 ciscotsp001.tsp| SendMessage() sent message number 3, sequence number 0, 181 bytes

07/17/2002 20:06:39.348 ciscotsp001.tsp|<--SendMessage()

07/17/2002 20:06:39.348 ciscotsp001.tsp|-->CCtiInterface::WaitForSynchronousResponse()

07/17/2002 20:06:39.348 ciscotsp001.tsp| CCtiInterface::WaitForSynchronousResponse() Waiting to receive synchronous response or timeout 15000

07/17/2002 20:06:39.489 ciscotsp001.tsp| CSelsiusTSPLink::GetMessage() select() returned 1.

07/17/2002 20:06:39.489 ciscotsp001.tsp| CSelsiusTSPLink::GetMessage() Message number=4, length=21

07/17/2002 20:06:39.489 ciscotsp001.tsp|-->ProviderOpenResponse::Trace

07/17/2002 20:06:39.489 ciscotsp001.tsp| ProviderOpenResponse::Trace :dwSequenceNumber = 0x00000000

07/17/2002 20:06:39.489 ciscotsp001.tsp| ProviderOpenResponse::Trace

dummyHeader.dwLen =45

dummyHeader.dwType =0

dwMagicCookie =0xFFFFDDDD

dwProtocolVersion =0x00030000

dwPDUNumber =4

dwHeaderSize =32

dwFixedSize =20

dwVariableSize =1

07/17/2002 20:06:39.489 ciscotsp001.tsp| ProviderOpenResponse::Trace

dwProviderInfoOffset =0x00000034

dwProviderInfoSize =1

ProviderInfo =

dwClientHeartbeat =0x0000001E

dwServerHeartbeat =0x0000001E

07/17/2002 20:06:39.489 ciscotsp001.tsp|<--ProviderOpenResponse::Trace

07/17/2002 20:06:39.489 ciscotsp001.tsp| CSelsiusTSPLink::GetMessage() Read 53 bytes, message number is 4

07/17/2002 20:06:39.489 ciscotsp001.tsp|<--CSelsiusTSPLink::GetMessage()

07/17/2002 20:06:39.489 ciscotsp001.tsp| CCtiInterface::ReceiveLoop() Received message number 4

07/17/2002 20:06:39.489 ciscotsp001.tsp|-->CCtiInterface::ProcessResponse()

07/17/2002 20:06:39.489 ciscotsp001.tsp|-->CCtiInterface::FindAndRemoveSequenceNumber()

07/17/2002 20:06:39.489 ciscotsp001.tsp| CCtiInterface::FindAndRemoveSequenceNumber() Found mapping for sequenceNumber(0x00000000) to responseObject(0x024A2318)

07/17/2002 20:06:39.489 ciscotsp001.tsp|<--CCtiInterface::FindAndRemoveSequenceNumber()

07/17/2002 20:06:39.489 ciscotsp001.tsp| CCtiInterface::ProcessResponse() wait till 15000 sec for the processing of ProviderOpenResponse

07/17/2002 20:06:39.489 ciscotsp001.tsp|<--CCtiInterface::WaitForSynchronousResponse()

07/17/2002 20:06:39.489 ciscotsp001.tsp| CCtiInterface::SendAndProcessProviderOpenRequest() EnteredCriticalSection m_SequenceNumberMapCriticalSection

07/17/2002 20:06:39.489 ciscotsp001.tsp| CCtiInterface::ProcessResponse() WaitForSingleObject (m_hProviderOpenResponseProcessingDone) Successful

07/17/2002 20:06:39.489 ciscotsp001.tsp|<--CCtiInterface::ProcessResponse()

07/17/2002 20:06:39.489 ciscotsp001.tsp| CCtiInterface::ReceiveLoop() receive thread: Waiting for messages

07/17/2002 20:06:39.489 ciscotsp001.tsp|-->CSelsiusTSPLink::GetMessage()

07/17/2002 20:06:39.489 ciscotsp001.tsp| CSelsiusTSPLink::GetMessage() Receive thread waiting on select()

07/17/2002 20:06:39.489 ciscotsp001.tsp|-->CCtiInterface::RemoveFromSequenceNumberMap()

07/17/2002 20:06:39.489 ciscotsp001.tsp| CCtiInterface::RemoveFromSequenceNumberMap() Removing map entry for sequenceNumber(0x00000000)

07/17/2002 20:06:39.489 ciscotsp001.tsp| CCtiInterface::RemoveFromSequenceNumberMap() *ERROR* Map entry does not exists for sequenceNumber(0x00000000)

07/17/2002 20:06:39.489 ciscotsp001.tsp|<--CCtiInterface::RemoveFromSequenceNumberMap()

07/17/2002 20:06:39.489 ciscotsp001.tsp|-->CCtiInterface::ProcessProviderOpenResponse()

07/17/2002 20:06:39.489 ciscotsp001.tsp| CCtiInterface::ProcessProviderOpenResponse() CallManager Version

07/17/2002 20:06:39.489 ciscotsp001.tsp| CCtiInterface::ProcessProviderOpenResponse() ServerHeartbeat=30, ClientHeartbeat=30

07/17/2002 20:06:39.489 ciscotsp001.tsp|-->CCtiInterface::StartHeartbeatThread()

07/17/2002 20:06:39.489 ciscotsp001.tsp| CCtiInterface::StartHeartbeatThread() Created heartbeat thread.

07/17/2002 20:06:39.489 ciscotsp001.tsp|<--CCtiInterface::StartHeartbeatThread()

07/17/2002 20:06:39.489 ciscotsp001.tsp| CCtiInterface::ProcessProviderOpenResponse() Result of provider open request is 0

07/17/2002 20:06:39.489 ciscotsp001.tsp|<--CCtiInterface::ProcessProviderOpenResponse()

07/17/2002 20:06:39.489 ciscotsp001.tsp|-->CCtiInterface::AddToSequenceNumberMap()

07/17/2002 20:06:39.489 ciscotsp001.tsp| CCtiInterface::AddToSequenceNumberMap() Adding map entry for sequenceNumber(0x00000000) to responseObject(0x024A2318)

07/17/2002 20:06:39.489 ciscotsp001.tsp|<--CCtiInterface::AddToSequenceNumberMap()

07/17/2002 20:06:39.489 ciscotsp001.tsp| CCtiInterface::SendAndProcessProviderOpenRequest() Added ResponseObj in map for ProviderOpenCompletedEvent - LeavingCriticalSection m_SequenceNumberMapCriticalSection

07/17/2002 20:06:39.489 ciscotsp001.tsp|-->CCtiInterface::WaitForSynchronousResponse()

07/17/2002 20:06:39.489 ciscotsp001.tsp| CCtiInterface::WaitForSynchronousResponse() Waiting to receive synchronous response or timeout 30000

07/17/2002 20:06:39.489 ciscotsp001.tsp|-->CCtiInterface::HeartbeatThreadEntry()

07/17/2002 20:06:39.489 ciscotsp001.tsp|-->CCtiInterface::HeartbeatLoop()

07/17/2002 20:06:39.649 ciscotsp001.tsp| CSelsiusTSPLink::GetMessage() select() returned 1.

07/17/2002 20:06:39.649 ciscotsp001.tsp| CSelsiusTSPLink::GetMessage() Message number=126, length=83

07/17/2002 20:06:39.649 ciscotsp001.tsp|-->ProviderOpenCompletedEvent::Trace

07/17/2002 20:06:39.649 ciscotsp001.tsp| ProviderOpenCompletedEvent::Trace

dummyHeader.dwLen =107

dummyHeader.dwType =0

dwMagicCookie =0xFFFFDDDD

dwProtocolVersion =0x00030000

dwPDUNumber =126

dwHeaderSize =32

dwFixedSize =32

dwVariableSize =51

07/17/2002 20:06:39.649 ciscotsp001.tsp| ProviderOpenCompletedEvent::Trace

dwReason =0x8CCC0060

dwSequnceNumber =0

dwProviderInfoOffset =64

dwProviderInfoSize =0

dwClientHeartbeat = 64

dwServerHeartbeat = 51

dwDescriptionOffset = 0x00000000

dwDescriptionSize = 0x024D61E8

07/17/2002 20:06:39.649 ciscotsp001.tsp| ProviderOpenCompletedEvent::Trace

ProviderInfo =

Description = Directory login failed - invalid user credentials.

07/17/2002 20:06:39.649 ciscotsp001.tsp|<--ProviderOpenCompletedEvent::Trace

07/17/2002 20:06:39.649 ciscotsp001.tsp| CSelsiusTSPLink::GetMessage() Read 115 bytes, message number is 126

07/17/2002 20:06:39.649 ciscotsp001.tsp|<--CSelsiusTSPLink::GetMessage()

07/17/2002 20:06:39.649 ciscotsp001.tsp| CCtiInterface::ReceiveLoop() Received message number 126

07/17/2002 20:06:39.649 ciscotsp001.tsp|-->CCtiInterface::ProcessResponse()

07/17/2002 20:06:39.649 ciscotsp001.tsp|-->CCtiInterface::FindAndRemoveSequenceNumber()

07/17/2002 20:06:39.649 ciscotsp001.tsp| CCtiInterface::FindAndRemoveSequenceNumber() Found mapping for sequenceNumber(0x00000000) to responseObject(0x024A2318)

07/17/2002 20:06:39.649 ciscotsp001.tsp|<--CCtiInterface::FindAndRemoveSequenceNumber()

07/17/2002 20:06:39.649 ciscotsp001.tsp|<--CCtiInterface::ProcessResponse()

07/17/2002 20:06:39.649 ciscotsp001.tsp| CCtiInterface::ReceiveLoop() receive thread: Waiting for messages

07/17/2002 20:06:39.649 ciscotsp001.tsp|-->CSelsiusTSPLink::GetMessage()

07/17/2002 20:06:39.649 ciscotsp001.tsp| CSelsiusTSPLink::GetMessage() Receive thread waiting on select()

07/17/2002 20:06:39.669 ciscotsp001.tsp|<--CCtiInterface::WaitForSynchronousResponse()

07/17/2002 20:06:39.669 ciscotsp001.tsp|-->CCtiInterface::ProcessProviderOpenCompletedEvent()

07/17/2002 20:06:39.669 ciscotsp001.tsp| CCtiInterface::ProcessProviderOpenCompletedEvent() ProviderOpenCompletedEvent returned failure Reason = -1932787616, Description = Directory login failed - invalid user credentials.

07/17/2002 20:06:39.669 ciscotsp001.tsp| CCtiInterface::ProcessProviderOpenCompletedEvent() Result of provider open request is -1932787616

07/17/2002 20:06:39.669 ciscotsp001.tsp|<--CCtiInterface::ProcessProviderOpenCompletedEvent()

07/17/2002 20:06:39.669 ciscotsp001.tsp|-->CCtiInterface::RemoveFromSequenceNumberMap()

07/17/2002 20:06:39.669 ciscotsp001.tsp| CCtiInterface::RemoveFromSequenceNumberMap() Removing map entry for sequenceNumber(0x00000000)

07/17/2002 20:06:39.669 ciscotsp001.tsp| CCtiInterface::RemoveFromSequenceNumberMap() *ERROR* Map entry does not exists for sequenceNumber(0x00000000)

07/17/2002 20:06:39.669 ciscotsp001.tsp|<--CCtiInterface::RemoveFromSequenceNumberMap()

07/17/2002 20:06:39.669 ciscotsp001.tsp|<--CCtiInterface::SendAndProcessProviderOpenRequest()

07/17/2002 20:06:39.669 ciscotsp001.tsp|<--CCtiInterface::SendProviderOpenRequest()

07/17/2002 20:06:39.669 ciscotsp001.tsp| SelsiusTSP::ProviderOpen() *ERROR* Directory login failed. Check user name and login in TSP configuration.

07/17/2002 20:06:39.669 ciscotsp001.tsp|<--SelsiusTSP::ProviderOpen()

07/17/2002 20:06:39.669 ciscotsp001.tsp| SelsiusTSP::SetupConnectionAndInitializeTSP() TSPConnectState = 16 initFrom = 0

07/17/2002 20:06:39.669 ciscotsp001.tsp|<--SelsiusTSP::SetupConnectionAndInitializeTSP()

07/17/2002 20:06:39.669 ciscotsp001.tsp| CSelsiusTSP::StartConnectThread() *ERROR* TSP initialization failure. Error: 3, TSP state: 1. TSP will reconnect and is OUT_OF_SERVICE

07/17/2002 20:06:39.669 ciscotsp001.tsp| CSelsiusTSP::StartConnectThread() Created connect thread.

07/17/2002 20:06:39.669 ciscotsp001.tsp|<--CSelsiusTSP::StartConnectThread()

07/17/2002 20:06:39.669 ciscotsp001.tsp| CSelsiusTSP::CSelsiusTSP() this=0x024DDB20

07/17/2002 20:06:39.669 ciscotsp001.tsp|<--CSelsiusTSP::CSelsiusTSP()

07/17/2002 20:06:39.669 ciscotsp001.tsp|<--InitGlobalTSP()

07/17/2002 20:06:39.669 ciscotsp001.tsp|-->SelsiusTSP::TSPI_lineNegotiateTSPIVersion(-1)

07/17/2002 20:06:39.669 ciscotsp001.tsp| SelsiusTSP::TSPI_lineNegotiateTSPIVersion(-1) TSPI Version is 0x00020001

07/17/2002 20:06:39.669 ciscotsp001.tsp|<--SelsiusTSP::TSPI_lineNegotiateTSPIVersion(-1)

07/17/2002 20:06:39.669 ciscotsp001.tsp| TSPI_lineNegotiateTSPIVersion()

TSPI_lineNegotiateTSPIVersion returns = 0x00000000

TSPI return version = 0x00020001

07/17/2002 20:06:39.669 ciscotsp001.tsp|<--TSPI_lineNegotiateTSPIVersion()

07/17/2002 20:06:39.669 ciscotsp001.tsp|-->TSPI_providerEnumDevices()

07/17/2002 20:06:39.669 ciscotsp001.tsp| TSPI_providerEnumDevices()

dwPermanentProviderID = 0x00000014

hProvider = 0x030C5750

lpfnLineCreateProc = 0x733F1B90

lpfnPhoneCreateProc = 0x733F8E08

07/17/2002 20:06:39.669 ciscotsp001.tsp|-->SelsiusTSP::TSPI_providerEnumDevices()

07/17/2002 20:06:39.669 ciscotsp001.tsp| SelsiusTSP::TSPI_providerEnumDevices() Returning 0 lines 0 phones.

07/17/2002 20:06:39.669 ciscotsp001.tsp|<--SelsiusTSP::TSPI_providerEnumDevices()

07/17/2002 20:06:39.669 ciscotsp001.tsp| TSPI_providerEnumDevices()

TSPI_providerEnumDevices returns = 0x00000000

dwNumLines = 0

dwNumPhones = 0

07/17/2002 20:06:39.669 ciscotsp001.tsp|<--TSPI_providerEnumDevices()

07/17/2002 20:06:39.669 ciscotsp001.tsp|-->TSPI_providerInit()

07/17/2002 20:06:39.669 ciscotsp001.tsp| TSPI_providerInit()

dwTSPIVersion = 0x00020001

dwPermanentProviderID = 0x00000014

lpfnCompletionProc = 0x733FD4B1

07/17/2002 20:06:39.669 ciscotsp001.tsp|-->SelsiusTSP::TSPI_providerInit()

07/17/2002 20:06:39.669 ciscotsp001.tsp| SelsiusTSP::TSPI_providerInit() m_pfnCompletionProc=0x733FD4B1

07/17/2002 20:06:39.669 ciscotsp001.tsp| SelsiusTSP::TSPI_providerInit() dwLineDeviceIDBase=8,dwPhoneDeviceIDBase=0,dwPermanentProviderID=20

07/17/2002 20:06:39.669 ciscotsp001.tsp|<--SelsiusTSP::TSPI_providerInit()

07/17/2002 20:06:39.669 ciscotsp001.tsp| TSPI_providerInit()

TSPI_providerInit returns = 0x00000000

dwLineDeviceIDBase = 0x00000008

dwPhoneDeviceIDBase = 0x00000000

dwNumLines = 0

dwNumPhones = 0

lpdwTSPIOptions = 0x030C575C

07/17/2002 20:06:39.669 ciscotsp001.tsp|<--TSPI_providerInit()

07/17/2002 20:06:39.669 ciscotsp001.tsp|-->SelsiusTSP::ConnectThreadEntry()

07/17/2002 20:06:39.669 ciscotsp001.tsp|-->SelsiusTSP::ConnectLoop()

07/17/2002 20:06:39.669 ciscotsp001.tsp| SelsiusTSP::ConnectLoop() Waiting for signal from main thread that there is a request

07/17/2002 20:06:40.741 ciscotsp001.tsp| CSelsiusTSPLink::GetMessage() select() returned 1.

07/17/2002 20:06:40.741 ciscotsp001.tsp| CSelsiusTSPLink::GetMessage() *ERROR* recv() error=0

07/17/2002 20:06:40.741 ciscotsp001.tsp|<--CSelsiusTSPLink::GetMessage()

07/17/2002 20:06:40.741 ciscotsp001.tsp| CCtiInterface::ReceiveLoop() Link error: lost connection to CTI Manager

07/17/2002 20:06:40.741 ciscotsp001.tsp|-->CCtiInterface::StopHeartbeatThread()

07/17/2002 20:06:40.741 ciscotsp001.tsp| CCtiInterface::StopHeartbeatThread() waiting for heartbeat thread to stop

07/17/2002 20:06:40.741 ciscotsp001.tsp| CCtiInterface::HeartbeatLoop() request to stop the heartbeat thread

07/17/2002 20:06:40.741 ciscotsp001.tsp|<--CCtiInterface::HeartbeatLoop()

07/17/2002 20:06:40.741 ciscotsp001.tsp| CCtiInterface::HeartbeatThreadEntry() Heartbeat thread exiting.

07/17/2002 20:06:40.741 ciscotsp001.tsp| CCtiInterface::StopHeartbeatThread() heartbeat thread stopped

07/17/2002 20:06:40.741 ciscotsp001.tsp|<--CCtiInterface::StopHeartbeatThread()

07/17/2002 20:06:40.741 ciscotsp001.tsp|-->CCtiInterface::AbortPendingRequests()

07/17/2002 20:06:40.741 ciscotsp001.tsp|<--CCtiInterface::AbortPendingRequests()

07/17/2002 20:06:40.741 ciscotsp001.tsp|-->CCtiInterface::ProcessEvent()

07/17/2002 20:06:40.741 ciscotsp001.tsp|-->CTapiApiQueue::AddTail()

07/17/2002 20:06:40.741 ciscotsp001.tsp| CTapiApiQueue::AddTail() ApiID=310, num in request queue=1

07/17/2002 20:06:40.741 ciscotsp001.tsp| CTapiApiQueue::AddTail() signaling thread that message Unknown PDU Number is in queue.

07/17/2002 20:06:40.741 ciscotsp001.tsp|<--CTapiApiQueue::AddTail()

07/17/2002 20:06:40.741 ciscotsp001.tsp|<--CCtiInterface::ProcessEvent()

07/17/2002 20:06:40.741 ciscotsp001.tsp| CCtiInterface::ReceiveLoop() Exiting ReceiveLoop(). Done=1

07/17/2002 20:06:40.741 ciscotsp001.tsp|<--CCtiInterface::ReceiveLoop()

07/17/2002 20:06:40.741 ciscotsp001.tsp| CCtiInterface::ReceiveThreadEntry() Receive thread exiting.

07/17/2002 20:06:40.741 ciscotsp001.tsp|-->SelsiusTSP::ProcessEventQueueUntilEmpty()

07/17/2002 20:06:40.741 ciscotsp001.tsp| SelsiusTSP::ProcessEventQueueUntilEmpty() num in queue=[0]

07/17/2002 20:06:40.741 ciscotsp001.tsp|-->SelsiusTSP::LostConnectionWorker()

07/17/2002 20:06:40.741 ciscotsp001.tsp| SelsiusTSP::LostConnectionWorker() Setting the reconnect signal

07/17/2002 20:06:40.741 ciscotsp001.tsp| SelsiusTSP::ConnectLoop() Received connect event.

07/17/2002 20:06:40.741 ciscotsp001.tsp| SelsiusTSP::ConnectLoop() TSP shutting down/does not want to reconnect due to permanent failure and hence do not try to connect

07/17/2002 20:06:40.741 ciscotsp001.tsp| SelsiusTSP::ConnectLoop() Waiting for signal from main thread that there is a request

07/17/2002 20:06:40.741 ciscotsp001.tsp|<--SelsiusTSP::LostConnectionWorker()

07/17/2002 20:06:40.741 ciscotsp001.tsp|<--SelsiusTSP::ProcessEventQueueUntilEmpty()

07/17/2002 20:06:40.741 ciscotsp001.tsp| SelsiusTSP::SignalingLoop() event thread waiting for signal from main thread that there is a request

07/17/2002 20:06:42.804 ciscotsp001.tsp|-->TSPI_providerUIIdentify()

07/17/2002 20:06:42.804 ciscotsp001.tsp|-->SelsiusTSP::TSPI_providerUIIdentify()

07/17/2002 20:06:42.804 ciscotsp001.tsp| SelsiusTSP::TSPI_providerUIIdentify() TUISPDllPath=C:\WINNT\System32\CiscoTUISP001.dll

07/17/2002 20:06:42.804 ciscotsp001.tsp| SelsiusTSP::TSPI_providerUIIdentify() lpszUIDLLName=C

07/17/2002 20:06:42.804 ciscotsp001.tsp|<--SelsiusTSP::TSPI_providerUIIdentify()

07/17/2002 20:06:42.804 ciscotsp001.tsp| TSPI_providerUIIdentify()

TSPI_providerEnumDevices returns = 0x00000000

lpszUIDLLName = C

07/17/2002 20:06:42.804 ciscotsp001.tsp|<--TSPI_providerUIIdentify()

07/17/2002 20:06:42.804 ciscotsp001.tsp|-->TSPI_providerShutdown()

07/17/2002 20:06:42.804 ciscotsp001.tsp|-->SelsiusTSP::TSPI_providerShutdown()

07/17/2002 20:06:42.804 ciscotsp001.tsp|<--SelsiusTSP::TSPI_providerShutdown()

07/17/2002 20:06:42.804 ciscotsp001.tsp| TSPI_providerShutdown() After GlobalTSP->TSPI_providerShutdown()

07/17/2002 20:06:42.804 ciscotsp001.tsp|-->CSelsiusTSP::~CSelsiusTSP()

07/17/2002 20:06:42.804 ciscotsp001.tsp| CSelsiusTSP::~CSelsiusTSP() *ERROR* Could not send ProviderCloseRequest. Connection to Call Manager lost

07/17/2002 20:06:42.804 ciscotsp001.tsp|-->CSelsiusTSP::StopWorkerThread()

07/17/2002 20:06:42.804 ciscotsp001.tsp| SelsiusTSP::WorkerLoop() worker thread received exiting event.

07/17/2002 20:06:42.804 ciscotsp001.tsp|<--SelsiusTSP::WorkerLoop()

07/17/2002 20:06:42.804 ciscotsp001.tsp| SelsiusTSP::WorkerThreadEntry() Worker thread exiting.

07/17/2002 20:06:42.804 ciscotsp001.tsp| SelsiusTSP::SignalingLoop() event thread received exiting event.

07/17/2002 20:06:42.804 ciscotsp001.tsp|<--SelsiusTSP::SignalingLoop()

07/17/2002 20:06:42.804 ciscotsp001.tsp|-->SelsiusTSP::UninitializeComObjects()

07/17/2002 20:06:42.804 ciscotsp001.tsp|-->CSelsiusTSPWaveList::Uninit()

07/17/2002 20:06:42.804 ciscotsp001.tsp| CSelsiusTSPWaveList::Uninit() Back from AtlUnadvise()

07/17/2002 20:06:42.804 ciscotsp001.tsp|<--CSelsiusTSPWaveList::Uninit()

07/17/2002 20:06:42.804 ciscotsp001.tsp|-->CSelsiusTSPWaveList::~CSelsiusTSPWaveList()

07/17/2002 20:06:42.804 ciscotsp001.tsp|<--CSelsiusTSPWaveList::~CSelsiusTSPWaveList()

07/17/2002 20:06:42.804 ciscotsp001.tsp| SelsiusTSP::UninitializeComObjects() Calling CoUninitialize()

07/17/2002 20:06:42.804 ciscotsp001.tsp|<--SelsiusTSP::UninitializeComObjects()

07/17/2002 20:06:42.804 ciscotsp001.tsp| SelsiusTSP::EventThreadEntry() Event thread exiting.

07/17/2002 20:06:42.804 ciscotsp001.tsp| CSelsiusTSP::StopWorkerThread() waiting for worker thread to stop

07/17/2002 20:06:42.804 ciscotsp001.tsp| CSelsiusTSP::StopWorkerThread() worker thread stopped

07/17/2002 20:06:42.804 ciscotsp001.tsp| CSelsiusTSP::StopWorkerThread() num in queue=[0]

07/17/2002 20:06:42.804 ciscotsp001.tsp|<--CSelsiusTSP::StopWorkerThread()

07/17/2002 20:06:42.804 ciscotsp001.tsp|-->CSelsiusTSP::StopEventThread()

07/17/2002 20:06:42.804 ciscotsp001.tsp| CSelsiusTSP::StopEventThread() waiting for event thread to stop

07/17/2002 20:06:42.804 ciscotsp001.tsp| CSelsiusTSP::StopEventThread() event thread stopped

07/17/2002 20:06:42.804 ciscotsp001.tsp| CSelsiusTSP::StopEventThread() num in queue=[0]

07/17/2002 20:06:42.804 ciscotsp001.tsp|<--CSelsiusTSP::StopEventThread()

07/17/2002 20:06:42.804 ciscotsp001.tsp|-->CSelsiusTSP::StopConnectThread()

07/17/2002 20:06:42.804 ciscotsp001.tsp| SelsiusTSP::ConnectLoop() Received exiting event.

07/17/2002 20:06:42.804 ciscotsp001.tsp|<--SelsiusTSP::ConnectLoop()

07/17/2002 20:06:42.804 ciscotsp001.tsp| SelsiusTSP::ConnectThreadEntry() ConnectLoop() returned.

07/17/2002 20:06:42.804 ciscotsp001.tsp| SelsiusTSP::ConnectThreadEntry() Connect thread exiting.

07/17/2002 20:06:42.804 ciscotsp001.tsp| CSelsiusTSP::StopConnectThread() waiting for connect thread to stop

07/17/2002 20:06:42.804 ciscotsp001.tsp| CSelsiusTSP::StopConnectThread() connect thread stopped

07/17/2002 20:06:42.804 ciscotsp001.tsp|-->CCtiInterface::CloseLink()

07/17/2002 20:06:42.804 ciscotsp001.tsp| CCtiInterface::CloseLink() Closing connection to CallManager

07/17/2002 20:06:42.804 ciscotsp001.tsp| CCtiInterface::CloseLink() waiting for receive thread to stop

07/17/2002 20:06:42.804 ciscotsp001.tsp| CCtiInterface::CloseLink() receive thread stopped

07/17/2002 20:06:42.804 ciscotsp001.tsp|-->CCtiInterface::StopHeartbeatThread()

07/17/2002 20:06:42.804 ciscotsp001.tsp| CCtiInterface::StopHeartbeatThread() heartbeat thread already stopped

07/17/2002 20:06:42.804 ciscotsp001.tsp|<--CCtiInterface::StopHeartbeatThread()

07/17/2002 20:06:42.804 ciscotsp001.tsp|<--CCtiInterface::CloseLink()

07/17/2002 20:06:42.804 ciscotsp001.tsp|<--CSelsiusTSP::StopConnectThread()

07/17/2002 20:06:42.804 ciscotsp001.tsp| CSelsiusTSP::~CSelsiusTSP() deleting CTI Interface object

07/17/2002 20:06:42.804 ciscotsp001.tsp|-->CCtiInterface::~CCtiInterface()

07/17/2002 20:06:42.804 ciscotsp001.tsp|<--CCtiInterface::~CCtiInterface()

07/17/2002 20:06:42.804 ciscotsp001.tsp|<--CSelsiusTSP::~CSelsiusTSP()

07/17/2002 20:06:42.804 ciscotsp001.tsp|<--TSPI_providerShutdown()

07/17/2002 20:06:47.811 ciscotsp001.tsp|-->CTspTrace::~CTspTrace()

Thanks for HELP

Ing. Helmut Schranz

mii ag, lerchenfelder straße 2/203, a-1080 wien

tel: (+43) (0)1 4070587-1251, fax: (+43) (0)1 4070587-7125

mobile: (+43) (0)699 12826005

mailto:helmut.schranz@mii.at

http://www.mii.at